Sep 06 10:34:00 volumio-jonas systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service...
Sep 06 10:34:00 volumio-jonas dbus-daemon[674]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.53' (uid=0 pid=5484 comm="timedatectl show --property=NTPSynchronized --valu")
Sep 06 10:34:00 volumio-jonas systemd[1]: Starting systemd-timedated.service - Time & Date Service...
Sep 06 10:34:00 volumio-jonas dbus-daemon[674]: [system] Successfully activated service 'org.freedesktop.timedate1'
Sep 06 10:34:00 volumio-jonas systemd[1]: Started systemd-timedated.service - Time & Date Service.
Sep 06 10:34:00 volumio-jonas setdatetime-helper.sh[5483]: Time is already synchronized.
Sep 06 10:34:00 volumio-jonas systemd[1]: setdatetime-helper.timer.service: Deactivated successfully.
Sep 06 10:34:00 volumio-jonas systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service.
Sep 06 10:34:30 volumio-jonas systemd[1]: systemd-timedated.service: Deactivated successfully.
Sep 06 10:34:32 volumio-jonas volumio[1099]: verbose: New Socket.io Connection to 192.168.1.63 from 192.168.211.144 UA: Mozilla/5.0 (Linux; Android 7.1.1; XT1562 Build/NPD26.48-24-1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/119.0.6045.193 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: Received Get System Info
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: Discovery: Getting this device information
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::volumioGetState
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::volumioGetState
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::volumioGetVisibleSources
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::volumioGetState
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: Received Get System Info
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: Discovery: Getting this device information
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::volumioGetState
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::volumioGetState
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: Listing playlists
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::volumioGetQueue
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreStateMachine::getQueue
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CorePlayQueue::getQueue
Sep 06 10:34:33 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Sep 06 10:34:41 volumio-jonas volumio-remote-updater[711]: No test mode
Sep 06 10:34:41 volumio-jonas volumio-remote-updater[711]: Alpha test mode enabled
Sep 06 10:34:41 volumio-jonas volumio[1099]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"IMPROVEMENTS
\n\n- CD-ROM playback reliability improved using
cdspeedctl \n- MPD upgraded to version 0.24.5-2 for Volumio 4
\n
\nFIXES
\n\n- ALSA capture: Null dir response resolved
\n- Triggerhappy: Now runs in service-only mode with proper rebind
\n- SMB: Resolved a race condition regression
\n- Multiroom:\n
\n - Fixed use of undefined service variable
\n - Updated
volumio-alsa-plugin to 2.0.0 \n - Removed obsolete segment files
\n
\n \n- UPnP: Fixed device description templates and XML structure placeholders
\n- Backend: Applied workaround for
libxmljs breaking changes affecting WebRadio \n- SMB: Attempted protocol downgrade for better performance
\n
\n","title":"Update v4.018","updateavailable":true}
Sep 06 10:34:41 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Sep 06 10:34:44 volumio-jonas volumio[1099]: info: Update: [object Object]
Sep 06 10:34:44 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkSystemIntegrity
Sep 06 10:34:44 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 06 10:34:50 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , setTestSystem
Sep 06 10:34:50 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkUpdates
Sep 06 10:34:50 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Sep 06 10:34:50 volumio-jonas volumio-remote-updater[711]: PROGRESS: 0, STATUS: "Starting update", ETA: "6m"
Sep 06 10:34:50 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: updater_comm , notifyProgress
Sep 06 10:34:50 volumio-jonas volumio-remote-updater[5673]: /bin/rm: cannot remove '/imgpart/rck*': No such file or directory
Sep 06 10:34:50 volumio-jonas volumio-remote-updater[5673]: /bin/rm: cannot remove '/imgpart/*.part': No such file or directory
Sep 06 10:34:50 volumio-jonas volumio-remote-updater[5673]: /bin/rm: cannot remove '/imgpart/*.zs-old': No such file or directory
Sep 06 10:34:50 volumio-jonas volumio-remote-updater[711]: PROGRESS: 5, STATUS: "Preparing update", ETA: "5m"
Sep 06 10:34:50 volumio-jonas volumio[1099]: info: Killing processes that might interfere with OTA Updates
Sep 06 10:34:50 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Sep 06 10:34:50 volumio-jonas volumio[1099]: updateProgress
Sep 06 10:34:50 volumio-jonas volumio[1099]: {
Sep 06 10:34:50 volumio-jonas volumio[1099]: downloadSpeed: '',
Sep 06 10:34:50 volumio-jonas volumio[1099]: eta: '5m',
Sep 06 10:34:50 volumio-jonas volumio[1099]: progress: 5,
Sep 06 10:34:50 volumio-jonas volumio[1099]: status: 'Aktualisierung wird vorbereitet'
Sep 06 10:34:50 volumio-jonas volumio[1099]: }
Sep 06 10:34:50 volumio-jonas sudo[5681]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall matchbox-keyboard
Sep 06 10:34:50 volumio-jonas sudo[5681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Sep 06 10:34:50 volumio-jonas sudo[5681]: pam_unix(sudo:session): session closed for user root
Sep 06 10:34:50 volumio-jonas volumio[1099]: error: Cannot kill process: Error: Command failed: /usr/bin/sudo /usr/bin/killall matchbox-keyboard
Sep 06 10:34:50 volumio-jonas volumio[1099]: matchbox-keyboard: no process found
Sep 06 10:34:50 volumio-jonas sudo[5684]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall matchbox-window-manager
Sep 06 10:34:50 volumio-jonas sudo[5684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Sep 06 10:34:50 volumio-jonas sudo[5684]: pam_unix(sudo:session): session closed for user root
Sep 06 10:34:50 volumio-jonas volumio[1099]: error: Cannot kill process: Error: Command failed: /usr/bin/sudo /usr/bin/killall matchbox-window-manager
Sep 06 10:34:50 volumio-jonas volumio[1099]: matchbox-window-manager: no process found
Sep 06 10:34:50 volumio-jonas volumio-remote-updater[711]: PROGRESS: 5, STATUS: "Preparing update", ETA: "5m"
Sep 06 10:34:50 volumio-jonas volumio-remote-updater[711]: PROGRESS: 5, STATUS: "Preparing update", ETA: "5m"
Sep 06 10:34:50 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Sep 06 10:34:50 volumio-jonas volumio[1099]: updateProgress
Sep 06 10:34:50 volumio-jonas volumio[1099]: {
Sep 06 10:34:50 volumio-jonas volumio[1099]: downloadSpeed: '',
Sep 06 10:34:50 volumio-jonas volumio[1099]: eta: '5m',
Sep 06 10:34:50 volumio-jonas volumio[1099]: progress: 5,
Sep 06 10:34:50 volumio-jonas volumio[1099]: status: 'Aktualisierung wird vorbereitet'
Sep 06 10:34:50 volumio-jonas volumio[1099]: }
Sep 06 10:34:50 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Sep 06 10:34:50 volumio-jonas volumio[1099]: updateProgress
Sep 06 10:34:50 volumio-jonas volumio[1099]: {
Sep 06 10:34:50 volumio-jonas volumio[1099]: downloadSpeed: '',
Sep 06 10:34:50 volumio-jonas volumio[1099]: eta: '5m',
Sep 06 10:34:50 volumio-jonas volumio[1099]: progress: 5,
Sep 06 10:34:50 volumio-jonas volumio[1099]: status: 'Aktualisierung wird vorbereitet'
Sep 06 10:34:50 volumio-jonas volumio[1099]: }
Sep 06 10:34:50 volumio-jonas volumio[1099]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7
Sep 06 10:34:51 volumio-jonas volumio[1099]: info: CALLMETHOD: user_interface websocket broadcastMessage [object Object]
Sep 06 10:34:51 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Sep 06 10:34:54 volumio-jonas volumio[1099]: info: CoreCommandRouter::Close All Modals sent
Sep 06 10:35:06 volumio-jonas volumio[1099]: Searching all installed plugins
Sep 06 10:35:06 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 06 10:35:06 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: , search
Sep 06 10:35:06 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , search
Sep 06 10:35:06 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search
Sep 06 10:35:06 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: last_100 , search
Sep 06 10:35:06 volumio-jonas volumio[1099]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100
Sep 06 10:35:06 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: webradio , search
Sep 06 10:35:06 volumio-jonas volumio[1099]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search
Sep 06 10:35:06 volumio-jonas volumio[1099]: Use XMLElement.setAttribute instead
Sep 06 10:35:06 volumio-jonas volumio[1099]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 06 10:35:06 volumio-jonas volumio[1099]: TypeError: children[i].attr(...).value is not a function
Sep 06 10:35:06 volumio-jonas volumio[1099]: at /volumio/app/plugins/music_service/webradio/index.js:956:46
Sep 06 10:35:06 volumio-jonas volumio[1099]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5)
Sep 06 10:35:06 volumio-jonas volumio[1099]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 06 10:35:07 volumio-jonas sudo[5782]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-06 10:34'
Sep 06 10:35:07 volumio-jonas sudo[5782]: 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="1da895bfdbb6eb90d965df32a25a3568df7c84ed"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed May 28 19:10:18 UTC 2025"
VOLUMIO_VERSION="4.003"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="1bd40b20372a0e1ffe6ebc6d3df45570"