-- Logs begin at Mon 2025-11-03 18:47:50 CST, end at Mon 2025-11-03 18:49:02 CST. -- Nov 03 18:48:00 volumio wpa_supplicant[1007]: wlan0: SME: Trying to authenticate with 50:4f:3b:10:bf:2f (SSID='Xiaomi_lf28_5G' freq=5220 MHz) Nov 03 18:48:00 volumio kernel: wlan0: authenticate with 50:4f:3b:10:bf:2f Nov 03 18:48:00 volumio wpa_supplicant[1007]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=CN Nov 03 18:48:00 volumio kernel: wlan0: send auth to 50:4f:3b:10:bf:2f (try 1/3) Nov 03 18:48:00 volumio wpa_supplicant[1007]: wlan0: Trying to associate with 50:4f:3b:10:bf:2f (SSID='Xiaomi_lf28_5G' freq=5220 MHz) Nov 03 18:48:00 volumio kernel: wlan0: authenticated Nov 03 18:48:00 volumio kernel: wlan0: associate with 50:4f:3b:10:bf:2f (try 1/3) Nov 03 18:48:00 volumio kernel: wlan0: RX AssocResp from 50:4f:3b:10:bf:2f (capab=0x1511 status=0 aid=3) Nov 03 18:48:00 volumio wpa_supplicant[1007]: wlan0: Associated with 50:4f:3b:10:bf:2f Nov 03 18:48:00 volumio wpa_supplicant[1007]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 03 18:48:00 volumio kernel: wlan0: associated Nov 03 18:48:00 volumio wpa_supplicant[1007]: wlan0: WPA: Key negotiation completed with 50:4f:3b:10:bf:2f [PTK=CCMP GTK=CCMP] Nov 03 18:48:00 volumio wpa_supplicant[1007]: wlan0: CTRL-EVENT-CONNECTED - Connection to 50:4f:3b:10:bf:2f completed [id=0 id_str=] Nov 03 18:48:00 volumio dhcpcd[833]: wlan0: carrier acquired Nov 03 18:48:00 volumio dhcpcd[833]: wlan0: IAID 1d:7a:a0:9e Nov 03 18:48:00 volumio dhcpcd[833]: wlan0: soliciting an IPv6 router Nov 03 18:48:00 volumio wireless.js[716]: trying... Nov 03 18:48:00 volumio sudo[1039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 03 18:48:00 volumio sudo[1039]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:00 volumio sudo[1039]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:01 volumio dhcpcd[833]: wlan0: rebinding lease of 192.168.100.116 Nov 03 18:48:01 volumio dhcpcd[833]: wlan0: probing address 192.168.100.116/24 Nov 03 18:48:01 volumio kernel: platform USBC000:00: deferred probe pending Nov 03 18:48:01 volumio wireless.js[716]: trying... Nov 03 18:48:01 volumio sudo[1042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 03 18:48:01 volumio sudo[1042]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:01 volumio sudo[1042]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:02 volumio volumio-time-update[725]: volumio-time-update-util: Fetching time from Volumio... Nov 03 18:48:02 volumio volumio-time-update[725]: volumio-time-update-util: Date not found in response Nov 03 18:48:02 volumio volumio-time-update[725]: volumio-time-update-util: Retrying in 5 seconds... Nov 03 18:48:02 volumio wireless.js[716]: trying... Nov 03 18:48:02 volumio sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 03 18:48:02 volumio sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:02 volumio sudo[1050]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:03 volumio wireless.js[716]: trying... Nov 03 18:48:03 volumio sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 03 18:48:03 volumio sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:03 volumio sudo[1053]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:04 volumio wireless.js[716]: trying... Nov 03 18:48:04 volumio sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 03 18:48:04 volumio sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:04 volumio sudo[1056]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:05 volumio volumio-remote-updater[724]: [2025-11-03 18:48:05] [info] asio async_connect error: system:111 (Connection refused) Nov 03 18:48:05 volumio volumio-remote-updater[724]: [2025-11-03 18:48:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 03 18:48:05 volumio volumio-remote-updater[724]: [2025-11-03 18:48:05] [error] handle_connect error: Underlying Transport Error Nov 03 18:48:05 volumio dhcpcd[833]: wlan0: leased 192.168.100.116 for 43200 seconds Nov 03 18:48:05 volumio avahi-daemon[707]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.116. Nov 03 18:48:05 volumio dhcpcd[833]: wlan0: adding route to 192.168.100.0/24 Nov 03 18:48:05 volumio dhcpcd[833]: wlan0: adding default route via 192.168.100.1 Nov 03 18:48:05 volumio avahi-daemon[707]: New relevant interface wlan0.IPv4 for mDNS. Nov 03 18:48:05 volumio avahi-daemon[707]: Registering new address record for 192.168.100.116 on wlan0.IPv4. Nov 03 18:48:05 volumio ntpd[910]: ntpd exiting on signal 15 (Terminated) Nov 03 18:48:05 volumio systemd[1]: Stopping Network Time Service... Nov 03 18:48:05 volumio systemd[1]: ntp.service: Succeeded. Nov 03 18:48:05 volumio systemd[1]: Stopped Network Time Service. Nov 03 18:48:05 volumio systemd[1]: Starting Network Time Service... Nov 03 18:48:05 volumio ntpd[1092]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 03 18:48:05 volumio ntpd[1092]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 03 18:48:05 volumio systemd[1]: Started Network Time Service. Nov 03 18:48:05 volumio ntpd[1098]: proto: precision = 0.063 usec (-24) Nov 03 18:48:05 volumio ntpd[1098]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 03 18:48:05 volumio ntpd[1098]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 03 18:48:05 volumio ntpd[1098]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1042 days ago Nov 03 18:48:05 volumio ntpd[1098]: Listen and drop on 0 v6wildcard [::]:123 Nov 03 18:48:05 volumio ntpd[1098]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 03 18:48:05 volumio ntpd[1098]: Listen normally on 2 lo 127.0.0.1:123 Nov 03 18:48:05 volumio ntpd[1098]: Listen normally on 3 wlan0 192.168.100.116:123 Nov 03 18:48:05 volumio ntpd[1098]: Listening on routing socket on fd #20 for interface updates Nov 03 18:48:05 volumio ntpd[1098]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 03 18:48:05 volumio ntpd[1098]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 03 18:48:05 volumio wireless.js[716]: trying... Nov 03 18:48:05 volumio sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 03 18:48:05 volumio sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:05 volumio sudo[1101]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:05 volumio wireless.js[716]: Connected to: ----Xiaomi_lf28_5G Nov 03 18:48:05 volumio wireless.js[716]: ---- Nov 03 18:48:06 volumio sudo[1104]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 03 18:48:06 volumio sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:06 volumio sudo[1104]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:06 volumio wireless.js[716]: ... joined AP, wlan0 IPv4 is 192.168.100.116, ipV6 is undefined Nov 03 18:48:06 volumio wireless.js[716]: It's done! AP Nov 03 18:48:06 volumio systemd[1]: Started Wireless Services. Nov 03 18:48:06 volumio systemd[1]: Started Volumio Backend Module. Nov 03 18:48:06 volumio systemd[1]: Started Start Volumio Kiosk. Nov 03 18:48:06 volumio systemd[1]: Started Volumio Cpu Tweaker. Nov 03 18:48:06 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Nov 03 18:48:06 volumio volumio-cpu-tweak[1111]: Setting RT Priority for mpd Nov 03 18:48:06 volumio volumio-cpu-tweak[1111]: Not enough cores to set MPD affinity Nov 03 18:48:06 volumio volumio-cpu-tweak[1111]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Nov 03 18:48:06 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Nov 03 18:48:06 volumio startx[1110]: X.Org X Server 1.20.4 Nov 03 18:48:06 volumio startx[1110]: X Protocol Version 11, Revision 0 Nov 03 18:48:06 volumio startx[1110]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Nov 03 18:48:06 volumio startx[1110]: Current Operating System: Linux volumio 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Nov 03 18:48:06 volumio startx[1110]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=47887e29-9707-47e6-b0dd-43e3e80bada7 bootpart=UUID=C2C3-4C63 datapart=UUID=28747106-f667-4326-abe4-5435a33543d6 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no Nov 03 18:48:06 volumio startx[1110]: Build Date: 18 December 2021 09:05:36AM Nov 03 18:48:06 volumio startx[1110]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Nov 03 18:48:06 volumio startx[1110]: Current version of pixman: 0.36.0 Nov 03 18:48:06 volumio startx[1110]: Before reporting problems, check http://wiki.x.org Nov 03 18:48:06 volumio startx[1110]: to make sure that you have the latest version. Nov 03 18:48:06 volumio startx[1110]: Markers: (--) probed, (**) from config file, (==) default setting, Nov 03 18:48:06 volumio startx[1110]: (++) from command line, (!!) notice, (II) informational, Nov 03 18:48:06 volumio startx[1110]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Nov 03 18:48:06 volumio startx[1110]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Nov 3 18:48:06 2025 Nov 03 18:48:06 volumio startx[1110]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Nov 03 18:48:06 volumio kernel: broken atomic modeset userspace detected, disabling atomic Nov 03 18:48:06 volumio systemd[1]: soundcard-init.service: Succeeded. Nov 03 18:48:06 volumio startx[1110]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Nov 03 18:48:06 volumio ntpd[1098]: Soliciting pool server 139.199.214.202 Nov 03 18:48:07 volumio volumio[1109]: info: ------------------------------------------- Nov 03 18:48:07 volumio volumio[1109]: info: ----- Volumio3 ---- Nov 03 18:48:07 volumio volumio[1109]: info: ------------------------------------------- Nov 03 18:48:07 volumio volumio[1109]: info: ----- System startup ---- Nov 03 18:48:07 volumio volumio[1109]: info: ------------------------------------------- Nov 03 18:48:07 volumio volumio[1109]: info: MYVOLUMIO Environment detected Nov 03 18:48:07 volumio volumio[1109]: info: Plugin folders cleanup Nov 03 18:48:07 volumio volumio[1109]: info: Scanning into folder /volumio/app/plugins/ Nov 03 18:48:07 volumio volumio[1109]: info: Scanning category audio_interface Nov 03 18:48:07 volumio volumio[1109]: info: Scanning category miscellanea Nov 03 18:48:07 volumio volumio[1109]: info: Scanning category music_service Nov 03 18:48:07 volumio volumio[1109]: info: Scanning category plugins.json Nov 03 18:48:07 volumio volumio[1109]: info: Scanning category system_controller Nov 03 18:48:07 volumio volumio[1109]: info: Scanning category user_interface Nov 03 18:48:07 volumio volumio[1109]: info: Scanning into folder /data/plugins/ Nov 03 18:48:07 volumio volumio[1109]: info: Plugin folders cleanup completed Nov 03 18:48:07 volumio volumio[1109]: info: ------------------------------------------- Nov 03 18:48:07 volumio volumio[1109]: info: ----- Core plugins startup ---- Nov 03 18:48:07 volumio volumio[1109]: info: ------------------------------------------- Nov 03 18:48:07 volumio volumio[1109]: info: Loading plugins from folder /volumio/app/plugins/ Nov 03 18:48:07 volumio volumio[1109]: info: Adding plugin upnp to MyMusic Plugins Nov 03 18:48:07 volumio volumio[1109]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 03 18:48:07 volumio volumio[1109]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 03 18:48:07 volumio volumio[1109]: info: Loading plugins from folder /data/plugins/ Nov 03 18:48:07 volumio volumio[1109]: info: Loading plugin "system"... Nov 03 18:48:07 volumio volumio[1109]: info: Loading plugin "appearance"... Nov 03 18:48:07 volumio volumio-time-update[725]: volumio-time-update-util: Fetching time from Volumio... Nov 03 18:48:07 volumio ntpd[1098]: Soliciting pool server 162.159.200.1 Nov 03 18:48:07 volumio ntpd[1098]: Soliciting pool server 119.28.183.184 Nov 03 18:48:08 volumio nmbd[858]: [2025/11/03 18:48:08.193399, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 03 18:48:08 volumio nmbd[858]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 03 18:48:08 volumio systemd[1]: Started Samba NMB Daemon. Nov 03 18:48:08 volumio systemd[1]: Starting Samba Winbind Daemon... Nov 03 18:48:08 volumio winbindd[1317]: [2025/11/03 18:48:08.377468, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 03 18:48:08 volumio winbindd[1317]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 03 18:48:08 volumio winbindd[1317]: [2025/11/03 18:48:08.384013, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 03 18:48:08 volumio systemd[1]: Started Samba Winbind Daemon. Nov 03 18:48:08 volumio winbindd[1317]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 03 18:48:08 volumio systemd[1]: Starting Samba SMB Daemon... Nov 03 18:48:08 volumio volumio-time-update[725]: volumio-time-update-util: Setting system time to: 2025-11-03 18:48:08 Nov 03 18:48:08 volumio sudo[1323]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-11-03 18:48:08 Nov 03 18:48:08 volumio sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:08 volumio dbus-daemon[711]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.8' (uid=0 pid=1325 comm="timedatectl set-time 2025-11-03 18:48:08 ") Nov 03 18:48:08 volumio systemd[1]: Starting Time & Date Service... Nov 03 18:48:08 volumio dbus-daemon[711]: [system] Successfully activated service 'org.freedesktop.timedate1' Nov 03 18:48:08 volumio systemd[1]: Started Time & Date Service. Nov 03 18:48:08 volumio systemd-timedated[1326]: Changed local time to Mon Nov 3 18:48:08 2025 Nov 03 18:48:08 volumio sudo[1323]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:08 volumio volumio-time-update[725]: volumio-time-update-util: System time updated successfully. Nov 03 18:48:08 volumio systemd[1]: Started Volumio Time Update Utility. Nov 03 18:48:08 volumio smbd[1324]: [2025/11/03 18:48:08.144247, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 03 18:48:08 volumio systemd[1]: Started Samba SMB Daemon. Nov 03 18:48:08 volumio smbd[1324]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 03 18:48:08 volumio systemd[1]: Reached target Multi-User System. Nov 03 18:48:08 volumio systemd[1]: Reached target Graphical Interface. Nov 03 18:48:08 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 03 18:48:08 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 03 18:48:08 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 03 18:48:08 volumio volumio[1109]: info: Loading plugin "network"... Nov 03 18:48:08 volumio systemd[1]: Startup finished in 6.742s (firmware) + 9.450s (loader) + 6.715s (kernel) + 19.224s (userspace) = 42.133s. Nov 03 18:48:08 volumio volumio[1109]: info: Refreshing Cached IP Addresses Nov 03 18:48:08 volumio ntpd[1098]: Soliciting pool server 84.16.67.12 Nov 03 18:48:08 volumio ntpd[1098]: Soliciting pool server 193.182.111.142 Nov 03 18:48:08 volumio sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 03 18:48:08 volumio sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:08 volumio ntpd[1098]: Soliciting pool server 95.111.202.5 Nov 03 18:48:08 volumio sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 03 18:48:08 volumio sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:08 volumio sudo[1339]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:08 volumio sudo[1340]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:08 volumio volumio[1109]: info: Loading plugin "services"... Nov 03 18:48:08 volumio volumio[1109]: info: Loading plugin "alsa_controller"... Nov 03 18:48:08 volumio sudo[1353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 03 18:48:08 volumio sudo[1353]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:08 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 18:48:08 volumio volumio[1109]: info: Loading plugin "wizard"... Nov 03 18:48:08 volumio volumio[1109]: info: Loading plugin "networkfs"... Nov 03 18:48:08 volumio volumio[1109]: info: Starting Udev Watcher for removable devices Nov 03 18:48:08 volumio volumio[1109]: info: Ignoring mount for partition: boot Nov 03 18:48:08 volumio volumio[1109]: info: Ignoring mount for partition: volumio Nov 03 18:48:08 volumio volumio[1109]: info: Ignoring mount for partition: volumio_data Nov 03 18:48:08 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 03 18:48:08 volumio sudo[1366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=huangcf,password=Aa80041,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.100.111/vol1/1000/movie /mnt/NAS/nas Nov 03 18:48:08 volumio sudo[1366]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:08 volumio volumio[1109]: info: Loading plugin "volumio_command_line_client"... Nov 03 18:48:08 volumio volumio[1109]: info: Loading plugin "upnp"... Nov 03 18:48:08 volumio volumio[1109]: info: [1762166888387] Starting Upmpd Daemon Nov 03 18:48:08 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 03 18:48:08 volumio volumio[1109]: info: Loading plugin "my_music"... Nov 03 18:48:08 volumio volumio[1109]: info: Loading plugin "mpd"... Nov 03 18:48:08 volumio kernel: FS-Cache: Loaded Nov 03 18:48:08 volumio kernel: Key type dns_resolver registered Nov 03 18:48:08 volumio kernel: Key type cifs.spnego registered Nov 03 18:48:08 volumio kernel: Key type cifs.idmap registered Nov 03 18:48:08 volumio kernel: CIFS: Attempting to mount //192.168.100.111/vol1/1000/movie Nov 03 18:48:08 volumio volumio[1109]: info: Loading plugin "upnp_browser"... Nov 03 18:48:08 volumio sudo[1366]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:08 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Nov 03 18:48:08 volumio kernel: CIFS: VFS: \\192.168.100.111 Send error in SessSetup = -13 Nov 03 18:48:08 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Nov 03 18:48:08 volumio volumio[1109]: info: Loading plugin "alarm-clock"... Nov 03 18:48:09 volumio volumio[1109]: info: Loading plugin "airplay_emulation"... Nov 03 18:48:09 volumio volumio[1109]: info: Starting Shairport Sync Nov 03 18:48:09 volumio volumio[1109]: info: Loading plugin "last_100"... Nov 03 18:48:09 volumio volumio[1109]: info: Loading plugin "webradio"... Nov 03 18:48:09 volumio volumio[1109]: info: Loading plugin "i2s_dacs"... Nov 03 18:48:09 volumio volumio[1109]: info: I2S DAC not set, start Auto-detection Nov 03 18:48:09 volumio volumio[1109]: info: Loading plugin "volumiodiscovery"... Nov 03 18:48:09 volumio volumio[1109]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 03 18:48:09 volumio volumio[1109]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 03 18:48:09 volumio volumio[1109]: *** WARNING *** For more information see Nov 03 18:48:09 volumio node[1109]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 03 18:48:09 volumio volumio[1109]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 03 18:48:09 volumio volumio[1109]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 03 18:48:09 volumio volumio[1109]: *** WARNING *** For more information see Nov 03 18:48:09 volumio node[1109]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 03 18:48:09 volumio node[1109]: *** WARNING *** For more information see Nov 03 18:48:09 volumio node[1109]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 03 18:48:09 volumio node[1109]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 03 18:48:09 volumio node[1109]: *** WARNING *** For more information see Nov 03 18:48:09 volumio volumio[1109]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 03 18:48:09 volumio volumio[1109]: info: Discovery: Started advertising with name: Volumio Nov 03 18:48:09 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 03 18:48:09 volumio volumio[1109]: info: Loading plugin "outputs"... Nov 03 18:48:09 volumio volumio[1109]: info: Loading plugin "albumart"... Nov 03 18:48:09 volumio volumio[1109]: info: Plugin example_plugin is not enabled Nov 03 18:48:09 volumio volumio[1109]: info: Loading plugin "inputs"... Nov 03 18:48:09 volumio volumio[1109]: info: Loading plugin "updater_comm"... Nov 03 18:48:09 volumio ntpd[1098]: Soliciting pool server 108.59.2.24 Nov 03 18:48:09 volumio ntpd[1098]: Soliciting pool server 116.203.151.74 Nov 03 18:48:09 volumio ntpd[1098]: Soliciting pool server 162.159.200.123 Nov 03 18:48:09 volumio volumio[1109]: Forking 1 albumart workers Nov 03 18:48:09 volumio ntpd[1098]: Soliciting pool server 111.230.189.174 Nov 03 18:48:09 volumio volumio[1109]: info: Plugin mpdemulation is not enabled Nov 03 18:48:09 volumio volumio[1109]: info: Loading plugin "rest_api"... Nov 03 18:48:09 volumio volumio[1109]: Starting albumart workers Nov 03 18:48:09 volumio volumio[1109]: info: Loading plugin "websocket"... Nov 03 18:48:09 volumio volumio[1109]: info: Starting Socket.io Server version 2.3.0 Nov 03 18:48:09 volumio volumio[1109]: info: Loading i18n strings for locale zh Nov 03 18:48:09 volumio volumio[1109]: Updating browse sources language Nov 03 18:48:09 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 18:48:09 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 18:48:09 volumio volumio[1109]: info: CoreCommandRouter::initPlayerControls Nov 03 18:48:09 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:09 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:09 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:09 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:09 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 18:48:09 volumio volumio[1109]: Express server listening on port 3000 Nov 03 18:48:09 volumio volumio[1109]: [Metrics] WebUI: 3s 688.71ms Nov 03 18:48:09 volumio volumio[1109]: info: Setting Device type: x86 Nov 03 18:48:09 volumio volumio[1109]: info: CoreStateMachine::resetVolumioState Nov 03 18:48:09 volumio volumio[1109]: info: CoreStateMachine::getcurrentVolume Nov 03 18:48:09 volumio volumio[1109]: info: CoreCommandRouter::volumioRetrievevolume Nov 03 18:48:09 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:09 volumio volumio[1109]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 03 18:48:09 volumio volumio[1109]: info: Completed loading Core Plugins Nov 03 18:48:09 volumio volumio[1109]: info: Preparing to generate the ALSA configuration file Nov 03 18:48:09 volumio volumio[1109]: info: Cannot read play queue from file Nov 03 18:48:09 volumio volumio[1109]: info: Volumio Network Manager: Network status updated: 2 Nov 03 18:48:09 volumio volumio[1109]: info: Cannot mount NAS nas at system boot, trial number 1 ,retrying in 5 seconds Nov 03 18:48:09 volumio volumio[1109]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Nov 03 18:48:10 volumio volumio[1109]: verbose: New Socket.io Connection to 192.168.100.116:3000 from 192.168.100.139 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Nov 03 18:48:10 volumio volumio[1109]: info: Asound.conf file unchanged, so no further update is needed Nov 03 18:48:10 volumio volumio[1109]: info: Output device has changed, restarting MPD Nov 03 18:48:10 volumio volumio[1109]: info: Output device has changed, restarting Shairport Sync Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:10 volumio sudo[1482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 03 18:48:10 volumio sudo[1482]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:10 volumio sudo[1482]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:10 volumio sudo[1484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 03 18:48:10 volumio sudo[1484]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:10 volumio volumio[1109]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 03 18:48:10 volumio volumio[1109]: info: ___________ START PLUGINS ___________ Nov 03 18:48:10 volumio systemd[1]: Stopping Music Player Daemon... Nov 03 18:48:10 volumio volumio[1109]: info: ControllerMpd::onStart: Initializing MPD Nov 03 18:48:10 volumio volumio[1109]: info: Creating MPD Configuration file Nov 03 18:48:10 volumio sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 03 18:48:10 volumio sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 18:48:10 volumio systemd[1]: mpd.service: Succeeded. Nov 03 18:48:10 volumio systemd[1]: Stopped Music Player Daemon. Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 03 18:48:10 volumio volumio[1109]: info: [1762166890148] CoreMusicLibrary::Adding element 媒体服务器 Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 18:48:10 volumio sudo[1491]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:10 volumio sudo[1493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 03 18:48:10 volumio systemd[1]: Starting Music Player Daemon... Nov 03 18:48:10 volumio sudo[1493]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:10 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 03 18:48:10 volumio systemd[1]: mpd.service: Succeeded. Nov 03 18:48:10 volumio systemd[1]: Stopped Music Player Daemon. Nov 03 18:48:10 volumio ntpd[1098]: Soliciting pool server 193.182.111.143 Nov 03 18:48:10 volumio ntpd[1098]: Soliciting pool server 119.28.206.193 Nov 03 18:48:10 volumio volumio[1109]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:10 volumio systemd[1]: Starting Music Player Daemon... Nov 03 18:48:10 volumio volumio[1109]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 03 18:48:10 volumio volumio[1109]: info: [1762166890236] CoreMusicLibrary::Adding element Last_100 Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 03 18:48:10 volumio volumio[1109]: info: [1762166890237] CoreMusicLibrary::Adding element Webradio Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 18:48:10 volumio volumio[1109]: info: Initializing BBC Radios Nov 03 18:48:10 volumio sudo[1498]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 03 18:48:10 volumio sudo[1498]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:10 volumio sudo[1498]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: Volumio Calling Home Nov 03 18:48:10 volumio volumio[1109]: info: CoreStateMachine::setRepeat null single undefined Nov 03 18:48:10 volumio volumio[1109]: info: CoreStateMachine::pushState Nov 03 18:48:10 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Nov 03 18:48:10 volumio volumio[1109]: info: CoreStateMachine::setRandom null Nov 03 18:48:10 volumio volumio[1109]: info: CoreStateMachine::pushState Nov 03 18:48:10 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Nov 03 18:48:10 volumio volumio[1109]: info: VolumeController:: Volume=41 Mute =false Nov 03 18:48:10 volumio volumio[1109]: info: CoreStateMachine::pushState Nov 03 18:48:10 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Nov 03 18:48:10 volumio volumio[1109]: info: CoreStateMachine::updateTrackBlock Nov 03 18:48:10 volumio volumio[1109]: info: CorePlayQueue::getTrackBlock Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioRetrievevolume Nov 03 18:48:10 volumio volumio[1109]: info: MPD Permissions set Nov 03 18:48:10 volumio volumio[1109]: verbose: New Socket.io Connection to 192.168.100.116 from 192.168.100.139 UA: Mozilla/5.0 (Linux; Android 16; 25113PN0EC Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 18:48:10 volumio volumio[1109]: info: Discovery: Getting this device information Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:10 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 18:48:10 volumio volumio[1109]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:10 volumio volumio[1109]: info: VolumeController:: Volume=41 Mute =false Nov 03 18:48:10 volumio volumio[1109]: info: CoreStateMachine::pushState Nov 03 18:48:10 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Nov 03 18:48:10 volumio volumio[1109]: info: Starting Shairport Sync Nov 03 18:48:10 volumio volumio[1109]: info: Starting Shairport Sync Nov 03 18:48:10 volumio volumio[1109]: info: Starting Shairport Sync Nov 03 18:48:10 volumio sudo[1543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 03 18:48:10 volumio sudo[1543]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:10 volumio sudo[1545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 03 18:48:10 volumio sudo[1545]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:10 volumio volumio[1109]: info: Discovery: adding db905a27-3a29-4611-8301-46bb0d76efd1 Nov 03 18:48:10 volumio volumio[1109]: info: Discovery: Found device Volumio Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:10 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 03 18:48:10 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 03 18:48:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 03 18:48:10 volumio sudo[1548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 03 18:48:10 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 03 18:48:10 volumio sudo[1543]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 03 18:48:10 volumio volumio[1109]: verbose: New Socket.io Connection to 192.168.100.116:3000 from 192.168.100.139 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Nov 03 18:48:10 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 03 18:48:10 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 03 18:48:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 03 18:48:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 03 18:48:10 volumio sudo[1545]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:10 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:10 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:10 volumio mpd[1515]: Nov 03 18:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 03 18:48:10 volumio systemd[1]: Started Music Player Daemon. Nov 03 18:48:10 volumio sudo[1484]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:10 volumio volumio[1109]: info: Shairport-Sync Started Nov 03 18:48:10 volumio sudo[1493]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:10 volumio volumio[1109]: Error adding Membership: Error: addMembership EINVAL Nov 03 18:48:10 volumio volumio[1109]: info: Shairport-Sync Started Nov 03 18:48:10 volumio volumio[1109]: info: Shairport-Sync Started Nov 03 18:48:10 volumio volumio[1109]: info: Completed starting Core Plugins Nov 03 18:48:10 volumio volumio[1109]: info: ------------------------------------------- Nov 03 18:48:10 volumio volumio[1109]: info: ----- MyVolumio plugins startup ---- Nov 03 18:48:10 volumio volumio[1109]: info: ------------------------------------------- Nov 03 18:48:10 volumio volumio[1109]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 03 18:48:10 volumio volumio[1109]: error: MPD error: The expression evaluated to a falsy value: Nov 03 18:48:10 volumio volumio[1109]: assert.ok(self.idling) Nov 03 18:48:10 volumio volumio[1109]: error: The expression evaluated to a falsy value: Nov 03 18:48:10 volumio volumio[1109]: assert.ok(self.idling) Nov 03 18:48:10 volumio volumio[1109]: error: updateQueue error: null Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioGetVisibleSources Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:10 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 03 18:48:10 volumio volumio[1109]: info: Received Get System Info Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 18:48:10 volumio volumio[1109]: info: Discovery: Getting this device information Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:10 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:10 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:10 volumio volumio[1109]: info: Listing playlists Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioGetQueue Nov 03 18:48:10 volumio volumio[1109]: info: CoreStateMachine::getQueue Nov 03 18:48:10 volumio volumio[1109]: info: CorePlayQueue::getQueue Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:10 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 03 18:48:10 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 03 18:48:10 volumio volumio[1109]: info: MPD running with PID1515 Nov 03 18:48:10 volumio volumio[1109]: ,establishing connection Nov 03 18:48:10 volumio volumio[1109]: error: updateQueue error: null Nov 03 18:48:11 volumio volumio[1109]: info: Volumio called home Nov 03 18:48:11 volumio ntpd[1098]: Soliciting pool server 139.199.215.251 Nov 03 18:48:11 volumio volumio[1109]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 03 18:48:12 volumio volumio[1109]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 03 18:48:12 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 03 18:48:12 volumio volumio[1109]: info: CoreCommandRouter::volumioGetVisibleSources Nov 03 18:48:12 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 18:48:12 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:12 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:12 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 03 18:48:12 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 03 18:48:12 volumio volumio[1109]: info: Received Get System Info Nov 03 18:48:12 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 18:48:12 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 18:48:12 volumio volumio[1109]: info: Discovery: Getting this device information Nov 03 18:48:12 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:12 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:12 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 18:48:12 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:12 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:12 volumio volumio[1109]: info: Listing playlists Nov 03 18:48:13 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 03 18:48:13 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 03 18:48:13 volumio sudo[1353]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:13 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:13 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:14 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 03 18:48:14 volumio volumio[1109]: info: Received Get System Info Nov 03 18:48:14 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 18:48:14 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 18:48:14 volumio volumio[1109]: info: Discovery: Getting this device information Nov 03 18:48:14 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:14 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:14 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 18:48:14 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 03 18:48:14 volumio sudo[1760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=huangcf,password=Aa80041,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.100.111/vol1/1000/movie /mnt/NAS/nas Nov 03 18:48:14 volumio sudo[1760]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:14 volumio kernel: CIFS: Attempting to mount //192.168.100.111/vol1/1000/movie Nov 03 18:48:15 volumio sudo[1760]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:15 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Nov 03 18:48:15 volumio kernel: CIFS: VFS: \\192.168.100.111 Send error in SessSetup = -13 Nov 03 18:48:15 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Nov 03 18:48:15 volumio volumio[1109]: info: Cannot mount NAS nas at system boot, trial number 2 ,retrying in 5 seconds Nov 03 18:48:15 volumio ntpd[1098]: receive: Unexpected origin timestamp 0xecb306ef.34bda8f0 does not match aorg 0000000000.00000000 from server@139.199.215.251 xmt 0xecb306ef.d0161ce0 Nov 03 18:48:15 volumio ntpd[1098]: receive: Unexpected origin timestamp 0xecb306ef.34c63cec does not match aorg 0000000000.00000000 from server@119.28.206.193 xmt 0xecb306ef.d1aa6809 Nov 03 18:48:15 volumio volumio-remote-updater[724]: [2025-11-03 18:48:15] [connect] Successful connection Nov 03 18:48:15 volumio volumio-remote-updater[724]: [2025-11-03 18:48:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1762166895 101 Nov 03 18:48:15 volumio volumio[1109]: 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: 6 Nov 03 18:48:15 volumio ntpd[1098]: receive: Unexpected origin timestamp 0xecb306ef.34cd577a does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xecb306ef.e4a7793e Nov 03 18:48:15 volumio ntpd[1098]: receive: Unexpected origin timestamp 0xecb306ef.34cb7ea3 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xecb306ef.e7c3e3f0 Nov 03 18:48:16 volumio ntpd[1098]: receive: Unexpected origin timestamp 0xecb306ef.34c909ce does not match aorg 0000000000.00000000 from server@108.59.2.24 xmt 0xecb306ef.eaf32d7a Nov 03 18:48:16 volumio ntpd[1098]: receive: Unexpected origin timestamp 0xecb306ef.34cc2811 does not match aorg 0000000000.00000000 from server@193.182.111.142 xmt 0xecb306ef.f6c9c6f7 Nov 03 18:48:16 volumio ntpd[1098]: receive: Unexpected origin timestamp 0xecb306ef.34ccc042 does not match aorg 0000000000.00000000 from server@84.16.67.12 xmt 0xecb306ef.f8ee4d03 Nov 03 18:48:16 volumio ntpd[1098]: receive: Unexpected origin timestamp 0xecb306ef.34c4b2be does not match aorg 0000000000.00000000 from server@193.182.111.143 xmt 0xecb306ef.fa170880 Nov 03 18:48:16 volumio volumio[1109]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 03 18:48:18 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:18 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:18 volumio sudo[1780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 03 18:48:18 volumio sudo[1780]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:18 volumio sudo[1780]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:19 volumio sudo[1782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 03 18:48:19 volumio sudo[1782]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:19 volumio sudo[1782]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:19 volumio sudo[1786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 03 18:48:19 volumio sudo[1786]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:19 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Nov 03 18:48:19 volumio sudo[1786]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:19 volumio volumio[1109]: info: Upmpdcli Daemon Started Nov 03 18:48:19 volumio volumio[1788]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 03 18:48:19 volumio sudo[1805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 03 18:48:19 volumio sudo[1805]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:19 volumio sudo[1807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 03 18:48:19 volumio sudo[1807]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:19 volumio sudo[1807]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:19 volumio sudo[1805]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:19 volumio volumio[1109]: verbose: New Socket.io Connection to 192.168.100.116 from 192.168.100.139 UA: Mozilla/5.0 (Linux; Android 16; 25113PN0EC Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Nov 03 18:48:19 volumio volumio[1788]: ........................................++++ Nov 03 18:48:19 volumio sudo[1811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 03 18:48:19 volumio sudo[1811]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:19 volumio sudo[1811]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:19 volumio sudo[1813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 03 18:48:19 volumio sudo[1813]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:19 volumio sudo[1813]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:19 volumio volumio[1109]: verbose: New Socket.io Connection to 192.168.100.116 from 192.168.100.139 UA: Mozilla/5.0 (Linux; Android 16; 25113PN0EC Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Nov 03 18:48:19 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 03 18:48:19 volumio volumio[1109]: info: CoreCommandRouter::volumioGetVisibleSources Nov 03 18:48:19 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 18:48:19 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:19 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:19 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 03 18:48:19 volumio volumio[1109]: info: CoreCommandRouter::volumioGetQueue Nov 03 18:48:19 volumio volumio[1109]: info: CoreStateMachine::getQueue Nov 03 18:48:19 volumio volumio[1109]: info: CorePlayQueue::getQueue Nov 03 18:48:19 volumio volumio[1109]: info: Listing playlists Nov 03 18:48:19 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 03 18:48:19 volumio volumio[1109]: info: Received Get System Info Nov 03 18:48:19 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 18:48:19 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 18:48:19 volumio volumio[1109]: info: Discovery: Getting this device information Nov 03 18:48:19 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:19 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:19 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 18:48:19 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:19 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:19 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 03 18:48:19 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 03 18:48:20 volumio sudo[1817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=huangcf,password=Aa80041,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.100.111/vol1/1000/movie /mnt/NAS/nas Nov 03 18:48:20 volumio sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:20 volumio volumio[1788]: ...................................................................................................................................++++ Nov 03 18:48:20 volumio volumio[1788]: e is 65537 (0x010001) Nov 03 18:48:20 volumio kernel: CIFS: Attempting to mount //192.168.100.111/vol1/1000/movie Nov 03 18:48:20 volumio volumio[1788]: writing RSA key Nov 03 18:48:20 volumio sudo[1817]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:20 volumio volumio[1109]: info: Cannot mount NAS nas at system boot, trial number 3 ,retrying in 5 seconds Nov 03 18:48:20 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Nov 03 18:48:20 volumio kernel: CIFS: VFS: \\192.168.100.111 Send error in SessSetup = -13 Nov 03 18:48:20 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 03 18:48:20 volumio volumio[1109]: info: Adding plugin bluetooth to MyMusic Plugins Nov 03 18:48:20 volumio volumio[1109]: info: Adding plugin multiroom to MyMusic Plugins Nov 03 18:48:20 volumio volumio[1109]: info: Adding plugin metavolumio to MyMusic Plugins Nov 03 18:48:20 volumio volumio[1109]: info: Adding plugin cd_controller to MyMusic Plugins Nov 03 18:48:20 volumio volumio[1109]: info: Adding plugin qobuzconnect to MyMusic Plugins Nov 03 18:48:20 volumio volumio[1109]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 03 18:48:20 volumio volumio[1109]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 03 18:48:20 volumio volumio[1109]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 03 18:48:21 volumio volumio[1109]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 03 18:48:21 volumio volumio[1109]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 03 18:48:21 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:21 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:21 volumio volumio[1109]: info: Starting MyVolumio Remote Streaming Endpoints Nov 03 18:48:21 volumio volumio[1109]: info: MyVolumio not started Nov 03 18:48:21 volumio volumio[1109]: info: Initializing device activation check Nov 03 18:48:21 volumio volumio[1109]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 03 18:48:21 volumio volumio[1109]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 03 18:48:21 volumio systemd[1]: systemd-fsckd.service: Succeeded. Nov 03 18:48:21 volumio volumio[1109]: info: Starting Streaming Service Transparent Proxy Nov 03 18:48:21 volumio volumio[1109]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 03 18:48:21 volumio volumio[1109]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 03 18:48:21 volumio volumio[1109]: info: Streaming services startup Nov 03 18:48:21 volumio volumio[1109]: info: Starting Streaming Daemon Nov 03 18:48:21 volumio sudo[1844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 03 18:48:21 volumio sudo[1844]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:21 volumio sudo[1844]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:21 volumio volumio[1109]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 03 18:48:21 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 03 18:48:21 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 03 18:48:21 volumio volumio[1109]: info: CURURI: music-library Nov 03 18:48:21 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 03 18:48:21 volumio volumio[1109]: info: Received Get System Info Nov 03 18:48:21 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 18:48:21 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 18:48:21 volumio volumio[1109]: info: Discovery: Getting this device information Nov 03 18:48:21 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:21 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:21 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 18:48:21 volumio volumio[1109]: error: Cannot start Volumio Streaming Daemon Nov 03 18:48:21 volumio volumio[1109]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 03 18:48:21 volumio volumio[1109]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 03 18:48:22 volumio volumio[1109]: error: Failed LSINFO: null Nov 03 18:48:22 volumio volumio[1109]: info: Preload queue cleared Nov 03 18:48:22 volumio volumio[1109]: STREAMING PROXY: Starting server on port 3245 Nov 03 18:48:22 volumio volumio[1109]: Node JS runtime: 14 Nov 03 18:48:22 volumio sh[631]: timed out Nov 03 18:48:22 volumio dhcpcd[678]: timed out Nov 03 18:48:22 volumio sh[631]: dhcpcd exited Nov 03 18:48:22 volumio dhcpcd[678]: dhcpcd exited Nov 03 18:48:22 volumio sh[631]: ifup: failed to bring up eth0 Nov 03 18:48:22 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Nov 03 18:48:22 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Nov 03 18:48:22 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Nov 03 18:48:23 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 03 18:48:25 volumio sudo[1854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=huangcf,password=Aa80041,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.100.111/vol1/1000/movie /mnt/NAS/nas Nov 03 18:48:25 volumio sudo[1854]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:25 volumio kernel: CIFS: Attempting to mount //192.168.100.111/vol1/1000/movie Nov 03 18:48:25 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Nov 03 18:48:25 volumio kernel: CIFS: VFS: \\192.168.100.111 Send error in SessSetup = -13 Nov 03 18:48:25 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Nov 03 18:48:25 volumio sudo[1854]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:25 volumio volumio[1109]: info: Cannot mount NAS nas at system boot, trial number 4 ,retrying in 5 seconds Nov 03 18:48:26 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 03 18:48:26 volumio volumio[1109]: info: CURURI: music-library Nov 03 18:48:26 volumio volumio[1109]: error: Failed LSINFO: null Nov 03 18:48:26 volumio volumio[1109]: info: Preload queue cleared Nov 03 18:48:29 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 03 18:48:30 volumio sudo[1874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=huangcf,password=Aa80041,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.100.111/vol1/1000/movie /mnt/NAS/nas Nov 03 18:48:30 volumio sudo[1874]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:30 volumio kernel: CIFS: Attempting to mount //192.168.100.111/vol1/1000/movie Nov 03 18:48:30 volumio sudo[1874]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:30 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Nov 03 18:48:30 volumio kernel: CIFS: VFS: \\192.168.100.111 Send error in SessSetup = -13 Nov 03 18:48:30 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Nov 03 18:48:30 volumio volumio[1109]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 03 18:48:33 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 03 18:48:33 volumio volumio[1109]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 03 18:48:33 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 03 18:48:33 volumio volumio[1109]: info: Received Get System Version Nov 03 18:48:33 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 18:48:33 volumio volumio[1109]: info: Received Get System Info Nov 03 18:48:33 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 18:48:33 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 18:48:33 volumio volumio[1109]: info: Discovery: Getting this device information Nov 03 18:48:33 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:33 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:33 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 03 18:48:36 volumio volumio[1109]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:36 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 18:48:36 volumio volumio[1109]: info: Retrieving Cloud Streaming UI Nov 03 18:48:36 volumio volumio[1109]: info: Getting Tidal Cloud Configuration Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 18:48:36 volumio volumio[1109]: info: Getting Qobuz Cloud Configuration Nov 03 18:48:36 volumio volumio[1109]: info: Asking plugin for UI Config Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 18:48:36 volumio volumio[1109]: info: Getting Spotify Cloud Configuration Nov 03 18:48:36 volumio volumio[1109]: info: Asking plugin for UI Config Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 18:48:36 volumio volumio[1109]: info: Saving Spotify Acccount Nov 03 18:48:36 volumio volumio[1109]: info: Got Tidal Cloud Configuration Nov 03 18:48:36 volumio volumio[1109]: info: Got it Nov 03 18:48:36 volumio volumio[1109]: info: Got it Nov 03 18:48:36 volumio volumio[1109]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 18:48:36 volumio volumio[1109]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::volumioGetBrowseSources Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::volumioGetBrowseSources Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::volumioGetBrowseSources Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 03 18:48:36 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 03 18:48:37 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 18:48:37 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 18:48:37 volumio volumio[1109]: info: Discovery: Getting this device information Nov 03 18:48:37 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:37 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:37 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 18:48:38 volumio systemd[1]: systemd-timedated.service: Succeeded. Nov 03 18:48:39 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Nov 03 18:48:39 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Nov 03 18:48:40 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 03 18:48:40 volumio volumio[1109]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 03 18:48:40 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 03 18:48:40 volumio volumio[1109]: info: Received Get System Version Nov 03 18:48:40 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 18:48:40 volumio volumio[1109]: info: Received Get System Info Nov 03 18:48:40 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 18:48:40 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 18:48:40 volumio volumio[1109]: info: Discovery: Getting this device information Nov 03 18:48:40 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:40 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:40 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 18:48:40 volumio volumio[1109]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L fnOS-LF-2.local. -m SMB3_11 Nov 03 18:48:40 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 03 18:48:40 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 18:48:40 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:40 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 03 18:48:41 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 03 18:48:41 volumio volumio-remote-updater[724]: No test mode Nov 03 18:48:41 volumio volumio-remote-updater[724]: No alpha test mode Nov 03 18:48:41 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:41 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:41 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 03 18:48:41 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 03 18:48:41 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 03 18:48:41 volumio volumio[1109]: info: CoreCommandRouter::volumioGetBrowseSources Nov 03 18:48:41 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 18:48:41 volumio volumio[1109]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 03 18:48:41 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 03 18:48:41 volumio volumio[1109]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 03 18:48:41 volumio volumio[1109]: info: Completed starting MyVolumio Plugin Nov 03 18:48:41 volumio volumio[1109]: [Metrics] CommandRouter: 34s 898.65ms Nov 03 18:48:41 volumio volumio[1109]: info: CoreCommandRouter::volumiosetStartupVolume Nov 03 18:48:41 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:41 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:41 volumio volumio[1109]: info: CoreCommandRouter::Close All Modals sent Nov 03 18:48:41 volumio volumio[1109]: info: CoreCommandRouter::Close All Modals sent Nov 03 18:48:42 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 03 18:48:42 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 03 18:48:42 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 03 18:48:44 volumio volumio[1109]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 18:48:44 volumio volumio[1109]: Error: dns service error: unknown Nov 03 18:48:44 volumio volumio[1109]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Nov 03 18:48:44 volumio volumio[1109]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Nov 03 18:48:44 volumio volumio[1109]: errorCode: -65537 Nov 03 18:48:44 volumio volumio[1109]: } Nov 03 18:48:44 volumio volumio[1109]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 18:48:44 volumio sudo[1924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-03 18:47 Nov 03 18:48:44 volumio sudo[1924]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:44 volumio sudo[1924]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:50 volumio volumio-remote-updater[724]: [2025-11-03 18:48:50] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 03 18:48:50 volumio volumio-remote-updater[724]: [2025-11-03 18:48:50] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 03 18:48:50 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 03 18:48:50 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 03 18:48:50 volumio systemd[1]: Started dynamicswap service. Nov 03 18:48:50 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 03 18:48:50 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 03 18:48:50 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Nov 03 18:48:50 volumio systemd[1]: Started dynamicswap service. Nov 03 18:48:50 volumio systemd[1]: Stopped Volumio Backend Module. Nov 03 18:48:50 volumio systemd[1]: Started Volumio Backend Module. Nov 03 18:48:51 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 03 18:48:51 volumio volumio[1952]: info: ------------------------------------------- Nov 03 18:48:51 volumio volumio[1952]: info: ----- Volumio3 ---- Nov 03 18:48:51 volumio volumio[1952]: info: ------------------------------------------- Nov 03 18:48:51 volumio volumio[1952]: info: ----- System startup ---- Nov 03 18:48:51 volumio volumio[1952]: info: ------------------------------------------- Nov 03 18:48:51 volumio volumio[1952]: info: MYVOLUMIO Environment detected Nov 03 18:48:51 volumio volumio[1952]: info: Plugin folders cleanup Nov 03 18:48:51 volumio volumio[1952]: info: Scanning into folder /volumio/app/plugins/ Nov 03 18:48:51 volumio volumio[1952]: info: Scanning category audio_interface Nov 03 18:48:51 volumio volumio[1952]: info: Scanning category miscellanea Nov 03 18:48:51 volumio volumio[1952]: info: Scanning category music_service Nov 03 18:48:51 volumio volumio[1952]: info: Scanning category plugins.json Nov 03 18:48:51 volumio volumio[1952]: info: Scanning category system_controller Nov 03 18:48:51 volumio volumio[1952]: info: Scanning category user_interface Nov 03 18:48:51 volumio volumio[1952]: info: Scanning into folder /data/plugins/ Nov 03 18:48:51 volumio volumio[1952]: info: Plugin folders cleanup completed Nov 03 18:48:51 volumio volumio[1952]: info: ------------------------------------------- Nov 03 18:48:51 volumio volumio[1952]: info: ----- Core plugins startup ---- Nov 03 18:48:51 volumio volumio[1952]: info: ------------------------------------------- Nov 03 18:48:51 volumio volumio[1952]: info: Loading plugins from folder /volumio/app/plugins/ Nov 03 18:48:51 volumio volumio[1952]: info: Adding plugin upnp to MyMusic Plugins Nov 03 18:48:51 volumio volumio[1952]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 03 18:48:51 volumio volumio[1952]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 03 18:48:51 volumio volumio[1952]: info: Loading plugins from folder /data/plugins/ Nov 03 18:48:51 volumio volumio[1952]: info: Loading plugin "system"... Nov 03 18:48:51 volumio volumio[1952]: info: Loading plugin "appearance"... Nov 03 18:48:51 volumio volumio[1952]: info: Loading plugin "network"... Nov 03 18:48:51 volumio volumio[1952]: info: Refreshing Cached IP Addresses Nov 03 18:48:51 volumio sudo[1978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 03 18:48:51 volumio sudo[1978]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:51 volumio sudo[1978]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:51 volumio sudo[1980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 03 18:48:51 volumio sudo[1980]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:51 volumio sudo[1980]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:51 volumio volumio[1952]: info: Loading plugin "services"... Nov 03 18:48:51 volumio volumio[1952]: info: Loading plugin "alsa_controller"... Nov 03 18:48:51 volumio sudo[1993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 03 18:48:51 volumio sudo[1993]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:51 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 18:48:51 volumio volumio[1952]: info: Loading plugin "wizard"... Nov 03 18:48:51 volumio volumio[1952]: info: Loading plugin "networkfs"... Nov 03 18:48:51 volumio volumio[1952]: info: Starting Udev Watcher for removable devices Nov 03 18:48:51 volumio volumio[1952]: info: Ignoring mount for partition: boot Nov 03 18:48:51 volumio volumio[1952]: info: Ignoring mount for partition: volumio Nov 03 18:48:51 volumio volumio[1952]: info: Ignoring mount for partition: volumio_data Nov 03 18:48:51 volumio sudo[2006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=huangcf,password=Aa80041,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.100.111/vol1/1000/movie /mnt/NAS/nas Nov 03 18:48:51 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 03 18:48:51 volumio volumio[1952]: info: Loading plugin "volumio_command_line_client"... Nov 03 18:48:51 volumio sudo[2006]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:51 volumio volumio[1952]: info: Loading plugin "upnp"... Nov 03 18:48:51 volumio volumio[1952]: info: [1762166931951] Starting Upmpd Daemon Nov 03 18:48:51 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 03 18:48:51 volumio volumio[1952]: info: Loading plugin "my_music"... Nov 03 18:48:51 volumio volumio[1952]: info: Loading plugin "mpd"... Nov 03 18:48:51 volumio kernel: CIFS: Attempting to mount //192.168.100.111/vol1/1000/movie Nov 03 18:48:52 volumio volumio[1952]: info: Loading plugin "upnp_browser"... Nov 03 18:48:52 volumio sudo[2006]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:52 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Nov 03 18:48:52 volumio kernel: CIFS: VFS: \\192.168.100.111 Send error in SessSetup = -13 Nov 03 18:48:52 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Nov 03 18:48:52 volumio volumio[1952]: info: Loading plugin "alarm-clock"... Nov 03 18:48:52 volumio volumio[1952]: info: Loading plugin "airplay_emulation"... Nov 03 18:48:52 volumio volumio[1952]: info: Starting Shairport Sync Nov 03 18:48:52 volumio volumio[1952]: info: Loading plugin "last_100"... Nov 03 18:48:52 volumio volumio[1952]: info: Loading plugin "webradio"... Nov 03 18:48:52 volumio volumio[1952]: info: Loading plugin "i2s_dacs"... Nov 03 18:48:52 volumio volumio[1952]: info: I2S DAC not set, start Auto-detection Nov 03 18:48:52 volumio volumio[1952]: info: Loading plugin "volumiodiscovery"... Nov 03 18:48:52 volumio volumio[1952]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 03 18:48:52 volumio volumio[1952]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 03 18:48:52 volumio volumio[1952]: *** WARNING *** For more information see Nov 03 18:48:52 volumio volumio[1952]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 03 18:48:52 volumio node[1952]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 03 18:48:52 volumio volumio[1952]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 03 18:48:52 volumio volumio[1952]: *** WARNING *** For more information see Nov 03 18:48:52 volumio node[1952]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 03 18:48:52 volumio node[1952]: *** WARNING *** For more information see Nov 03 18:48:52 volumio node[1952]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 03 18:48:52 volumio node[1952]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 03 18:48:52 volumio node[1952]: *** WARNING *** For more information see Nov 03 18:48:52 volumio volumio[1952]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 03 18:48:52 volumio volumio[1952]: info: Discovery: Started advertising with name: Volumio Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 03 18:48:52 volumio volumio[1952]: info: Loading plugin "outputs"... Nov 03 18:48:52 volumio volumio[1952]: info: Loading plugin "albumart"... Nov 03 18:48:52 volumio volumio[1952]: info: Plugin example_plugin is not enabled Nov 03 18:48:52 volumio volumio[1952]: info: Loading plugin "inputs"... Nov 03 18:48:52 volumio volumio[1952]: info: Loading plugin "updater_comm"... Nov 03 18:48:52 volumio volumio[1952]: info: Plugin mpdemulation is not enabled Nov 03 18:48:52 volumio volumio[1952]: info: Loading plugin "rest_api"... Nov 03 18:48:52 volumio volumio[1952]: info: Loading plugin "websocket"... Nov 03 18:48:52 volumio volumio[1952]: info: Starting Socket.io Server version 2.3.0 Nov 03 18:48:52 volumio volumio[1952]: info: Loading i18n strings for locale zh Nov 03 18:48:52 volumio volumio[1952]: Updating browse sources language Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 18:48:52 volumio volumio[1952]: Forking 1 albumart workers Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::initPlayerControls Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: Express server listening on port 3000 Nov 03 18:48:52 volumio volumio[1952]: [Metrics] WebUI: 1s 201.86ms Nov 03 18:48:52 volumio volumio[1952]: info: Setting Device type: x86 Nov 03 18:48:52 volumio volumio[1952]: info: CoreStateMachine::resetVolumioState Nov 03 18:48:52 volumio volumio[1952]: info: CoreStateMachine::getcurrentVolume Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::volumioRetrievevolume Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 03 18:48:52 volumio volumio[1952]: info: Completed loading Core Plugins Nov 03 18:48:52 volumio volumio[1952]: info: Preparing to generate the ALSA configuration file Nov 03 18:48:52 volumio volumio[1952]: info: Cannot read play queue from file Nov 03 18:48:52 volumio volumio[1952]: info: Volumio Network Manager: Network status updated: 2 Nov 03 18:48:52 volumio volumio[1952]: info: Cannot mount NAS nas at system boot, trial number 1 ,retrying in 5 seconds Nov 03 18:48:52 volumio volumio[1952]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Nov 03 18:48:52 volumio volumio[1952]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 03 18:48:52 volumio volumio[1952]: verbose: New Socket.io Connection to 192.168.100.116:3000 from 192.168.100.139 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Nov 03 18:48:52 volumio volumio[1952]: Starting albumart workers Nov 03 18:48:52 volumio volumio[1952]: info: Asound.conf file unchanged, so no further update is needed Nov 03 18:48:52 volumio volumio[1952]: info: Output device has changed, restarting MPD Nov 03 18:48:52 volumio volumio[1952]: info: Output device has changed, restarting Shairport Sync Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:52 volumio sudo[2080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 03 18:48:52 volumio sudo[2080]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:52 volumio sudo[2078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 03 18:48:52 volumio sudo[2078]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:52 volumio sudo[2078]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:52 volumio volumio[1952]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 03 18:48:52 volumio systemd[1]: Stopping Music Player Daemon... Nov 03 18:48:52 volumio volumio[1952]: info: ___________ START PLUGINS ___________ Nov 03 18:48:52 volumio volumio[1952]: info: ControllerMpd::onStart: Initializing MPD Nov 03 18:48:52 volumio volumio[1952]: info: Creating MPD Configuration file Nov 03 18:48:52 volumio systemd[1]: mpd.service: Succeeded. Nov 03 18:48:52 volumio systemd[1]: Stopped Music Player Daemon. Nov 03 18:48:52 volumio systemd[1]: Starting Music Player Daemon... Nov 03 18:48:52 volumio sudo[2088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 03 18:48:52 volumio sudo[2088]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 03 18:48:52 volumio volumio[1952]: info: [1762166932691] CoreMusicLibrary::Adding element 媒体服务器 Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 18:48:52 volumio sudo[2091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 03 18:48:52 volumio sudo[2088]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:52 volumio sudo[2086]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 03 18:48:52 volumio sudo[2086]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:52 volumio sudo[2091]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:52 volumio sudo[2086]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:52 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 03 18:48:52 volumio systemd[1]: mpd.service: Succeeded. Nov 03 18:48:52 volumio systemd[1]: Stopped Music Player Daemon. Nov 03 18:48:52 volumio systemd[1]: Starting Music Player Daemon... Nov 03 18:48:52 volumio volumio[1952]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 03 18:48:52 volumio volumio[1952]: info: [1762166932783] CoreMusicLibrary::Adding element Last_100 Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 03 18:48:52 volumio volumio[1952]: info: [1762166932784] CoreMusicLibrary::Adding element Webradio Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 18:48:52 volumio volumio[1952]: info: Initializing BBC Radios Nov 03 18:48:52 volumio sudo[2097]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 03 18:48:52 volumio sudo[2097]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:52 volumio sudo[2097]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:52 volumio volumio[1952]: info: Volumio Calling Home Nov 03 18:48:52 volumio volumio[1952]: info: CoreStateMachine::setRepeat null single undefined Nov 03 18:48:52 volumio volumio[1952]: info: CoreStateMachine::pushState Nov 03 18:48:52 volumio volumio[1952]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::volumioPushState Nov 03 18:48:52 volumio volumio[1952]: info: CoreStateMachine::setRandom null Nov 03 18:48:52 volumio volumio[1952]: info: CoreStateMachine::pushState Nov 03 18:48:52 volumio volumio[1952]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:52 volumio volumio[1952]: info: CoreCommandRouter::volumioPushState Nov 03 18:48:53 volumio volumio[1952]: info: VolumeController:: Volume=41 Mute =false Nov 03 18:48:53 volumio volumio[1952]: info: CoreStateMachine::pushState Nov 03 18:48:53 volumio volumio[1952]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::volumioPushState Nov 03 18:48:53 volumio volumio[1952]: info: CoreStateMachine::updateTrackBlock Nov 03 18:48:53 volumio volumio[1952]: info: CorePlayQueue::getTrackBlock Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::volumioRetrievevolume Nov 03 18:48:53 volumio volumio[1952]: info: MPD Permissions set Nov 03 18:48:53 volumio volumio[1952]: info: MPD Permissions set Nov 03 18:48:53 volumio volumio[1952]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 18:48:53 volumio volumio[1952]: verbose: New Socket.io Connection to 192.168.100.116 from 192.168.100.139 UA: Mozilla/5.0 (Linux; Android 16; 25113PN0EC Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 03 18:48:53 volumio volumio[1952]: info: VolumeController:: Volume=41 Mute =false Nov 03 18:48:53 volumio volumio[1952]: info: CoreStateMachine::pushState Nov 03 18:48:53 volumio volumio[1952]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::volumioPushState Nov 03 18:48:53 volumio volumio[1952]: info: Starting Shairport Sync Nov 03 18:48:53 volumio volumio[1952]: info: Starting Shairport Sync Nov 03 18:48:53 volumio sudo[2141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 03 18:48:53 volumio volumio[1952]: info: Starting Shairport Sync Nov 03 18:48:53 volumio sudo[2141]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:53 volumio volumio[1952]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 03 18:48:53 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 03 18:48:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 03 18:48:53 volumio sudo[2145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 03 18:48:53 volumio sudo[2145]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 03 18:48:53 volumio sudo[2141]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 03 18:48:53 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 03 18:48:53 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 03 18:48:53 volumio volumio[1952]: info: Shairport-Sync Started Nov 03 18:48:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 03 18:48:53 volumio volumio[1952]: Error adding Membership: Error: addMembership EINVAL Nov 03 18:48:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 03 18:48:53 volumio sudo[2146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 03 18:48:53 volumio sudo[2146]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:53 volumio sudo[2145]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 03 18:48:53 volumio volumio[1952]: info: Discovery: adding db905a27-3a29-4611-8301-46bb0d76efd1 Nov 03 18:48:53 volumio volumio[1952]: info: Discovery: Found device Volumio Nov 03 18:48:53 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:53 volumio volumio[1952]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:53 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 03 18:48:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 03 18:48:53 volumio volumio[1952]: info: Shairport-Sync Started Nov 03 18:48:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 03 18:48:53 volumio sudo[2146]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:53 volumio volumio[1952]: info: Shairport-Sync Started Nov 03 18:48:53 volumio mpd[2105]: Nov 03 18:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 03 18:48:53 volumio systemd[1]: Started Music Player Daemon. Nov 03 18:48:53 volumio sudo[2091]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:53 volumio sudo[2080]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:53 volumio volumio[1952]: info: Completed starting Core Plugins Nov 03 18:48:53 volumio volumio[1952]: info: ------------------------------------------- Nov 03 18:48:53 volumio volumio[1952]: info: ----- MyVolumio plugins startup ---- Nov 03 18:48:53 volumio volumio[1952]: info: ------------------------------------------- Nov 03 18:48:53 volumio volumio[1952]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 03 18:48:53 volumio volumio[1952]: error: MPD error: The expression evaluated to a falsy value: Nov 03 18:48:53 volumio volumio[1952]: assert.ok(self.idling) Nov 03 18:48:53 volumio volumio[1952]: error: The expression evaluated to a falsy value: Nov 03 18:48:53 volumio volumio[1952]: assert.ok(self.idling) Nov 03 18:48:53 volumio volumio[1952]: info: MPD running with PID2105 Nov 03 18:48:53 volumio volumio[1952]: ,establishing connection Nov 03 18:48:53 volumio volumio[1952]: error: updateQueue error: null Nov 03 18:48:53 volumio volumio[1952]: error: updateQueue error: null Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::volumioGetVisibleSources Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:53 volumio volumio[1952]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::volumioGetQueue Nov 03 18:48:53 volumio volumio[1952]: info: CoreStateMachine::getQueue Nov 03 18:48:53 volumio volumio[1952]: info: CorePlayQueue::getQueue Nov 03 18:48:53 volumio volumio[1952]: info: Listing playlists Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 03 18:48:53 volumio volumio[1952]: info: Received Get System Info Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 18:48:53 volumio volumio[1952]: info: Discovery: Getting this device information Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:53 volumio volumio[1952]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:53 volumio volumio[1952]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 18:48:53 volumio volumio[1952]: info: Discovery: Getting this device information Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:53 volumio volumio[1952]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 18:48:53 volumio volumio[1952]: verbose: New Socket.io Connection to 192.168.100.116:3000 from 192.168.100.139 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:53 volumio volumio[1952]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 03 18:48:53 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 03 18:48:53 volumio volumio[1952]: info: Volumio called home Nov 03 18:48:54 volumio volumio[1952]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Nov 03 18:48:54 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 03 18:48:54 volumio volumio[1952]: info: CoreCommandRouter::volumioGetVisibleSources Nov 03 18:48:54 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 18:48:54 volumio volumio[1952]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:54 volumio volumio[1952]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:54 volumio volumio[1952]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:54 volumio volumio[1952]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:54 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 03 18:48:54 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 03 18:48:54 volumio volumio[1952]: info: Received Get System Info Nov 03 18:48:54 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 18:48:54 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 18:48:54 volumio volumio[1952]: info: Discovery: Getting this device information Nov 03 18:48:54 volumio volumio[1952]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:54 volumio volumio[1952]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:54 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 18:48:54 volumio volumio[1952]: info: CoreCommandRouter::volumioGetState Nov 03 18:48:54 volumio volumio[1952]: info: CorePlayQueue::getTrack 0 Nov 03 18:48:54 volumio volumio[1952]: info: Listing playlists Nov 03 18:48:55 volumio volumio-remote-updater[724]: [2025-11-03 18:48:55] [connect] Successful connection Nov 03 18:48:55 volumio volumio-remote-updater[724]: [2025-11-03 18:48:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1762166935 101 Nov 03 18:48:55 volumio volumio[1952]: 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: 7 Nov 03 18:48:56 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 03 18:48:56 volumio sudo[1993]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:57 volumio sudo[2163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=huangcf,password=Aa80041,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.100.111/vol1/1000/movie /mnt/NAS/nas Nov 03 18:48:57 volumio sudo[2163]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:48:57 volumio kernel: CIFS: Attempting to mount //192.168.100.111/vol1/1000/movie Nov 03 18:48:57 volumio sudo[2163]: pam_unix(sudo:session): session closed for user root Nov 03 18:48:57 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Nov 03 18:48:57 volumio kernel: CIFS: VFS: \\192.168.100.111 Send error in SessSetup = -13 Nov 03 18:48:57 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Nov 03 18:48:57 volumio volumio[1952]: info: Cannot mount NAS nas at system boot, trial number 2 ,retrying in 5 seconds Nov 03 18:48:58 volumio volumio[1952]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 03 18:49:00 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 03 18:49:00 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Nov 03 18:49:01 volumio volumio[1952]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 03 18:49:01 volumio sudo[2185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 03 18:49:01 volumio sudo[2185]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:49:01 volumio sudo[2185]: pam_unix(sudo:session): session closed for user root Nov 03 18:49:01 volumio sudo[2187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 03 18:49:01 volumio sudo[2187]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:49:01 volumio sudo[2187]: pam_unix(sudo:session): session closed for user root Nov 03 18:49:01 volumio sudo[2191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 03 18:49:01 volumio sudo[2191]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 18:49:01 volumio sudo[2191]: pam_unix(sudo:session): session closed for user root Nov 03 18:49:01 volumio volumio[1952]: info: Upmpdcli Daemon Started Nov 03 18:49:02 volumio volumio[1952]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 18:49:02 volumio volumio[1952]: TypeError: Cannot read property 'replace' of undefined Nov 03 18:49:02 volumio volumio[1952]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Nov 03 18:49:02 volumio volumio[1952]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Nov 03 18:49:02 volumio volumio[1952]: at listOnTimeout (internal/timers.js:557:17) Nov 03 18:49:02 volumio volumio[1952]: at processTimers (internal/timers.js:500:7) Nov 03 18:49:02 volumio volumio[1952]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 18:49:02 volumio sudo[2202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-03 18:48 Nov 03 18:49:02 volumio sudo[2202]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"