-- Logs begin at Thu 2019-02-14 18:11:58 HKT, end at Sun 2025-02-16 01:25:17 HKT. -- Feb 16 01:24:12 volumio systemd-timedated[1056]: Changed local time to Sun Feb 16 01:24:12 2025 Feb 16 01:24:12 volumio sudo[1054]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:12 volumio volumio-time-update[617]: volumio-time-update-util: System time updated successfully. Feb 16 01:24:12 volumio systemd[1]: Started Volumio Time Update Utility. Feb 16 01:24:12 volumio wireless.js[614]: trying... Feb 16 01:24:12 volumio sudo[1058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 16 01:24:12 volumio sudo[1058]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:12 volumio sudo[1058]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:12 volumio nmbd[800]: [2025/02/16 01:24:12.400012, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 16 01:24:12 volumio nmbd[800]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 16 01:24:12 volumio systemd[1]: Started Samba NMB Daemon. Feb 16 01:24:12 volumio nmbd[800]: [2025/02/16 01:24:12.413621, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 16 01:24:12 volumio nmbd[800]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.100.124 for name WORKGROUP<1d>. Feb 16 01:24:12 volumio nmbd[800]: This response was from IP 192.168.100.9, reporting an IP address of 192.168.100.9. Feb 16 01:24:12 volumio systemd[1]: Starting Samba Winbind Daemon... Feb 16 01:24:12 volumio winbindd[1060]: [2025/02/16 01:24:12.639130, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 16 01:24:12 volumio winbindd[1060]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 16 01:24:12 volumio winbindd[1060]: [2025/02/16 01:24:12.660208, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 16 01:24:12 volumio systemd[1]: Started Samba Winbind Daemon. Feb 16 01:24:12 volumio winbindd[1060]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 16 01:24:12 volumio systemd[1]: Starting Samba SMB Daemon... Feb 16 01:24:12 volumio winbindd[1060]: [2025/02/16 01:24:12.995826, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Feb 16 01:24:12 volumio winbindd[1060]: res_names->count = 3, expected 4 Feb 16 01:24:13 volumio smbd[1065]: [2025/02/16 01:24:13.010681, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 16 01:24:13 volumio systemd[1]: Started Samba SMB Daemon. Feb 16 01:24:13 volumio smbd[1065]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 16 01:24:13 volumio wireless.js[614]: trying... Feb 16 01:24:13 volumio sudo[1074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 16 01:24:13 volumio sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:13 volumio sudo[1074]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:14 volumio wireless.js[614]: trying... Feb 16 01:24:14 volumio sudo[1077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 16 01:24:14 volumio sudo[1077]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:14 volumio sudo[1077]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:15 volumio wireless.js[614]: trying... Feb 16 01:24:15 volumio sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 16 01:24:15 volumio sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:15 volumio sudo[1080]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:16 volumio wireless.js[614]: trying... Feb 16 01:24:16 volumio sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 16 01:24:16 volumio sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:16 volumio sudo[1083]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:17 volumio wireless.js[614]: trying... Feb 16 01:24:17 volumio sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 16 01:24:17 volumio sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:17 volumio sudo[1086]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:18 volumio wireless.js[614]: trying... Feb 16 01:24:18 volumio sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 16 01:24:18 volumio sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:18 volumio sudo[1089]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:18 volumio volumio-remote-updater[598]: [2025-02-16 01:24:18] [info] asio async_connect error: system:111 (Connection refused) Feb 16 01:24:18 volumio volumio-remote-updater[598]: [2025-02-16 01:24:18] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 16 01:24:18 volumio volumio-remote-updater[598]: [2025-02-16 01:24:18] [error] handle_connect error: Underlying Transport Error Feb 16 01:24:19 volumio wireless.js[614]: trying... Feb 16 01:24:19 volumio sudo[1095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 16 01:24:19 volumio sudo[1095]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:19 volumio sudo[1095]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:20 volumio wireless.js[614]: trying... Feb 16 01:24:20 volumio sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 16 01:24:20 volumio sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:20 volumio sudo[1098]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:20 volumio wpa_supplicant[906]: wlan0: Failed to initiate sched scan Feb 16 01:24:21 volumio wireless.js[614]: Overtime, starting plan B Feb 16 01:24:21 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 16 01:24:21 volumio systemd[1]: Started Wireless Services. Feb 16 01:24:21 volumio systemd[1]: Started Volumio Backend Module. Feb 16 01:24:21 volumio systemd[1]: Started Volumio Cpu Tweaker. Feb 16 01:24:21 volumio systemd[1]: Reached target Multi-User System. Feb 16 01:24:21 volumio systemd[1]: Reached target Graphical Interface. Feb 16 01:24:21 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 16 01:24:21 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 16 01:24:21 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 16 01:24:21 volumio systemd[1]: Startup finished in 11.804s (kernel) + 28.735s (userspace) = 40.540s. Feb 16 01:24:21 volumio volumio-cpu-tweak[1104]: Setting RT Priority for mpd Feb 16 01:24:21 volumio volumio-cpu-tweak[1104]: Setting MPD Affinity Feb 16 01:24:21 volumio volumio-cpu-tweak[1104]: pid 832's current affinity mask: f Feb 16 01:24:21 volumio volumio-cpu-tweak[1104]: pid 832's new affinity mask: 3 Feb 16 01:24:21 volumio volumio-cpu-tweak[1104]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 16 01:24:21 volumio volumio-cpu-tweak[1104]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 16 01:24:21 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Feb 16 01:24:22 volumio volumio[1103]: info: ------------------------------------------- Feb 16 01:24:22 volumio volumio[1103]: info: ----- Volumio3 ---- Feb 16 01:24:22 volumio volumio[1103]: info: ------------------------------------------- Feb 16 01:24:22 volumio volumio[1103]: info: ----- System startup ---- Feb 16 01:24:22 volumio volumio[1103]: info: ------------------------------------------- Feb 16 01:24:23 volumio volumio[1103]: info: MYVOLUMIO Environment detected Feb 16 01:24:23 volumio volumio[1103]: info: Plugin folders cleanup Feb 16 01:24:23 volumio volumio[1103]: info: Scanning into folder /volumio/app/plugins/ Feb 16 01:24:23 volumio volumio[1103]: info: Scanning category audio_interface Feb 16 01:24:23 volumio volumio[1103]: info: Scanning category miscellanea Feb 16 01:24:23 volumio volumio[1103]: info: Scanning category music_service Feb 16 01:24:23 volumio volumio[1103]: info: Scanning category plugins.json Feb 16 01:24:23 volumio volumio[1103]: info: Scanning category system_controller Feb 16 01:24:23 volumio volumio[1103]: info: Scanning category user_interface Feb 16 01:24:23 volumio volumio[1103]: info: Scanning into folder /data/plugins/ Feb 16 01:24:23 volumio volumio[1103]: info: Plugin folders cleanup completed Feb 16 01:24:23 volumio volumio[1103]: info: ------------------------------------------- Feb 16 01:24:23 volumio volumio[1103]: info: ----- Core plugins startup ---- Feb 16 01:24:23 volumio volumio[1103]: info: ------------------------------------------- Feb 16 01:24:23 volumio volumio[1103]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 01:24:23 volumio volumio[1103]: info: Adding plugin upnp to MyMusic Plugins Feb 16 01:24:23 volumio volumio[1103]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 01:24:23 volumio volumio[1103]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 01:24:23 volumio volumio[1103]: info: Loading plugins from folder /data/plugins/ Feb 16 01:24:23 volumio volumio[1103]: info: Loading plugin "system"... Feb 16 01:24:23 volumio volumio[1103]: info: Loading plugin "appearance"... Feb 16 01:24:24 volumio volumio[1103]: info: Loading plugin "network"... Feb 16 01:24:24 volumio volumio[1103]: info: Refreshing Cached IP Addresses Feb 16 01:24:24 volumio sudo[1135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 01:24:24 volumio sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:24 volumio sudo[1135]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:24 volumio sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 01:24:24 volumio sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:24 volumio sudo[1137]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:24 volumio volumio[1103]: info: Loading plugin "services"... Feb 16 01:24:24 volumio volumio[1103]: info: Loading plugin "alsa_controller"... Feb 16 01:24:24 volumio sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 16 01:24:24 volumio sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:24 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 01:24:24 volumio volumio[1103]: info: Loading plugin "wizard"... Feb 16 01:24:24 volumio volumio[1103]: info: Loading plugin "networkfs"... Feb 16 01:24:24 volumio volumio[1103]: info: Starting Udev Watcher for removable devices Feb 16 01:24:24 volumio sudo[1163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.199.215:mnt/MyPool/Mystorage/Hi-Fi /mnt/NAS/Truenas Feb 16 01:24:24 volumio sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:24 volumio sudo[1165]: 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.100.210/music /mnt/NAS/Hp Feb 16 01:24:24 volumio sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:24 volumio volumio[1103]: info: Ignoring mount for partition: boot Feb 16 01:24:24 volumio volumio[1103]: info: Ignoring mount for partition: volumio Feb 16 01:24:24 volumio volumio[1103]: info: Ignoring mount for partition: volumio_data Feb 16 01:24:24 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 01:24:24 volumio volumio[1103]: info: Loading plugin "volumio_command_line_client"... Feb 16 01:24:24 volumio volumio[1103]: info: Loading plugin "upnp"... Feb 16 01:24:24 volumio volumio[1103]: info: [1739640264877] Starting Upmpd Daemon Feb 16 01:24:24 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 01:24:24 volumio volumio[1103]: info: Loading plugin "my_music"... Feb 16 01:24:24 volumio volumio[1103]: info: Loading plugin "mpd"... Feb 16 01:24:24 volumio kernel: Key type cifs.spnego registered Feb 16 01:24:24 volumio kernel: Key type cifs.idmap registered Feb 16 01:24:24 volumio 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 16 01:24:24 volumio kernel: CIFS: Attempting to mount //192.168.100.210/music Feb 16 01:24:25 volumio systemd[1]: systemd-fsckd.service: Succeeded. Feb 16 01:24:25 volumio kernel: cryptd: max_cpu_qlen set to 1000 Feb 16 01:24:25 volumio sudo[1165]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:25 volumio volumio[1103]: info: Loading plugin "upnp_browser"... Feb 16 01:24:25 volumio volumio[1103]: info: Loading plugin "alarm-clock"... Feb 16 01:24:25 volumio volumio[1103]: info: Loading plugin "airplay_emulation"... Feb 16 01:24:25 volumio sudo[1146]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:25 volumio volumio[1103]: info: Starting Shairport Sync Feb 16 01:24:25 volumio volumio[1103]: info: Loading plugin "last_100"... Feb 16 01:24:25 volumio volumio[1103]: info: Loading plugin "webradio"... Feb 16 01:24:25 volumio volumio[1103]: info: Loading plugin "i2s_dacs"... Feb 16 01:24:25 volumio volumio[1103]: info: Loading plugin "volumiodiscovery"... Feb 16 01:24:25 volumio volumio[1103]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 01:24:25 volumio volumio[1103]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 01:24:25 volumio node[1103]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 01:24:25 volumio volumio[1103]: *** WARNING *** For more information see Feb 16 01:24:25 volumio volumio[1103]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 01:24:25 volumio volumio[1103]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 01:24:25 volumio volumio[1103]: *** WARNING *** For more information see Feb 16 01:24:25 volumio node[1103]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 01:24:25 volumio node[1103]: *** WARNING *** For more information see Feb 16 01:24:25 volumio node[1103]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 01:24:25 volumio node[1103]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 01:24:25 volumio node[1103]: *** WARNING *** For more information see Feb 16 01:24:25 volumio volumio[1103]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 01:24:25 volumio volumio[1103]: info: Discovery: Started advertising with name: Volumio Feb 16 01:24:25 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 01:24:25 volumio volumio[1103]: info: Loading plugin "outputs"... Feb 16 01:24:25 volumio volumio[1103]: info: Loading plugin "albumart"... Feb 16 01:24:25 volumio volumio[1103]: info: Plugin example_plugin is not enabled Feb 16 01:24:25 volumio volumio[1103]: info: Loading plugin "inputs"... Feb 16 01:24:25 volumio volumio[1103]: info: Loading plugin "updater_comm"... Feb 16 01:24:26 volumio volumio[1103]: info: Plugin mpdemulation is not enabled Feb 16 01:24:26 volumio volumio[1103]: info: Loading plugin "rest_api"... Feb 16 01:24:26 volumio volumio[1103]: info: Loading plugin "websocket"... Feb 16 01:24:26 volumio volumio[1103]: info: Starting Socket.io Server version 2.3.0 Feb 16 01:24:26 volumio volumio[1103]: Forking 3 albumart workers Feb 16 01:24:26 volumio volumio[1103]: info: Loading i18n strings for locale zh Feb 16 01:24:26 volumio volumio[1103]: Updating browse sources language Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::initPlayerControls Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 01:24:26 volumio volumio[1103]: Express server listening on port 3000 Feb 16 01:24:26 volumio volumio[1103]: [Metrics] WebUI: 4s 97.71ms Feb 16 01:24:26 volumio volumio[1103]: info: CoreStateMachine::resetVolumioState Feb 16 01:24:26 volumio volumio[1103]: info: CoreStateMachine::getcurrentVolume Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 01:24:26 volumio volumio[1103]: info: Volumio Network Manager: Network status updated: 1 Feb 16 01:24:26 volumio volumio[1103]: verbose: New Socket.io Connection to 192.168.100.124 from 192.168.100.219 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Feb 16 01:24:26 volumio volumio[1103]: verbose: New Socket.io Connection to 192.168.100.124 from 192.168.100.219 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 2 Feb 16 01:24:26 volumio volumio[1103]: verbose: New Socket.io Connection to 192.168.100.124 from 192.168.100.219 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 01:24:26 volumio volumio[1103]: info: Reloading queue from file Feb 16 01:24:26 volumio volumio[1103]: info: Setting Device type: Raspberry PI Feb 16 01:24:26 volumio volumio[1103]: Starting albumart workers Feb 16 01:24:26 volumio volumio[1103]: Starting albumart workers Feb 16 01:24:26 volumio volumio[1103]: info: VolumeController:: Volume=100 Mute =true Feb 16 01:24:26 volumio volumio[1103]: info: CoreStateMachine::pushState Feb 16 01:24:26 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Feb 16 01:24:26 volumio volumio[1103]: Starting albumart workers Feb 16 01:24:26 volumio volumio[1103]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Feb 16 01:24:26 volumio volumio[1103]: info: CoreStateMachine::updateTrackBlock Feb 16 01:24:26 volumio volumio[1103]: info: CorePlayQueue::getTrackBlock Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 01:24:26 volumio volumio[1103]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 16 01:24:26 volumio volumio[1103]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Feb 16 01:24:26 volumio volumio[1103]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 16 01:24:26 volumio volumio[1103]: info: CoreStateMachine::setRepeat true single undefined Feb 16 01:24:26 volumio volumio[1103]: info: CoreStateMachine::pushState Feb 16 01:24:26 volumio volumio[1103]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Feb 16 01:24:26 volumio volumio[1103]: info: CoreStateMachine::setRandom true Feb 16 01:24:26 volumio volumio[1103]: info: CoreStateMachine::pushState Feb 16 01:24:26 volumio volumio[1103]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Feb 16 01:24:26 volumio volumio[1103]: info: VolumeController:: Volume=100 Mute =true Feb 16 01:24:26 volumio volumio[1103]: info: CoreStateMachine::pushState Feb 16 01:24:26 volumio volumio[1103]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Feb 16 01:24:26 volumio volumio[1103]: info: Discovery: adding e60be0db-ccc8-4f7f-b578-56cf614378eb Feb 16 01:24:26 volumio volumio[1103]: info: Discovery: Found device Volumio Feb 16 01:24:26 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Feb 16 01:24:26 volumio volumio[1103]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:28 volumio volumio-remote-updater[598]: [2025-02-16 01:24:28] [connect] Successful connection Feb 16 01:24:28 volumio volumio-remote-updater[598]: [2025-02-16 01:24:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739640268 101 Feb 16 01:24:28 volumio volumio[1103]: 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: 4 Feb 16 01:24:31 volumio volumio[1103]: verbose: New Socket.io Connection to 192.168.100.124 from 192.168.100.219 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Feb 16 01:24:31 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 16 01:24:31 volumio volumio[1103]: info: CoreCommandRouter::volumioGetVisibleSources Feb 16 01:24:31 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 01:24:31 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Feb 16 01:24:31 volumio volumio[1103]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:31 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 16 01:24:31 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 16 01:24:31 volumio volumio[1103]: info: Received Get System Info Feb 16 01:24:31 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 01:24:31 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 01:24:31 volumio volumio[1103]: info: Discovery: Getting this device information Feb 16 01:24:31 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Feb 16 01:24:31 volumio volumio[1103]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:31 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 01:24:31 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Feb 16 01:24:31 volumio volumio[1103]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:31 volumio volumio[1103]: info: Listing playlists Feb 16 01:24:31 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Feb 16 01:24:31 volumio volumio[1103]: info: CoreStateMachine::getQueue Feb 16 01:24:31 volumio volumio[1103]: info: CorePlayQueue::getQueue Feb 16 01:24:31 volumio wpa_supplicant[906]: wlan0: Failed to initiate sched scan Feb 16 01:24:34 volumio sudo[1293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 01:24:34 volumio sudo[1293]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:34 volumio sudo[1293]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:34 volumio sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 01:24:34 volumio sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:34 volumio sudo[1295]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:34 volumio sudo[1299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 16 01:24:34 volumio sudo[1299]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:35 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 16 01:24:35 volumio sudo[1299]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:35 volumio volumio[1103]: info: Upmpdcli Daemon Started Feb 16 01:24:35 volumio volumio[1301]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 16 01:24:37 volumio volumio[1103]: info: CoreCommandRouter::volumioPlay Feb 16 01:24:37 volumio volumio[1103]: info: CoreStateMachine::play index undefined Feb 16 01:24:37 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 01:24:37 volumio volumio[1103]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:37 volumio volumio[1103]: info: CoreStateMachine::startPlaybackTimer Feb 16 01:24:37 volumio volumio[1103]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:37 volumio volumio[1103]: verbose: ControllerMpd::clearAddPlayTracks NAS/Hp/1/01.I Want It That Way.flac Feb 16 01:24:37 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 01:24:39 volumio wpa_supplicant[906]: wlan0: Failed to initiate sched scan Feb 16 01:24:42 volumio systemd[1]: systemd-timedated.service: Succeeded. Feb 16 01:24:44 volumio volumio[1301]: ...................................................................................................++++ Feb 16 01:24:45 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 01:24:45 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 16 01:24:45 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 16 01:24:45 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 16 01:24:45 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 16 01:24:45 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 01:24:45 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 01:24:45 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 01:24:45 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 01:24:45 volumio volumio[1103]: info: CoreCommandRouter::volumioGetBrowseSources Feb 16 01:24:45 volumio volumio[1103]: info: CoreCommandRouter::volumioGetBrowseSources Feb 16 01:24:45 volumio volumio[1103]: info: CoreCommandRouter::volumioGetBrowseSources Feb 16 01:24:45 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 01:24:45 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 16 01:24:46 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 16 01:24:46 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand rescan Feb 16 01:24:46 volumio volumio[1103]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 01:24:46 volumio volumio[1103]: TypeError: Cannot read property 'then' of undefined Feb 16 01:24:46 volumio volumio[1103]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Feb 16 01:24:46 volumio volumio[1103]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1886:15) Feb 16 01:24:46 volumio volumio[1103]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Feb 16 01:24:46 volumio volumio[1103]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1081:26) Feb 16 01:24:46 volumio volumio[1103]: at Socket.emit (events.js:315:20) Feb 16 01:24:46 volumio volumio[1103]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 16 01:24:46 volumio volumio[1103]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 16 01:24:46 volumio volumio[1103]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 01:24:47 volumio sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-16 01:23 Feb 16 01:24:47 volumio sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:47 volumio sudo[1351]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:48 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 16 01:24:48 volumio volumio-remote-updater[598]: [2025-02-16 01:24:48] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 16 01:24:48 volumio volumio-remote-updater[598]: [2025-02-16 01:24:48] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 16 01:24:48 volumio sudo[1163]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:48 volumio wpa_supplicant[906]: wlan0: Failed to initiate sched scan Feb 16 01:24:48 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 16 01:24:48 volumio systemd[1]: Started dynamicswap service. Feb 16 01:24:48 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 16 01:24:48 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 16 01:24:48 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Feb 16 01:24:48 volumio systemd[1]: Stopped Volumio Backend Module. Feb 16 01:24:48 volumio systemd[1]: Started Volumio Backend Module. Feb 16 01:24:48 volumio systemd[1]: Started dynamicswap service. Feb 16 01:24:48 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 16 01:24:49 volumio volumio[1366]: info: ------------------------------------------- Feb 16 01:24:49 volumio volumio[1366]: info: ----- Volumio3 ---- Feb 16 01:24:49 volumio volumio[1366]: info: ------------------------------------------- Feb 16 01:24:49 volumio volumio[1366]: info: ----- System startup ---- Feb 16 01:24:49 volumio volumio[1366]: info: ------------------------------------------- Feb 16 01:24:50 volumio volumio[1366]: info: MYVOLUMIO Environment detected Feb 16 01:24:50 volumio volumio[1366]: info: Plugin folders cleanup Feb 16 01:24:50 volumio volumio[1366]: info: Scanning into folder /volumio/app/plugins/ Feb 16 01:24:50 volumio volumio[1366]: info: Scanning category audio_interface Feb 16 01:24:50 volumio volumio[1366]: info: Scanning category miscellanea Feb 16 01:24:50 volumio volumio[1366]: info: Scanning category music_service Feb 16 01:24:50 volumio volumio[1366]: info: Scanning category plugins.json Feb 16 01:24:50 volumio volumio[1366]: info: Scanning category system_controller Feb 16 01:24:50 volumio volumio[1366]: info: Scanning category user_interface Feb 16 01:24:50 volumio volumio[1366]: info: Scanning into folder /data/plugins/ Feb 16 01:24:50 volumio volumio[1366]: info: Plugin folders cleanup completed Feb 16 01:24:50 volumio volumio[1366]: info: ------------------------------------------- Feb 16 01:24:50 volumio volumio[1366]: info: ----- Core plugins startup ---- Feb 16 01:24:50 volumio volumio[1366]: info: ------------------------------------------- Feb 16 01:24:50 volumio volumio[1366]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 01:24:50 volumio volumio[1366]: info: Adding plugin upnp to MyMusic Plugins Feb 16 01:24:50 volumio volumio[1366]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 01:24:50 volumio volumio[1366]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 01:24:50 volumio volumio[1366]: info: Loading plugins from folder /data/plugins/ Feb 16 01:24:50 volumio volumio[1366]: info: Loading plugin "system"... Feb 16 01:24:50 volumio volumio[1366]: info: Loading plugin "appearance"... Feb 16 01:24:50 volumio volumio[1366]: info: Loading plugin "network"... Feb 16 01:24:50 volumio volumio[1366]: info: Refreshing Cached IP Addresses Feb 16 01:24:50 volumio sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 01:24:50 volumio sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:50 volumio sudo[1396]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:50 volumio sudo[1398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 01:24:50 volumio sudo[1398]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:50 volumio sudo[1398]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:50 volumio volumio[1366]: info: Loading plugin "services"... Feb 16 01:24:50 volumio volumio[1366]: info: Loading plugin "alsa_controller"... Feb 16 01:24:50 volumio sudo[1407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 16 01:24:50 volumio sudo[1407]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:50 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 01:24:50 volumio volumio[1366]: info: Loading plugin "wizard"... Feb 16 01:24:50 volumio volumio[1366]: info: Loading plugin "networkfs"... Feb 16 01:24:50 volumio volumio[1366]: info: Cannot mount NAS Hp at system boot, trial number 1 ,retrying in 5 seconds Feb 16 01:24:50 volumio volumio[1366]: info: Starting Udev Watcher for removable devices Feb 16 01:24:50 volumio sudo[1424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.199.215:mnt/MyPool/Mystorage/Hi-Fi /mnt/NAS/Truenas Feb 16 01:24:50 volumio sudo[1424]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:50 volumio volumio[1366]: info: Ignoring mount for partition: boot Feb 16 01:24:50 volumio volumio[1366]: info: Ignoring mount for partition: volumio Feb 16 01:24:50 volumio volumio[1366]: info: Ignoring mount for partition: volumio_data Feb 16 01:24:50 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 01:24:50 volumio volumio[1366]: info: Loading plugin "volumio_command_line_client"... Feb 16 01:24:50 volumio volumio[1366]: info: Loading plugin "upnp"... Feb 16 01:24:50 volumio volumio[1366]: info: [1739640290912] Starting Upmpd Daemon Feb 16 01:24:50 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 01:24:50 volumio volumio[1366]: info: Loading plugin "my_music"... Feb 16 01:24:50 volumio volumio[1366]: info: Loading plugin "mpd"... Feb 16 01:24:51 volumio volumio[1366]: info: Loading plugin "upnp_browser"... Feb 16 01:24:51 volumio volumio[1366]: info: Loading plugin "alarm-clock"... Feb 16 01:24:51 volumio volumio[1366]: info: Loading plugin "airplay_emulation"... Feb 16 01:24:51 volumio volumio[1366]: info: Starting Shairport Sync Feb 16 01:24:51 volumio volumio[1366]: info: Loading plugin "last_100"... Feb 16 01:24:51 volumio volumio[1366]: info: Loading plugin "webradio"... Feb 16 01:24:51 volumio volumio[1366]: info: Loading plugin "i2s_dacs"... Feb 16 01:24:51 volumio volumio[1366]: info: Loading plugin "volumiodiscovery"... Feb 16 01:24:51 volumio volumio[1366]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 01:24:51 volumio volumio[1366]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 01:24:51 volumio node[1366]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 01:24:51 volumio volumio[1366]: *** WARNING *** For more information see Feb 16 01:24:51 volumio volumio[1366]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 01:24:51 volumio volumio[1366]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 01:24:51 volumio volumio[1366]: *** WARNING *** For more information see Feb 16 01:24:51 volumio node[1366]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 01:24:51 volumio node[1366]: *** WARNING *** For more information see Feb 16 01:24:51 volumio node[1366]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 01:24:51 volumio node[1366]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 01:24:51 volumio node[1366]: *** WARNING *** For more information see Feb 16 01:24:51 volumio volumio[1366]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 01:24:51 volumio volumio[1366]: info: Discovery: Started advertising with name: Volumio Feb 16 01:24:51 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 01:24:51 volumio volumio[1366]: info: Loading plugin "outputs"... Feb 16 01:24:51 volumio volumio[1366]: info: Loading plugin "albumart"... Feb 16 01:24:51 volumio volumio[1366]: info: Plugin example_plugin is not enabled Feb 16 01:24:51 volumio volumio[1366]: info: Loading plugin "inputs"... Feb 16 01:24:51 volumio volumio[1366]: info: Loading plugin "updater_comm"... Feb 16 01:24:51 volumio volumio[1366]: info: Plugin mpdemulation is not enabled Feb 16 01:24:51 volumio volumio[1366]: info: Loading plugin "rest_api"... Feb 16 01:24:51 volumio volumio[1366]: info: Loading plugin "websocket"... Feb 16 01:24:51 volumio volumio[1366]: info: Starting Socket.io Server version 2.3.0 Feb 16 01:24:51 volumio volumio[1366]: info: Loading i18n strings for locale zh Feb 16 01:24:51 volumio volumio[1366]: Updating browse sources language Feb 16 01:24:51 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 01:24:51 volumio volumio[1366]: Forking 3 albumart workers Feb 16 01:24:51 volumio sudo[1407]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:51 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 01:24:51 volumio volumio[1366]: info: CoreCommandRouter::initPlayerControls Feb 16 01:24:51 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:24:51 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:24:51 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:24:51 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:24:51 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:24:51 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:24:51 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:24:51 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:24:51 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 01:24:51 volumio volumio[1366]: Express server listening on port 3000 Feb 16 01:24:51 volumio volumio[1366]: [Metrics] WebUI: 2s 500.38ms Feb 16 01:24:51 volumio volumio[1366]: info: CoreStateMachine::resetVolumioState Feb 16 01:24:51 volumio volumio[1366]: info: CoreStateMachine::getcurrentVolume Feb 16 01:24:51 volumio volumio[1366]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 01:24:52 volumio volumio[1366]: info: Volumio Network Manager: Network status updated: 1 Feb 16 01:24:52 volumio volumio[1366]: verbose: New Socket.io Connection to 192.168.100.124 from 192.168.100.219 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Feb 16 01:24:52 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 01:24:52 volumio volumio[1366]: info: Reloading queue from file Feb 16 01:24:52 volumio volumio[1366]: info: CoreStateMachine::setRepeat true single undefined Feb 16 01:24:52 volumio volumio[1366]: info: CoreStateMachine::pushState Feb 16 01:24:52 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:52 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 01:24:52 volumio volumio[1366]: info: CoreCommandRouter::volumioPushState Feb 16 01:24:52 volumio volumio[1366]: info: CoreStateMachine::setRandom true Feb 16 01:24:52 volumio volumio[1366]: info: CoreStateMachine::pushState Feb 16 01:24:52 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:52 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 01:24:52 volumio volumio[1366]: info: CoreCommandRouter::volumioPushState Feb 16 01:24:52 volumio volumio[1366]: info: Setting Device type: Raspberry PI Feb 16 01:24:52 volumio volumio[1366]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 16 01:24:52 volumio volumio[1366]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Feb 16 01:24:52 volumio volumio[1366]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 16 01:24:52 volumio volumio[1366]: info: VolumeController:: Volume=100 Mute =true Feb 16 01:24:52 volumio volumio[1366]: info: CoreStateMachine::pushState Feb 16 01:24:52 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:52 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 01:24:52 volumio volumio[1366]: info: CoreCommandRouter::volumioPushState Feb 16 01:24:52 volumio volumio[1366]: info: CoreStateMachine::updateTrackBlock Feb 16 01:24:52 volumio volumio[1366]: info: CorePlayQueue::getTrackBlock Feb 16 01:24:52 volumio volumio[1366]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 01:24:52 volumio volumio[1366]: info: VolumeController:: Volume=100 Mute =true Feb 16 01:24:52 volumio volumio[1366]: info: CoreStateMachine::pushState Feb 16 01:24:52 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:52 volumio volumio[1366]: info: CoreCommandRouter::volumioPushState Feb 16 01:24:52 volumio volumio[1366]: Starting albumart workers Feb 16 01:24:52 volumio volumio[1366]: Starting albumart workers Feb 16 01:24:52 volumio volumio[1366]: info: Discovery: adding e60be0db-ccc8-4f7f-b578-56cf614378eb Feb 16 01:24:52 volumio volumio[1366]: info: Discovery: Found device Volumio Feb 16 01:24:52 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Feb 16 01:24:52 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:52 volumio volumio[1366]: Starting albumart workers Feb 16 01:24:53 volumio volumio-remote-updater[598]: [2025-02-16 01:24:53] [connect] Successful connection Feb 16 01:24:53 volumio volumio-remote-updater[598]: [2025-02-16 01:24:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739640293 101 Feb 16 01:24:53 volumio volumio[1366]: 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 16 01:24:55 volumio volumio[1366]: verbose: New Socket.io Connection to 192.168.100.124 from 192.168.100.219 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 Feb 16 01:24:55 volumio volumio[1366]: info: Cannot mount NAS Hp at system boot, trial number 2 ,retrying in 5 seconds Feb 16 01:24:55 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 01:24:55 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 01:24:55 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 16 01:24:55 volumio volumio[1366]: info: CoreCommandRouter::volumioGetVisibleSources Feb 16 01:24:55 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 01:24:55 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Feb 16 01:24:55 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:55 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 16 01:24:55 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 16 01:24:55 volumio volumio[1366]: info: Received Get System Info Feb 16 01:24:55 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 01:24:55 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 01:24:55 volumio volumio[1366]: info: Discovery: Getting this device information Feb 16 01:24:55 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Feb 16 01:24:55 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:55 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 01:24:55 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Feb 16 01:24:55 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Feb 16 01:24:55 volumio volumio[1366]: info: Listing playlists Feb 16 01:24:55 volumio volumio[1366]: info: CoreCommandRouter::volumioGetQueue Feb 16 01:24:55 volumio volumio[1366]: info: CoreStateMachine::getQueue Feb 16 01:24:55 volumio volumio[1366]: info: CorePlayQueue::getQueue Feb 16 01:24:57 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 01:24:57 volumio wpa_supplicant[906]: wlan0: Failed to initiate sched scan Feb 16 01:24:58 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 16 01:24:58 volumio volumio[1366]: verbose: ControllerMpd::sendMpdCommand update Feb 16 01:24:58 volumio volumio[1366]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 01:24:58 volumio volumio[1366]: TypeError: Cannot read property 'then' of undefined Feb 16 01:24:58 volumio volumio[1366]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Feb 16 01:24:58 volumio volumio[1366]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1899:15) Feb 16 01:24:58 volumio volumio[1366]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Feb 16 01:24:58 volumio volumio[1366]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1085:26) Feb 16 01:24:58 volumio volumio[1366]: at Socket.emit (events.js:315:20) Feb 16 01:24:58 volumio volumio[1366]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 16 01:24:58 volumio volumio[1366]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 16 01:24:58 volumio volumio[1366]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 01:24:58 volumio sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-16 01:23 Feb 16 01:24:58 volumio sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:24:58 volumio sudo[1541]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:58 volumio volumio-remote-updater[598]: [2025-02-16 01:24:58] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 16 01:24:58 volumio volumio-remote-updater[598]: [2025-02-16 01:24:58] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 16 01:24:58 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 16 01:24:58 volumio sudo[1424]: pam_unix(sudo:session): session closed for user root Feb 16 01:24:58 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 16 01:24:58 volumio systemd[1]: Started dynamicswap service. Feb 16 01:24:58 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 16 01:24:58 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 16 01:24:58 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Feb 16 01:24:58 volumio systemd[1]: Started dynamicswap service. Feb 16 01:24:58 volumio systemd[1]: Stopped Volumio Backend Module. Feb 16 01:24:58 volumio systemd[1]: Started Volumio Backend Module. Feb 16 01:24:58 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 16 01:24:59 volumio volumio[1301]: .........................................................................................................................................................................++++ Feb 16 01:24:59 volumio volumio[1301]: e is 65537 (0x010001) Feb 16 01:24:59 volumio volumio[1301]: writing RSA key Feb 16 01:24:59 volumio volumio[1554]: info: ------------------------------------------- Feb 16 01:24:59 volumio volumio[1554]: info: ----- Volumio3 ---- Feb 16 01:24:59 volumio volumio[1554]: info: ------------------------------------------- Feb 16 01:24:59 volumio volumio[1554]: info: ----- System startup ---- Feb 16 01:24:59 volumio volumio[1554]: info: ------------------------------------------- Feb 16 01:25:00 volumio volumio[1554]: info: MYVOLUMIO Environment detected Feb 16 01:25:00 volumio volumio[1554]: info: Plugin folders cleanup Feb 16 01:25:00 volumio volumio[1554]: info: Scanning into folder /volumio/app/plugins/ Feb 16 01:25:00 volumio volumio[1554]: info: Scanning category audio_interface Feb 16 01:25:00 volumio volumio[1554]: info: Scanning category miscellanea Feb 16 01:25:00 volumio volumio[1554]: info: Scanning category music_service Feb 16 01:25:00 volumio volumio[1554]: info: Scanning category plugins.json Feb 16 01:25:00 volumio volumio[1554]: info: Scanning category system_controller Feb 16 01:25:00 volumio volumio[1554]: info: Scanning category user_interface Feb 16 01:25:00 volumio volumio[1554]: info: Scanning into folder /data/plugins/ Feb 16 01:25:00 volumio volumio[1554]: info: Plugin folders cleanup completed Feb 16 01:25:00 volumio volumio[1554]: info: ------------------------------------------- Feb 16 01:25:00 volumio volumio[1554]: info: ----- Core plugins startup ---- Feb 16 01:25:00 volumio volumio[1554]: info: ------------------------------------------- Feb 16 01:25:00 volumio volumio[1554]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 01:25:00 volumio volumio[1554]: info: Adding plugin upnp to MyMusic Plugins Feb 16 01:25:00 volumio volumio[1554]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 01:25:00 volumio volumio[1554]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 01:25:00 volumio volumio[1554]: info: Loading plugins from folder /data/plugins/ Feb 16 01:25:00 volumio volumio[1554]: info: Loading plugin "system"... Feb 16 01:25:00 volumio volumio[1554]: info: Loading plugin "appearance"... Feb 16 01:25:00 volumio volumio[1554]: info: Loading plugin "network"... Feb 16 01:25:00 volumio volumio[1554]: info: Refreshing Cached IP Addresses Feb 16 01:25:00 volumio sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 01:25:00 volumio sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:25:00 volumio sudo[1598]: pam_unix(sudo:session): session closed for user root Feb 16 01:25:00 volumio sudo[1600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 01:25:00 volumio sudo[1600]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:25:00 volumio sudo[1600]: pam_unix(sudo:session): session closed for user root Feb 16 01:25:00 volumio volumio[1554]: info: Loading plugin "services"... Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "alsa_controller"... Feb 16 01:25:01 volumio sudo[1609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 16 01:25:01 volumio sudo[1609]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:25:01 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "wizard"... Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "networkfs"... Feb 16 01:25:01 volumio volumio[1554]: info: Cannot mount NAS Hp at system boot, trial number 1 ,retrying in 5 seconds Feb 16 01:25:01 volumio volumio[1554]: info: Starting Udev Watcher for removable devices Feb 16 01:25:01 volumio sudo[1626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.199.215:mnt/MyPool/Mystorage/Hi-Fi /mnt/NAS/Truenas Feb 16 01:25:01 volumio sudo[1626]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:25:01 volumio volumio[1554]: info: Ignoring mount for partition: boot Feb 16 01:25:01 volumio volumio[1554]: info: Ignoring mount for partition: volumio Feb 16 01:25:01 volumio volumio[1554]: info: Ignoring mount for partition: volumio_data Feb 16 01:25:01 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "volumio_command_line_client"... Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "upnp"... Feb 16 01:25:01 volumio volumio[1554]: info: [1739640301149] Starting Upmpd Daemon Feb 16 01:25:01 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "my_music"... Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "mpd"... Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "upnp_browser"... Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "alarm-clock"... Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "airplay_emulation"... Feb 16 01:25:01 volumio volumio[1554]: info: Starting Shairport Sync Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "last_100"... Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "webradio"... Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "i2s_dacs"... Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "volumiodiscovery"... Feb 16 01:25:01 volumio volumio[1554]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 01:25:01 volumio volumio[1554]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 01:25:01 volumio volumio[1554]: *** WARNING *** For more information see Feb 16 01:25:01 volumio node[1554]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 01:25:01 volumio volumio[1554]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 01:25:01 volumio volumio[1554]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 01:25:01 volumio volumio[1554]: *** WARNING *** For more information see Feb 16 01:25:01 volumio node[1554]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 01:25:01 volumio node[1554]: *** WARNING *** For more information see Feb 16 01:25:01 volumio node[1554]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 01:25:01 volumio node[1554]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 01:25:01 volumio node[1554]: *** WARNING *** For more information see Feb 16 01:25:01 volumio volumio[1554]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 01:25:01 volumio volumio[1554]: info: Discovery: Started advertising with name: Volumio Feb 16 01:25:01 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "outputs"... Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "albumart"... Feb 16 01:25:01 volumio volumio[1554]: info: Plugin example_plugin is not enabled Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "inputs"... Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "updater_comm"... Feb 16 01:25:01 volumio volumio[1554]: info: Plugin mpdemulation is not enabled Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "rest_api"... Feb 16 01:25:01 volumio volumio[1554]: info: Loading plugin "websocket"... Feb 16 01:25:01 volumio volumio[1554]: info: Starting Socket.io Server version 2.3.0 Feb 16 01:25:01 volumio volumio[1554]: info: Loading i18n strings for locale zh Feb 16 01:25:01 volumio volumio[1554]: Updating browse sources language Feb 16 01:25:01 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 01:25:01 volumio volumio[1554]: Forking 3 albumart workers Feb 16 01:25:01 volumio sudo[1609]: pam_unix(sudo:session): session closed for user root Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::initPlayerControls Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 01:25:02 volumio volumio[1554]: Express server listening on port 3000 Feb 16 01:25:02 volumio volumio[1554]: [Metrics] WebUI: 2s 415.75ms Feb 16 01:25:02 volumio volumio[1554]: info: CoreStateMachine::resetVolumioState Feb 16 01:25:02 volumio volumio[1554]: info: CoreStateMachine::getcurrentVolume Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 01:25:02 volumio volumio[1554]: info: Volumio Network Manager: Network status updated: 1 Feb 16 01:25:02 volumio volumio[1554]: verbose: New Socket.io Connection to 192.168.100.124 from 192.168.100.219 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 01:25:02 volumio volumio[1554]: info: Reloading queue from file Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::volumioGetVisibleSources Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::volumioGetState Feb 16 01:25:02 volumio volumio[1554]: info: CorePlayQueue::getTrack 0 Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 16 01:25:02 volumio volumio[1554]: info: Received Get System Info Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 01:25:02 volumio volumio[1554]: info: Discovery: Getting this device information Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::volumioGetState Feb 16 01:25:02 volumio volumio[1554]: info: CorePlayQueue::getTrack 0 Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::volumioGetState Feb 16 01:25:02 volumio volumio[1554]: info: CorePlayQueue::getTrack 0 Feb 16 01:25:02 volumio volumio[1554]: info: Listing playlists Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::volumioGetQueue Feb 16 01:25:02 volumio volumio[1554]: info: CoreStateMachine::getQueue Feb 16 01:25:02 volumio volumio[1554]: info: CorePlayQueue::getQueue Feb 16 01:25:02 volumio volumio[1554]: info: CoreStateMachine::setRepeat true single undefined Feb 16 01:25:02 volumio volumio[1554]: info: CoreStateMachine::pushState Feb 16 01:25:02 volumio volumio[1554]: info: CorePlayQueue::getTrack 0 Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::volumioPushState Feb 16 01:25:02 volumio volumio[1554]: info: CoreStateMachine::setRandom true Feb 16 01:25:02 volumio volumio[1554]: info: CoreStateMachine::pushState Feb 16 01:25:02 volumio volumio[1554]: info: CorePlayQueue::getTrack 0 Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::volumioPushState Feb 16 01:25:02 volumio volumio[1554]: info: Setting Device type: Raspberry PI Feb 16 01:25:02 volumio volumio[1554]: info: VolumeController:: Volume=100 Mute =true Feb 16 01:25:02 volumio volumio[1554]: info: CoreStateMachine::pushState Feb 16 01:25:02 volumio volumio[1554]: info: CorePlayQueue::getTrack 0 Feb 16 01:25:02 volumio volumio[1554]: Starting albumart workers Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::volumioPushState Feb 16 01:25:02 volumio volumio[1554]: info: CoreStateMachine::updateTrackBlock Feb 16 01:25:02 volumio volumio[1554]: info: CorePlayQueue::getTrackBlock Feb 16 01:25:02 volumio volumio[1554]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 01:25:02 volumio volumio[1554]: Starting albumart workers Feb 16 01:25:02 volumio volumio[1554]: Starting albumart workers Feb 16 01:25:02 volumio volumio[1554]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 16 01:25:02 volumio volumio[1554]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Feb 16 01:25:02 volumio volumio[1554]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 16 01:25:02 volumio ntpd[824]: Soliciting pool server 193.182.111.12 Feb 16 01:25:02 volumio volumio[1554]: info: VolumeController::SetAlsaVolume0 Feb 16 01:25:02 volumio volumio[1554]: info: CoreStateMachine::pushState Feb 16 01:25:03 volumio volumio[1554]: info: CorePlayQueue::getTrack 0 Feb 16 01:25:03 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 01:25:03 volumio volumio[1554]: info: CoreCommandRouter::volumioPushState Feb 16 01:25:03 volumio volumio[1554]: info: VolumeController:: Volume=100 Mute =false Feb 16 01:25:03 volumio volumio[1554]: info: CoreStateMachine::pushState Feb 16 01:25:03 volumio volumio[1554]: info: CorePlayQueue::getTrack 0 Feb 16 01:25:03 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 01:25:03 volumio volumio[1554]: info: CoreCommandRouter::volumioPushState Feb 16 01:25:03 volumio volumio[1554]: info: Discovery: adding e60be0db-ccc8-4f7f-b578-56cf614378eb Feb 16 01:25:03 volumio volumio[1554]: info: Discovery: Found device Volumio Feb 16 01:25:03 volumio volumio[1554]: info: CoreCommandRouter::volumioGetState Feb 16 01:25:03 volumio volumio[1554]: info: CorePlayQueue::getTrack 0 Feb 16 01:25:03 volumio volumio-remote-updater[598]: [2025-02-16 01:25:03] [connect] Successful connection Feb 16 01:25:03 volumio volumio-remote-updater[598]: [2025-02-16 01:25:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739640303 101 Feb 16 01:25:03 volumio volumio[1554]: 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 16 01:25:03 volumio ntpd[824]: Soliciting pool server 202.118.1.130 Feb 16 01:25:03 volumio ntpd[824]: Soliciting pool server 193.182.111.141 Feb 16 01:25:04 volumio ntpd[824]: Soliciting pool server 162.159.200.1 Feb 16 01:25:04 volumio ntpd[824]: Soliciting pool server 78.46.102.180 Feb 16 01:25:04 volumio ntpd[824]: Soliciting pool server 193.182.111.14 Feb 16 01:25:04 volumio ntpd[824]: Soliciting pool server 162.159.200.123 Feb 16 01:25:05 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 01:25:05 volumio ntpd[824]: Soliciting pool server 5.79.108.34 Feb 16 01:25:05 volumio ntpd[824]: Soliciting pool server 84.16.67.12 Feb 16 01:25:05 volumio ntpd[824]: Soliciting pool server 185.209.85.222 Feb 16 01:25:05 volumio ntpd[824]: Soliciting pool server 5.79.108.34 Feb 16 01:25:06 volumio volumio[1554]: info: Cannot mount NAS Hp at system boot, trial number 2 ,retrying in 5 seconds Feb 16 01:25:07 volumio wpa_supplicant[906]: wlan0: Failed to initiate sched scan Feb 16 01:25:08 volumio volumio[1554]: info: CoreCommandRouter::volumioPlay Feb 16 01:25:08 volumio volumio[1554]: info: CoreStateMachine::play index undefined Feb 16 01:25:08 volumio volumio[1554]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 01:25:08 volumio volumio[1554]: info: CorePlayQueue::getTrack 0 Feb 16 01:25:08 volumio volumio[1554]: info: CoreStateMachine::startPlaybackTimer Feb 16 01:25:08 volumio volumio[1554]: info: CorePlayQueue::getTrack 0 Feb 16 01:25:08 volumio volumio[1554]: verbose: ControllerMpd::clearAddPlayTracks NAS/Hp/1/01.I Want It That Way.flac Feb 16 01:25:08 volumio volumio[1554]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 01:25:09 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 01:25:12 volumio ntpd[824]: receive: Unexpected origin timestamp 0xeb5b4c76.b9ac95f1 does not match aorg 0000000000.00000000 from server@84.16.67.12 xmt 0xeb5b4c77.f37809f5 Feb 16 01:25:12 volumio ntpd[824]: receive: Unexpected origin timestamp 0xeb5b4c76.b9af58ee does not match aorg 0000000000.00000000 from server@193.182.111.141 xmt 0xeb5b4c77.f1d06b09 Feb 16 01:25:12 volumio volumio[1554]: info: Cannot mount NAS Hp at system boot, trial number 3 ,retrying in 5 seconds Feb 16 01:25:12 volumio sudo[1715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 01:25:12 volumio sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:25:12 volumio sudo[1715]: pam_unix(sudo:session): session closed for user root Feb 16 01:25:12 volumio sudo[1717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 01:25:12 volumio sudo[1717]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:25:12 volumio sudo[1717]: pam_unix(sudo:session): session closed for user root Feb 16 01:25:12 volumio sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 16 01:25:12 volumio sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:25:12 volumio sudo[1721]: pam_unix(sudo:session): session closed for user root Feb 16 01:25:12 volumio volumio[1554]: info: Upmpdcli Daemon Started Feb 16 01:25:14 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 01:25:15 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Feb 16 01:25:15 volumio sudo[1724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Hp Feb 16 01:25:15 volumio sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 01:25:15 volumio systemd[1]: mnt-NAS-Hp.mount: Succeeded. Feb 16 01:25:15 volumio sudo[1724]: pam_unix(sudo:session): session closed for user root Feb 16 01:25:15 volumio volumio[1554]: info: Share Hp successfully unmounted Feb 16 01:25:16 volumio volumio[1554]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 16 01:25:17 volumio volumio[1554]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 01:25:17 volumio volumio[1554]: TypeError: Cannot read property 'replace' of undefined Feb 16 01:25:17 volumio volumio[1554]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Feb 16 01:25:17 volumio volumio[1554]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Feb 16 01:25:17 volumio volumio[1554]: at listOnTimeout (internal/timers.js:554:17) Feb 16 01:25:17 volumio volumio[1554]: at processTimers (internal/timers.js:497:7) Feb 16 01:25:17 volumio volumio[1554]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 01:25:17 volumio wpa_supplicant[906]: wlan0: Failed to initiate sched scan Feb 16 01:25:17 volumio sudo[1739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-16 01:24 Feb 16 01:25:17 volumio sudo[1739]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"