Jul 11 08:41:03 volumio go-librespot[1447]: time="2025-07-11T08:41:03+02:00" level=trace msg="sent dealer ping" Jul 11 08:41:03 volumio go-librespot[1447]: time="2025-07-11T08:41:03+02:00" level=trace msg="received dealer pong" Jul 11 08:41:09 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 11 08:41:09 volumio dbus-daemon[600]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.58' (uid=0 pid=4323 comm="timedatectl show --property=NTPSynchronized --valu") Jul 11 08:41:09 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 11 08:41:09 volumio dbus-daemon[600]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 11 08:41:09 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 11 08:41:09 volumio setdatetime-helper.sh[4322]: Time is already synchronized. Jul 11 08:41:09 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 11 08:41:09 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 11 08:41:11 volumio go-librespot[1447]: time="2025-07-11T08:41:11+02:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:episode:0lWWCUPMg3Zt1jsmKbNscy/resume-point-revision/835dee47-4f98-4eb5-8140-469afdd8f960" Jul 11 08:41:11 volumio go-librespot[1447]: time="2025-07-11T08:41:11+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 287" Jul 11 08:41:11 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 08:41:11 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 08:41:11 volumio volumio[1120]: info: Discovery: Getting this device information Jul 11 08:41:11 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Jul 11 08:41:11 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Jul 11 08:41:11 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 08:41:11 volumio volumio[1120]: verbose: New Socket.io Connection to 192.168.1.155:3000 from 192.168.1.149 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jul 11 08:41:11 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Jul 11 08:41:11 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Jul 11 08:41:11 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 11 08:41:11 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 11 08:41:14 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Jul 11 08:41:14 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Jul 11 08:41:15 volumio volumio[1120]: verbose: New Socket.io Connection to 192.168.1.155:3000 from 192.168.1.149 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jul 11 08:41:15 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Jul 11 08:41:15 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Jul 11 08:41:15 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 11 08:41:15 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 11 08:41:15 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Jul 11 08:41:15 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Jul 11 08:41:20 volumio sudo[4342]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 11 08:41:20 volumio sudo[4342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 08:41:20 volumio sudo[4342]: pam_unix(sudo:session): session closed for user root Jul 11 08:41:20 volumio sudo[4344]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 11 08:41:20 volumio sudo[4344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 08:41:20 volumio sudo[4344]: pam_unix(sudo:session): session closed for user root Jul 11 08:41:22 volumio volumio[1120]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.149 UA: Mozilla/5.0 (Linux; Android 14; SM-A145R Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.67 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jul 11 08:41:22 volumio sudo[4348]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 11 08:41:22 volumio sudo[4348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 08:41:22 volumio sudo[4348]: pam_unix(sudo:session): session closed for user root Jul 11 08:41:22 volumio sudo[4350]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 11 08:41:22 volumio sudo[4350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 08:41:22 volumio sudo[4350]: pam_unix(sudo:session): session closed for user root Jul 11 08:41:22 volumio volumio[1120]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.149 UA: Mozilla/5.0 (Linux; Android 14; SM-A145R Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.67 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jul 11 08:41:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 11 08:41:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 11 08:41:23 volumio volumio[1120]: info: CoreCommandRouter::volumioGetVisibleSources Jul 11 08:41:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 08:41:23 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Jul 11 08:41:23 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Jul 11 08:41:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 11 08:41:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 11 08:41:23 volumio volumio[1120]: info: Received Get System Info Jul 11 08:41:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 08:41:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 08:41:23 volumio volumio[1120]: info: Discovery: Getting this device information Jul 11 08:41:23 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Jul 11 08:41:23 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Jul 11 08:41:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 08:41:23 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Jul 11 08:41:23 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Jul 11 08:41:23 volumio volumio[1120]: info: Listing playlists Jul 11 08:41:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 11 08:41:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 11 08:41:24 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 11 08:41:24 volumio volumio[1120]: info: Received Get System Info Jul 11 08:41:24 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 08:41:24 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 08:41:24 volumio volumio[1120]: info: Discovery: Getting this device information Jul 11 08:41:24 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Jul 11 08:41:24 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Jul 11 08:41:24 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 08:41:25 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 11 08:41:26 volumio go-librespot[1447]: time="2025-07-11T08:41:26+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 286" Jul 11 08:41:26 volumio go-librespot[1447]: time="2025-07-11T08:41:26+02:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:episode:0lWWCUPMg3Zt1jsmKbNscy/resume-point-revision/22423072-8f8b-4bcc-b164-0c4e967acd0a" Jul 11 08:41:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 11 08:41:26 volumio volumio[1120]: info: Received Get System Info Jul 11 08:41:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 08:41:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 08:41:26 volumio volumio[1120]: info: Discovery: Getting this device information Jul 11 08:41:26 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Jul 11 08:41:26 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Jul 11 08:41:26 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 08:41:30 volumio volumio[1120]: info: Retrieving Cloud Streaming UI Jul 11 08:41:30 volumio volumio[1120]: info: Getting Tidal Cloud Configuration Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 08:41:30 volumio volumio[1120]: info: Getting Qobuz Cloud Configuration Jul 11 08:41:30 volumio volumio[1120]: info: Asking plugin for UI Config Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 08:41:30 volumio volumio[1120]: info: Getting Spotify Cloud Configuration Jul 11 08:41:30 volumio volumio[1120]: info: Asking plugin for UI Config Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 08:41:30 volumio volumio[1120]: info: Saving Spotify Acccount Jul 11 08:41:30 volumio volumio[1120]: info: Got Tidal Cloud Configuration Jul 11 08:41:30 volumio volumio[1120]: info: Got it Jul 11 08:41:30 volumio volumio[1120]: info: Got it Jul 11 08:41:30 volumio volumio[1120]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 08:41:30 volumio volumio[1120]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetBrowseSources Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetBrowseSources Jul 11 08:41:30 volumio volumio[1120]: info: CoreCommandRouter::volumioGetBrowseSources Jul 11 08:41:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 11 08:41:31 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 11 08:41:32 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 08:41:33 volumio go-librespot[1447]: time="2025-07-11T08:41:33+02:00" level=trace msg="sent dealer ping" Jul 11 08:41:33 volumio go-librespot[1447]: time="2025-07-11T08:41:33+02:00" level=trace msg="received dealer pong" Jul 11 08:41:35 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 11 08:41:39 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 11 08:41:41 volumio go-librespot[1447]: time="2025-07-11T08:41:41+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 286" Jul 11 08:41:41 volumio go-librespot[1447]: time="2025-07-11T08:41:41+02:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:episode:0lWWCUPMg3Zt1jsmKbNscy/resume-point-revision/28b0365a-c5ca-4c56-9918-426fd3614dba" Jul 11 08:41:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 11 08:41:41 volumio volumio[1120]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 11 08:41:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 11 08:41:41 volumio volumio[1120]: info: Received Get System Version Jul 11 08:41:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 11 08:41:41 volumio volumio[1120]: info: Received Get System Info Jul 11 08:41:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 08:41:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 08:41:41 volumio volumio[1120]: info: Discovery: Getting this device information Jul 11 08:41:41 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Jul 11 08:41:41 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Jul 11 08:41:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 08:41:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 08:41:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 08:41:41 volumio volumio[1120]: info: Discovery: Getting this device information Jul 11 08:41:41 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Jul 11 08:41:41 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Jul 11 08:41:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 08:41:53 volumio volumio[1120]: info: CoreCommandRouter::volumioGetQueue Jul 11 08:41:53 volumio volumio[1120]: info: CoreStateMachine::getQueue Jul 11 08:41:53 volumio volumio[1120]: info: CorePlayQueue::getQueue Jul 11 08:41:56 volumio go-librespot[1447]: time="2025-07-11T08:41:56+02:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:episode:0lWWCUPMg3Zt1jsmKbNscy/resume-point-revision/3c14d4ee-5872-43ac-b6c3-6b487ab41a45" Jul 11 08:41:56 volumio go-librespot[1447]: time="2025-07-11T08:41:56+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 287" Jul 11 08:42:02 volumio go-librespot[1447]: time="2025-07-11T08:42:02+02:00" level=trace msg="received accesspoint ping" Jul 11 08:42:02 volumio go-librespot[1447]: time="2025-07-11T08:42:02+02:00" level=trace msg="received accesspoint pong ack" Jul 11 08:42:03 volumio go-librespot[1447]: time="2025-07-11T08:42:03+02:00" level=trace msg="sent dealer ping" Jul 11 08:42:03 volumio go-librespot[1447]: time="2025-07-11T08:42:03+02:00" level=trace msg="received dealer pong" Jul 11 08:42:11 volumio go-librespot[1447]: time="2025-07-11T08:42:11+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 287" Jul 11 08:42:11 volumio go-librespot[1447]: time="2025-07-11T08:42:11+02:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:episode:0lWWCUPMg3Zt1jsmKbNscy/resume-point-revision/9f6fa8ea-9c23-43e6-8185-e9f8a34259d4" Jul 11 08:42:11 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 08:42:11 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 08:42:11 volumio volumio[1120]: info: Discovery: Getting this device information Jul 11 08:42:11 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Jul 11 08:42:11 volumio volumio[1120]: info: CorePlayQueue::getTrack 0 Jul 11 08:42:11 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 08:42:12 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 11 08:42:12 volumio volumio[1120]: info: Preload queue cleared Jul 11 08:42:15 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 11 08:42:15 volumio volumio[1120]: info: Preload queue cleared Jul 11 08:42:18 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 11 08:42:19 volumio volumio[1120]: Use XMLElement.setAttribute instead Jul 11 08:42:19 volumio volumio[1120]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 08:42:19 volumio volumio[1120]: TypeError: children[i].attr(...).value is not a function Jul 11 08:42:19 volumio volumio[1120]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 Jul 11 08:42:19 volumio volumio[1120]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jul 11 08:42:19 volumio volumio[1120]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 08:42:19 volumio sudo[4478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-11 08:41' Jul 11 08:42:19 volumio sudo[4478]: 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"