-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Mon 2026-02-23 09:20:16 +07. -- Feb 23 09:19:07 music systemd-timedated[973]: Changed local time to Mon Feb 23 09:19:07 2026 Feb 23 09:19:07 music sudo[971]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:07 music volumio-time-update[628]: volumio-time-update-util: System time updated successfully. Feb 23 09:19:07 music systemd[1]: Started Volumio Time Update Utility. Feb 23 09:19:07 music wireless.js[590]: trying... Feb 23 09:19:07 music sudo[975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 09:19:07 music sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:07 music sudo[975]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:08 music nmbd[745]: [2026/02/23 09:19:08.238810, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 23 09:19:08 music nmbd[745]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 23 09:19:08 music systemd[1]: Started Samba NMB Daemon. Feb 23 09:19:08 music volumio-remote-updater[644]: [2026-02-23 09:19:08] [info] asio async_connect error: system:111 (Connection refused) Feb 23 09:19:08 music volumio-remote-updater[644]: [2026-02-23 09:19:08] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 23 09:19:08 music volumio-remote-updater[644]: [2026-02-23 09:19:08] [error] handle_connect error: Underlying Transport Error Feb 23 09:19:08 music nmbd[745]: [2026/02/23 09:19:08.251290, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 23 09:19:08 music nmbd[745]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.100.84 for name WORKGROUP<1d>. Feb 23 09:19:08 music nmbd[745]: This response was from IP 192.168.100.20, reporting an IP address of 192.168.100.20. Feb 23 09:19:08 music systemd[1]: Starting Samba Winbind Daemon... Feb 23 09:19:08 music winbindd[977]: [2026/02/23 09:19:08.532289, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 23 09:19:08 music winbindd[977]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 23 09:19:08 music winbindd[977]: [2026/02/23 09:19:08.557510, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 23 09:19:08 music winbindd[977]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 23 09:19:08 music systemd[1]: Started Samba Winbind Daemon. Feb 23 09:19:08 music systemd[1]: Starting Samba SMB Daemon... Feb 23 09:19:08 music wireless.js[590]: trying... Feb 23 09:19:08 music sudo[984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 09:19:08 music sudo[984]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:08 music sudo[984]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:09 music smbd[982]: [2026/02/23 09:19:09.094270, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 23 09:19:09 music systemd[1]: Started Samba SMB Daemon. Feb 23 09:19:09 music smbd[982]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 23 09:19:09 music wireless.js[590]: trying... Feb 23 09:19:09 music sudo[994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 09:19:09 music sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:09 music sudo[994]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:10 music wireless.js[590]: trying... Feb 23 09:19:10 music sudo[997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 09:19:10 music sudo[997]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:10 music sudo[997]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:11 music wireless.js[590]: trying... Feb 23 09:19:11 music sudo[1000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 09:19:11 music sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:11 music sudo[1000]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:12 music wireless.js[590]: trying... Feb 23 09:19:12 music sudo[1003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 09:19:12 music sudo[1003]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:12 music sudo[1003]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:13 music wireless.js[590]: trying... Feb 23 09:19:13 music sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 09:19:13 music sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:13 music sudo[1006]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:14 music wireless.js[590]: trying... Feb 23 09:19:14 music sudo[1009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 09:19:14 music sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:14 music sudo[1009]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:15 music wireless.js[590]: trying... Feb 23 09:19:15 music sudo[1012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 09:19:15 music sudo[1012]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:15 music sudo[1012]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:16 music wireless.js[590]: trying... Feb 23 09:19:16 music sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 09:19:16 music sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:16 music sudo[1015]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:17 music wireless.js[590]: trying... Feb 23 09:19:17 music sudo[1018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 09:19:17 music sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:17 music sudo[1018]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:18 music volumio-remote-updater[644]: [2026-02-23 09:19:18] [info] asio async_connect error: system:111 (Connection refused) Feb 23 09:19:18 music volumio-remote-updater[644]: [2026-02-23 09:19:18] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 23 09:19:18 music volumio-remote-updater[644]: [2026-02-23 09:19:18] [error] handle_connect error: Underlying Transport Error Feb 23 09:19:18 music wireless.js[590]: trying... Feb 23 09:19:18 music sudo[1021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 09:19:18 music sudo[1021]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:18 music sudo[1021]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:19 music wireless.js[590]: trying... Feb 23 09:19:19 music sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 09:19:19 music sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:19 music sudo[1024]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:20 music wireless.js[590]: trying... Feb 23 09:19:20 music sudo[1027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 09:19:20 music sudo[1027]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:20 music sudo[1027]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:21 music wireless.js[590]: trying... Feb 23 09:19:21 music sudo[1030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 09:19:21 music sudo[1030]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:21 music sudo[1030]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:22 music wireless.js[590]: trying... Feb 23 09:19:22 music sudo[1033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 09:19:22 music sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:22 music sudo[1033]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:23 music wpa_supplicant[920]: wlan0: Failed to initiate sched scan Feb 23 09:19:23 music wireless.js[590]: Overtime, starting plan B Feb 23 09:19:23 music kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 23 09:19:23 music systemd[1]: Started Wireless Services. Feb 23 09:19:23 music systemd[1]: Started Volumio Backend Module. Feb 23 09:19:23 music systemd[1]: Started Volumio Cpu Tweaker. Feb 23 09:19:23 music systemd[1]: Reached target Multi-User System. Feb 23 09:19:23 music systemd[1]: Reached target Graphical Interface. Feb 23 09:19:23 music systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 23 09:19:23 music systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 23 09:19:23 music systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 23 09:19:23 music systemd[1]: Startup finished in 15.722s (kernel) + 35.546s (userspace) = 51.269s. Feb 23 09:19:23 music volumio-cpu-tweak[1039]: Setting RT Priority for mpd Feb 23 09:19:23 music volumio-cpu-tweak[1039]: pid 35's current scheduling policy: SCHED_OTHER Feb 23 09:19:23 music volumio-cpu-tweak[1039]: pid 35's current scheduling priority: 0 Feb 23 09:19:23 music volumio-cpu-tweak[1039]: Setting MPD Affinity Feb 23 09:19:23 music volumio-cpu-tweak[1039]: pid 3's current affinity mask: f Feb 23 09:19:23 music volumio-cpu-tweak[1039]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 23 09:19:23 music volumio-cpu-tweak[1039]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 23 09:19:24 music systemd[1]: volumio_cpu_tweak.service: Succeeded. Feb 23 09:19:24 music systemd[1]: systemd-fsckd.service: Succeeded. Feb 23 09:19:25 music volumio[1038]: info: ------------------------------------------- Feb 23 09:19:25 music volumio[1038]: info: ----- Volumio3 ---- Feb 23 09:19:25 music volumio[1038]: info: ------------------------------------------- Feb 23 09:19:25 music volumio[1038]: info: ----- System startup ---- Feb 23 09:19:25 music volumio[1038]: info: ------------------------------------------- Feb 23 09:19:26 music volumio[1038]: info: MYVOLUMIO Environment detected Feb 23 09:19:26 music volumio[1038]: info: Plugin folders cleanup Feb 23 09:19:26 music volumio[1038]: info: Scanning into folder /volumio/app/plugins/ Feb 23 09:19:26 music volumio[1038]: info: Scanning category audio_interface Feb 23 09:19:26 music volumio[1038]: info: Scanning category miscellanea Feb 23 09:19:26 music volumio[1038]: info: Scanning category music_service Feb 23 09:19:26 music volumio[1038]: info: Scanning category plugins.json Feb 23 09:19:26 music volumio[1038]: info: Scanning category system_controller Feb 23 09:19:26 music volumio[1038]: info: Scanning category user_interface Feb 23 09:19:26 music volumio[1038]: info: Scanning into folder /data/plugins/ Feb 23 09:19:26 music volumio[1038]: info: Scanning category music_service Feb 23 09:19:26 music volumio[1038]: info: Plugin folders cleanup completed Feb 23 09:19:26 music volumio[1038]: info: ------------------------------------------- Feb 23 09:19:26 music volumio[1038]: info: ----- Core plugins startup ---- Feb 23 09:19:26 music volumio[1038]: info: ------------------------------------------- Feb 23 09:19:26 music volumio[1038]: info: Loading plugins from folder /volumio/app/plugins/ Feb 23 09:19:26 music volumio[1038]: info: Adding plugin upnp to MyMusic Plugins Feb 23 09:19:26 music volumio[1038]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 23 09:19:26 music volumio[1038]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 23 09:19:26 music volumio[1038]: info: Loading plugins from folder /data/plugins/ Feb 23 09:19:26 music volumio[1038]: info: Loading plugin "system"... Feb 23 09:19:26 music volumio[1038]: info: Loading plugin "appearance"... Feb 23 09:19:27 music volumio[1038]: info: Loading plugin "network"... Feb 23 09:19:27 music volumio[1038]: info: Refreshing Cached IP Addresses Feb 23 09:19:27 music sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 09:19:27 music sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:27 music sudo[1070]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:27 music sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 09:19:27 music sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:27 music sudo[1072]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:27 music volumio[1038]: info: Loading plugin "services"... Feb 23 09:19:27 music volumio[1038]: info: Loading plugin "volumio5onboarding"... Feb 23 09:19:27 music sudo[1081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 23 09:19:27 music sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:27 music volumio[1038]: info: Loading plugin "alsa_controller"... Feb 23 09:19:27 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 09:19:27 music volumio[1038]: info: Loading plugin "wizard"... Feb 23 09:19:27 music volumio[1038]: info: Loading plugin "networkfs"... Feb 23 09:19:27 music volumio[1038]: info: Starting Udev Watcher for removable devices Feb 23 09:19:27 music sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.10.100/media /mnt/NAS/home Feb 23 09:19:27 music sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:27 music volumio[1038]: info: Ignoring mount for partition: boot Feb 23 09:19:27 music volumio[1038]: info: Ignoring mount for partition: volumio Feb 23 09:19:27 music volumio[1038]: info: Ignoring mount for partition: volumio_data Feb 23 09:19:27 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 09:19:27 music volumio[1038]: info: Loading plugin "volumio_command_line_client"... Feb 23 09:19:27 music volumio[1038]: info: Loading plugin "upnp"... Feb 23 09:19:27 music volumio[1038]: info: [1771813167922] Starting Upmpd Daemon Feb 23 09:19:27 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 09:19:27 music volumio[1038]: info: Loading plugin "my_music"... Feb 23 09:19:27 music volumio[1038]: info: Loading plugin "mpd"... Feb 23 09:19:28 music kernel: Key type cifs.spnego registered Feb 23 09:19:28 music kernel: Key type cifs.idmap registered Feb 23 09:19:28 music kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Feb 23 09:19:28 music kernel: CIFS: Attempting to mount //192.168.10.100/media Feb 23 09:19:28 music volumio-remote-updater[644]: [2026-02-23 09:19:28] [connect] Successful connection Feb 23 09:19:28 music volumio[1038]: info: Loading plugin "upnp_browser"... Feb 23 09:19:28 music volumio[1038]: info: Starting UPNP Browser Feb 23 09:19:28 music volumio[1038]: info: Loading plugin "alarm-clock"... Feb 23 09:19:28 music sudo[1081]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:28 music volumio[1038]: info: Loading plugin "airplay_emulation"... Feb 23 09:19:28 music volumio[1038]: info: Starting Shairport Sync Feb 23 09:19:28 music volumio[1038]: info: Loading plugin "last_100"... Feb 23 09:19:28 music volumio[1038]: info: Loading plugin "webradio"... Feb 23 09:19:28 music volumio[1038]: info: Loading plugin "i2s_dacs"... Feb 23 09:19:28 music volumio[1038]: info: I2S DAC not set, start Auto-detection Feb 23 09:19:28 music volumio[1038]: info: Loading plugin "volumiodiscovery"... Feb 23 09:19:28 music volumio[1038]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 09:19:28 music volumio[1038]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 09:19:28 music volumio[1038]: *** WARNING *** For more information see Feb 23 09:19:28 music node[1038]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 09:19:28 music volumio[1038]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 09:19:28 music volumio[1038]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 09:19:28 music volumio[1038]: *** WARNING *** For more information see Feb 23 09:19:28 music node[1038]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 09:19:28 music node[1038]: *** WARNING *** For more information see Feb 23 09:19:28 music node[1038]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 09:19:28 music node[1038]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 09:19:28 music node[1038]: *** WARNING *** For more information see Feb 23 09:19:28 music volumio[1038]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 09:19:28 music volumio[1038]: info: Discovery: Started advertising with name: Music Feb 23 09:19:28 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 09:19:28 music volumio[1038]: info: Loading plugin "spop"... Feb 23 09:19:29 music volumio[1038]: info: Loading plugin "outputs"... Feb 23 09:19:29 music volumio[1038]: info: Loading plugin "albumart"... Feb 23 09:19:29 music volumio[1038]: info: Plugin example_plugin is not enabled Feb 23 09:19:29 music volumio[1038]: info: Loading plugin "inputs"... Feb 23 09:19:29 music volumio[1038]: info: Loading plugin "updater_comm"... Feb 23 09:19:30 music volumio[1038]: info: Plugin mpdemulation is not enabled Feb 23 09:19:30 music volumio[1038]: info: Loading plugin "rest_api"... Feb 23 09:19:30 music volumio[1038]: info: Loading plugin "websocket"... Feb 23 09:19:30 music volumio[1038]: info: Starting Socket.io Server version 2.3.0 Feb 23 09:19:30 music volumio[1038]: Forking 3 albumart workers Feb 23 09:19:30 music volumio[1038]: info: Loading i18n strings for locale vi Feb 23 09:19:30 music volumio[1038]: Updating browse sources language Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::initPlayerControls Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:19:30 music volumio[1038]: Express server listening on port 3000 Feb 23 09:19:30 music volumio[1038]: [Metrics] WebUI: 5s 421.77ms Feb 23 09:19:30 music volumio[1038]: info: CoreStateMachine::resetVolumioState Feb 23 09:19:30 music volumio[1038]: info: CoreStateMachine::getcurrentVolume Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:19:30 music volumio[1038]: info: Volumio Network Manager: Network status updated: 1 Feb 23 09:19:30 music volumio[1038]: verbose: New Socket.io Connection to 192.168.100.84 from 10.10.1.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 23 09:19:30 music volumio-remote-updater[644]: [2026-02-23 09:19:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1771813168 101 Feb 23 09:19:30 music volumio[1038]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Feb 23 09:19:30 music volumio[1038]: verbose: New Socket.io Connection to 192.168.100.84:3000 from 192.168.100.221 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 23 09:19:30 music volumio[1038]: Starting albumart workers Feb 23 09:19:30 music volumio[1038]: Starting albumart workers Feb 23 09:19:30 music volumio[1038]: Starting albumart workers Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 23 09:19:30 music volumio[1038]: info: Reloading queue from file Feb 23 09:19:30 music volumio[1038]: info: VolumeController:: Volume=21 Mute =false Feb 23 09:19:30 music volumio[1038]: info: CoreStateMachine::pushState Feb 23 09:19:30 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::volumioPushState Feb 23 09:19:30 music volumio[1038]: info: CoreStateMachine::updateTrackBlock Feb 23 09:19:30 music volumio[1038]: info: CorePlayQueue::getTrackBlock Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 09:19:30 music volumio[1038]: info: CoreStateMachine::setRepeat null single undefined Feb 23 09:19:30 music volumio[1038]: info: CoreStateMachine::pushState Feb 23 09:19:30 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::volumioPushState Feb 23 09:19:30 music volumio[1038]: info: CoreStateMachine::setRandom true Feb 23 09:19:30 music volumio[1038]: info: CoreStateMachine::pushState Feb 23 09:19:30 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::volumioPushState Feb 23 09:19:30 music volumio[1038]: info: Setting Device type: Raspberry PI Feb 23 09:19:30 music volumio[1038]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 23 09:19:30 music volumio[1038]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Feb 23 09:19:30 music volumio[1038]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:30 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:30 music volumio[1038]: info: Discovery: adding c49d034b-f8a9-4c26-a198-6157ce2e00cb Feb 23 09:19:30 music volumio[1038]: info: Discovery: Found device Music Feb 23 09:19:30 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:30 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:30 music volumio[1038]: info: Discovery: adding fcffbed1-3530-4afd-8f65-1776e96e1324 Feb 23 09:19:30 music volumio[1038]: info: Discovery: Found device phongkhach Feb 23 09:19:31 music volumio[1038]: info: Discovery: Connecting to remote: 192.168.100.221 Feb 23 09:19:31 music volumio[1038]: info: VolumeController:: Volume=21 Mute =false Feb 23 09:19:31 music volumio[1038]: info: CoreStateMachine::pushState Feb 23 09:19:31 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:31 music volumio[1038]: info: CoreCommandRouter::volumioPushState Feb 23 09:19:31 music volumio[1038]: info: Discovery: Connected to remote: 192.168.100.221 Feb 23 09:19:31 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:31 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:31 music volumio[1038]: info: Listing playlists Feb 23 09:19:31 music volumio[1038]: info: Listing playlists Feb 23 09:19:31 music kernel: hwmon hwmon1: Undervoltage detected! Feb 23 09:19:31 music volumio[1038]: verbose: New Socket.io Connection to 192.168.100.84 from 10.10.1.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 23 09:19:31 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 09:19:31 music volumio[1038]: info: CoreCommandRouter::volumioGetVisibleSources Feb 23 09:19:31 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 09:19:31 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:31 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:31 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:31 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:31 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 23 09:19:31 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 09:19:32 music volumio[1038]: info: Received Get System Info Feb 23 09:19:32 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 09:19:32 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 09:19:32 music volumio[1038]: info: Discovery: Getting this device information Feb 23 09:19:32 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:32 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:32 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 09:19:32 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:32 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:32 music volumio[1038]: info: Listing playlists Feb 23 09:19:34 music wpa_supplicant[920]: wlan0: Failed to initiate sched scan Feb 23 09:19:37 music systemd[1]: systemd-timedated.service: Succeeded. Feb 23 09:19:37 music sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 09:19:37 music sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:37 music sudo[1198]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:37 music sudo[1200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 09:19:37 music sudo[1200]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:37 music sudo[1200]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:37 music sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 23 09:19:37 music sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:38 music kernel: hwmon hwmon1: Voltage normalised Feb 23 09:19:38 music systemd[1]: Started UPnP Renderer front-end to MPD. Feb 23 09:19:38 music sudo[1204]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:38 music volumio[1038]: info: Upmpdcli Daemon Started Feb 23 09:19:38 music sudo[1098]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:38 music volumio[1038]: info: Cannot mount NAS home at system boot, trial number 1 ,retrying in 5 seconds Feb 23 09:19:38 music volumio[1038]: info: Completed loading Core Plugins Feb 23 09:19:38 music volumio[1038]: info: Preparing to generate the ALSA configuration file Feb 23 09:19:38 music kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 23 09:19:38 music kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Feb 23 09:19:38 music volumio[1038]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 23 09:19:38 music volumio[1038]: info: Asound.conf file unchanged, so no further update is needed Feb 23 09:19:38 music volumio[1038]: info: Output device has changed, restarting MPD Feb 23 09:19:38 music sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 09:19:38 music volumio[1038]: info: Output device has changed, restarting Shairport Sync Feb 23 09:19:38 music sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:19:38 music sudo[1209]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:38 music sudo[1211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 09:19:38 music sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:38 music volumio[1038]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 09:19:38 music volumio[1038]: info: ___________ START PLUGINS ___________ Feb 23 09:19:38 music systemd[1]: Listening on mpd.socket. Feb 23 09:19:38 music systemd[1]: Starting Music Player Daemon... Feb 23 09:19:38 music volumio[1038]: info: ControllerMpd::onStart: Initializing MPD Feb 23 09:19:38 music volumio[1038]: info: Creating MPD Configuration file Feb 23 09:19:38 music sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 23 09:19:38 music sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 09:19:38 music volumio[1038]: info: [1771813178249] CoreMusicLibrary::Adding element Máy chủ Media Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 09:19:38 music sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 09:19:38 music volumio[1038]: info: UPNP Browser: Client initialized successfully Feb 23 09:19:38 music sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 09:19:38 music sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:38 music systemd[1]: Started Volumio5 Onboarding Server. Feb 23 09:19:38 music sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:38 music sudo[1220]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:38 music sudo[1223]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:19:38 music sudo[1218]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 09:19:38 music sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:38 music sudo[1218]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 23 09:19:38 music sudo[1218]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:38 music systemd[1]: mpd.service: Succeeded. Feb 23 09:19:38 music systemd[1]: Stopped Music Player Daemon. Feb 23 09:19:38 music systemd[1]: Starting Music Player Daemon... Feb 23 09:19:38 music volumio[1038]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 09:19:38 music volumio[1038]: info: [1771813178367] CoreMusicLibrary::Adding element Last_100 Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 09:19:38 music volumio[1038]: info: [1771813178370] CoreMusicLibrary::Adding element Webradio Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 09:19:38 music sudo[1233]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 09:19:38 music sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:38 music volumio[1038]: info: Initializing BBC Radios Feb 23 09:19:38 music sudo[1233]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 23 09:19:38 music sudo[1233]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: Creating Spotify config file Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: Volumio Calling Home Feb 23 09:19:38 music volumio[1038]: info: MPD Permissions set Feb 23 09:19:38 music volumio[1038]: info: MPD Permissions set Feb 23 09:19:38 music volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 23 09:19:38 music volumio[1038]: info: Spotify config file written Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:38 music sudo[1258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 09:19:38 music sudo[1258]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:19:38 music volumio[1038]: info: No need to fix Spotify hosts Feb 23 09:19:38 music volumio[1038]: info: Starting Shairport Sync Feb 23 09:19:38 music volumio[1038]: info: Starting Shairport Sync Feb 23 09:19:38 music sudo[1270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 09:19:38 music sudo[1270]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:38 music systemd[1]: Started go-librespot Daemon. Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:38 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:38 music volumio[1038]: info: Starting Shairport Sync Feb 23 09:19:38 music sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 09:19:38 music sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:38 music go-librespot[1268]: go-librespot daemon starting... Feb 23 09:19:38 music volumio5-onboarding[1226]: time=2026-02-23T09:19:38.809+07:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 23 09:19:38 music sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 09:19:38 music sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:38 music sudo[1258]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:38 music systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 23 09:19:38 music systemd[1]: shairport-sync.service: Succeeded. Feb 23 09:19:38 music systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 23 09:19:38 music volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Feb 23 09:19:38 music systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 23 09:19:38 music sudo[1272]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:38 music sudo[1270]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:38 music volumio[1038]: info: Shairport-Sync Started Feb 23 09:19:38 music volumio[1038]: Error adding Membership: Error: addMembership EINVAL Feb 23 09:19:38 music sudo[1277]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:38 music volumio[1038]: info: Shairport-Sync Started Feb 23 09:19:38 music volumio[1038]: info: Received Get System Info Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 09:19:38 music volumio[1038]: info: Discovery: Getting this device information Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:38 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 09:19:38 music volumio5-onboarding[1226]: time=2026-02-23T09:19:38.945+07:00 level=INFO msg="system info for 364a2dfb3dae66e30c6056843aa3bb31" deviceName=Music deviceVariant=volumio deviceModel= softwareVersion=3.908 Feb 23 09:19:38 music volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Feb 23 09:19:38 music volumio[1038]: info: Received Get System Info Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 09:19:38 music volumio[1038]: info: Discovery: Getting this device information Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:38 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 09:19:38 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 09:19:39 music volumio5-onboarding[1226]: time=2026-02-23T09:19:39.023+07:00 level=INFO msg="enabling local network discovery" Feb 23 09:19:39 music volumio[1038]: info: Volumio called home Feb 23 09:19:39 music volumio5-onboarding[1226]: time=2026-02-23T09:19:39.123+07:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 23 09:19:39 music volumio[1038]: info: Received Get System Info Feb 23 09:19:39 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 09:19:39 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 09:19:39 music volumio[1038]: info: Discovery: Getting this device information Feb 23 09:19:39 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:39 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:39 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 09:19:39 music volumio-remote-updater[644]: No test mode Feb 23 09:19:39 music volumio-remote-updater[644]: No alpha test mode Feb 23 09:19:39 music go-librespot[1268]: time="2026-02-23T09:19:39+07:00" level=info msg="running go-librespot 0.6.2" Feb 23 09:19:39 music go-librespot[1268]: time="2026-02-23T09:19:39+07:00" level=debug msg="app state loaded" Feb 23 09:19:39 music go-librespot[1268]: time="2026-02-23T09:19:39+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 09:19:39 music volumio[1038]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 23 09:19:39 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 23 09:19:39 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 09:19:39 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 09:19:39 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 09:19:40 music kernel: hwmon hwmon1: Undervoltage detected! Feb 23 09:19:40 music go-librespot[1268]: time="2026-02-23T09:19:40+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 09:19:40 music go-librespot[1268]: time="2026-02-23T09:19:40+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 09:19:40 music go-librespot[1268]: time="2026-02-23T09:19:40+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 09:19:40 music go-librespot[1268]: time="2026-02-23T09:19:40+07:00" level=info msg="zeroconf server listening on port 42869" Feb 23 09:19:40 music volumio[1038]: info: New Spotify access tokenBQBuroLKI2... Feb 23 09:19:40 music volumio[1038]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 23 09:19:40 music go-librespot[1268]: time="2026-02-23T09:19:40+07:00" level=debug msg="obtained new client token: AAA+8qcClVl7osYTLXWsVKsBjimMiJ7oq2BmzieVA57ErvaxeOLfxVF+m/Pb1+5ISGCxo5mtGehhhDRwlG8M1bOHAM1/jcQZG9WJi2Zsd36WaPGncJsKdDdZvOEifDM568yaGs8V58ggq/eaDH5XQPlF0P93bbRjdMbbwaMRn0hDgVzsdLHD8TwssXpnP1lhCvKeMMMGc/SObgyDXaIu0c9LP8ulBUJWzBbFyLJFBmMlJ/b90ge0AetyoA==" Feb 23 09:19:40 music volumio[1038]: SPOTIFY: User informations: {"country":"VN","display_name":"dothe cuong","email":"thecuong26@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31dtlzsybofen5dgeejovhizgh3y"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31dtlzsybofen5dgeejovhizgh3y","id":"31dtlzsybofen5dgeejovhizgh3y","images":[],"product":"premium","type":"user","uri":"spotify:user:31dtlzsybofen5dgeejovhizgh3y"} Feb 23 09:19:40 music volumio[1038]: info: Spotify Successfully logged in Feb 23 09:19:40 music volumio[1038]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 09:19:40 music volumio[1038]: info: [1771813180414] CoreMusicLibrary::Adding element Spotify Feb 23 09:19:40 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 09:19:40 music volumio[1038]: Cannot find translation for source Spotify Feb 23 09:19:40 music go-librespot[1268]: time="2026-02-23T09:19:40+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 23 09:19:40 music go-librespot[1268]: time="2026-02-23T09:19:40+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 23 09:19:40 music go-librespot[1268]: time="2026-02-23T09:19:40+07:00" level=debug msg="completed keyexchange" Feb 23 09:19:40 music go-librespot[1268]: time="2026-02-23T09:19:40+07:00" level=debug msg="completed challenge" Feb 23 09:19:40 music go-librespot[1268]: time="2026-02-23T09:19:40+07:00" level=info msg="authenticated AP" username="31************************3y" Feb 23 09:19:41 music go-librespot[1268]: time="2026-02-23T09:19:41+07:00" level=info msg="authenticated Login5" username="31************************3y" Feb 23 09:19:41 music go-librespot[1268]: time="2026-02-23T09:19:41+07:00" level=debug msg="initializing zeroconf session" username="31************************3y" Feb 23 09:19:41 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:41 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:41 music volumio[1038]: info: go-librespot daemon successfully initialized Feb 23 09:19:42 music mpd[1239]: Feb 23 09:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 09:19:42 music systemd[1]: Started Music Player Daemon. Feb 23 09:19:42 music sudo[1211]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:42 music sudo[1225]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:42 music volumio[1038]: info: Completed starting Core Plugins Feb 23 09:19:42 music volumio[1038]: info: ------------------------------------------- Feb 23 09:19:42 music volumio[1038]: info: ----- MyVolumio plugins startup ---- Feb 23 09:19:42 music volumio[1038]: info: ------------------------------------------- Feb 23 09:19:42 music volumio[1038]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 23 09:19:42 music volumio[1038]: error: MPD error: The expression evaluated to a falsy value: Feb 23 09:19:42 music volumio[1038]: assert.ok(self.idling) Feb 23 09:19:42 music volumio[1038]: error: The expression evaluated to a falsy value: Feb 23 09:19:42 music volumio[1038]: assert.ok(self.idling) Feb 23 09:19:42 music volumio[1038]: info: MPD running with PID1239 Feb 23 09:19:42 music volumio[1038]: ,establishing connection Feb 23 09:19:42 music volumio[1038]: error: MPD error: The expression evaluated to a falsy value: Feb 23 09:19:42 music volumio[1038]: assert.ok(self.idling) Feb 23 09:19:42 music volumio[1038]: error: The expression evaluated to a falsy value: Feb 23 09:19:42 music volumio[1038]: assert.ok(self.idling) Feb 23 09:19:42 music volumio[1038]: error: updateQueue error: null Feb 23 09:19:42 music volumio[1038]: info: Received Get System Info Feb 23 09:19:42 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 09:19:42 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 09:19:42 music volumio[1038]: info: Discovery: Getting this device information Feb 23 09:19:42 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:42 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:42 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 09:19:42 music wpa_supplicant[920]: wlan0: Failed to initiate sched scan Feb 23 09:19:42 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 09:19:42 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 09:19:43 music volumio[1206]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 23 09:19:43 music sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.10.100/media /mnt/NAS/home Feb 23 09:19:43 music sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:43 music kernel: CIFS: Attempting to mount //192.168.10.100/media Feb 23 09:19:43 music go-librespot[1268]: time="2026-02-23T09:19:43+07:00" level=debug msg="dealer connection opened" Feb 23 09:19:43 music go-librespot[1268]: time="2026-02-23T09:19:43+07:00" level=trace msg="starting accesspoint recv loop" Feb 23 09:19:43 music go-librespot[1268]: time="2026-02-23T09:19:43+07:00" level=trace msg="starting dealer recv loop" Feb 23 09:19:43 music go-librespot[1268]: time="2026-02-23T09:19:43+07:00" level=trace msg="received accesspoint ping" Feb 23 09:19:43 music go-librespot[1268]: time="2026-02-23T09:19:43+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 23 09:19:43 music go-librespot[1268]: time="2026-02-23T09:19:43+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 23 09:19:43 music go-librespot[1268]: time="2026-02-23T09:19:43+07:00" level=debug msg="received connection id: Yzg3OWI1NmUtODI5...REQzRDdBQkEwNw==" Feb 23 09:19:43 music go-librespot[1268]: time="2026-02-23T09:19:43+07:00" level=debug msg="put connect state because NEW_DEVICE" Feb 23 09:19:43 music go-librespot[1268]: time="2026-02-23T09:19:43+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 23 09:19:43 music go-librespot[1268]: time="2026-02-23T09:19:43+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 23 09:19:43 music go-librespot[1268]: time="2026-02-23T09:19:43+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 23 09:19:43 music go-librespot[1268]: time="2026-02-23T09:19:43+07:00" level=trace msg="received accesspoint pong ack" Feb 23 09:19:44 music volumio[1038]: info: Initializing connection to go-librespot Websocket Feb 23 09:19:44 music go-librespot[1268]: time="2026-02-23T09:19:44+07:00" level=debug msg="new websocket client" Feb 23 09:19:44 music volumio[1038]: info: Connection to go-librespot Websocket established Feb 23 09:19:46 music kernel: hwmon hwmon1: Voltage normalised Feb 23 09:19:47 music volumio[1038]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 23 09:19:47 music volumio[1038]: info: Getting Spotify volume Feb 23 09:19:47 music volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 23 09:19:47 music volumio[1038]: info: Spotify volume: 100 Feb 23 09:19:47 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:47 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:47 music volumio[1038]: SPOTIFY: RECEIVED VOLUMIO VOLUME 21 Feb 23 09:19:47 music volumio[1038]: SPOTIFY: SPOTIFY VOLUME 100 Feb 23 09:19:47 music volumio[1038]: SPOTIFY: VOLUMIO VOLUME 21 Feb 23 09:19:47 music volumio[1038]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 23 09:19:47 music volumio[1038]: info: Setting Spotify Volume from Volumio: 21 Feb 23 09:19:48 music kernel: hwmon hwmon1: Undervoltage detected! Feb 23 09:19:49 music volumio[1038]: SPOTIFY: SETTING SPOTIFY VOLUME 21 Feb 23 09:19:49 music volumio[1038]: info: Sending Spotify command with payload to local API: /player/volume Feb 23 09:19:49 music go-librespot[1268]: time="2026-02-23T09:19:49+07:00" level=debug msg="update volume requested to 13762/65535" Feb 23 09:19:49 music go-librespot[1268]: time="2026-02-23T09:19:49+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 23 09:19:49 music go-librespot[1268]: time="2026-02-23T09:19:49+07:00" level=trace msg="emitting websocket event: volume" Feb 23 09:19:49 music volumio[1038]: SPOTIFY: received: {"type":"volume","data":{"value":21,"max":100}} Feb 23 09:19:49 music volumio[1038]: SPOTIFY: RECEIVED SPOTIFY VOLUME 21 Feb 23 09:19:50 music volumio[1038]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 09:19:50 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 23 09:19:50 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 23 09:19:50 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 23 09:19:50 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 23 09:19:50 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:19:50 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:19:50 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:19:50 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 09:19:50 music volumio[1038]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 09:19:50 music volumio[1038]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 09:19:50 music volumio[1038]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 09:19:50 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 23 09:19:50 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 23 09:19:51 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:51 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:51 music volumio[1038]: info: Listing playlists Feb 23 09:19:51 music volumio[1038]: info: Listing playlists Feb 23 09:19:51 music volumio[1038]: info: Received Get System Info Feb 23 09:19:51 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 09:19:51 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 09:19:51 music volumio[1038]: info: Discovery: Getting this device information Feb 23 09:19:51 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:51 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:51 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 09:19:51 music wpa_supplicant[920]: wlan0: Failed to initiate sched scan Feb 23 09:19:51 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 09:19:51 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 23 09:19:52 music volumio[1038]: info: Adding plugin bluetooth to MyMusic Plugins Feb 23 09:19:52 music volumio[1038]: info: Adding plugin multiroom to MyMusic Plugins Feb 23 09:19:52 music volumio[1038]: info: Adding plugin metavolumio to MyMusic Plugins Feb 23 09:19:52 music volumio[1038]: info: Adding plugin cd_controller to MyMusic Plugins Feb 23 09:19:52 music volumio[1038]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 23 09:19:52 music volumio[1038]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 23 09:19:52 music volumio[1038]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 23 09:19:52 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:19:52 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:19:52 music volumio[1038]: info: Starting MyVolumio Remote Streaming Endpoints Feb 23 09:19:52 music volumio[1038]: info: MyVolumio login type: Token Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 23 09:19:52 music volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 23 09:19:53 music kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 23 09:19:53 music kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Feb 23 09:19:53 music sudo[1318]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:53 music volumio[1038]: info: Starting Streaming Service Transparent Proxy Feb 23 09:19:53 music volumio[1038]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 23 09:19:53 music volumio[1038]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 23 09:19:53 music volumio[1038]: info: Streaming services startup Feb 23 09:19:53 music volumio[1038]: info: Starting Streaming Daemon Feb 23 09:19:53 music sudo[1347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 23 09:19:53 music sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:53 music sudo[1347]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:53 music volumio[1038]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 23 09:19:53 music volumio[1038]: error: Cannot start Volumio Streaming Daemon Feb 23 09:19:53 music volumio[1038]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 23 09:19:53 music volumio[1038]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 23 09:19:53 music volumio[1038]: info: Cannot mount NAS home at system boot, trial number 2 ,retrying in 5 seconds Feb 23 09:19:54 music volumio[1038]: error: MyVolumio Custom Token format not valid, refreshing it Feb 23 09:19:54 music volumio[1038]: STREAMING PROXY: Starting server on port 3245 Feb 23 09:19:54 music volumio[1038]: Node JS runtime: 14 Feb 23 09:19:54 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 23 09:19:54 music kernel: hwmon hwmon1: Voltage normalised Feb 23 09:19:55 music volumio[1038]: info: MyVolumio login type: Token Feb 23 09:19:55 music volumio[1206]: ......................................................................................................................++++ Feb 23 09:19:56 music volumio[1038]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 23 09:19:56 music volumio[1038]: info: MyVolumio token set successfully Feb 23 09:19:56 music volumio[1038]: info: MYVOLUMIO: Adding device Feb 23 09:19:56 music volumio[1038]: info: MYVOLUMIO: Evaluating Server Feb 23 09:19:56 music kernel: hwmon hwmon1: Undervoltage detected! Feb 23 09:19:57 music volumio[1038]: info: MyVolumio status changed Feb 23 09:19:57 music volumio[1038]: info: Streaming services startup Feb 23 09:19:57 music volumio[1038]: info: Starting Streaming Daemon Feb 23 09:19:57 music volumio[1038]: info: Removing browser output: myVolumio user plan is not superstar Feb 23 09:19:57 music volumio[1038]: info: Removing audio output: Feb 23 09:19:57 music volumio[1038]: info: Stoppping Tunnel 1 Feb 23 09:19:57 music sudo[1414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 23 09:19:57 music sudo[1414]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:57 music sudo[1414]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:57 music sudo[1417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 23 09:19:57 music volumio[1038]: error: Cannot start Volumio Streaming Daemon Feb 23 09:19:57 music volumio[1038]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 23 09:19:57 music volumio[1038]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 23 09:19:57 music sudo[1417]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:57 music sudo[1417]: pam_unix(sudo:session): session closed for user root Feb 23 09:19:57 music volumio[1038]: info: Remote SSH Stopped Feb 23 09:19:57 music volumio[1038]: info: Setting Geolocation for MyVolumio to as1 Feb 23 09:19:57 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:19:57 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:19:57 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:19:58 music volumio[1038]: info: Successfully Added MyVolumio device Feb 23 09:19:58 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 23 09:19:58 music volumio[1038]: info: Updating MyVolumio device info Feb 23 09:19:58 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:19:59 music volumio[1038]: info: Received Get System Info Feb 23 09:19:59 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 09:19:59 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 09:19:59 music volumio[1038]: info: Discovery: Getting this device information Feb 23 09:19:59 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:19:59 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:19:59 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 09:19:59 music wpa_supplicant[920]: wlan0: Failed to initiate sched scan Feb 23 09:19:59 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 09:19:59 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 09:19:59 music sudo[1434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.10.100/media /mnt/NAS/home Feb 23 09:19:59 music sudo[1434]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 09:19:59 music kernel: CIFS: Attempting to mount //192.168.10.100/media Feb 23 09:20:00 music volumio[1038]: info: Successfully Updated MyVolumio device Feb 23 09:20:01 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:20:01 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:20:02 music ntpd[800]: Soliciting pool server 103.186.65.246 Feb 23 09:20:02 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Feb 23 09:20:02 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 23 09:20:02 music kernel: hwmon hwmon1: Voltage normalised Feb 23 09:20:03 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 23 09:20:03 music ntpd[800]: Soliciting pool server 160.25.232.6 Feb 23 09:20:04 music ntpd[800]: Soliciting pool server 160.25.232.6 Feb 23 09:20:04 music volumio[1206]: .......................................................................++++ Feb 23 09:20:04 music volumio[1206]: e is 65537 (0x010001) Feb 23 09:20:04 music volumio[1206]: writing RSA key Feb 23 09:20:04 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 23 09:20:04 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 23 09:20:06 music volumio[1038]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L phongkhach.local. -m SMB3_11 Feb 23 09:20:06 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 23 09:20:07 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 23 09:20:08 music volumio[1038]: info: Received Get System Info Feb 23 09:20:08 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 09:20:08 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 09:20:08 music volumio[1038]: info: Discovery: Getting this device information Feb 23 09:20:08 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:20:08 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:20:08 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 09:20:08 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 09:20:08 music wpa_supplicant[920]: wlan0: Failed to initiate sched scan Feb 23 09:20:08 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 09:20:08 music volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 23 09:20:08 music ntpd[800]: Soliciting pool server 103.130.217.41 Feb 23 09:20:08 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:20:08 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:20:08 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 09:20:08 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:20:08 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 09:20:08 music volumio-remote-updater[644]: No test mode Feb 23 09:20:08 music volumio-remote-updater[644]: No alpha test mode Feb 23 09:20:08 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 23 09:20:09 music volumio[1038]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 23 09:20:09 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 23 09:20:09 music sudo[1434]: pam_unix(sudo:session): session closed for user root Feb 23 09:20:09 music volumio[1038]: info: Cannot mount NAS home at system boot, trial number 3 ,retrying in 5 seconds Feb 23 09:20:09 music kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 23 09:20:09 music kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Feb 23 09:20:10 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 23 09:20:11 music volumio[1038]: info: CoreCommandRouter::volumioGetState Feb 23 09:20:11 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:20:11 music volumio[1038]: info: Listing playlists Feb 23 09:20:11 music volumio[1038]: info: Listing playlists Feb 23 09:20:12 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:20:12 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:20:12 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 23 09:20:12 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 23 09:20:12 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 23 09:20:12 music volumio[1038]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 09:20:12 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 09:20:13 music go-librespot[1268]: time="2026-02-23T09:20:13+07:00" level=trace msg="sent dealer ping" Feb 23 09:20:13 music go-librespot[1268]: time="2026-02-23T09:20:13+07:00" level=trace msg="received dealer pong" Feb 23 09:20:13 music volumio[1038]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 23 09:20:13 music volumio[1038]: info: Completed starting MyVolumio Plugin Feb 23 09:20:13 music volumio[1038]: [Metrics] CommandRouter: 48s 273.80ms Feb 23 09:20:13 music volumio[1038]: info: CoreCommandRouter::volumiosetStartupVolume Feb 23 09:20:13 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 09:20:13 music volumio[1038]: info: VolumeController:: Setting startup Volume 30 Feb 23 09:20:13 music volumio[1038]: info: VolumeController::SetAlsaVolume30 Feb 23 09:20:13 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 09:20:13 music volumio[1038]: info: CoreCommandRouter::Close All Modals sent Feb 23 09:20:13 music volumio[1038]: info: CoreCommandRouter::Close All Modals sent Feb 23 09:20:13 music volumio[1038]: info: CoreStateMachine::pushState Feb 23 09:20:13 music volumio[1038]: info: CorePlayQueue::getTrack 0 Feb 23 09:20:13 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 09:20:13 music volumio[1038]: info: CoreCommandRouter::volumioPushState Feb 23 09:20:13 music volumio[1038]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Feb 23 09:20:13 music volumio[1038]: SPOTIFY: SPOTIFY VOLUME 21 Feb 23 09:20:13 music volumio[1038]: SPOTIFY: VOLUMIO VOLUME 30 Feb 23 09:20:13 music volumio[1038]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 23 09:20:13 music volumio[1038]: info: Setting Spotify Volume from Volumio: 30 Feb 23 09:20:14 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 23 09:20:14 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 23 09:20:14 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 23 09:20:14 music volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 23 09:20:14 music volumio[1038]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 09:20:14 music volumio[1038]: TypeError: Cannot read property 'replace' of undefined Feb 23 09:20:14 music volumio[1038]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Feb 23 09:20:14 music volumio[1038]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Feb 23 09:20:14 music volumio[1038]: at listOnTimeout (internal/timers.js:554:17) Feb 23 09:20:14 music volumio[1038]: at processTimers (internal/timers.js:497:7) Feb 23 09:20:14 music volumio[1038]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 09:20:16 music wpa_supplicant[920]: wlan0: Failed to initiate sched scan Feb 23 09:20:16 music sudo[1493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-23 09:19 Feb 23 09:20:16 music sudo[1493]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"