Jun 07 17:17:00 volumio volumio[3833]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jun 07 17:17:02 volumio volumio[3833]: info: MYVOLUMIO: Adding device
Jun 07 17:17:02 volumio volumio[3833]: info: MYVOLUMIO: Evaluating Server
Jun 07 17:17:02 volumio volumio[3833]: info: Setting Geolocation for MyVolumio to as1
Jun 07 17:17:02 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 07 17:17:02 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 07 17:17:02 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 07 17:17:03 volumio volumio[3833]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jun 07 17:17:03 volumio volumio[3833]: info: Updating MyVolumio device info
Jun 07 17:17:03 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 07 17:17:03 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 07 17:17:03 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 07 17:17:04 volumio volumio[3833]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jun 07 17:17:09 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jun 07 17:17:09 volumio volumio[3833]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Jun 07 17:17:09 volumio volumio[3833]: info: CoreCommandRouter::volumioGetState
Jun 07 17:17:09 volumio volumio[3833]: info: CorePlayQueue::getTrack 0
Jun 07 17:17:10 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 07 17:17:10 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 07 17:17:10 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jun 07 17:17:10 volumio volumio-remote-updater[604]: Test mode enabled
Jun 07 17:17:10 volumio volumio-remote-updater[604]: No alpha test mode
Jun 07 17:17:10 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jun 07 17:17:10 volumio volumio[3833]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"NEW ADDITIONS
\n\n- Alsa restor handling
\n- Kernel 6.12.27
\n- New partition layout
\n
\nFIXES
\n\n- Udev init order
\n- Repo node modules collection
\n
\n","title":"Update v4.011","updateavailable":true}
Jun 07 17:17:10 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jun 07 17:17:14 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 07 17:17:14 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 07 17:17:14 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jun 07 17:17:14 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jun 07 17:17:14 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jun 07 17:17:14 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jun 07 17:17:14 volumio volumio[3833]: info: CoreCommandRouter::volumioGetBrowseSources
Jun 07 17:17:14 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 07 17:17:15 volumio volumio[3833]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Jun 07 17:17:15 volumio volumio[3833]: info: Completed starting MyVolumio Plugin
Jun 07 17:17:15 volumio volumio[3833]: [Metrics] CommandRouter: 43s 896.89ms
Jun 07 17:17:15 volumio volumio[3833]: info: CoreCommandRouter::volumiosetStartupVolume
Jun 07 17:17:15 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 07 17:17:15 volumio volumio[3833]: info: VolumeController:: Setting startup Volume 100
Jun 07 17:17:15 volumio volumio[3833]: info: VolumeController::SetAlsaVolume100
Jun 07 17:17:15 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 07 17:17:15 volumio volumio[3833]: info: CoreCommandRouter::Close All Modals sent
Jun 07 17:17:15 volumio volumio[3833]: info: CoreCommandRouter::Close All Modals sent
Jun 07 17:17:15 volumio volumio[3833]: info: CoreStateMachine::pushState
Jun 07 17:17:15 volumio volumio[3833]: info: CorePlayQueue::getTrack 0
Jun 07 17:17:15 volumio volumio[3833]: info: CoreCommandRouter::volumioPushState
Jun 07 17:17:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jun 07 17:17:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jun 07 17:17:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jun 07 17:17:22 volumio volumio[3833]: info: BOOT COMPLETED
Jun 07 17:17:24 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service...
Jun 07 17:17:24 volumio dbus-daemon[587]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.683' (uid=0 pid=4202 comm="timedatectl show --property=NTPSynchronized --valu")
Jun 07 17:17:24 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service...
Jun 07 17:17:25 volumio dbus-daemon[587]: [system] Successfully activated service 'org.freedesktop.timedate1'
Jun 07 17:17:25 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service.
Jun 07 17:17:25 volumio setdatetime-helper.sh[4201]: Time is already synchronized.
Jun 07 17:17:25 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully.
Jun 07 17:17:25 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service.
Jun 07 17:17:55 volumio systemd[1]: systemd-timedated.service: Deactivated successfully.
Jun 07 17:18:16 volumio volumio[3833]: Searching all installed plugins
Jun 07 17:18:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 07 17:18:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: , search
Jun 07 17:18:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: mpd , search
Jun 07 17:18:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search
Jun 07 17:18:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: last_100 , search
Jun 07 17:18:16 volumio volumio[3833]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100
Jun 07 17:18:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: webradio , search
Jun 07 17:18:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search
Jun 07 17:18:16 volumio volumio[3833]: info: [youtube2] InnertubeLoader: creating Innertube instance...
Jun 07 17:18:16 volumio volumio[3833]: Searching all installed plugins
Jun 07 17:18:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 07 17:18:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: , search
Jun 07 17:18:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: mpd , search
Jun 07 17:18:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search
Jun 07 17:18:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: last_100 , search
Jun 07 17:18:16 volumio volumio[3833]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100
Jun 07 17:18:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: webradio , search
Jun 07 17:18:16 volumio volumio[3833]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search
Jun 07 17:18:17 volumio volumio[3833]: Use XMLElement.setAttribute instead
Jun 07 17:18:17 volumio volumio[3833]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 07 17:18:17 volumio volumio[3833]: TypeError: children[i].attr(...).value is not a function
Jun 07 17:18:17 volumio volumio[3833]: at /volumio/app/plugins/music_service/webradio/index.js:956:46
Jun 07 17:18:17 volumio volumio[3833]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5)
Jun 07 17:18:17 volumio volumio[3833]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 07 17:18:17 volumio sudo[4294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-07 17:17'
Jun 07 17:18:17 volumio sudo[4294]: 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="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri May 23 08:02:06 UTC 2025"
VOLUMIO_VERSION="0.069"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="76fa67e30b28261e1dc7c3a89e610b9e"