-- Logs begin at Sat 2025-12-27 17:34:21 +07, end at Sat 2025-12-27 17:37:58 +07. --
Dec 27 17:36:17 chot volumio[773]: info: Enabling plugin music_services_shield
Dec 27 17:36:17 chot volumio[773]: info: Loading plugin "music_services_shield"...
Dec 27 17:36:17 chot volumio[773]: info: PLUGIN START: music_services_shield
Dec 27 17:36:17 chot sudo[2388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh
Dec 27 17:36:17 chot sudo[2388]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 17:36:17 chot kernel: cgroup: new mount options do not match the existing superblock, will be ignored
Dec 27 17:36:17 chot sudo[2388]: pam_unix(sudo:session): session closed for user root
Dec 27 17:36:17 chot volumio[773]: info: Done.
Dec 27 17:36:17 chot sudo[2416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh
Dec 27 17:36:17 chot sudo[2416]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 17:36:17 chot systemd[1]: Starting Shield Volumio Music Services in the User CPU Set...
Dec 27 17:36:18 chot volumio[2425]: cset: --> shielding system active with
Dec 27 17:36:18 chot volumio[2425]: cset: "system" cpuset of CPUSPEC(1-3) with 127 tasks running
Dec 27 17:36:18 chot volumio[2425]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running
Dec 27 17:36:18 chot systemd[1]: Started Shield Volumio Music Services in the User CPU Set.
Dec 27 17:36:18 chot systemd[1]: Reloading.
Dec 27 17:36:18 chot systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly.
Dec 27 17:36:18 chot systemd[1]: serial-getty@ttyS3.service: Current command vanished from the unit file, execution of the command list won't be resumed.
Dec 27 17:36:18 chot sudo[2416]: pam_unix(sudo:session): session closed for user root
Dec 27 17:36:18 chot volumio[773]: info: succeeded
Dec 27 17:36:21 chot volumio[773]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/Systeminfo/volumio/buster/armhf
Dec 27 17:36:23 chot volumio[773]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/Systeminfo/volumio/buster/armhf
Dec 27 17:36:23 chot volumio[773]: info: Folder /tmp/plugins removed
Dec 27 17:36:23 chot volumio[773]: info: Check plugin dependencies
Dec 27 17:36:23 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 27 17:36:23 chot volumio[773]: info: Checking if plugin already exists
Dec 27 17:36:23 chot volumio[773]: info: Rename folder
Dec 27 17:36:23 chot volumio[773]: info: Folder /tmp/downloaded_plugin.zip removed
Dec 27 17:36:23 chot volumio[773]: info: Move to category
Dec 27 17:36:23 chot volumio[773]: info: Checking if install.sh is present
Dec 27 17:36:23 chot volumio[773]: info: Executing install.sh
Dec 27 17:36:23 chot sudo[2535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/Systeminfo/install.sh
Dec 27 17:36:23 chot sudo[2535]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 17:36:23 chot volumio[773]: info: Installing systeminfo
Dec 27 17:36:23 chot volumio[773]: info: Configuration file doesn't exist, nothing to do
Dec 27 17:36:23 chot volumio[773]: info: Detected cpu architecture as armv7l
Dec 27 17:36:23 chot sudo[2545]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/user_interface/Systeminfo/c/hw_params_arm /data/plugins/user_interface/Systeminfo/hw_params
Dec 27 17:36:23 chot sudo[2545]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 17:36:23 chot sudo[2545]: pam_unix(sudo:session): session closed for user root
Dec 27 17:36:23 chot sudo[2554]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/user_interface/Systeminfo/hw_params
Dec 27 17:36:23 chot sudo[2554]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 17:36:23 chot sudo[2554]: pam_unix(sudo:session): session closed for user root
Dec 27 17:36:24 chot sudo[2563]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/user_interface/Systeminfo/firmware.sh
Dec 27 17:36:24 chot sudo[2563]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 17:36:24 chot sudo[2563]: pam_unix(sudo:session): session closed for user root
Dec 27 17:36:24 chot sudo[2535]: pam_unix(sudo:session): session closed for user root
Dec 27 17:36:24 chot volumio[773]: Plugin install end detected on script
Dec 27 17:36:24 chot volumio[773]: info: Install script completed
Dec 27 17:36:24 chot volumio[773]: info: Adding reference to registry
Dec 27 17:36:24 chot volumio[773]: info: Done installing plugin.
Dec 27 17:36:24 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 27 17:36:24 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Dec 27 17:36:24 chot volumio[773]: info: Folder /tmp/plugins removed
Dec 27 17:36:24 chot volumio[773]: info: Folder /tmp/downloaded_plugin.zip removed
Dec 27 17:36:24 chot volumio[773]: info: Folder /data/temp removed
Dec 27 17:36:40 chot volumio[773]: info: Enabling plugin Systeminfo
Dec 27 17:36:40 chot volumio[773]: info: Loading plugin "Systeminfo"...
Dec 27 17:36:40 chot volumio[773]: info: PLUGIN START: Systeminfo
Dec 27 17:36:40 chot volumio[773]: info: Done.
Dec 27 17:36:43 chot volumio[773]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 27 17:36:45 chot volumio-time-update[561]: volumio-time-update-util: Date not found in response
Dec 27 17:36:45 chot volumio-time-update[561]: volumio-time-update-util: Retrying in 5 seconds...
Dec 27 17:36:50 chot volumio[773]: info: CALLMETHOD: system_hardware music_services_shield saveConfig [object Object]
Dec 27 17:36:50 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: music_services_shield , saveConfig
Dec 27 17:36:50 chot sudo[2650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh
Dec 27 17:36:50 chot sudo[2650]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 17:36:50 chot sudo[2650]: pam_unix(sudo:session): session closed for user root
Dec 27 17:36:50 chot volumio[773]: info: succeeded
Dec 27 17:36:50 chot volumio-time-update[561]: volumio-time-update-util: Fetching time from Volumio...
Dec 27 17:36:51 chot volumio-time-update[561]: volumio-time-update-util: Setting system time to: 2025-12-27 17:36:51
Dec 27 17:36:51 chot sudo[2679]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-12-27 17:36:51
Dec 27 17:36:51 chot sudo[2679]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 17:36:51 chot dbus-daemon[556]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.13' (uid=0 pid=2684 comm="timedatectl set-time 2025-12-27 17:36:51 ")
Dec 27 17:36:51 chot systemd[1]: Starting Time & Date Service...
Dec 27 17:36:51 chot dbus-daemon[556]: [system] Successfully activated service 'org.freedesktop.timedate1'
Dec 27 17:36:51 chot systemd[1]: Started Time & Date Service.
Dec 27 17:36:51 chot systemd-timedated[2685]: Changed local time to Sat Dec 27 17:36:51 2025
Dec 27 17:36:51 chot sudo[2679]: pam_unix(sudo:session): session closed for user root
Dec 27 17:36:51 chot volumio-time-update[561]: volumio-time-update-util: System time updated successfully.
Dec 27 17:36:51 chot systemd[1]: Started Volumio Time Update Utility.
Dec 27 17:36:51 chot systemd[1]: Reached target Multi-User System.
Dec 27 17:36:51 chot systemd[1]: Reached target Graphical Interface.
Dec 27 17:36:51 chot systemd[1]: Starting Update UTMP about System Runlevel Changes...
Dec 27 17:36:51 chot systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Dec 27 17:36:51 chot systemd[1]: Started Update UTMP about System Runlevel Changes.
Dec 27 17:36:51 chot systemd[1]: Startup finished in 14.182s (kernel) + 2min 30.497s (userspace) = 2min 44.679s.
Dec 27 17:36:53 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 27 17:36:53 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Dec 27 17:36:55 chot volumio[773]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 27 17:36:56 chot volumio[773]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=tinkerboard&variante=volumio&version=3.886&uuid=70672ae70a8def84d494ea169164a7a0" http://updates.volumio.org/downloader-v1/track-device
Dec 27 17:36:56 chot volumio[773]: % Total % Received % Xferd Average Speed Time Time Time Current
Dec 27 17:36:56 chot volumio[773]: Dload Upload Total Spent Left Speed
Dec 27 17:36:56 chot volumio[773]: [9.9K blob data]
Dec 27 17:36:56 chot volumio[773]: curl: (7) Failed to connect to updates.volumio.org port 80: Invalid argument
Dec 27 17:36:56 chot volumio[773]: retrying in 5 seconds, trial 1
Dec 27 17:36:56 chot volumio[773]: info: Volumio Calling Home
Dec 27 17:36:57 chot volumio[773]: info: CALLMETHOD: user_interface Systeminfo getsysteminfo undefined
Dec 27 17:36:57 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: Systeminfo , getsysteminfo
Dec 27 17:36:57 chot sudo[2697]: pam_unix(sudo:auth): conversation failed
Dec 27 17:36:57 chot sudo[2697]: pam_unix(sudo:auth): auth could not identify password for [volumio]
Dec 27 17:37:00 chot volumio[773]: info: board is
Dec 27 17:37:00 chot volumio[773]: Storage info 11860M 206M 11038M
Dec 27 17:37:00 chot volumio[773]: Storage info 11860M 206M 11038M
Dec 27 17:37:00 chot volumio[773]: BBBBBBBBBBBBBB-CPU Temp 56 °C
Dec 27 17:37:00 chot volumio[773]: info: Firmware detection failed: Error: Command failed: sudo vcgencmd version
Dec 27 17:37:00 chot volumio[773]: sudo: no tty present and no askpass program specified
Dec 27 17:37:00 chot volumio[773]: info: Volumio called home
Dec 27 17:37:01 chot volumio[773]: info: Error reading Systeminfo/config.json, detection failed Cannot read property 'value' of undefined {"stack":"TypeError: Cannot read property 'value' of undefined\n at /data/plugins/user_interface/Systeminfo/index.js:165:60\n at /data/plugins/user_interface/Systeminfo/node_modules/graceful-fs/graceful-fs.js:115:16\n at FSReqCallback.readFileAfterClose [as oncomplete] (internal/fs/read_file_context.js:71:3)"}
Dec 27 17:37:02 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 27 17:37:21 chot systemd[1]: systemd-timedated.service: Succeeded.
Dec 27 17:37:32 chot volumio[773]: info: CoreCommandRouter::Close All Modals sent
Dec 27 17:37:34 chot volumio[773]: info: CoreCommandRouter::volumioGetState
Dec 27 17:37:34 chot volumio[773]: info: CorePlayQueue::getTrack 0
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 27 17:37:37 chot volumio[773]: info: Retrieving Cloud Streaming UI
Dec 27 17:37:37 chot volumio[773]: info: Getting Tidal Cloud Configuration
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 27 17:37:37 chot volumio[773]: info: Getting Qobuz Cloud Configuration
Dec 27 17:37:37 chot volumio[773]: info: Asking plugin for UI Config
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 27 17:37:37 chot volumio[773]: info: Getting Spotify Cloud Configuration
Dec 27 17:37:37 chot volumio[773]: info: Asking plugin for UI Config
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 27 17:37:37 chot volumio[773]: info: Saving Spotify Acccount
Dec 27 17:37:37 chot volumio[773]: info: Got Tidal Cloud Configuration
Dec 27 17:37:37 chot volumio[773]: info: Got it
Dec 27 17:37:37 chot volumio[773]: info: Got it
Dec 27 17:37:37 chot volumio[773]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 27 17:37:37 chot volumio[773]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 27 17:37:37 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Dec 27 17:37:41 chot volumio[773]: info: Disabling MyMusic plugin upnp
Dec 27 17:37:41 chot sudo[3380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service
Dec 27 17:37:41 chot sudo[3380]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 17:37:41 chot volumio[773]: error: Upnp client error: Error: This socket has been ended by the other party
Dec 27 17:37:41 chot systemd[1]: Stopping UPnP Renderer front-end to MPD...
Dec 27 17:37:41 chot volumio[773]: info: Disabling MyMusic plugin airplay_emulation
Dec 27 17:37:41 chot volumio[773]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync
Dec 27 17:37:41 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 27 17:37:41 chot volumio[773]: info: Disabling plugin airplay_emulation
Dec 27 17:37:41 chot volumio[773]: info: Done.
Dec 27 17:37:41 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 27 17:37:41 chot sudo[3387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync
Dec 27 17:37:41 chot sudo[3387]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 17:37:41 chot systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Dec 27 17:37:41 chot systemd[1]: shairport-sync.service: Succeeded.
Dec 27 17:37:41 chot systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Dec 27 17:37:41 chot sudo[3387]: pam_unix(sudo:session): session closed for user root
Dec 27 17:37:41 chot volumio[773]: info: Shairport-Sync Stopped
Dec 27 17:37:42 chot volumio[773]: info: Disabling MyMusic plugin upnp_browser
Dec 27 17:37:42 chot volumio[773]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers
Dec 27 17:37:42 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 27 17:37:42 chot volumio[773]: info: Disabling plugin upnp_browser
Dec 27 17:37:42 chot volumio[773]: info: Done.
Dec 27 17:37:43 chot volumio[773]: info: Disabling MyMusic plugin upnp
Dec 27 17:37:43 chot sudo[3397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service
Dec 27 17:37:43 chot sudo[3397]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 17:37:45 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 27 17:37:49 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 27 17:37:51 chot volumio[773]: info: CALLMETHOD: miscellanea my_music updateMusicLibraryBrowseSourcesVisibility [object Object]
Dec 27 17:37:51 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibraryBrowseSourcesVisibility
Dec 27 17:37:51 chot volumio[773]: info: CoreCommandRouter::volumioUpdateToBrowseSources
Dec 27 17:37:51 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 27 17:37:53 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 27 17:37:57 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 27 17:37:58 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares
Dec 27 17:37:58 chot volumio[773]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService
Dec 27 17:37:58 chot volumio[773]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 27 17:37:58 chot volumio[773]: Error: getaddrinfo -3007
Dec 27 17:37:58 chot volumio[773]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11)
Dec 27 17:37:58 chot volumio[773]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10)
Dec 27 17:37:58 chot volumio[773]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) {
Dec 27 17:37:58 chot volumio[773]: code: -3007,
Dec 27 17:37:58 chot volumio[773]: errno: -3007,
Dec 27 17:37:58 chot volumio[773]: syscall: 'getaddrinfo'
Dec 27 17:37:58 chot volumio[773]: }
Dec 27 17:37:58 chot volumio[773]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 27 17:37:58 chot sudo[3446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 17:36
Dec 27 17:37:58 chot sudo[3446]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 10 (buster)"
NAME="Debian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
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="b43c85cc0006d3f1efecba101311ec96e334d1fd"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62"
VOLUMIO_ARCH="armv7"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:03:54 PM CET"
VOLUMIO_VERSION="3.886"
VOLUMIO_HARDWARE="tinkerboard"
VOLUMIO_DEVICENAME="Asus Tinkerboard"
VOLUMIO_HASH="35e446ff98e90abcfcab1bb2733dfd3a"