May 23 12:17:17 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 23 12:17:17 volumio dbus-daemon[624]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.63' (uid=0 pid=4294 comm="timedatectl show --property=NTPSynchronized --valu") May 23 12:17:17 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 23 12:17:17 volumio systemd[1]: systemd-timedated.service: Main process exited, code=killed, status=11/SEGV May 23 12:17:17 volumio systemd[1]: systemd-timedated.service: Failed with result 'signal'. May 23 12:17:17 volumio systemd[1]: Failed to start systemd-timedated.service - Time & Date Service. May 23 12:17:17 volumio kernel: traps: (imedated)[4296] general protection fault ip:7f0a0f25d087 sp:7ffd4672cdf0 error:0 in libc.so.6[95087,7f0a0f1ee000+155000] May 23 12:17:37 volumio volumio[897]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 12:17:37 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 23 12:17:37 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 23 12:17:37 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 23 12:17:37 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 23 12:17:37 volumio volumio[897]: info: Received Get System Version May 23 12:17:37 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 23 12:17:42 volumio timedatectl[4294]: Failed to parse bus message: Connection timed out May 23 12:17:42 volumio setdatetime-helper.sh[4293]: Time is not synchronized. Attempting to sync... May 23 12:17:42 volumio dbus-daemon[624]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) May 23 12:17:42 volumio sudo[4372]: root : unable to resolve host volumio: System error May 23 12:17:42 volumio setdatetime-helper.sh[4372]: sudo: unable to resolve host volumio: System error May 23 12:17:42 volumio sudo[4372]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Fri, 23 May 2025 10:17:43 GMT#015' May 23 12:17:42 volumio sudo[4372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 23 12:17:43 volumio setdatetime-helper.sh[4373]: Fri May 23 12:17:43 CEST 2025 May 23 12:17:43 volumio sudo[4372]: pam_unix(sudo:session): session closed for user root May 23 12:17:43 volumio setdatetime-helper.sh[4293]: Time synchronized successfully. May 23 12:17:43 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 23 12:17:43 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 23 12:17:43 volumio volumio[897]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::volumioGetVisibleSources May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::volumioGetState May 23 12:17:44 volumio volumio[897]: info: CorePlayQueue::getTrack 0 May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 23 12:17:44 volumio volumio[897]: info: Received Get System Info May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 12:17:44 volumio volumio[897]: info: Discovery: Getting this device information May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::volumioGetState May 23 12:17:44 volumio volumio[897]: info: CorePlayQueue::getTrack 0 May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::volumioGetState May 23 12:17:44 volumio volumio[897]: info: CorePlayQueue::getTrack 0 May 23 12:17:44 volumio volumio[897]: info: Listing playlists May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 23 12:17:44 volumio volumio[897]: info: Received Get System Version May 23 12:17:44 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 23 12:17:45 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 12:17:45 volumio volumio[897]: info: Received Get System Info May 23 12:17:45 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 12:17:45 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 12:17:45 volumio volumio[897]: info: Discovery: Getting this device information May 23 12:17:45 volumio volumio[897]: info: CoreCommandRouter::volumioGetState May 23 12:17:45 volumio volumio[897]: info: CorePlayQueue::getTrack 0 May 23 12:17:45 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 12:17:46 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 23 12:17:49 volumio volumio[897]: info: CoreCommandRouter::volumioGetState May 23 12:17:49 volumio volumio[897]: info: CorePlayQueue::getTrack 0 May 23 12:17:53 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 23 12:18:01 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 23 12:18:01 volumio volumio[897]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 23 12:18:01 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 23 12:18:01 volumio volumio[897]: info: Received Get System Version May 23 12:18:01 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 23 12:18:01 volumio volumio[897]: info: Received Get System Info May 23 12:18:01 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 12:18:01 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 12:18:01 volumio volumio[897]: info: Discovery: Getting this device information May 23 12:18:01 volumio volumio[897]: info: CoreCommandRouter::volumioGetState May 23 12:18:01 volumio volumio[897]: info: CorePlayQueue::getTrack 0 May 23 12:18:01 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 12:18:30 volumio ifplugd(eth0)[842]: Link beat lost. May 23 12:18:31 volumio dhcpcd[684]: eth0: carrier lost May 23 12:18:31 volumio dhcpcd[621]: eth0: carrier lost May 23 12:18:31 volumio kernel: e1000: eth0 NIC Link is Down May 23 12:18:31 volumio kernel: e1000 0000:00:03.0 eth0: Reset adapter May 23 12:18:31 volumio avahi-daemon[620]: Withdrawing address record for 10.0.2.15 on eth0. May 23 12:18:31 volumio avahi-daemon[620]: Leaving mDNS multicast group on interface eth0.IPv4 with address 10.0.2.15. May 23 12:18:31 volumio volumio[897]: info: Discovery: A device disappeared from network May 23 12:18:31 volumio avahi-daemon[620]: Interface eth0.IPv4 no longer relevant for mDNS. May 23 12:18:31 volumio dhcpcd[621]: eth0: deleting route to 10.0.2.0/24 May 23 12:18:31 volumio dhcpcd[621]: eth0: deleting default route via 10.0.2.2 May 23 12:18:31 volumio dhcpcd[684]: eth0: deleting route to 10.0.2.0/24 May 23 12:18:31 volumio dhcpcd[684]: eth0: deleting default route via 10.0.2.2 May 23 12:18:31 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 23 12:18:31 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 23 12:18:31 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 23 12:18:31 volumio systemd[1]: welcome.service: Deactivated successfully. May 23 12:18:31 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 23 12:18:31 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 23 12:18:31 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 23 12:18:31 volumio welcome[4513]: Resolved ip:[0] May 23 12:18:31 volumio dbus-daemon[624]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.64' (uid=0 pid=4512 comm="timedatectl show --property=NTPSynchronized --valu") May 23 12:18:31 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 23 12:18:31 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 23 12:18:31 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 23 12:18:31 volumio dbus-daemon[624]: [system] Successfully activated service 'org.freedesktop.timedate1' May 23 12:18:31 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 23 12:18:31 volumio setdatetime-helper.sh[4509]: Time is not synchronized. Attempting to sync... May 23 12:18:31 volumio setdatetime-helper.sh[4509]: Sync attempt failed. May 23 12:18:31 volumio systemd[1]: setdatetime-helper.timer.service: Main process exited, code=exited, status=1/FAILURE May 23 12:18:31 volumio systemd[1]: setdatetime-helper.timer.service: Failed with result 'exit-code'. May 23 12:18:31 volumio systemd[1]: Failed to start setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 23 12:18:32 volumio volumio[897]: info: CoreCommandRouter::volumioPlay May 23 12:18:32 volumio volumio[897]: info: CoreStateMachine::play index undefined May 23 12:18:32 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 12:18:32 volumio volumio[897]: info: CorePlayQueue::getTrack 0 May 23 12:18:33 volumio ntpd[818]: IO: Deleting interface #3 eth0, 10.0.2.15#123, interface stats: received=531, sent=532, dropped=0, active_time=1399 secs May 23 12:18:33 volumio ntpd[818]: PROTO: 79.133.44.141 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 185.13.148.71 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 213.202.247.29 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 94.130.184.193 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 185.255.121.15 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 217.91.44.17 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 212.132.97.26 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 129.70.132.32 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 217.197.91.176 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 188.174.253.188 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 85.215.189.120 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 51.75.67.47 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 85.214.83.151 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 144.91.126.59 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 5.45.97.204 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 157.90.16.34 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 162.159.200.1 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 129.159.192.248 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio ntpd[818]: PROTO: 176.9.157.155 unlink local addr 10.0.2.15 -> May 23 12:18:33 volumio dhcpcd[621]: eth0: carrier acquired May 23 12:18:33 volumio dhcpcd[684]: eth0: carrier acquired May 23 12:18:33 volumio kernel: e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX May 23 12:18:33 volumio dhcpcd[684]: eth0: IAID 27:17:91:2c May 23 12:18:33 volumio dhcpcd[621]: eth0: IAID 27:17:91:2c May 23 12:18:33 volumio dhcpcd[621]: eth0: soliciting an IPv6 router May 23 12:18:33 volumio dhcpcd[621]: eth0: rebinding lease of 10.0.2.15 May 23 12:18:33 volumio dhcpcd[621]: eth0: NAK: wrong network from 192.168.10.1 May 23 12:18:33 volumio dhcpcd[621]: eth0: message: wrong network May 23 12:18:33 volumio dhcpcd[621]: eth0: soliciting a DHCP lease May 23 12:18:33 volumio ifplugd(eth0)[842]: Link beat detected. May 23 12:18:34 volumio dhcpcd[684]: eth0: soliciting an IPv6 router May 23 12:18:34 volumio dhcpcd[684]: eth0: soliciting a DHCP lease May 23 12:18:36 volumio dhcpcd[621]: eth0: offered 192.168.10.153 from 192.168.10.1 May 23 12:18:36 volumio dhcpcd[684]: eth0: offered 192.168.10.153 from 192.168.10.1 May 23 12:18:36 volumio dhcpcd[621]: eth0: probing address 192.168.10.153/24 May 23 12:18:36 volumio dhcpcd[684]: eth0: probing address 192.168.10.153/24 May 23 12:18:39 volumio volumio[897]: info: CoreCommandRouter::volumioGetQueue May 23 12:18:39 volumio volumio[897]: info: CoreStateMachine::getQueue May 23 12:18:39 volumio volumio[897]: info: CorePlayQueue::getQueue May 23 12:18:40 volumio volumio[897]: info: Volumio Network Manager: Network status updated: 0 May 23 12:18:41 volumio dhcpcd[621]: eth0: leased 192.168.10.153 for 86400 seconds May 23 12:18:41 volumio avahi-daemon[620]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.10.153. May 23 12:18:41 volumio dhcpcd[621]: eth0: adding route to 192.168.10.0/24 May 23 12:18:41 volumio dhcpcd[621]: eth0: adding default route via 192.168.10.1 May 23 12:18:41 volumio avahi-daemon[620]: New relevant interface eth0.IPv4 for mDNS. May 23 12:18:41 volumio avahi-daemon[620]: Registering new address record for 192.168.10.153 on eth0.IPv4. May 23 12:18:41 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 23 12:18:41 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 23 12:18:41 volumio systemd[1]: welcome.service: Deactivated successfully. May 23 12:18:41 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 23 12:18:41 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 23 12:18:41 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 23 12:18:41 volumio dhcpcd[684]: eth0: leased 192.168.10.153 for 86400 seconds May 23 12:18:41 volumio welcome[4573]: Resolved ip:[1] 192.168.10.153 May 23 12:18:41 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 23 12:18:41 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 23 12:18:41 volumio dhcpcd[684]: eth0: adding route to 192.168.10.0/24 May 23 12:18:41 volumio dhcpcd[684]: eth0: adding default route via 192.168.10.1 May 23 12:18:41 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 23 12:18:41 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 23 12:18:41 volumio systemd[1]: welcome.service: Deactivated successfully. May 23 12:18:41 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 23 12:18:41 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 23 12:18:41 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 23 12:18:41 volumio welcome[4603]: Resolved ip:[1] 192.168.10.153 May 23 12:18:41 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 23 12:18:41 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 23 12:18:42 volumio volumio[897]: info: Discovery: adding e0793147-2f01-4d90-a2f3-ad74e08a6b6d May 23 12:18:42 volumio volumio[897]: info: Discovery: Found device kapi_Volumio May 23 12:18:42 volumio volumio[897]: info: CoreCommandRouter::volumioGetState May 23 12:18:42 volumio volumio[897]: info: CorePlayQueue::getTrack 0 May 23 12:18:43 volumio ntpd[818]: IO: Listen normally on 4 eth0 192.168.10.153:123 May 23 12:18:43 volumio ntpd[818]: IO: new interface(s) found: waking up resolver May 23 12:18:45 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 23 12:18:45 volumio volumio[897]: info: Preload queue cleared May 23 12:18:49 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 23 12:18:49 volumio volumio[897]: info: Getting webradio selection May 23 12:18:49 volumio volumio[897]: info: Preload queue cleared May 23 12:18:50 volumio volumio[897]: info: Volumio Network Manager: Network status updated: 1 May 23 12:18:58 volumio volumio[897]: Searching plugin music_service/webradio May 23 12:18:58 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 23 12:18:59 volumio volumio[897]: Use XMLElement.setAttribute instead May 23 12:18:59 volumio volumio[897]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 12:18:59 volumio volumio[897]: TypeError: children[i].attr(...).value is not a function May 23 12:18:59 volumio volumio[897]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 May 23 12:18:59 volumio volumio[897]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 23 12:18:59 volumio volumio[897]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 12:18:59 volumio sudo[4640]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-23 12:17' May 23 12:18:59 volumio sudo[4640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a9e1adf9740e24b1b1a392b34b6acf37064d0420" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 21 14:28:31 UTC 2025" VOLUMIO_VERSION="0.068" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="244a93a40b2e4c18f4124fa55cfb0ef0"