-- Logs begin at Sat 2026-04-04 10:15:49 CST, end at Sat 2026-04-04 10:17:38 CST. -- Apr 04 10:16:00 volumio systemd[1]: Started Permit User Sessions. Apr 04 10:16:00 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 04 10:16:00 volumio systemd[1]: Starting Hold until boot process finishes up... Apr 04 10:16:00 volumio systemd[1]: Received SIGRTMIN+21 from PID 304 (plymouthd). Apr 04 10:16:00 volumio systemd[1]: plymouth-quit.service: Succeeded. Apr 04 10:16:00 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Apr 04 10:16:00 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Apr 04 10:16:00 volumio systemd[1]: Started Hold until boot process finishes up. Apr 04 10:16:00 volumio systemd[1]: Received SIGRTMIN+21 from PID 304 (n/a). Apr 04 10:16:00 volumio systemd[1]: Started Getty on tty1. Apr 04 10:16:00 volumio systemd[1]: Reached target Login Prompts. Apr 04 10:16:00 volumio ifplugd(eth0)[851]: ifplugd 0.28 initializing. Apr 04 10:16:00 volumio ifplugd(eth0)[851]: Using interface eth0/00:80:64:A2:56:3D with driver (version: 6.6.32-volumio) Apr 04 10:16:00 volumio ifplugd(eth0)[851]: Using detection mode: SIOCETHTOOL Apr 04 10:16:00 volumio ifplugd(eth0)[851]: Initialization complete, link beat not detected. Apr 04 10:16:00 volumio ntpd[826]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 04 10:16:00 volumio ntpd[826]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 04 10:16:00 volumio systemd[1]: Started Network Time Service. Apr 04 10:16:00 volumio ntpd[852]: proto: precision = 0.267 usec (-22) Apr 04 10:16:00 volumio ntpd[852]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 04 10:16:00 volumio ifplugd[824]: Network Interface Plugging Daemon...start eth0...done. Apr 04 10:16:00 volumio ntpd[852]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 04 10:16:00 volumio systemd[1]: Started LSB: Brings up/down network automatically. Apr 04 10:16:00 volumio ntpd[852]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1194 days ago Apr 04 10:16:00 volumio ntpd[852]: Listen and drop on 0 v6wildcard [::]:123 Apr 04 10:16:00 volumio ntpd[852]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 04 10:16:00 volumio ntpd[852]: Listen normally on 2 lo 127.0.0.1:123 Apr 04 10:16:00 volumio ntpd[852]: Listening on routing socket on fd #19 for interface updates Apr 04 10:16:00 volumio ntpd[852]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 04 10:16:00 volumio ntpd[852]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 04 10:16:01 volumio wireless.js[566]: Cleaning previous... Apr 04 10:16:01 volumio ntpd[852]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 04 10:16:01 volumio nmbd[835]: [2026/04/04 10:16:01.738695, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 04 10:16:01 volumio nmbd[835]: started asyncdns process 889 Apr 04 10:16:01 volumio sudo[882]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 04 10:16:01 volumio sudo[882]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:01 volumio nmbd[835]: [2026/04/04 10:16:01.754949, 0] ../lib/util/become_daemon.c:149(daemon_status) Apr 04 10:16:01 volumio nmbd[835]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Apr 04 10:16:01 volumio nmbd[835]: [2026/04/04 10:16:01.755167, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Apr 04 10:16:01 volumio nmbd[835]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Apr 04 10:16:01 volumio sudo[882]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:01 volumio sudo[891]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 04 10:16:01 volumio sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:01 volumio sudo[891]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:02 volumio wireless.js[566]: Stopped aP Apr 04 10:16:02 volumio sudo[908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 04 10:16:02 volumio sudo[908]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:02 volumio sudo[908]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:02 volumio sudo[913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 04 10:16:02 volumio sudo[913]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:02 volumio sudo[913]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:02 volumio ntpd[852]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 04 10:16:02 volumio sudo[924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 04 10:16:02 volumio sudo[924]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:02 volumio sudo[924]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:02 volumio volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Apr 04 10:16:02 volumio sudo[928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 04 10:16:02 volumio sudo[928]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:02 volumio systemd[1]: apt-daily.service: Succeeded. Apr 04 10:16:02 volumio systemd[1]: Started Daily apt download activities. Apr 04 10:16:02 volumio volumio-time-update[545]: volumio-time-update-util: Date not found in response Apr 04 10:16:02 volumio volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Apr 04 10:16:02 volumio volumio-remote-updater[556]: [2026-04-04 10:16:02] [info] asio async_connect error: system:111 (Connection refused) Apr 04 10:16:02 volumio volumio-remote-updater[556]: [2026-04-04 10:16:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 04 10:16:02 volumio volumio-remote-updater[556]: [2026-04-04 10:16:02] [error] handle_connect error: Underlying Transport Error Apr 04 10:16:03 volumio systemd[1]: systemd-rfkill.service: Succeeded. Apr 04 10:16:03 volumio ntpd[852]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 04 10:16:04 volumio ntpd[852]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 04 10:16:04 volumio sudo[928]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:04 volumio wireless.js[566]: SETTING APPROPRIATE REG DOMAIN: CN Apr 04 10:16:04 volumio sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 04 10:16:04 volumio sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:04 volumio sudo[941]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:04 volumio sudo[943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Apr 04 10:16:04 volumio sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:04 volumio sudo[943]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:04 volumio wireless.js[566]: SUCCESSFULLY SET NEW REGDOMAIN: CN Apr 04 10:16:04 volumio wireless.js[566]: Start wireless flow Apr 04 10:16:04 volumio wireless.js[566]: Stopped hotspot (if there).. Apr 04 10:16:04 volumio sudo[948]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 04 10:16:04 volumio sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:04 volumio sudo[948]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:04 volumio sudo[950]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 04 10:16:04 volumio sudo[950]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:04 volumio sudo[950]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:04 volumio wireless.js[566]: DHCP IP Apr 04 10:16:04 volumio wireless.js[566]: Start ap Apr 04 10:16:04 volumio wpa_supplicant[952]: Successfully initialized wpa_supplicant Apr 04 10:16:04 volumio sudo[953]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Apr 04 10:16:04 volumio sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:04 volumio dhcpcd[954]: sending commands to master dhcpcd process Apr 04 10:16:04 volumio dhcpcd[622]: control command: /sbin/dhcpcd Apr 04 10:16:04 volumio sudo[953]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:05 volumio wireless.js[566]: trying... Apr 04 10:16:05 volumio sudo[958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 10:16:05 volumio sudo[958]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:05 volumio sudo[958]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:06 volumio wireless.js[566]: trying... Apr 04 10:16:06 volumio sudo[961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 10:16:06 volumio sudo[961]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:06 volumio sudo[961]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:06 volumio kernel: wlan0: authenticate with fe:a0:5a:5d:58:76 Apr 04 10:16:06 volumio wpa_supplicant[956]: wlan0: SME: Trying to authenticate with fe:a0:5a:5d:58:76 (SSID='OrayBox-5G-5874' freq=5320 MHz) Apr 04 10:16:06 volumio kernel: wlan0: send auth to fe:a0:5a:5d:58:76 (try 1/3) Apr 04 10:16:06 volumio wpa_supplicant[956]: wlan0: Trying to associate with fe:a0:5a:5d:58:76 (SSID='OrayBox-5G-5874' freq=5320 MHz) Apr 04 10:16:06 volumio kernel: wlan0: authenticated Apr 04 10:16:06 volumio kernel: wlan0: associate with fe:a0:5a:5d:58:76 (try 1/3) Apr 04 10:16:06 volumio kernel: wlan0: RX AssocResp from fe:a0:5a:5d:58:76 (capab=0x931 status=0 aid=3) Apr 04 10:16:06 volumio kernel: wlan0: associated Apr 04 10:16:06 volumio kernel: ath: EEPROM regdomain: 0x809c Apr 04 10:16:06 volumio kernel: ath: EEPROM indicates we should expect a country code Apr 04 10:16:06 volumio kernel: ath: doing EEPROM country->regdmn map search Apr 04 10:16:06 volumio kernel: ath: country maps to regdmn code: 0x52 Apr 04 10:16:06 volumio kernel: ath: Country alpha2 being used: CN Apr 04 10:16:06 volumio kernel: ath: Regpair used: 0x52 Apr 04 10:16:06 volumio kernel: ath: regdomain 0x809c dynamically updated by country element Apr 04 10:16:06 volumio wpa_supplicant[956]: wlan0: Associated with fe:a0:5a:5d:58:76 Apr 04 10:16:06 volumio wpa_supplicant[956]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 04 10:16:06 volumio wpa_supplicant[956]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Apr 04 10:16:06 volumio kernel: cryptd: max_cpu_qlen set to 1000 Apr 04 10:16:07 volumio wpa_supplicant[956]: wlan0: WPA: Key negotiation completed with fe:a0:5a:5d:58:76 [PTK=CCMP GTK=CCMP] Apr 04 10:16:07 volumio wpa_supplicant[956]: wlan0: CTRL-EVENT-CONNECTED - Connection to fe:a0:5a:5d:58:76 completed [id=0 id_str=] Apr 04 10:16:07 volumio dhcpcd[622]: wlan0: carrier acquired Apr 04 10:16:07 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by fe:a0:5a:5d:58:76 Apr 04 10:16:07 volumio dhcpcd[622]: wlan0: IAID 8e:3a:44:89 Apr 04 10:16:07 volumio volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Apr 04 10:16:07 volumio volumio-time-update[545]: volumio-time-update-util: Date not found in response Apr 04 10:16:07 volumio volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Apr 04 10:16:07 volumio dhcpcd[622]: wlan0: rebinding lease of 192.168.11.129 Apr 04 10:16:07 volumio dhcpcd[622]: wlan0: NAK: wrong network from 192.168.100.1 Apr 04 10:16:07 volumio dhcpcd[622]: wlan0: message: wrong network Apr 04 10:16:07 volumio wireless.js[566]: trying... Apr 04 10:16:07 volumio dhcpcd[622]: wlan0: soliciting a DHCP lease Apr 04 10:16:07 volumio sudo[996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 10:16:07 volumio sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:07 volumio sudo[996]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:07 volumio dhcpcd[622]: wlan0: soliciting an IPv6 router Apr 04 10:16:08 volumio wireless.js[566]: trying... Apr 04 10:16:08 volumio sudo[999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 10:16:08 volumio sudo[999]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:08 volumio sudo[999]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:09 volumio wireless.js[566]: trying... Apr 04 10:16:09 volumio sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 10:16:09 volumio sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:09 volumio sudo[1002]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:10 volumio volumio-remote-updater[556]: [2026-04-04 10:16:10] [info] asio async_connect error: system:111 (Connection refused) Apr 04 10:16:10 volumio volumio-remote-updater[556]: [2026-04-04 10:16:10] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 04 10:16:10 volumio volumio-remote-updater[556]: [2026-04-04 10:16:10] [error] handle_connect error: Underlying Transport Error Apr 04 10:16:10 volumio wireless.js[566]: trying... Apr 04 10:16:10 volumio dhcpcd[622]: wlan0: offered 192.168.100.129 from 192.168.100.1 Apr 04 10:16:10 volumio dhcpcd[622]: wlan0: probing address 192.168.100.129/24 Apr 04 10:16:10 volumio sudo[1005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 10:16:10 volumio sudo[1005]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:10 volumio sudo[1005]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:11 volumio wireless.js[566]: trying... Apr 04 10:16:11 volumio sudo[1008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 10:16:11 volumio sudo[1008]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:11 volumio sudo[1008]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:12 volumio volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Apr 04 10:16:12 volumio volumio-time-update[545]: volumio-time-update-util: Date not found in response Apr 04 10:16:12 volumio volumio-time-update[545]: volumio-time-update-util: Retrying in 5 seconds... Apr 04 10:16:12 volumio wireless.js[566]: trying... Apr 04 10:16:12 volumio sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 10:16:12 volumio sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:12 volumio sudo[1016]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:13 volumio wireless.js[566]: trying... Apr 04 10:16:13 volumio sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 10:16:13 volumio sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:13 volumio sudo[1019]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:14 volumio wireless.js[566]: trying... Apr 04 10:16:14 volumio sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 10:16:14 volumio sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:14 volumio sudo[1022]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:15 volumio wireless.js[566]: trying... Apr 04 10:16:15 volumio sudo[1025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 10:16:15 volumio sudo[1025]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:15 volumio sudo[1025]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:15 volumio dhcpcd[622]: wlan0: leased 192.168.100.129 for 43200 seconds Apr 04 10:16:15 volumio avahi-daemon[550]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.129. Apr 04 10:16:15 volumio avahi-daemon[550]: New relevant interface wlan0.IPv4 for mDNS. Apr 04 10:16:15 volumio avahi-daemon[550]: Registering new address record for 192.168.100.129 on wlan0.IPv4. Apr 04 10:16:15 volumio dhcpcd[622]: wlan0: adding route to 192.168.100.0/24 Apr 04 10:16:15 volumio dhcpcd[622]: wlan0: adding default route via 192.168.100.1 Apr 04 10:16:16 volumio ntpd[852]: ntpd exiting on signal 15 (Terminated) Apr 04 10:16:16 volumio systemd[1]: Stopping Network Time Service... Apr 04 10:16:16 volumio systemd[1]: ntp.service: Succeeded. Apr 04 10:16:16 volumio systemd[1]: Stopped Network Time Service. Apr 04 10:16:16 volumio systemd[1]: Starting Network Time Service... Apr 04 10:16:16 volumio ntpd[1061]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 04 10:16:16 volumio ntpd[1061]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 04 10:16:16 volumio systemd[1]: Started Network Time Service. Apr 04 10:16:16 volumio ntpd[1067]: proto: precision = 0.267 usec (-22) Apr 04 10:16:16 volumio ntpd[1067]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 04 10:16:16 volumio ntpd[1067]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 04 10:16:16 volumio ntpd[1067]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1194 days ago Apr 04 10:16:16 volumio ntpd[1067]: Listen and drop on 0 v6wildcard [::]:123 Apr 04 10:16:16 volumio ntpd[1067]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 04 10:16:16 volumio ntpd[1067]: Listen normally on 2 lo 127.0.0.1:123 Apr 04 10:16:16 volumio ntpd[1067]: Listen normally on 3 wlan0 192.168.100.129:123 Apr 04 10:16:16 volumio ntpd[1067]: Listening on routing socket on fd #20 for interface updates Apr 04 10:16:16 volumio ntpd[1067]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 04 10:16:16 volumio ntpd[1067]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 04 10:16:16 volumio wireless.js[566]: trying... Apr 04 10:16:16 volumio sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 10:16:16 volumio sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:16 volumio sudo[1070]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:16 volumio wireless.js[566]: Connected to: ----OrayBox-5G-5874 Apr 04 10:16:16 volumio wireless.js[566]: ---- Apr 04 10:16:16 volumio nmbd[835]: [2026/04/04 10:16:16.763842, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 04 10:16:16 volumio nmbd[835]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 04 10:16:16 volumio systemd[1]: Started Samba NMB Daemon. Apr 04 10:16:16 volumio sudo[1073]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 04 10:16:16 volumio sudo[1073]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:16 volumio systemd[1]: Starting Samba Winbind Daemon... Apr 04 10:16:16 volumio sudo[1073]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:16 volumio nmbd[835]: [2026/04/04 10:16:16.816374, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 04 10:16:16 volumio nmbd[835]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.100.129 for name WORKGROUP<1d>. Apr 04 10:16:16 volumio nmbd[835]: This response was from IP 192.168.100.1, reporting an IP address of 192.168.100.1. Apr 04 10:16:16 volumio wireless.js[566]: ... joined AP, wlan0 IPv4 is 192.168.100.129, ipV6 is undefined Apr 04 10:16:16 volumio wireless.js[566]: It's done! AP Apr 04 10:16:16 volumio iwconfig[1079]: Error for wireless request "Set Power Management" (8B2C) : Apr 04 10:16:16 volumio iwconfig[1079]: SET failed on device wlan0 ; Operation not supported. Apr 04 10:16:16 volumio systemd[1]: Started Wireless Services. Apr 04 10:16:16 volumio systemd[1]: Started Volumio Backend Module. Apr 04 10:16:16 volumio systemd[1]: Started Volumio Cpu Tweaker. Apr 04 10:16:17 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Apr 04 10:16:17 volumio volumio-cpu-tweak[1081]: Setting RT Priority for mpd Apr 04 10:16:17 volumio volumio-cpu-tweak[1081]: pid 35's current scheduling policy: SCHED_OTHER Apr 04 10:16:17 volumio volumio-cpu-tweak[1081]: pid 35's current scheduling priority: 0 Apr 04 10:16:17 volumio volumio-cpu-tweak[1081]: Not enough cores to set MPD affinity Apr 04 10:16:17 volumio volumio-cpu-tweak[1081]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 04 10:16:17 volumio ntpd[1067]: Soliciting pool server 17.253.84.253 Apr 04 10:16:17 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 04 10:16:17 volumio volumio-time-update[545]: volumio-time-update-util: Fetching time from Volumio... Apr 04 10:16:17 volumio winbindd[1074]: [2026/04/04 10:16:17.884384, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 04 10:16:17 volumio winbindd[1074]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 04 10:16:17 volumio systemd[1]: Started Samba Winbind Daemon. Apr 04 10:16:17 volumio winbindd[1074]: [2026/04/04 10:16:17.922515, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 04 10:16:17 volumio winbindd[1074]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 04 10:16:17 volumio systemd[1]: Starting Samba SMB Daemon... Apr 04 10:16:18 volumio ntpd[1067]: Soliciting pool server 84.16.67.12 Apr 04 10:16:19 volumio volumio-time-update[545]: volumio-time-update-util: Setting system time to: 2026-04-04 10:16:18 Apr 04 10:16:19 volumio sudo[1149]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-04-04 10:16:18 Apr 04 10:16:19 volumio sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:19 volumio dbus-daemon[547]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.5' (uid=0 pid=1153 comm="timedatectl set-time 2026-04-04 10:16:18 ") Apr 04 10:16:19 volumio ntpd[1067]: Soliciting pool server 101.35.255.34 Apr 04 10:16:19 volumio systemd[1]: Starting Time & Date Service... Apr 04 10:16:19 volumio smbd[1125]: [2026/04/04 10:16:19.765830, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 04 10:16:19 volumio smbd[1125]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 04 10:16:19 volumio systemd[1]: Started Samba SMB Daemon. Apr 04 10:16:19 volumio dbus-daemon[547]: [system] Successfully activated service 'org.freedesktop.timedate1' Apr 04 10:16:19 volumio systemd[1]: Started Time & Date Service. Apr 04 10:16:18 volumio systemd-timedated[1159]: Changed local time to Sat Apr 4 10:16:18 2026 Apr 04 10:16:18 volumio sudo[1149]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:18 volumio volumio-time-update[545]: volumio-time-update-util: System time updated successfully. Apr 04 10:16:18 volumio systemd[1]: Started Volumio Time Update Utility. Apr 04 10:16:18 volumio systemd[1]: Reached target Multi-User System. Apr 04 10:16:18 volumio systemd[1]: Reached target Graphical Interface. Apr 04 10:16:18 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 04 10:16:18 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 04 10:16:18 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 04 10:16:18 volumio systemd[1]: Startup finished in 26.692s (kernel) + 32.829s (userspace) = 59.521s. Apr 04 10:16:18 volumio ntpd[1067]: Soliciting pool server 119.28.206.193 Apr 04 10:16:19 volumio systemd[1]: soundcard-init.service: Succeeded. Apr 04 10:16:19 volumio ntpd[1067]: Soliciting pool server 95.111.202.5 Apr 04 10:16:19 volumio ntpd[1067]: Soliciting pool server 162.159.200.123 Apr 04 10:16:20 volumio volumio[1080]: info: ------------------------------------------- Apr 04 10:16:20 volumio ntpd[1067]: Soliciting pool server 118.143.17.82 Apr 04 10:16:20 volumio ntpd[1067]: Soliciting pool server 114.66.25.29 Apr 04 10:16:20 volumio volumio[1080]: info: ----- Volumio3 ---- Apr 04 10:16:20 volumio volumio[1080]: info: ------------------------------------------- Apr 04 10:16:20 volumio volumio[1080]: info: ----- System startup ---- Apr 04 10:16:20 volumio volumio[1080]: info: ------------------------------------------- Apr 04 10:16:20 volumio volumio-remote-updater[556]: [2026-04-04 10:16:20] [connect] Successful connection Apr 04 10:16:21 volumio ntpd[1067]: Soliciting pool server 162.159.200.1 Apr 04 10:16:21 volumio volumio[1080]: info: MYVOLUMIO Environment detected Apr 04 10:16:21 volumio volumio[1080]: info: Plugin folders cleanup Apr 04 10:16:21 volumio volumio[1080]: info: Scanning into folder /volumio/app/plugins/ Apr 04 10:16:21 volumio volumio[1080]: info: Scanning category audio_interface Apr 04 10:16:21 volumio volumio[1080]: info: Scanning category miscellanea Apr 04 10:16:21 volumio volumio[1080]: info: Scanning category music_service Apr 04 10:16:21 volumio volumio[1080]: info: Scanning category plugins.json Apr 04 10:16:21 volumio volumio[1080]: info: Scanning category system_controller Apr 04 10:16:21 volumio volumio[1080]: info: Scanning category user_interface Apr 04 10:16:21 volumio volumio[1080]: info: Scanning into folder /data/plugins/ Apr 04 10:16:21 volumio volumio[1080]: info: Plugin folders cleanup completed Apr 04 10:16:21 volumio volumio[1080]: info: ------------------------------------------- Apr 04 10:16:21 volumio volumio[1080]: info: ----- Core plugins startup ---- Apr 04 10:16:21 volumio volumio[1080]: info: ------------------------------------------- Apr 04 10:16:21 volumio volumio[1080]: info: Loading plugins from folder /volumio/app/plugins/ Apr 04 10:16:21 volumio volumio[1080]: info: Adding plugin upnp to MyMusic Plugins Apr 04 10:16:21 volumio volumio[1080]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 04 10:16:21 volumio volumio[1080]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 04 10:16:21 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 04 10:16:21 volumio volumio[1080]: info: Loading plugins from folder /data/plugins/ Apr 04 10:16:21 volumio volumio[1080]: info: Loading plugin "system"... Apr 04 10:16:21 volumio volumio[1080]: info: Loading plugin "appearance"... Apr 04 10:16:23 volumio volumio[1080]: info: Loading plugin "network"... Apr 04 10:16:23 volumio volumio[1080]: info: Refreshing Cached IP Addresses Apr 04 10:16:23 volumio sudo[1231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 04 10:16:23 volumio sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:23 volumio sudo[1231]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:23 volumio sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 04 10:16:23 volumio sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:23 volumio sudo[1233]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:23 volumio volumio[1080]: info: Loading plugin "services"... Apr 04 10:16:23 volumio volumio[1080]: info: Loading plugin "volumio5onboarding"... Apr 04 10:16:23 volumio sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 04 10:16:23 volumio sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:23 volumio volumio[1080]: info: Loading plugin "alsa_controller"... Apr 04 10:16:23 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 10:16:23 volumio volumio[1080]: info: Loading plugin "wizard"... Apr 04 10:16:23 volumio volumio[1080]: info: Loading plugin "networkfs"... Apr 04 10:16:24 volumio volumio[1080]: info: Starting Udev Watcher for removable devices Apr 04 10:16:24 volumio sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=admin,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.11.1/文件1 /mnt/NAS/文件1 Apr 04 10:16:24 volumio sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:24 volumio sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=admin,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.11.1/文件2 /mnt/NAS/文件2 Apr 04 10:16:24 volumio sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:24 volumio volumio[1080]: info: Ignoring mount for partition: boot Apr 04 10:16:24 volumio volumio[1080]: info: Ignoring mount for partition: volumio Apr 04 10:16:24 volumio volumio[1080]: info: Ignoring mount for partition: volumio_data Apr 04 10:16:24 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 04 10:16:24 volumio volumio[1080]: info: Loading plugin "volumio_command_line_client"... Apr 04 10:16:24 volumio volumio[1080]: info: Loading plugin "upnp"... Apr 04 10:16:24 volumio kernel: FS-Cache: Loaded Apr 04 10:16:24 volumio volumio[1080]: info: [1775268984238] Starting Upmpd Daemon Apr 04 10:16:24 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 04 10:16:24 volumio volumio[1080]: info: Loading plugin "my_music"... Apr 04 10:16:24 volumio kernel: Key type dns_resolver registered Apr 04 10:16:24 volumio volumio[1080]: info: Loading plugin "mpd"... Apr 04 10:16:24 volumio kernel: Key type cifs.spnego registered Apr 04 10:16:24 volumio kernel: Key type cifs.idmap registered Apr 04 10:16:24 volumio kernel: CIFS: Attempting to mount //192.168.11.1/文件2 Apr 04 10:16:24 volumio sh[522]: timed out Apr 04 10:16:24 volumio dhcpcd[558]: timed out Apr 04 10:16:24 volumio sh[522]: dhcpcd exited Apr 04 10:16:24 volumio dhcpcd[558]: dhcpcd exited Apr 04 10:16:24 volumio sh[522]: ifup: failed to bring up eth0 Apr 04 10:16:24 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 04 10:16:24 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 04 10:16:24 volumio volumio[1080]: info: Loading plugin "upnp_browser"... Apr 04 10:16:27 volumio ntpd[1067]: receive: Unexpected origin timestamp 0xed7af2f9.5539214c does not match aorg 0000000000.00000000 from server@118.143.17.82 xmt 0xed7af2fb.2c0c2d31 Apr 04 10:16:27 volumio volumio[1080]: info: Starting UPNP Browser Apr 04 10:16:27 volumio volumio[1080]: info: Loading plugin "alarm-clock"... Apr 04 10:16:27 volumio ntpd[1067]: receive: Unexpected origin timestamp 0xed7af2f9.553fd1cf does not match aorg 0000000000.00000000 from server@95.111.202.5 xmt 0xed7af2fb.399b1656 Apr 04 10:16:27 volumio volumio[1080]: info: Loading plugin "airplay_emulation"... Apr 04 10:16:27 volumio volumio[1080]: info: Starting Shairport Sync Apr 04 10:16:27 volumio volumio[1080]: info: Loading plugin "last_100"... Apr 04 10:16:27 volumio volumio[1080]: info: Loading plugin "webradio"... Apr 04 10:16:27 volumio volumio[1080]: info: Loading plugin "i2s_dacs"... Apr 04 10:16:27 volumio volumio[1080]: info: I2S DAC not set, start Auto-detection Apr 04 10:16:27 volumio volumio[1080]: info: Loading plugin "volumiodiscovery"... Apr 04 10:16:27 volumio volumio[1080]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 04 10:16:27 volumio volumio[1080]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 04 10:16:27 volumio volumio[1080]: *** WARNING *** For more information see Apr 04 10:16:27 volumio volumio[1080]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 04 10:16:27 volumio volumio[1080]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 04 10:16:27 volumio volumio[1080]: *** WARNING *** For more information see Apr 04 10:16:27 volumio node[1080]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 04 10:16:27 volumio node[1080]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 04 10:16:27 volumio node[1080]: *** WARNING *** For more information see Apr 04 10:16:27 volumio node[1080]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 04 10:16:27 volumio node[1080]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 04 10:16:27 volumio node[1080]: *** WARNING *** For more information see Apr 04 10:16:27 volumio volumio[1080]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 04 10:16:27 volumio volumio[1080]: info: Discovery: Started advertising with name: Volumio Apr 04 10:16:27 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 04 10:16:27 volumio volumio[1080]: info: Loading plugin "outputs"... Apr 04 10:16:27 volumio volumio[1080]: info: Loading plugin "albumart"... Apr 04 10:16:27 volumio volumio[1080]: info: Plugin example_plugin is not enabled Apr 04 10:16:27 volumio volumio[1080]: info: Loading plugin "inputs"... Apr 04 10:16:27 volumio volumio[1080]: info: Loading plugin "updater_comm"... Apr 04 10:16:28 volumio volumio[1080]: Forking 1 albumart workers Apr 04 10:16:28 volumio volumio[1080]: info: Plugin mpdemulation is not enabled Apr 04 10:16:28 volumio volumio[1080]: info: Loading plugin "rest_api"... Apr 04 10:16:28 volumio volumio[1080]: info: Loading plugin "websocket"... Apr 04 10:16:28 volumio volumio[1080]: info: Starting Socket.io Server version 2.3.0 Apr 04 10:16:28 volumio volumio[1080]: info: Loading i18n strings for locale zh Apr 04 10:16:28 volumio volumio[1080]: Updating browse sources language Apr 04 10:16:28 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 10:16:29 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 10:16:29 volumio volumio[1080]: info: CoreCommandRouter::initPlayerControls Apr 04 10:16:29 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:29 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:29 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:29 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 10:16:29 volumio volumio[1080]: Express server listening on port 3000 Apr 04 10:16:29 volumio volumio[1080]: [Metrics] WebUI: 8s 469.85ms Apr 04 10:16:29 volumio volumio[1080]: info: Setting Device type: x86 Apr 04 10:16:29 volumio volumio[1080]: info: CoreStateMachine::resetVolumioState Apr 04 10:16:29 volumio volumio[1080]: info: CoreStateMachine::getcurrentVolume Apr 04 10:16:29 volumio volumio[1080]: info: CoreCommandRouter::volumioRetrievevolume Apr 04 10:16:29 volumio volumio[1080]: info: CoreStateMachine::pushState Apr 04 10:16:29 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:16:29 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 10:16:29 volumio volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 04 10:16:29 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:16:29 volumio volumio[1080]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 04 10:16:29 volumio volumio[1080]: info: Completed loading Core Plugins Apr 04 10:16:29 volumio volumio[1080]: info: Preparing to generate the ALSA configuration file Apr 04 10:16:29 volumio volumio[1080]: info: Volumio Network Manager: Network status updated: 2 Apr 04 10:16:29 volumio volumio[1080]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 04 10:16:29 volumio volumio[1080]: Starting albumart workers Apr 04 10:16:30 volumio volumio[1080]: info: Reloading queue from file Apr 04 10:16:30 volumio volumio[1080]: info: Asound.conf file unchanged, so no further update is needed Apr 04 10:16:30 volumio volumio[1080]: info: Output device has changed, restarting MPD Apr 04 10:16:30 volumio volumio[1080]: info: Output device has changed, restarting Shairport Sync Apr 04 10:16:30 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:30 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:16:30 volumio sudo[1299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 04 10:16:30 volumio sudo[1299]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:30 volumio sudo[1299]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:30 volumio sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 04 10:16:30 volumio sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:30 volumio systemd[1]: Listening on mpd.socket. Apr 04 10:16:30 volumio systemd[1]: Starting Music Player Daemon... Apr 04 10:16:30 volumio volumio[1080]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 04 10:16:30 volumio volumio[1080]: info: ___________ START PLUGINS ___________ Apr 04 10:16:30 volumio volumio[1080]: info: ControllerMpd::onStart: Initializing MPD Apr 04 10:16:30 volumio volumio[1080]: info: Creating MPD Configuration file Apr 04 10:16:30 volumio sudo[1307]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 04 10:16:30 volumio sudo[1307]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 04 10:16:30 volumio sudo[1307]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:30 volumio sudo[1307]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:30 volumio sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Apr 04 10:16:30 volumio sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:31 volumio systemd[1]: Started Volumio5 Onboarding Server. Apr 04 10:16:31 volumio sudo[1309]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:31 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 10:16:31 volumio sudo[1314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 04 10:16:31 volumio volumio[1080]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 04 10:16:31 volumio volumio[1080]: info: [1775268991178] CoreMusicLibrary::Adding element 媒体服务器 Apr 04 10:16:31 volumio sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:31 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 10:16:31 volumio sudo[1314]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:31 volumio volumio[1080]: info: UPNP Browser: Client initialized successfully Apr 04 10:16:31 volumio sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 04 10:16:31 volumio sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:31 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:31 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:16:31 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 04 10:16:31 volumio systemd[1]: mpd.service: Succeeded. Apr 04 10:16:31 volumio systemd[1]: Stopped Music Player Daemon. Apr 04 10:16:31 volumio systemd[1]: Starting Music Player Daemon... Apr 04 10:16:31 volumio sudo[1320]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 04 10:16:31 volumio sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:31 volumio sudo[1320]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 04 10:16:31 volumio sudo[1320]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:31 volumio volumio[1080]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 04 10:16:31 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:31 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:16:32 volumio volumio5-onboarding[1316]: time=2026-04-04T10:16:32.209+08:00 level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Apr 04 10:16:32 volumio sudo[1242]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:32 volumio volumio[1080]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 04 10:16:32 volumio volumio[1080]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 04 10:16:32 volumio volumio[1080]: info: [1775268992330] CoreMusicLibrary::Adding element Last_100 Apr 04 10:16:32 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 10:16:32 volumio volumio[1080]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 04 10:16:32 volumio volumio[1080]: info: [1775268992343] CoreMusicLibrary::Adding element Webradio Apr 04 10:16:32 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 10:16:32 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 10:16:32 volumio volumio[1080]: info: Initializing BBC Radios Apr 04 10:16:32 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 10:16:32 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:16:32 volumio volumio[1080]: info: Volumio Calling Home Apr 04 10:16:33 volumio volumio[1080]: info: CoreStateMachine::setRepeat null single undefined Apr 04 10:16:33 volumio volumio[1080]: info: CoreStateMachine::pushState Apr 04 10:16:33 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 04 10:16:33 volumio volumio[1080]: info: CoreStateMachine::setRandom null Apr 04 10:16:33 volumio volumio[1080]: info: CoreStateMachine::pushState Apr 04 10:16:33 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 04 10:16:33 volumio volumio[1080]: info: MPD Permissions set Apr 04 10:16:33 volumio volumio[1080]: info: MPD Permissions set Apr 04 10:16:33 volumio volumio[1080]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Apr 04 10:16:33 volumio dbus-daemon[547]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.12' (uid=0 pid=1316 comm="/usr/bin/volumio5-onboarding ") Apr 04 10:16:33 volumio volumio[1080]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 04 10:16:33 volumio systemd[1]: Starting Bluetooth service... Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:16:33 volumio kernel: jitterentropy: Initialization failed with host not compliant with requirements: 9 Apr 04 10:16:33 volumio volumio[1080]: info: Discovery: adding 71a94768-0d31-4a68-a406-e28bf78c462f Apr 04 10:16:33 volumio volumio[1080]: info: Discovery: Found device Volumio Apr 04 10:16:33 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:16:33 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:16:33 volumio kernel: DRBG: Continuing without Jitter RNG Apr 04 10:16:34 volumio volumio[1080]: info: Volumio called home Apr 04 10:16:34 volumio volumio[1080]: info: Starting Shairport Sync Apr 04 10:16:34 volumio volumio[1080]: info: Starting Shairport Sync Apr 04 10:16:34 volumio volumio[1080]: info: Starting Shairport Sync Apr 04 10:16:34 volumio kernel: Bluetooth: Core ver 2.22 Apr 04 10:16:34 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Apr 04 10:16:34 volumio kernel: Bluetooth: HCI device and connection manager initialized Apr 04 10:16:34 volumio kernel: Bluetooth: HCI socket layer initialized Apr 04 10:16:34 volumio kernel: Bluetooth: L2CAP socket layer initialized Apr 04 10:16:34 volumio kernel: Bluetooth: SCO socket layer initialized Apr 04 10:16:34 volumio bluetoothd[1357]: Bluetooth daemon 5.83 Apr 04 10:16:34 volumio bluetoothd[1357]: src/main.c:check_options() Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Apr 04 10:16:34 volumio sudo[1409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 04 10:16:34 volumio dbus-daemon[547]: [system] Successfully activated service 'org.bluez' Apr 04 10:16:34 volumio systemd[1]: Started Bluetooth service. Apr 04 10:16:34 volumio bluetoothd[1357]: Starting SDP server Apr 04 10:16:34 volumio bluetoothd[1357]: Excluding (cli) hostname Apr 04 10:16:34 volumio bluetoothd[1357]: Excluding (cli) scanparam Apr 04 10:16:34 volumio bluetoothd[1357]: Excluding (cli) battery Apr 04 10:16:34 volumio sudo[1409]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:34 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 04 10:16:34 volumio kernel: Bluetooth: BNEP filters: protocol multicast Apr 04 10:16:34 volumio kernel: Bluetooth: BNEP socket layer initialized Apr 04 10:16:34 volumio bluetoothd[1357]: Bluetooth management interface 1.22 initialized Apr 04 10:16:34 volumio volumio5-onboarding[1316]: time=2026-04-04T10:16:34.337+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" Apr 04 10:16:34 volumio volumio5-onboarding[1316]: time=2026-04-04T10:16:34.350+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" Apr 04 10:16:34 volumio volumio5-onboarding[1316]: time=2026-04-04T10:16:34.353+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" Apr 04 10:16:34 volumio volumio5-onboarding[1316]: time=2026-04-04T10:16:34.362+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" Apr 04 10:16:34 volumio volumio5-onboarding[1316]: time=2026-04-04T10:16:34.364+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" Apr 04 10:16:34 volumio volumio5-onboarding[1316]: time=2026-04-04T10:16:34.364+08:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" Apr 04 10:16:34 volumio sudo[1411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 04 10:16:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 04 10:16:34 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 04 10:16:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 04 10:16:34 volumio sudo[1411]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 04 10:16:34 volumio sudo[1409]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:34 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:16:34 volumio sudo[1415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 04 10:16:34 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:16:34 volumio sudo[1415]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 04 10:16:34 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 04 10:16:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 04 10:16:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 04 10:16:34 volumio sudo[1411]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 04 10:16:34 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 04 10:16:34 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 04 10:16:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 04 10:16:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 04 10:16:34 volumio sudo[1415]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:34 volumio volumio[1080]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Apr 04 10:16:34 volumio volumio[1080]: info: Received Get System Info Apr 04 10:16:34 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 10:16:34 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 10:16:34 volumio volumio[1080]: info: Discovery: Getting this device information Apr 04 10:16:34 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:16:34 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:16:34 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 10:16:34 volumio volumio5-onboarding[1316]: time=2026-04-04T10:16:34.930+08:00 level=INFO msg="system info for 4e2a1095c4773b4d6b5d1816f8c74775" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=3.912 Apr 04 10:16:35 volumio volumio[1080]: info: Shairport-Sync Started Apr 04 10:16:35 volumio volumio[1080]: Error adding Membership: Error: addMembership EINVAL Apr 04 10:16:35 volumio volumio[1080]: info: Shairport-Sync Started Apr 04 10:16:35 volumio volumio[1080]: info: Received Get System Info Apr 04 10:16:35 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 10:16:35 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 10:16:35 volumio volumio[1080]: info: Discovery: Getting this device information Apr 04 10:16:35 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:16:35 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:16:35 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 10:16:35 volumio volumio[1080]: info: Shairport-Sync Started Apr 04 10:16:35 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 04 10:16:35 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 04 10:16:35 volumio volumio5-onboarding[1316]: time=2026-04-04T10:16:35.113+08:00 level=INFO msg="enabling local network discovery" Apr 04 10:16:35 volumio volumio5-onboarding[1316]: time=2026-04-04T10:16:35.345+08:00 level=INFO msg="bootstrapping state" hasInternet=true Apr 04 10:16:35 volumio volumio[1080]: info: Received Get System Info Apr 04 10:16:35 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 10:16:35 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 10:16:35 volumio volumio[1080]: info: Discovery: Getting this device information Apr 04 10:16:35 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:16:35 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:16:35 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 10:16:36 volumio sudo[1442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 04 10:16:36 volumio sudo[1442]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:36 volumio sudo[1442]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:36 volumio sudo[1444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 04 10:16:36 volumio sudo[1444]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:36 volumio sudo[1444]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:36 volumio sudo[1448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 04 10:16:36 volumio sudo[1448]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:36 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 04 10:16:36 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Apr 04 10:16:36 volumio kernel: CIFS: Attempting to mount //192.168.11.1/文件1 Apr 04 10:16:36 volumio sudo[1259]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:36 volumio volumio[1080]: info: Cannot mount NAS 文件2 at system boot, trial number 1 ,retrying in 5 seconds Apr 04 10:16:36 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 04 10:16:36 volumio sudo[1448]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:36 volumio volumio[1080]: info: Upmpdcli Daemon Started Apr 04 10:16:37 volumio mpd[1328]: Apr 04 10:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 04 10:16:37 volumio volumio-remote-updater[556]: [2026-04-04 10:16:37] [connect] Successful connection Apr 04 10:16:37 volumio volumio-remote-updater[556]: [2026-04-04 10:16:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1775268997 101 Apr 04 10:16:37 volumio volumio[1080]: 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: 3 Apr 04 10:16:37 volumio systemd[1]: Started Music Player Daemon. Apr 04 10:16:37 volumio sudo[1301]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:37 volumio sudo[1317]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:37 volumio volumio[1080]: info: Completed starting Core Plugins Apr 04 10:16:37 volumio volumio[1080]: info: ------------------------------------------- Apr 04 10:16:37 volumio volumio[1080]: info: ----- MyVolumio plugins startup ---- Apr 04 10:16:37 volumio volumio[1080]: info: ------------------------------------------- Apr 04 10:16:37 volumio volumio[1080]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 04 10:16:37 volumio volumio[1080]: error: MPD error: The expression evaluated to a falsy value: Apr 04 10:16:37 volumio volumio[1080]: assert.ok(self.idling) Apr 04 10:16:37 volumio volumio[1080]: error: The expression evaluated to a falsy value: Apr 04 10:16:37 volumio volumio[1080]: assert.ok(self.idling) Apr 04 10:16:37 volumio volumio[1080]: info: MPD running with PID1328 Apr 04 10:16:37 volumio volumio[1080]: ,establishing connection Apr 04 10:16:37 volumio volumio[1080]: error: updateQueue error: null Apr 04 10:16:37 volumio volumio[1080]: error: updateQueue error: null Apr 04 10:16:37 volumio volumio[1450]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 04 10:16:41 volumio sudo[1471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 04 10:16:41 volumio sudo[1471]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:41 volumio sudo[1471]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:41 volumio sudo[1473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 04 10:16:41 volumio sudo[1473]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:41 volumio sudo[1473]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:41 volumio sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=admin,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.11.1/文件2 /mnt/NAS/文件2 Apr 04 10:16:41 volumio sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:41 volumio volumio[1080]: verbose: New Socket.io Connection to 192.168.100.129 from 192.168.100.139 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Edg/146.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Apr 04 10:16:41 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 04 10:16:42 volumio sudo[1482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 04 10:16:42 volumio sudo[1482]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:42 volumio sudo[1482]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:42 volumio sudo[1484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 04 10:16:42 volumio sudo[1484]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:42 volumio sudo[1484]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:42 volumio volumio[1080]: verbose: New Socket.io Connection to 192.168.100.129 from 192.168.100.139 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Edg/146.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Apr 04 10:16:42 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 04 10:16:42 volumio volumio[1080]: info: CoreCommandRouter::volumioGetVisibleSources Apr 04 10:16:42 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 10:16:42 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:16:42 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:16:42 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 04 10:16:42 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 04 10:16:42 volumio volumio[1080]: info: Received Get System Info Apr 04 10:16:42 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 10:16:42 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 10:16:42 volumio volumio[1080]: info: Discovery: Getting this device information Apr 04 10:16:42 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:16:43 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:16:43 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 10:16:43 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:16:43 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:16:43 volumio volumio[1080]: info: Listing playlists Apr 04 10:16:43 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 04 10:16:43 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 04 10:16:43 volumio volumio[1080]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 04 10:16:43 volumio volumio[1450]: ...........................................................................................................................................................++++ Apr 04 10:16:45 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:16:45 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:16:45 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 10:16:45 volumio volumio[1080]: info: Received Get System Info Apr 04 10:16:45 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 10:16:45 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 10:16:45 volumio volumio[1080]: info: Discovery: Getting this device information Apr 04 10:16:45 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:16:45 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:16:45 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 10:16:45 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 04 10:16:46 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 04 10:16:46 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Apr 04 10:16:46 volumio sudo[1257]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:46 volumio kernel: CIFS: Attempting to mount //192.168.11.1/文件2 Apr 04 10:16:46 volumio volumio[1080]: info: Cannot mount NAS 文件1 at system boot, trial number 1 ,retrying in 5 seconds Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 04 10:16:47 volumio volumio[1080]: info: Adding plugin bluetooth to MyMusic Plugins Apr 04 10:16:47 volumio volumio[1080]: info: Adding plugin multiroom to MyMusic Plugins Apr 04 10:16:47 volumio volumio[1080]: info: Adding plugin metavolumio to MyMusic Plugins Apr 04 10:16:47 volumio volumio[1080]: info: Adding plugin cd_controller to MyMusic Plugins Apr 04 10:16:47 volumio volumio[1080]: info: Adding plugin qobuzconnect to MyMusic Plugins Apr 04 10:16:47 volumio volumio[1080]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 04 10:16:47 volumio volumio[1080]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 04 10:16:47 volumio volumio[1080]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 04 10:16:48 volumio volumio[1450]: ..............................................................................................................++++ Apr 04 10:16:48 volumio volumio[1450]: e is 65537 (0x010001) Apr 04 10:16:48 volumio volumio[1450]: writing RSA key Apr 04 10:16:49 volumio volumio[1080]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 04 10:16:49 volumio volumio[1080]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 04 10:16:49 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:16:49 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:16:49 volumio volumio[1080]: info: Starting MyVolumio Remote Streaming Endpoints Apr 04 10:16:49 volumio volumio[1080]: info: MyVolumio login type: Token Apr 04 10:16:49 volumio volumio[1080]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 04 10:16:49 volumio volumio[1080]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 04 10:16:49 volumio systemd[1]: systemd-timedated.service: Succeeded. Apr 04 10:16:50 volumio volumio[1080]: info: Starting Streaming Service Transparent Proxy Apr 04 10:16:50 volumio volumio[1080]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 04 10:16:50 volumio volumio[1080]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 04 10:16:50 volumio volumio[1080]: info: Streaming services startup Apr 04 10:16:50 volumio volumio[1080]: info: Starting Streaming Daemon Apr 04 10:16:51 volumio sudo[1522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 04 10:16:51 volumio sudo[1522]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:51 volumio sudo[1522]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:51 volumio volumio[1080]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 04 10:16:51 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 04 10:16:51 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 04 10:16:51 volumio volumio[1080]: info: Retrieving Cloud Streaming UI Apr 04 10:16:51 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 04 10:16:51 volumio volumio[1080]: error: Cannot start Volumio Streaming Daemon Apr 04 10:16:51 volumio volumio[1080]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 04 10:16:51 volumio volumio[1080]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 04 10:16:51 volumio volumio[1080]: info: Getting Tidal Cloud Configuration Apr 04 10:16:51 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 04 10:16:51 volumio volumio[1080]: info: Getting Qobuz Cloud Configuration Apr 04 10:16:51 volumio volumio[1080]: info: Asking plugin for UI Config Apr 04 10:16:51 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 04 10:16:51 volumio volumio[1080]: info: Getting Spotify Cloud Configuration Apr 04 10:16:51 volumio volumio[1080]: info: Asking plugin for UI Config Apr 04 10:16:51 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 04 10:16:51 volumio volumio[1080]: info: Saving Spotify Acccount Apr 04 10:16:51 volumio volumio[1080]: info: Got Tidal Cloud Configuration Apr 04 10:16:51 volumio volumio[1080]: info: Got it Apr 04 10:16:51 volumio volumio[1080]: info: Got it Apr 04 10:16:51 volumio volumio[1080]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 04 10:16:51 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 04 10:16:51 volumio volumio[1080]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 04 10:16:51 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 04 10:16:51 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 04 10:16:51 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 04 10:16:51 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 04 10:16:51 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 10:16:51 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 10:16:51 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 10:16:51 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 10:16:51 volumio volumio[1080]: info: CoreCommandRouter::volumioGetBrowseSources Apr 04 10:16:51 volumio sudo[1530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=admin,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.11.1/文件1 /mnt/NAS/文件1 Apr 04 10:16:51 volumio sudo[1530]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:51 volumio volumio[1080]: error: MyVolumio Custom Token format not valid, refreshing it Apr 04 10:16:52 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:16:52 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:16:52 volumio volumio[1080]: STREAMING PROXY: Starting server on port 3245 Apr 04 10:16:52 volumio volumio[1080]: Node JS runtime: 14 Apr 04 10:16:52 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 04 10:16:52 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 04 10:16:52 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 04 10:16:52 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 04 10:16:52 volumio volumio[1080]: info: Retrieving Cloud Streaming UI Apr 04 10:16:52 volumio volumio[1080]: info: Getting Tidal Cloud Configuration Apr 04 10:16:52 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 04 10:16:52 volumio volumio[1080]: info: Getting Qobuz Cloud Configuration Apr 04 10:16:52 volumio volumio[1080]: info: Asking plugin for UI Config Apr 04 10:16:52 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 04 10:16:52 volumio volumio[1080]: info: Getting Spotify Cloud Configuration Apr 04 10:16:52 volumio volumio[1080]: info: Asking plugin for UI Config Apr 04 10:16:52 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 04 10:16:52 volumio volumio[1080]: info: Saving Spotify Acccount Apr 04 10:16:52 volumio volumio[1080]: info: Got Tidal Cloud Configuration Apr 04 10:16:52 volumio volumio[1080]: info: Got it Apr 04 10:16:52 volumio volumio[1080]: info: Got it Apr 04 10:16:52 volumio volumio[1080]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 04 10:16:52 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 04 10:16:52 volumio volumio[1080]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 04 10:16:52 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 04 10:16:52 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 04 10:16:52 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 04 10:16:53 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 04 10:16:53 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 10:16:53 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 10:16:53 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 10:16:53 volumio volumio[1080]: info: CoreCommandRouter::volumioGetBrowseSources Apr 04 10:16:53 volumio volumio[1080]: info: MyVolumio login type: Token Apr 04 10:16:53 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 04 10:16:53 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 04 10:16:54 volumio volumio[1080]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 04 10:16:54 volumio volumio[1080]: info: MyVolumio token set successfully Apr 04 10:16:54 volumio volumio[1080]: info: MYVOLUMIO: Adding device Apr 04 10:16:54 volumio volumio[1080]: info: MYVOLUMIO: Evaluating Server Apr 04 10:16:55 volumio volumio[1080]: info: MyVolumio status changed Apr 04 10:16:55 volumio volumio[1080]: info: Streaming services startup Apr 04 10:16:55 volumio volumio[1080]: info: Starting Streaming Daemon Apr 04 10:16:55 volumio volumio[1080]: info: Removing browser output: myVolumio user plan is not superstar Apr 04 10:16:55 volumio volumio[1080]: info: Removing audio output: Apr 04 10:16:55 volumio volumio[1080]: info: Stoppping Tunnel 1 Apr 04 10:16:55 volumio sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 04 10:16:55 volumio sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:55 volumio sudo[1561]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:55 volumio sudo[1564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 04 10:16:55 volumio volumio[1080]: error: Cannot start Volumio Streaming Daemon Apr 04 10:16:55 volumio volumio[1080]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 04 10:16:55 volumio volumio[1080]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 04 10:16:55 volumio sudo[1564]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:16:55 volumio sudo[1564]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:55 volumio volumio[1080]: info: Remote SSH Stopped Apr 04 10:16:56 volumio volumio[1080]: info: Setting Geolocation for MyVolumio to us2 Apr 04 10:16:56 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:16:56 volumio sudo[1477]: pam_unix(sudo:session): session closed for user root Apr 04 10:16:56 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 04 10:16:56 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Apr 04 10:16:56 volumio kernel: CIFS: Attempting to mount //192.168.11.1/文件1 Apr 04 10:16:56 volumio volumio[1080]: info: Cannot mount NAS 文件2 at system boot, trial number 2 ,retrying in 5 seconds Apr 04 10:16:57 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 04 10:16:57 volumio volumio[1080]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 04 10:16:57 volumio volumio[1080]: info: Updating MyVolumio device info Apr 04 10:16:57 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:16:58 volumio volumio[1080]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 04 10:17:01 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 04 10:17:01 volumio volumio[1080]: info: MYVOLUMIO: Adding device Apr 04 10:17:01 volumio volumio[1080]: info: MYVOLUMIO: Evaluating Server Apr 04 10:17:02 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 04 10:17:02 volumio volumio[1080]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 04 10:17:02 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 04 10:17:02 volumio volumio[1080]: info: Received Get System Version Apr 04 10:17:02 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 10:17:02 volumio volumio[1080]: info: Received Get System Info Apr 04 10:17:02 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 10:17:02 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 10:17:02 volumio volumio[1080]: info: Discovery: Getting this device information Apr 04 10:17:02 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:17:02 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:17:02 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 10:17:02 volumio sudo[1637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=admin,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.11.1/文件2 /mnt/NAS/文件2 Apr 04 10:17:02 volumio sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:17:02 volumio volumio[1080]: info: Setting Geolocation for MyVolumio to us2 Apr 04 10:17:02 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:17:02 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 10:17:02 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:17:02 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 04 10:17:02 volumio volumio[1080]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 04 10:17:02 volumio volumio-remote-updater[556]: No test mode Apr 04 10:17:02 volumio volumio-remote-updater[556]: No alpha test mode Apr 04 10:17:02 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 04 10:17:03 volumio volumio[1080]: info: Updating MyVolumio device info Apr 04 10:17:03 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:17:03 volumio volumio[1080]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 04 10:17:03 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 04 10:17:03 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 10:17:03 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 10:17:03 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 04 10:17:04 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 04 10:17:04 volumio volumio[1080]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 04 10:17:04 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:17:04 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:17:04 volumio volumio[1080]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 04 10:17:05 volumio volumio5-onboarding[1316]: failed to bootstrap state: failed to get music providers: could not get available plugins: could not get available plugins: context deadline exceeded Apr 04 10:17:05 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 04 10:17:05 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 04 10:17:05 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 04 10:17:05 volumio systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Apr 04 10:17:05 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Apr 04 10:17:05 volumio systemd[1]: Stopped Volumio5 Onboarding Server. Apr 04 10:17:05 volumio systemd[1]: Started Volumio5 Onboarding Server. Apr 04 10:17:05 volumio volumio5-onboarding[1644]: time=2026-04-04T10:17:05.488+08:00 level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Apr 04 10:17:05 volumio volumio[1080]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 04 10:17:05 volumio volumio5-onboarding[1644]: time=2026-04-04T10:17:05.529+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" Apr 04 10:17:05 volumio volumio5-onboarding[1644]: time=2026-04-04T10:17:05.531+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" Apr 04 10:17:05 volumio volumio5-onboarding[1644]: time=2026-04-04T10:17:05.533+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" Apr 04 10:17:05 volumio volumio5-onboarding[1644]: time=2026-04-04T10:17:05.535+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" Apr 04 10:17:05 volumio volumio5-onboarding[1644]: time=2026-04-04T10:17:05.537+08:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" Apr 04 10:17:05 volumio volumio5-onboarding[1644]: time=2026-04-04T10:17:05.537+08:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" Apr 04 10:17:05 volumio volumio[1080]: info: Received Get System Info Apr 04 10:17:05 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 10:17:05 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 10:17:05 volumio volumio[1080]: info: Discovery: Getting this device information Apr 04 10:17:05 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:17:05 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:17:05 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 10:17:05 volumio volumio5-onboarding[1644]: time=2026-04-04T10:17:05.547+08:00 level=INFO msg="system info for 4e2a1095c4773b4d6b5d1816f8c74775" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=3.912 Apr 04 10:17:05 volumio volumio[1080]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 04 10:17:05 volumio volumio[1080]: info: Received Get System Info Apr 04 10:17:05 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 10:17:05 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 10:17:05 volumio volumio[1080]: info: Discovery: Getting this device information Apr 04 10:17:05 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:17:05 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:17:05 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 10:17:05 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 04 10:17:05 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 04 10:17:05 volumio volumio5-onboarding[1644]: time=2026-04-04T10:17:05.569+08:00 level=INFO msg="enabling local network discovery" Apr 04 10:17:05 volumio volumio5-onboarding[1644]: time=2026-04-04T10:17:05.773+08:00 level=INFO msg="bootstrapping state" hasInternet=true Apr 04 10:17:05 volumio volumio[1080]: info: Received Get System Info Apr 04 10:17:05 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 10:17:05 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 10:17:05 volumio volumio[1080]: info: Discovery: Getting this device information Apr 04 10:17:05 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:17:05 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:17:05 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 10:17:05 volumio volumio-remote-updater[556]: No test mode Apr 04 10:17:05 volumio volumio-remote-updater[556]: No alpha test mode Apr 04 10:17:06 volumio volumio[1080]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 04 10:17:06 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 04 10:17:06 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 10:17:06 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 10:17:06 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 04 10:17:07 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 04 10:17:07 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Apr 04 10:17:07 volumio kernel: CIFS: Attempting to mount //192.168.11.1/文件2 Apr 04 10:17:07 volumio sudo[1530]: pam_unix(sudo:session): session closed for user root Apr 04 10:17:07 volumio volumio[1080]: info: Cannot mount NAS 文件1 at system boot, trial number 2 ,retrying in 5 seconds Apr 04 10:17:07 volumio volumio5-onboarding[1644]: time=2026-04-04T10:17:07.197+08:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Apr 04 10:17:07 volumio volumio5-onboarding[1644]: time=2026-04-04T10:17:07.200+08:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Apr 04 10:17:07 volumio volumio5-onboarding[1644]: time=2026-04-04T10:17:07.200+08:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Apr 04 10:17:07 volumio volumio5-onboarding[1644]: time=2026-04-04T10:17:07.200+08:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory" Apr 04 10:17:07 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 10:17:07 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 10:17:07 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 04 10:17:08 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Apr 04 10:17:08 volumio volumio[1080]: info: Share 文件1 successfully unmounted Apr 04 10:17:08 volumio sudo[1670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=admin,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.100.1/文件1 /mnt/NAS/文件1 Apr 04 10:17:08 volumio sudo[1670]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:17:08 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 10:17:08 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 04 10:17:09 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 04 10:17:09 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:17:09 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:17:09 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 04 10:17:09 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 04 10:17:09 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 04 10:17:09 volumio volumio[1080]: info: CoreCommandRouter::volumioGetBrowseSources Apr 04 10:17:09 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 10:17:09 volumio volumio5-onboarding[1644]: time=2026-04-04T10:17:09.844+08:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=VHO44GjkU6OsARDcpAaDtvQ6Gts1 tokenExpiry=2026-04-04T11:17:09.844+08:00 Apr 04 10:17:11 volumio volumio[1080]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 04 10:17:11 volumio volumio[1080]: info: Completed starting MyVolumio Plugin Apr 04 10:17:11 volumio volumio[1080]: [Metrics] CommandRouter: 49s 43.33ms Apr 04 10:17:11 volumio volumio[1080]: info: CoreCommandRouter::volumiosetStartupVolume Apr 04 10:17:11 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:17:11 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:17:11 volumio volumio[1080]: info: CoreCommandRouter::Close All Modals sent Apr 04 10:17:11 volumio volumio[1080]: info: CoreCommandRouter::Close All Modals sent Apr 04 10:17:11 volumio kernel: usb 8-1.1: USB disconnect, device number 4 Apr 04 10:17:11 volumio volumio[1080]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Apr 04 10:17:11 volumio volumio[1080]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Apr 04 10:17:11 volumio volumio[1080]: aplay: pcm_write:2053: write error: No such device Apr 04 10:17:11 volumio volumio[1080]: info: Apr 04 10:17:11 volumio volumio[1080]: ---------------------------- USB Audio Device Detached Apr 04 10:17:11 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 04 10:17:11 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 04 10:17:11 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 04 10:17:12 volumio sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=admin,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.100.1/文件1 /mnt/NAS/文件1 Apr 04 10:17:12 volumio sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:17:12 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 04 10:17:12 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 04 10:17:12 volumio volumio5-onboarding[1644]: time=2026-04-04T10:17:12.267+08:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 04 10:17:12 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 04 10:17:12 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 04 10:17:12 volumio kernel: usb 8-1.1: new high-speed USB device number 5 using xhci_hcd Apr 04 10:17:12 volumio kernel: usb 8-1.1: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.06 Apr 04 10:17:12 volumio kernel: usb 8-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Apr 04 10:17:12 volumio kernel: usb 8-1.1: Product: E50 Apr 04 10:17:12 volumio kernel: usb 8-1.1: Manufacturer: Topping Apr 04 10:17:12 volumio kernel: usb 8-1.1: 1:3 : unsupported format bits 0x100000000 Apr 04 10:17:12 volumio kernel: hid-generic 0003:152A:8750.0003: hiddev0,hidraw0: USB HID v1.10 Device [Topping E50] on usb-0000:05:00.0-1.1/input3 Apr 04 10:17:13 volumio volumio[1080]: info: Apr 04 10:17:13 volumio volumio[1080]: ---------------------------- USB Audio Device Attached Apr 04 10:17:13 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 04 10:17:13 volumio volumio[1080]: info: CoreCommandRouter::Close All Modals sent Apr 04 10:17:13 volumio volumio[1080]: info: Preparing to save Alsa Options, stopping services first Apr 04 10:17:13 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 04 10:17:13 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:17:13 volumio volumio[1080]: info: CoreCommandRouter::volumioPause Apr 04 10:17:13 volumio volumio[1080]: info: CoreStateMachine::pause Apr 04 10:17:13 volumio volumio[1080]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"E50","alsacard":"E50"},"i2s":false} Apr 04 10:17:13 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 04 10:17:13 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 04 10:17:13 volumio volumio[1080]: info: Device 5 does not have any Mixer Control Available Apr 04 10:17:13 volumio volumio[1080]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 04 10:17:13 volumio volumio[1080]: info: Updating Volume Controller Parameters: Device: 5 Name: E50 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 04 10:17:13 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 04 10:17:13 volumio volumio[1080]: info: Disabling external Volume Control Apr 04 10:17:13 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 04 10:17:13 volumio volumio[1080]: info: CoreStateMachine::pushState Apr 04 10:17:13 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:17:13 volumio volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 04 10:17:13 volumio volumio[1080]: info: Preparing to generate the ALSA configuration file Apr 04 10:17:13 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 04 10:17:13 volumio volumio[1080]: info: Asound.conf file unchanged, so no further update is needed Apr 04 10:17:13 volumio volumio[1080]: info: Output device has changed, restarting MPD Apr 04 10:17:13 volumio sudo[1705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 04 10:17:13 volumio sudo[1705]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:17:13 volumio sudo[1705]: pam_unix(sudo:session): session closed for user root Apr 04 10:17:13 volumio volumio[1080]: info: Output device has changed, restarting Shairport Sync Apr 04 10:17:13 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:17:13 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:17:13 volumio sudo[1708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 04 10:17:13 volumio sudo[1708]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:17:13 volumio systemd[1]: Stopping Music Player Daemon... Apr 04 10:17:13 volumio volumio[1080]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 04 10:17:13 volumio systemd[1]: mpd.service: Succeeded. Apr 04 10:17:13 volumio systemd[1]: Stopped Music Player Daemon. Apr 04 10:17:13 volumio systemd[1]: Starting Music Player Daemon... Apr 04 10:17:14 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 04 10:17:14 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:17:14 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 04 10:17:14 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 10:17:14 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 10:17:14 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 10:17:14 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 04 10:17:14 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 04 10:17:14 volumio volumio[1080]: error: MPD error: Error: write EPIPE Apr 04 10:17:14 volumio volumio[1080]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Apr 04 10:17:14 volumio volumio[1080]: info: MPD Permissions set Apr 04 10:17:14 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 04 10:17:14 volumio sudo[1715]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 04 10:17:14 volumio sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:17:14 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:17:14 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:17:14 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:17:14 volumio sudo[1715]: pam_unix(sudo:session): session closed for user root Apr 04 10:17:14 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 10:17:14 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 04 10:17:14 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 10:17:14 volumio volumio[1080]: info: Starting Shairport Sync Apr 04 10:17:14 volumio sudo[1727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 04 10:17:14 volumio sudo[1727]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:17:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 04 10:17:14 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 04 10:17:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 04 10:17:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 04 10:17:14 volumio sudo[1727]: pam_unix(sudo:session): session closed for user root Apr 04 10:17:14 volumio volumio[1080]: info: Shairport-Sync Started Apr 04 10:17:15 volumio mpd[1719]: Apr 04 10:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 04 10:17:16 volumio systemd[1]: Started Music Player Daemon. Apr 04 10:17:16 volumio sudo[1708]: pam_unix(sudo:session): session closed for user root Apr 04 10:17:16 volumio volumio[1080]: error: updateQueue error: null Apr 04 10:17:17 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 04 10:17:17 volumio sudo[1637]: pam_unix(sudo:session): session closed for user root Apr 04 10:17:17 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 04 10:17:17 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Apr 04 10:17:17 volumio kernel: CIFS: Attempting to mount //192.168.100.1/文件1 Apr 04 10:17:17 volumio volumio[1080]: info: Cannot mount NAS 文件2 at system boot, trial number 3 ,retrying in 5 seconds Apr 04 10:17:17 volumio volumio[1080]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 04 10:17:17 volumio volumio[1080]: info: CoreStateMachine::getcurrentVolume Apr 04 10:17:17 volumio volumio[1080]: info: CoreCommandRouter::volumioRetrievevolume Apr 04 10:17:17 volumio volumio[1080]: info: CoreStateMachine::pushState Apr 04 10:17:17 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:17:17 volumio volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 04 10:17:17 volumio kernel: CIFS: Attempting to mount //192.168.100.1/文件1 Apr 04 10:17:17 volumio sudo[1670]: pam_unix(sudo:session): session closed for user root Apr 04 10:17:17 volumio sudo[1685]: pam_unix(sudo:session): session closed for user root Apr 04 10:17:17 volumio volumio[1080]: info: Cannot mount NAS 文件1 at system boot, trial number 3 ,retrying in 5 seconds Apr 04 10:17:17 volumio volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:17:17 volumio volumio[1080]: info: Apr 04 10:17:17 volumio volumio[1080]: ---------------------------- MPD announces state update: update Apr 04 10:17:17 volumio volumio[1080]: info: ControllerMpd::getState Apr 04 10:17:17 volumio volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:17:17 volumio volumio[1080]: info: sendMpdCommand status took 21 milliseconds Apr 04 10:17:17 volumio volumio[1080]: info: sendMpdCommand status took 16 milliseconds Apr 04 10:17:17 volumio volumio[1080]: verbose: ControllerMpd::parseState Apr 04 10:17:17 volumio volumio[1080]: info: Command Router : Notfying DB Updatetrue Apr 04 10:17:17 volumio volumio[1080]: verbose: ControllerMpd::parseState Apr 04 10:17:17 volumio volumio[1080]: info: ControllerMpd::pushState Apr 04 10:17:17 volumio volumio[1080]: info: CoreCommandRouter::servicePushState Apr 04 10:17:17 volumio volumio[1080]: info: CoreStateMachine::pushState Apr 04 10:17:17 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:17:17 volumio volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 04 10:17:17 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:17:17 volumio volumio[1080]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 04 10:17:17 volumio volumio[1080]: verbose: CURRENT POSITION 0 Apr 04 10:17:17 volumio volumio[1080]: info: CoreStateMachine::syncState stateService stop Apr 04 10:17:17 volumio volumio[1080]: info: CoreStateMachine::syncState currentStatus stop Apr 04 10:17:17 volumio volumio[1080]: info: CoreStateMachine::pushState Apr 04 10:17:17 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:17:17 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 10:17:17 volumio volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 04 10:17:17 volumio volumio[1080]: info: No code Apr 04 10:17:17 volumio volumio[1080]: info: CoreStateMachine::pushState Apr 04 10:17:17 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 04 10:17:17 volumio volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 04 10:17:17 volumio volumio[1080]: info: ------------------------------ 182ms Apr 04 10:17:17 volumio volumio[1080]: info: [1775269037764] Database update started Apr 04 10:17:18 volumio volumio[1080]: info: BOOT COMPLETED Apr 04 10:17:18 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 04 10:17:20 volumio volumio[1080]: info: CoreCommandRouter::Close All Modals sent Apr 04 10:17:21 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 04 10:17:22 volumio sudo[1770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=admin,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.11.1/文件2 /mnt/NAS/文件2 Apr 04 10:17:22 volumio sudo[1770]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:17:22 volumio kernel: CIFS: Attempting to mount //192.168.11.1/文件2 Apr 04 10:17:22 volumio sudo[1775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=admin,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.100.1/文件1 /mnt/NAS/文件1 Apr 04 10:17:22 volumio sudo[1775]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:17:25 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 04 10:17:29 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 04 10:17:32 volumio sudo[1770]: pam_unix(sudo:session): session closed for user root Apr 04 10:17:32 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 04 10:17:32 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Apr 04 10:17:32 volumio volumio[1080]: info: Cannot mount NAS 文件2 at system boot, trial number 4 ,retrying in 5 seconds Apr 04 10:17:32 volumio kernel: CIFS: Attempting to mount //192.168.100.1/文件1 Apr 04 10:17:32 volumio sudo[1775]: pam_unix(sudo:session): session closed for user root Apr 04 10:17:32 volumio volumio[1080]: info: Cannot mount NAS 文件1 at system boot, trial number 4 ,retrying in 5 seconds Apr 04 10:17:33 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 04 10:17:37 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 04 10:17:37 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Apr 04 10:17:37 volumio volumio[1080]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 04 10:17:37 volumio volumio[1080]: TypeError: Cannot read property 'replace' of undefined Apr 04 10:17:37 volumio volumio[1080]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Apr 04 10:17:37 volumio volumio[1080]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Apr 04 10:17:37 volumio volumio[1080]: at listOnTimeout (internal/timers.js:557:17) Apr 04 10:17:37 volumio volumio[1080]: at processTimers (internal/timers.js:500:7) Apr 04 10:17:37 volumio volumio[1080]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 04 10:17:38 volumio sudo[1818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-04 10:16 Apr 04 10:17:38 volumio sudo[1818]: 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="3dada8b1e619a5feb94867e0865ace17474d7bce" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="30045b259d1a704a832ca7c1460c1fdfa4b723f9" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Mar 2026 09:58:18 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="54b3518c7dd8feba4753e0ad124ce801"