-- Logs begin at Mon 2025-09-15 08:47:45 +07, end at Mon 2025-09-15 08:49:01 +07. -- Sep 15 08:48:00 volumio mpd_monitor.sh[726]: mpd: no process found Sep 15 08:48:00 volumio systemd[1]: Started Permit User Sessions. Sep 15 08:48:00 volumio systemd[1]: Starting Hold until boot process finishes up... Sep 15 08:48:00 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Sep 15 08:48:00 volumio systemd[1]: musicservicesshield.service: Succeeded. Sep 15 08:48:00 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 15 08:48:00 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 15 08:48:00 volumio ntpd[801]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 15 08:48:00 volumio ntpd[801]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 15 08:48:00 volumio ntpd[801]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 993 days ago Sep 15 08:48:00 volumio ntpd[801]: Listen and drop on 0 v6wildcard [::]:123 Sep 15 08:48:00 volumio ntpd[801]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 15 08:48:00 volumio ntpd[801]: Listen normally on 2 lo 127.0.0.1:123 Sep 15 08:48:00 volumio ntpd[801]: Listening on routing socket on fd #19 for interface updates Sep 15 08:48:00 volumio ntpd[801]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 15 08:48:00 volumio ntpd[801]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 15 08:48:00 volumio systemd[1]: Listening on mpd.socket. Sep 15 08:48:00 volumio systemd[1]: Starting Music Player Daemon... Sep 15 08:48:00 volumio systemd[1]: Received SIGRTMIN+21 from PID 331 (plymouthd). Sep 15 08:48:00 volumio ntpd[801]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 15 08:48:00 volumio systemd[1]: plymouth-quit.service: Succeeded. Sep 15 08:48:00 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Sep 15 08:48:00 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Sep 15 08:48:00 volumio systemd[1]: Started Hold until boot process finishes up. Sep 15 08:48:00 volumio systemd[1]: Received SIGRTMIN+21 from PID 331 (plymouthd). Sep 15 08:48:00 volumio kernel: RTL8211B Gigabit Ethernet r8169-0-700:00: attached PHY driver (mii_bus:phy_addr=r8169-0-700:00, irq=MAC) Sep 15 08:48:00 volumio sh[652]: wlan0=wlan0 Sep 15 08:48:00 volumio dbus-daemon[723]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 15 08:48:00 volumio systemd[1]: Started Hostname Service. Sep 15 08:48:00 volumio systemd[1]: Started Getty on tty1. Sep 15 08:48:00 volumio systemd[1]: Reached target Login Prompts. Sep 15 08:48:00 volumio sh[640]: eth0: waiting for carrier Sep 15 08:48:00 volumio sh[640]: eth0: carrier acquired Sep 15 08:48:00 volumio dhcpcd[690]: eth0: waiting for carrier Sep 15 08:48:00 volumio dhcpcd[690]: eth0: carrier acquired Sep 15 08:48:00 volumio kernel: r8169 0000:07:00.0 eth0: Link is Down Sep 15 08:48:00 volumio systemd[1]: iptables.service: Succeeded. Sep 15 08:48:00 volumio ifplugd(eth0)[920]: ifplugd 0.28 initializing. Sep 15 08:48:00 volumio ifplugd(eth0)[920]: Using interface eth0/00:21:70:C1:4F:77 with driver (version: 6.6.32-volumio) Sep 15 08:48:00 volumio ifplugd(eth0)[920]: Using detection mode: SIOCETHTOOL Sep 15 08:48:00 volumio ifplugd(eth0)[920]: Initialization complete, link beat not detected. Sep 15 08:48:00 volumio dhcpcd[733]: DUID 00:01:00:01:2c:82:22:15:00:23:4e:07:c8:9b Sep 15 08:48:00 volumio dhcpcd[733]: wlan0: IAID 4e:07:c8:9b Sep 15 08:48:00 volumio dhcpcd[733]: DUID 00:01:00:01:2c:82:22:15:00:23:4e:07:c8:9b Sep 15 08:48:00 volumio dhcpcd[733]: wlan0: IAID 4e:07:c8:9b Sep 15 08:48:00 volumio sh[640]: DUID 00:01:00:01:2c:82:22:15:00:23:4e:07:c8:9b Sep 15 08:48:00 volumio sh[640]: eth0: IAID 70:c1:4f:77 Sep 15 08:48:00 volumio dhcpcd[690]: DUID 00:01:00:01:2c:82:22:15:00:23:4e:07:c8:9b Sep 15 08:48:00 volumio dhcpcd[690]: eth0: IAID 70:c1:4f:77 Sep 15 08:48:00 volumio sh[640]: eth0: adding address fe80::1033:a339:8585:2299 Sep 15 08:48:00 volumio sh[640]: ipv6_addaddr1: Permission denied Sep 15 08:48:00 volumio dhcpcd[690]: eth0: adding address fe80::1033:a339:8585:2299 Sep 15 08:48:00 volumio dhcpcd[690]: ipv6_addaddr1: Permission denied Sep 15 08:48:00 volumio dhcpcd[733]: wlan0: adding address fe80::b03:b82f:2d18:dba9 Sep 15 08:48:00 volumio dhcpcd[733]: ipv6_addaddr1: Permission denied Sep 15 08:48:00 volumio dhcpcd[733]: eth0: waiting for carrier Sep 15 08:48:00 volumio dhcpcd[733]: wlan0: carrier lost Sep 15 08:48:00 volumio dhcpcd[733]: wlan0: adding address fe80::b03:b82f:2d18:dba9 Sep 15 08:48:00 volumio dhcpcd[733]: ipv6_addaddr1: Permission denied Sep 15 08:48:00 volumio dhcpcd[733]: eth0: waiting for carrier Sep 15 08:48:00 volumio dhcpcd[733]: wlan0: carrier lost Sep 15 08:48:00 volumio dhcpcd[733]: eth0: carrier acquired Sep 15 08:48:00 volumio dhcpcd[733]: eth0: carrier acquired Sep 15 08:48:00 volumio sh[640]: eth0: soliciting an IPv6 router Sep 15 08:48:00 volumio dhcpcd[690]: eth0: soliciting an IPv6 router Sep 15 08:48:00 volumio sudo[877]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 08:48:00 volumio sudo[877]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:00 volumio sudo[824]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 15 08:48:00 volumio sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:00 volumio ifplugd[844]: Network Interface Plugging Daemon...start eth0...done. Sep 15 08:48:00 volumio sudo[824]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:00 volumio systemd[1]: Started LSB: Brings up/down network automatically. Sep 15 08:48:00 volumio dhcpcd[733]: eth0: IAID 70:c1:4f:77 Sep 15 08:48:00 volumio dhcpcd[733]: eth0: adding address fe80::1033:a339:8585:2299 Sep 15 08:48:00 volumio dhcpcd[733]: ipv6_addaddr1: Permission denied Sep 15 08:48:00 volumio dhcpcd[733]: eth0: IAID 70:c1:4f:77 Sep 15 08:48:00 volumio dhcpcd[733]: eth0: adding address fe80::1033:a339:8585:2299 Sep 15 08:48:00 volumio dhcpcd[733]: ipv6_addaddr1: Permission denied Sep 15 08:48:00 volumio sudo[938]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 15 08:48:00 volumio sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:00 volumio sudo[877]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 15 08:48:00 volumio sudo[877]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:00 volumio loadcpufreq[840]: Loading cpufreq kernel modules...done (acpi-cpufreq). Sep 15 08:48:00 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Sep 15 08:48:00 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Sep 15 08:48:00 volumio sudo[938]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:00 volumio cpufrequtils[958]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Sep 15 08:48:00 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Sep 15 08:48:00 volumio wireless.js[729]: Stopped aP Sep 15 08:48:00 volumio dhcpcd[733]: eth0: soliciting a DHCP lease Sep 15 08:48:00 volumio dhcpcd[733]: eth0: soliciting a DHCP lease Sep 15 08:48:00 volumio dhcpcd[733]: eth0: carrier lost Sep 15 08:48:00 volumio dhcpcd[733]: eth0: carrier lost Sep 15 08:48:00 volumio sh[640]: eth0: carrier lost Sep 15 08:48:00 volumio dhcpcd[690]: eth0: carrier lost Sep 15 08:48:00 volumio sudo[971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 15 08:48:00 volumio sudo[971]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:01 volumio kernel: b43-phy0: Loading firmware version 666.2 (2011-02-23 01:15:07) Sep 15 08:48:01 volumio ntpd[801]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 15 08:48:03 volumio volumio-time-update[730]: volumio-time-update-util: Fetching time from Volumio... Sep 15 08:48:03 volumio volumio-time-update[730]: volumio-time-update-util: Date not found in response Sep 15 08:48:03 volumio volumio-time-update[730]: volumio-time-update-util: Retrying in 5 seconds... Sep 15 08:48:03 volumio mpd[944]: Sep 15 08:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 15 08:48:04 volumio systemd[1]: Started Music Player Daemon. Sep 15 08:48:04 volumio mpd_monitor.sh[726]: MPD Monitor Service: MPD restarted due to no mpc output. Sep 15 08:48:04 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Sep 15 08:48:04 volumio volumio[1005]: cset: --> shielding system active with Sep 15 08:48:04 volumio volumio[1005]: cset: "system" cpuset of CPUSPEC(1) with 52 tasks running Sep 15 08:48:04 volumio volumio[1005]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Sep 15 08:48:04 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Sep 15 08:48:08 volumio volumio-remote-updater[720]: [2025-09-15 08:48:08] [info] Error getting remote endpoint: system:9 (Bad file descriptor) Sep 15 08:48:08 volumio volumio-remote-updater[720]: [2025-09-15 08:48:08] [info] asio async_shutdown error: system:9 (Bad file descriptor) Sep 15 08:48:08 volumio volumio-remote-updater[720]: [2025-09-15 08:48:08] [error] handle_connect error: Timer Expired Sep 15 08:48:08 volumio volumio-time-update[730]: volumio-time-update-util: Fetching time from Volumio... Sep 15 08:48:08 volumio volumio-time-update[730]: volumio-time-update-util: Date not found in response Sep 15 08:48:08 volumio volumio-time-update[730]: volumio-time-update-util: Retrying in 5 seconds... Sep 15 08:48:09 volumio ntpd[801]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 15 08:48:09 volumio sudo[971]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:09 volumio ntpd[801]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 15 08:48:09 volumio sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Sep 15 08:48:09 volumio sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:09 volumio sudo[1026]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:09 volumio sudo[1033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 15 08:48:09 volumio sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:09 volumio sudo[1033]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:09 volumio sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Sep 15 08:48:09 volumio sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:10 volumio sudo[1035]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:10 volumio wireless.js[729]: Start wireless flow Sep 15 08:48:10 volumio wireless.js[729]: Stopped hotspot (if there).. Sep 15 08:48:10 volumio sudo[1040]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 15 08:48:10 volumio sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:10 volumio sudo[1040]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:10 volumio sudo[1042]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 15 08:48:10 volumio sudo[1042]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:10 volumio sudo[1042]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:10 volumio wireless.js[729]: DHCP IP Sep 15 08:48:10 volumio wireless.js[729]: Start ap Sep 15 08:48:10 volumio wpa_supplicant[1044]: Successfully initialized wpa_supplicant Sep 15 08:48:10 volumio sudo[1045]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Sep 15 08:48:10 volumio sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:10 volumio dhcpcd[1046]: sending commands to master dhcpcd process Sep 15 08:48:10 volumio dhcpcd[733]: control command: /sbin/dhcpcd Sep 15 08:48:10 volumio dhcpcd[733]: control command: /sbin/dhcpcd Sep 15 08:48:10 volumio sudo[1045]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:11 volumio kernel: b43-phy0: Loading firmware version 666.2 (2011-02-23 01:15:07) Sep 15 08:48:11 volumio wireless.js[729]: trying... Sep 15 08:48:13 volumio volumio-time-update[730]: volumio-time-update-util: Fetching time from Volumio... Sep 15 08:48:13 volumio volumio-time-update[730]: volumio-time-update-util: Date not found in response Sep 15 08:48:13 volumio volumio-time-update[730]: volumio-time-update-util: Retrying in 5 seconds... Sep 15 08:48:18 volumio volumio-time-update[730]: volumio-time-update-util: Fetching time from Volumio... Sep 15 08:48:18 volumio volumio-time-update[730]: volumio-time-update-util: Date not found in response Sep 15 08:48:18 volumio volumio-time-update[730]: volumio-time-update-util: Retrying in 5 seconds... Sep 15 08:48:20 volumio systemd[1]: systemd-fsckd.service: Succeeded. Sep 15 08:48:20 volumio volumio-remote-updater[720]: [2025-09-15 08:48:20] [info] asio async_connect error: system:111 (Connection refused) Sep 15 08:48:20 volumio volumio-remote-updater[720]: [2025-09-15 08:48:20] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 15 08:48:20 volumio volumio-remote-updater[720]: [2025-09-15 08:48:20] [error] handle_connect error: Underlying Transport Error Sep 15 08:48:20 volumio sudo[1048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 15 08:48:20 volumio sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:20 volumio sudo[1048]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:20 volumio wireless.js[729]: trying... Sep 15 08:48:20 volumio sudo[1062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 15 08:48:20 volumio sudo[1062]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:20 volumio sudo[1062]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:21 volumio wireless.js[729]: trying... Sep 15 08:48:21 volumio kernel: wlan0: authenticate with 34:ce:00:2e:9d:d2 Sep 15 08:48:21 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Sep 15 08:48:21 volumio wpa_supplicant[1064]: wlan0: SME: Trying to authenticate with 34:ce:00:2e:9d:d2 (SSID='POLICE' freq=2437 MHz) Sep 15 08:48:21 volumio sudo[1066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 15 08:48:21 volumio sudo[1066]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:21 volumio sudo[1066]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:21 volumio kernel: wlan0: send auth to 34:ce:00:2e:9d:d2 (try 1/3) Sep 15 08:48:21 volumio kernel: wlan0: authenticated Sep 15 08:48:21 volumio wpa_supplicant[1064]: wlan0: Trying to associate with 34:ce:00:2e:9d:d2 (SSID='POLICE' freq=2437 MHz) Sep 15 08:48:21 volumio kernel: wlan0: associate with 34:ce:00:2e:9d:d2 (try 1/3) Sep 15 08:48:21 volumio kernel: wlan0: RX AssocResp from 34:ce:00:2e:9d:d2 (capab=0xc11 status=0 aid=4) Sep 15 08:48:21 volumio kernel: wlan0: associated Sep 15 08:48:21 volumio wpa_supplicant[1064]: wlan0: Associated with 34:ce:00:2e:9d:d2 Sep 15 08:48:21 volumio wpa_supplicant[1064]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 15 08:48:21 volumio wpa_supplicant[1064]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Sep 15 08:48:22 volumio wpa_supplicant[1064]: wlan0: WPA: Key negotiation completed with 34:ce:00:2e:9d:d2 [PTK=CCMP GTK=TKIP] Sep 15 08:48:22 volumio dhcpcd[733]: wlan0: carrier acquired Sep 15 08:48:22 volumio wpa_supplicant[1064]: wlan0: CTRL-EVENT-CONNECTED - Connection to 34:ce:00:2e:9d:d2 completed [id=0 id_str=] Sep 15 08:48:22 volumio dhcpcd[733]: wlan0: carrier acquired Sep 15 08:48:22 volumio dhcpcd[733]: wlan0: IAID 4e:07:c8:9b Sep 15 08:48:22 volumio dhcpcd[733]: wlan0: IAID 4e:07:c8:9b Sep 15 08:48:22 volumio wireless.js[729]: trying... Sep 15 08:48:22 volumio sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 15 08:48:22 volumio sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:22 volumio sudo[1085]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:22 volumio dhcpcd[733]: wlan0: rebinding lease of 192.168.31.190 Sep 15 08:48:22 volumio dhcpcd[733]: wlan0: rebinding lease of 192.168.31.190 Sep 15 08:48:22 volumio dhcpcd[733]: wlan0: probing address 192.168.31.190/24 Sep 15 08:48:22 volumio dhcpcd[733]: wlan0: probing address 192.168.31.190/24 Sep 15 08:48:23 volumio dhcpcd[733]: wlan0: soliciting an IPv6 router Sep 15 08:48:23 volumio dhcpcd[733]: wlan0: soliciting an IPv6 router Sep 15 08:48:23 volumio wireless.js[729]: trying... Sep 15 08:48:23 volumio sudo[1088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 15 08:48:23 volumio sudo[1088]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:23 volumio sudo[1088]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:23 volumio volumio-time-update[730]: volumio-time-update-util: Fetching time from Volumio... Sep 15 08:48:23 volumio volumio-time-update[730]: volumio-time-update-util: Date not found in response Sep 15 08:48:23 volumio volumio-time-update[730]: volumio-time-update-util: Retrying in 5 seconds... Sep 15 08:48:24 volumio wireless.js[729]: trying... Sep 15 08:48:24 volumio sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 15 08:48:24 volumio sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:24 volumio sudo[1099]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:25 volumio wireless.js[729]: trying... Sep 15 08:48:25 volumio sudo[1102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 15 08:48:25 volumio sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:25 volumio sudo[1102]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:26 volumio wireless.js[729]: trying... Sep 15 08:48:26 volumio sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 15 08:48:26 volumio sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:26 volumio sudo[1105]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:27 volumio wireless.js[729]: trying... Sep 15 08:48:27 volumio sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 15 08:48:27 volumio sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:27 volumio sudo[1108]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:27 volumio dhcpcd[733]: wlan0: leased 192.168.31.190 for 43200 seconds Sep 15 08:48:27 volumio dhcpcd[733]: wlan0: leased 192.168.31.190 for 43200 seconds Sep 15 08:48:27 volumio avahi-daemon[731]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.190. Sep 15 08:48:27 volumio dhcpcd[733]: wlan0: adding route to 192.168.31.0/24 Sep 15 08:48:27 volumio dhcpcd[733]: wlan0: adding default route via 192.168.31.1 Sep 15 08:48:27 volumio avahi-daemon[731]: New relevant interface wlan0.IPv4 for mDNS. Sep 15 08:48:27 volumio avahi-daemon[731]: Registering new address record for 192.168.31.190 on wlan0.IPv4. Sep 15 08:48:27 volumio dhcpcd[733]: wlan0: adding route to 192.168.31.0/24 Sep 15 08:48:27 volumio dhcpcd[733]: wlan0: adding default route via 192.168.31.1 Sep 15 08:48:27 volumio ntpd[801]: ntpd exiting on signal 15 (Terminated) Sep 15 08:48:27 volumio systemd[1]: Stopping Network Time Service... Sep 15 08:48:27 volumio systemd[1]: ntp.service: Succeeded. Sep 15 08:48:27 volumio systemd[1]: Stopped Network Time Service. Sep 15 08:48:28 volumio systemd[1]: Starting Network Time Service... Sep 15 08:48:28 volumio ntpd[1143]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 15 08:48:28 volumio ntpd[1143]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 15 08:48:28 volumio systemd[1]: Started Network Time Service. Sep 15 08:48:28 volumio ntpd[1149]: proto: precision = 1.606 usec (-19) Sep 15 08:48:28 volumio ntpd[1149]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 15 08:48:28 volumio dhcpcd[733]: forked to background, child pid 1150 Sep 15 08:48:28 volumio dhcpcd[733]: forked to background, child pid 1150 Sep 15 08:48:28 volumio ntpd[1149]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 15 08:48:28 volumio ntpd[1149]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 993 days ago Sep 15 08:48:28 volumio ntpd[1149]: Listen and drop on 0 v6wildcard [::]:123 Sep 15 08:48:28 volumio systemd[1]: Started DHCP Client Daemon. Sep 15 08:48:28 volumio ntpd[1149]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 15 08:48:28 volumio ntpd[1149]: Listen normally on 2 lo 127.0.0.1:123 Sep 15 08:48:28 volumio ntpd[1149]: Listen normally on 3 wlan0 192.168.31.190:123 Sep 15 08:48:28 volumio ntpd[1149]: Listening on routing socket on fd #20 for interface updates Sep 15 08:48:28 volumio ntpd[1149]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 15 08:48:28 volumio ntpd[1149]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 15 08:48:28 volumio wireless.js[729]: trying... Sep 15 08:48:28 volumio sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 15 08:48:28 volumio sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:28 volumio sudo[1153]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:28 volumio wireless.js[729]: Connected to: ----POLICE Sep 15 08:48:28 volumio wireless.js[729]: ---- Sep 15 08:48:28 volumio sudo[1156]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 08:48:28 volumio sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:28 volumio sudo[1156]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:28 volumio wireless.js[729]: ... joined AP, wlan0 IPv4 is 192.168.31.190, ipV6 is undefined Sep 15 08:48:28 volumio wireless.js[729]: It's done! AP Sep 15 08:48:28 volumio volumio-time-update[730]: volumio-time-update-util: Fetching time from Volumio... Sep 15 08:48:28 volumio iwconfig[1164]: Error for wireless request "Set Power Management" (8B2C) : Sep 15 08:48:28 volumio iwconfig[1164]: SET failed on device wlan0 ; Operation not supported. Sep 15 08:48:28 volumio systemd[1]: Started Wireless Services. Sep 15 08:48:28 volumio systemd[1]: Started Volumio Backend Module. Sep 15 08:48:28 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Sep 15 08:48:28 volumio systemd[1]: Started Start Volumio Kiosk. Sep 15 08:48:28 volumio systemd[1]: Started Volumio Cpu Tweaker. Sep 15 08:48:29 volumio ntpd[1149]: Soliciting pool server 115.165.161.155 Sep 15 08:48:29 volumio volumio-time-update[730]: volumio-time-update-util: Setting system time to: 2025-09-15 08:48:29 Sep 15 08:48:29 volumio sudo[1184]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-15 08:48:29 Sep 15 08:48:29 volumio sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:29 volumio volumio-cpu-tweak[1168]: Setting RT Priority for mpd Sep 15 08:48:29 volumio dbus-daemon[723]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.7' (uid=0 pid=1189 comm="timedatectl set-time 2025-09-15 08:48:29 ") Sep 15 08:48:29 volumio volumio-cpu-tweak[1168]: Not enough cores to set MPD affinity Sep 15 08:48:29 volumio volumio-cpu-tweak[1168]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Sep 15 08:48:29 volumio systemd[1]: Starting Time & Date Service... Sep 15 08:48:29 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 15 08:48:29 volumio dbus-daemon[723]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 15 08:48:29 volumio systemd[1]: Started Time & Date Service. Sep 15 08:48:29 volumio systemd-timedated[1211]: Changed local time to Mon Sep 15 08:48:29 2025 Sep 15 08:48:29 volumio sudo[1184]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:29 volumio volumio-time-update[730]: volumio-time-update-util: System time updated successfully. Sep 15 08:48:29 volumio systemd[1]: Started Volumio Time Update Utility. Sep 15 08:48:29 volumio startx[1167]: X.Org X Server 1.20.4 Sep 15 08:48:29 volumio startx[1167]: X Protocol Version 11, Revision 0 Sep 15 08:48:29 volumio startx[1167]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Sep 15 08:48:29 volumio startx[1167]: Current Operating System: Linux volumio 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Sep 15 08:48:29 volumio startx[1167]: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=154b3122-9867-4119-aa50-84841070a11c bootpart=UUID=2091-CBB4 datapart=UUID=58f2a288-be33-4bf3-9b18-8a1581abdffd hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no initrd=volumio.initrd Sep 15 08:48:29 volumio startx[1167]: Build Date: 18 December 2021 09:05:36AM Sep 15 08:48:29 volumio startx[1167]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Sep 15 08:48:29 volumio startx[1167]: Current version of pixman: 0.36.0 Sep 15 08:48:29 volumio startx[1167]: Before reporting problems, check http://wiki.x.org Sep 15 08:48:29 volumio startx[1167]: to make sure that you have the latest version. Sep 15 08:48:29 volumio startx[1167]: Markers: (--) probed, (**) from config file, (==) default setting, Sep 15 08:48:29 volumio startx[1167]: (++) from command line, (!!) notice, (II) informational, Sep 15 08:48:29 volumio startx[1167]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Sep 15 08:48:29 volumio startx[1167]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Sep 15 08:48:29 2025 Sep 15 08:48:29 volumio startx[1167]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Sep 15 08:48:29 volumio ntpd[1149]: Soliciting pool server 103.70.115.65 Sep 15 08:48:29 volumio sh[640]: timed out Sep 15 08:48:29 volumio sh[640]: dhcpcd exited Sep 15 08:48:29 volumio dhcpcd[690]: timed out Sep 15 08:48:29 volumio sh[640]: ifup: failed to bring up eth0 Sep 15 08:48:29 volumio dhcpcd[690]: dhcpcd exited Sep 15 08:48:29 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Sep 15 08:48:29 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Sep 15 08:48:29 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Sep 15 08:48:29 volumio systemd[1]: soundcard-init.service: Succeeded. Sep 15 08:48:30 volumio nmbd[795]: [2025/09/15 08:48:30.026980, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 15 08:48:30 volumio systemd[1]: Started Samba NMB Daemon. Sep 15 08:48:30 volumio nmbd[795]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 15 08:48:30 volumio systemd[1]: Starting Samba Winbind Daemon... Sep 15 08:48:30 volumio ntpd[1149]: Soliciting pool server 103.70.115.65 Sep 15 08:48:30 volumio volumio-remote-updater[720]: [2025-09-15 08:48:30] [info] asio async_connect error: system:111 (Connection refused) Sep 15 08:48:30 volumio volumio-remote-updater[720]: [2025-09-15 08:48:30] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 15 08:48:30 volumio volumio-remote-updater[720]: [2025-09-15 08:48:30] [error] handle_connect error: Underlying Transport Error Sep 15 08:48:30 volumio winbindd[1300]: [2025/09/15 08:48:30.741619, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 15 08:48:30 volumio winbindd[1300]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 15 08:48:30 volumio winbindd[1300]: [2025/09/15 08:48:30.860552, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 15 08:48:30 volumio winbindd[1300]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 15 08:48:30 volumio systemd[1]: Started Samba Winbind Daemon. Sep 15 08:48:30 volumio systemd[1]: Starting Samba SMB Daemon... Sep 15 08:48:31 volumio ntpd[1149]: Soliciting pool server 2401:5b60:0:2::21 Sep 15 08:48:31 volumio startx[1167]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Sep 15 08:48:32 volumio smbd[1306]: [2025/09/15 08:48:32.325552, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 15 08:48:32 volumio systemd[1]: Started Samba SMB Daemon. Sep 15 08:48:32 volumio smbd[1306]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 15 08:48:32 volumio systemd[1]: Reached target Multi-User System. Sep 15 08:48:32 volumio systemd[1]: Reached target Graphical Interface. Sep 15 08:48:32 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 15 08:48:32 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 15 08:48:32 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 15 08:48:32 volumio systemd[1]: Startup finished in 13.025s (kernel) + 49.084s (userspace) = 1min 2.110s. Sep 15 08:48:33 volumio volumio[1165]: info: ------------------------------------------- Sep 15 08:48:33 volumio volumio[1165]: info: ----- Volumio3 ---- Sep 15 08:48:33 volumio volumio[1165]: info: ------------------------------------------- Sep 15 08:48:33 volumio volumio[1165]: info: ----- System startup ---- Sep 15 08:48:33 volumio volumio[1165]: info: ------------------------------------------- Sep 15 08:48:34 volumio volumio[1165]: info: MYVOLUMIO Environment detected Sep 15 08:48:37 volumio volumio[1165]: info: Plugin folders cleanup Sep 15 08:48:37 volumio volumio[1165]: info: Scanning into folder /volumio/app/plugins/ Sep 15 08:48:37 volumio volumio[1165]: info: Scanning category audio_interface Sep 15 08:48:37 volumio volumio[1165]: info: Scanning category miscellanea Sep 15 08:48:37 volumio volumio[1165]: info: Scanning category music_service Sep 15 08:48:37 volumio volumio[1165]: info: Scanning category plugins.json Sep 15 08:48:37 volumio volumio[1165]: info: Scanning category system_controller Sep 15 08:48:37 volumio volumio[1165]: info: Scanning category user_interface Sep 15 08:48:37 volumio volumio[1165]: info: Scanning into folder /data/plugins/ Sep 15 08:48:37 volumio volumio[1165]: info: Scanning category audio_interface Sep 15 08:48:37 volumio volumio[1165]: info: Scanning category music_service Sep 15 08:48:37 volumio volumio[1165]: info: Scanning category system_controller Sep 15 08:48:37 volumio volumio[1165]: info: Scanning category system_hardware Sep 15 08:48:38 volumio volumio[1165]: info: Scanning category user_interface Sep 15 08:48:38 volumio volumio[1165]: info: Plugin folders cleanup completed Sep 15 08:48:38 volumio volumio[1165]: info: ------------------------------------------- Sep 15 08:48:38 volumio volumio[1165]: info: ----- Core plugins startup ---- Sep 15 08:48:38 volumio volumio[1165]: info: ------------------------------------------- Sep 15 08:48:38 volumio volumio[1165]: info: Loading plugins from folder /volumio/app/plugins/ Sep 15 08:48:38 volumio volumio[1165]: info: Adding plugin upnp to MyMusic Plugins Sep 15 08:48:38 volumio volumio[1165]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 15 08:48:38 volumio volumio[1165]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 15 08:48:38 volumio volumio[1165]: info: Loading plugins from folder /data/plugins/ Sep 15 08:48:39 volumio volumio[1165]: info: Loading plugin "system"... Sep 15 08:48:39 volumio volumio[1165]: info: Loading plugin "appearance"... Sep 15 08:48:41 volumio volumio-remote-updater[720]: [2025-09-15 08:48:41] [connect] Successful connection Sep 15 08:48:41 volumio volumio[1165]: info: Loading plugin "network"... Sep 15 08:48:41 volumio volumio[1165]: info: Refreshing Cached IP Addresses Sep 15 08:48:41 volumio sudo[1390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 08:48:41 volumio sudo[1390]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:41 volumio sudo[1392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 08:48:41 volumio sudo[1392]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:41 volumio sudo[1390]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:41 volumio sudo[1392]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:41 volumio volumio[1165]: info: Loading plugin "services"... Sep 15 08:48:41 volumio volumio[1165]: info: Loading plugin "alsa_controller"... Sep 15 08:48:41 volumio sudo[1401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 15 08:48:41 volumio sudo[1401]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:42 volumio volumio[1165]: warn: Unable to locate the audio output device SMSL USB AUDIO. Please configure a valid output device. Sep 15 08:48:42 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 08:48:42 volumio volumio[1165]: info: Loading plugin "wizard"... Sep 15 08:48:42 volumio volumio[1165]: info: Loading plugin "networkfs"... Sep 15 08:48:42 volumio volumio[1165]: info: Starting Udev Watcher for removable devices Sep 15 08:48:42 volumio volumio[1165]: info: Mounting Device MARGIN_1TB Sep 15 08:48:42 volumio sudo[1419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/MARGIN_1TB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 15 08:48:42 volumio sudo[1419]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:44 volumio ntfs-3g[1485]: Version 2017.3.23AR.3 integrated FUSE 28 Sep 15 08:48:44 volumio ntfs-3g[1485]: Mounted /dev/sdb1 (Read-Write, label "MARGIN 1TB", NTFS 3.1) Sep 15 08:48:44 volumio ntfs-3g[1485]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 15 08:48:44 volumio ntfs-3g[1485]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb1,blkdev,blksize=4096 Sep 15 08:48:44 volumio ntfs-3g[1485]: Global ownership and permissions enforced, configuration type 7 Sep 15 08:48:44 volumio sudo[1419]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:44 volumio volumio[1165]: info: Ignoring mount for partition: boot Sep 15 08:48:44 volumio volumio[1165]: info: Ignoring mount for partition: volumio Sep 15 08:48:44 volumio volumio[1165]: info: Ignoring mount for partition: volumio_data Sep 15 08:48:44 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 08:48:44 volumio volumio[1165]: info: Loading plugin "volumio_command_line_client"... Sep 15 08:48:45 volumio volumio[1165]: info: Loading plugin "upnp"... Sep 15 08:48:45 volumio volumio[1165]: info: [1757900925769] Starting Upmpd Daemon Sep 15 08:48:45 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 08:48:45 volumio volumio[1165]: info: Loading plugin "my_music"... Sep 15 08:48:46 volumio volumio[1165]: info: Loading plugin "mpd"... Sep 15 08:48:47 volumio sudo[1401]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:47 volumio volumio[1165]: info: Loading plugin "upnp_browser"... Sep 15 08:48:48 volumio volumio[1165]: info: Loading plugin "alarm-clock"... Sep 15 08:48:48 volumio volumio[1165]: info: Loading plugin "airplay_emulation"... Sep 15 08:48:48 volumio volumio[1165]: info: Starting Shairport Sync Sep 15 08:48:48 volumio volumio[1165]: info: Loading plugin "last_100"... Sep 15 08:48:48 volumio volumio[1165]: info: Loading plugin "webradio"... Sep 15 08:48:49 volumio volumio[1165]: info: Loading plugin "i2s_dacs"... Sep 15 08:48:49 volumio volumio[1165]: info: I2S DAC not set, start Auto-detection Sep 15 08:48:49 volumio volumio[1165]: info: Loading plugin "volumiodiscovery"... Sep 15 08:48:49 volumio volumio[1165]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 08:48:49 volumio volumio[1165]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 08:48:49 volumio volumio[1165]: *** WARNING *** For more information see Sep 15 08:48:49 volumio volumio[1165]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 08:48:49 volumio volumio[1165]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 08:48:49 volumio volumio[1165]: *** WARNING *** For more information see Sep 15 08:48:49 volumio node[1165]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 08:48:49 volumio node[1165]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 08:48:49 volumio node[1165]: *** WARNING *** For more information see Sep 15 08:48:49 volumio node[1165]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 08:48:49 volumio node[1165]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 08:48:49 volumio node[1165]: *** WARNING *** For more information see Sep 15 08:48:49 volumio volumio[1165]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 15 08:48:49 volumio volumio[1165]: info: Discovery: Started advertising with name: Volumio Sep 15 08:48:49 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 08:48:49 volumio volumio[1165]: info: Plugin bandcamp is not enabled Sep 15 08:48:49 volumio volumio[1165]: info: Plugin mixcloud is not enabled Sep 15 08:48:49 volumio volumio[1165]: info: Plugin soundcloud is not enabled Sep 15 08:48:49 volumio volumio[1165]: info: Plugin spop is not enabled Sep 15 08:48:49 volumio volumio[1165]: info: Loading plugin "youtube2"... Sep 15 08:48:54 volumio volumio[1165]: info: Plugin ytcr is not enabled Sep 15 08:48:54 volumio volumio[1165]: info: Plugin ytmusic is not enabled Sep 15 08:48:54 volumio volumio[1165]: info: Plugin autostart is not enabled Sep 15 08:48:54 volumio volumio[1165]: info: Plugin now_playing is not enabled Sep 15 08:48:54 volumio volumio[1165]: info: Loading plugin "outputs"... Sep 15 08:48:54 volumio volumio[1165]: info: Loading plugin "albumart"... Sep 15 08:48:54 volumio volumio[1165]: info: Plugin example_plugin is not enabled Sep 15 08:48:54 volumio volumio[1165]: info: Loading plugin "inputs"... Sep 15 08:48:54 volumio volumio[1165]: info: Loading plugin "updater_comm"... Sep 15 08:48:54 volumio volumio[1165]: Forking 1 albumart workers Sep 15 08:48:54 volumio volumio[1165]: info: Plugin mpdemulation is not enabled Sep 15 08:48:54 volumio volumio[1165]: info: Loading plugin "rest_api"... Sep 15 08:48:54 volumio volumio[1165]: info: Loading plugin "websocket"... Sep 15 08:48:54 volumio volumio[1165]: info: Starting Socket.io Server version 2.3.0 Sep 15 08:48:54 volumio volumio[1165]: info: Loading plugin "fusiondsp"... Sep 15 08:48:55 volumio volumio[1165]: Starting albumart workers Sep 15 08:48:55 volumio volumio[1165]: info: Applying required configuration parameters for plugin fusiondsp Sep 15 08:48:55 volumio volumio[1165]: info: Loading plugin "mpdoutput"... Sep 15 08:48:55 volumio volumio[1165]: info: Loading plugin "80s80s"... Sep 15 08:48:56 volumio volumio[1165]: info: Applying required configuration parameters for plugin 80s80s Sep 15 08:48:56 volumio volumio[1165]: info: [1757900936239] [80s80s] API delay: 30 Sep 15 08:48:56 volumio volumio[1165]: info: Plugin RoonBridge is not enabled Sep 15 08:48:56 volumio volumio[1165]: info: Plugin dmd2_music is not enabled Sep 15 08:48:56 volumio volumio[1165]: info: Plugin lms is not enabled Sep 15 08:48:56 volumio volumio[1165]: info: Loading plugin "minidlna"... Sep 15 08:48:56 volumio volumio[1165]: info: Applying required configuration parameters for plugin minidlna Sep 15 08:48:56 volumio volumio[1165]: info: Plugin backup_restore is not enabled Sep 15 08:48:56 volumio volumio[1165]: info: Plugin music_services_shield is not enabled Sep 15 08:48:56 volumio volumio[1165]: info: Plugin onkyo_control is not enabled Sep 15 08:48:56 volumio volumio[1165]: info: Plugin Systeminfo is not enabled Sep 15 08:48:56 volumio volumio[1165]: info: Plugin lastfm is not enabled Sep 15 08:48:56 volumio volumio[1165]: info: Plugin randomizer is not enabled Sep 15 08:48:56 volumio volumio[1165]: info: Loading i18n strings for locale vi Sep 15 08:48:56 volumio volumio[1165]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Sep 15 08:48:56 volumio volumio[1165]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Sep 15 08:48:56 volumio volumio[1165]: Updating browse sources language Sep 15 08:48:56 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 08:48:56 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 08:48:56 volumio volumio[1165]: info: CoreCommandRouter::initPlayerControls Sep 15 08:48:56 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:56 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:56 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:56 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:56 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 08:48:56 volumio volumio[1165]: Express server listening on port 3000 Sep 15 08:48:56 volumio volumio[1165]: [Metrics] WebUI: 24s 108.35ms Sep 15 08:48:56 volumio volumio[1165]: info: Setting Device type: x86 Sep 15 08:48:56 volumio volumio[1165]: info: CoreStateMachine::resetVolumioState Sep 15 08:48:56 volumio volumio[1165]: info: CoreStateMachine::getcurrentVolume Sep 15 08:48:56 volumio volumio[1165]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 08:48:56 volumio volumio[1165]: info: CoreStateMachine::pushState Sep 15 08:48:56 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 15 08:48:56 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 08:48:56 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 15 08:48:56 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 08:48:56 volumio sudo[1590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 08:48:56 volumio sudo[1590]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:56 volumio sudo[1590]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:56 volumio volumio[1165]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 15 08:48:56 volumio volumio[1165]: info: Completed loading Core Plugins Sep 15 08:48:56 volumio volumio[1165]: info: Preparing to generate the ALSA configuration file Sep 15 08:48:56 volumio sudo[1592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 08:48:56 volumio sudo[1592]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:56 volumio volumio-remote-updater[720]: [2025-09-15 08:48:56] [connect] Successful connection Sep 15 08:48:56 volumio sudo[1592]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:56 volumio volumio[1165]: info: Volumio Network Manager: Network status updated: 2 Sep 15 08:48:56 volumio volumio[1165]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Sep 15 08:48:56 volumio volumio[1165]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 08:48:56 volumio volumio[1165]: info: Reading ALSA contributions from plugins. Sep 15 08:48:56 volumio volumio-remote-updater[720]: [2025-09-15 08:48:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757900936 101 Sep 15 08:48:56 volumio volumio[1165]: 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: 1 Sep 15 08:48:56 volumio volumio[1165]: info: Reloading queue from file Sep 15 08:48:56 volumio volumio[1165]: info: CoreStateMachine::setRepeat false single undefined Sep 15 08:48:56 volumio volumio[1165]: info: CoreStateMachine::pushState Sep 15 08:48:56 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 15 08:48:56 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 08:48:56 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 15 08:48:56 volumio volumio[1165]: info: CoreStateMachine::setRandom true Sep 15 08:48:56 volumio volumio[1165]: info: CoreStateMachine::pushState Sep 15 08:48:56 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 15 08:48:56 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 08:48:56 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 15 08:48:57 volumio volumio[1165]: info: Discovery: adding 75f3fd1a-25bd-4de4-8c66-bfce4172eb28 Sep 15 08:48:57 volumio volumio[1165]: info: Discovery: Found device Volumio Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 15 08:48:57 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 15 08:48:57 volumio volumio[1165]: info: Asound.conf file unchanged, so no further update is needed Sep 15 08:48:57 volumio volumio[1165]: info: Output device has changed, restarting MPD Sep 15 08:48:57 volumio volumio[1165]: info: Output device has changed, restarting Shairport Sync Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 08:48:57 volumio sudo[1603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 08:48:57 volumio sudo[1605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 08:48:57 volumio sudo[1601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 15 08:48:57 volumio sudo[1601]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:57 volumio sudo[1603]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:57 volumio sudo[1605]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:57 volumio sudo[1603]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:57 volumio volumio[1165]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 08:48:57 volumio volumio[1165]: info: ___________ START PLUGINS ___________ Sep 15 08:48:57 volumio volumio[1165]: info: ControllerMpd::onStart: Initializing MPD Sep 15 08:48:57 volumio volumio[1165]: info: Creating MPD Configuration file Sep 15 08:48:57 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 15 08:48:57 volumio systemd[1]: musicservicesshield.service: Succeeded. Sep 15 08:48:57 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 15 08:48:57 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 15 08:48:57 volumio sudo[1601]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:57 volumio systemd[1]: Stopping Music Player Daemon... Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 08:48:57 volumio volumio[1165]: info: [1757900937265] CoreMusicLibrary::Adding element Máy chủ Media Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 08:48:57 volumio sudo[1614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 08:48:57 volumio sudo[1616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 08:48:57 volumio sudo[1614]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:57 volumio sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:57 volumio sudo[1614]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:57 volumio volumio[1165]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 08:48:57 volumio systemd[1]: mpd.service: Succeeded. Sep 15 08:48:57 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 08:48:57 volumio systemd[1]: Starting Music Player Daemon... Sep 15 08:48:57 volumio volumio[1165]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 08:48:57 volumio volumio[1165]: info: [1757900937626] CoreMusicLibrary::Adding element Last_100 Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 08:48:57 volumio volumio[1165]: info: [1757900937627] CoreMusicLibrary::Adding element Webradio Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 08:48:57 volumio volumio[1165]: info: Initializing BBC Radios Sep 15 08:48:57 volumio sudo[1623]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 08:48:57 volumio sudo[1623]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:57 volumio sudo[1623]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 08:48:57 volumio volumio[1165]: info: [1757900937960] CoreMusicLibrary::Adding element YouTube2 Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 08:48:57 volumio volumio[1165]: Cannot find translation for source YouTube2 Sep 15 08:48:57 volumio volumio[1165]: info: Loading i18n strings for locale vi Sep 15 08:48:57 volumio volumio[1165]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Sep 15 08:48:57 volumio volumio[1165]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 15 08:48:57 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:57 volumio volumio[1165]: info: FusionDsp - mixtype--------------------- None Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 08:48:58 volumio volumio[1165]: info: [1757900938008] CoreMusicLibrary::Adding element 80s80s Radio Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 08:48:58 volumio volumio[1165]: Cannot find translation for source YouTube2 Sep 15 08:48:58 volumio volumio[1165]: Cannot find translation for source 80s80s Radio Sep 15 08:48:58 volumio volumio[1165]: info: Loading i18n strings for locale vi Sep 15 08:48:58 volumio volumio[1165]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Sep 15 08:48:58 volumio volumio[1165]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Sep 15 08:48:58 volumio volumio[1165]: info: Volumio Calling Home Sep 15 08:48:58 volumio mpd[1633]: Sep 15 08:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 15 08:48:58 volumio volumio[1165]: info: Preparing to generate the ALSA configuration file Sep 15 08:48:58 volumio systemd[1]: Started Music Player Daemon. Sep 15 08:48:58 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Sep 15 08:48:58 volumio sudo[1616]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:58 volumio sudo[1605]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:58 volumio volumio[1165]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 08:48:58 volumio volumio[1165]: info: Reading ALSA contributions from plugins. Sep 15 08:48:58 volumio volumio[1165]: info: MPD Permissions set Sep 15 08:48:58 volumio volumio[1165]: info: MPD Permissions set Sep 15 08:48:58 volumio volumio[1165]: info: Upmpdcli Daemon Started Sep 15 08:48:58 volumio volumio[1165]: error: MPD error: The expression evaluated to a falsy value: Sep 15 08:48:58 volumio volumio[1165]: assert.ok(self.idling) Sep 15 08:48:58 volumio volumio[1165]: error: The expression evaluated to a falsy value: Sep 15 08:48:58 volumio volumio[1165]: assert.ok(self.idling) Sep 15 08:48:58 volumio volumio[1165]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 15 08:48:58 volumio volumio[1165]: info: MPD running with PID1633 Sep 15 08:48:58 volumio volumio[1165]: ,establishing connection Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:58 volumio volumio[1645]: cset: --> shielding system active with Sep 15 08:48:58 volumio volumio[1645]: cset: "system" cpuset of CPUSPEC(1) with 175 tasks running Sep 15 08:48:58 volumio volumio[1645]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:58 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 15 08:48:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:58 volumio volumio[1165]: error: updateQueue error: null Sep 15 08:48:58 volumio volumio[1165]: info: minidlna: Found miniDLNA version 1.2.1 Sep 15 08:48:58 volumio volumio[1165]: info: minidlna: Starting minidlna.service Sep 15 08:48:58 volumio volumio[1165]: info: Volumio called home Sep 15 08:48:58 volumio sudo[1676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Sep 15 08:48:58 volumio sudo[1676]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:58 volumio volumio[1165]: error: updateQueue error: null Sep 15 08:48:58 volumio volumio[1165]: info: Starting Shairport Sync Sep 15 08:48:58 volumio volumio[1165]: info: Starting Shairport Sync Sep 15 08:48:58 volumio sudo[1679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 08:48:58 volumio sudo[1679]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:58 volumio volumio[1165]: info: Starting Shairport Sync Sep 15 08:48:58 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Sep 15 08:48:58 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 08:48:58 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 08:48:58 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 08:48:58 volumio sudo[1676]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:58 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 08:48:59 volumio sudo[1679]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:59 volumio sudo[1683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 08:48:59 volumio sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:59 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 15 08:48:59 volumio volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 15 08:48:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 08:48:59 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 08:48:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 08:48:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 08:48:59 volumio sudo[1683]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:59 volumio sudo[1688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 08:48:59 volumio sudo[1688]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:48:59 volumio systemd[1]: minidlna.service: Succeeded. Sep 15 08:48:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 08:48:59 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 08:48:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 08:48:59 volumio volumio[1612]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 15 08:48:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 08:48:59 volumio sudo[1688]: pam_unix(sudo:session): session closed for user root Sep 15 08:48:59 volumio volumio[1165]: info: Shairport-Sync Started Sep 15 08:48:59 volumio volumio[1165]: Error adding Membership: Error: addMembership EINVAL Sep 15 08:48:59 volumio volumio[1165]: info: Shairport-Sync Started Sep 15 08:48:59 volumio volumio[1165]: info: minidlna: systemctl start minidlna.service succeeded. Sep 15 08:48:59 volumio volumio[1165]: info: Completed starting Core Plugins Sep 15 08:48:59 volumio volumio[1165]: info: ------------------------------------------- Sep 15 08:48:59 volumio volumio[1165]: info: ----- MyVolumio plugins startup ---- Sep 15 08:48:59 volumio volumio[1165]: info: ------------------------------------------- Sep 15 08:48:59 volumio volumio[1165]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 15 08:48:59 volumio volumio[1612]: .....++++ Sep 15 08:48:59 volumio volumio[1165]: info: Asound.conf file unchanged, so no further update is needed Sep 15 08:48:59 volumio volumio[1165]: info: Output device has changed, restarting MPD Sep 15 08:48:59 volumio sudo[1713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 08:48:59 volumio volumio[1165]: info: Output device has changed, restarting Shairport Sync Sep 15 08:48:59 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:48:59 volumio sudo[1713]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:49:00 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 08:49:00 volumio sudo[1713]: pam_unix(sudo:session): session closed for user root Sep 15 08:49:00 volumio sudo[1716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 08:49:00 volumio sudo[1716]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:49:00 volumio systemd[1]: musicservicesshield.service: Succeeded. Sep 15 08:49:00 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 15 08:49:00 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 15 08:49:00 volumio systemd[1]: Stopping Music Player Daemon... Sep 15 08:49:00 volumio systemd[1]: mpd.service: Succeeded. Sep 15 08:49:00 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 08:49:00 volumio volumio[1165]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 08:49:00 volumio systemd[1]: Starting Music Player Daemon... Sep 15 08:49:00 volumio volumio[1165]: info: Shairport-Sync Started Sep 15 08:49:00 volumio systemd[1]: systemd-timedated.service: Succeeded. Sep 15 08:49:00 volumio sudo[1721]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 08:49:00 volumio sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:49:00 volumio volumio[1165]: info: camilladsp service started and running in background, instance 1 Sep 15 08:49:00 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 08:49:00 volumio sudo[1721]: pam_unix(sudo:session): session closed for user root Sep 15 08:49:00 volumio volumio[1165]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 15 08:49:00 volumio volumio[1165]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 15 08:49:00 volumio volumio[1165]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 15 08:49:00 volumio volumio[1165]: info: FusionDsp loaded Sep 15 08:49:00 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 08:49:00 volumio sudo[1738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 15 08:49:00 volumio sudo[1738]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 08:49:00 volumio volumio[1165]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 15 08:49:00 volumio volumio[1165]: info: Adding Signal Path Element [object Object] Sep 15 08:49:00 volumio volumio[1165]: info: Adding fusiondspeq DSP Signal Path Element Sep 15 08:49:00 volumio volumio[1165]: info: FusionDsp - ---- installed callbackRead Sep 15 08:49:00 volumio volumio[1165]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 08:49:00 volumio volumio[1165]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 15 08:49:00 volumio volumio[1165]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Sep 15 08:49:00 volumio volumio[1165]: at onErrorNT (internal/child_process.js:472:16) Sep 15 08:49:00 volumio volumio[1165]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Sep 15 08:49:00 volumio volumio[1165]: at runNextTicks (internal/process/task_queues.js:64:3) Sep 15 08:49:00 volumio volumio[1165]: at listOnTimeout (internal/timers.js:526:9) Sep 15 08:49:00 volumio volumio[1165]: at processTimers (internal/timers.js:500:7) { Sep 15 08:49:00 volumio volumio[1165]: errno: -2, Sep 15 08:49:00 volumio volumio[1165]: code: 'ENOENT', Sep 15 08:49:00 volumio volumio[1165]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 15 08:49:00 volumio volumio[1165]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 15 08:49:00 volumio volumio[1165]: spawnargs: [ Sep 15 08:49:00 volumio volumio[1165]: '-p', Sep 15 08:49:00 volumio volumio[1165]: 9876, Sep 15 08:49:00 volumio volumio[1165]: '-o', Sep 15 08:49:00 volumio volumio[1165]: '/tmp/camilladsp.log', Sep 15 08:49:00 volumio volumio[1165]: '-l', Sep 15 08:49:00 volumio volumio[1165]: 'warn', Sep 15 08:49:00 volumio volumio[1165]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 15 08:49:00 volumio volumio[1165]: ] Sep 15 08:49:00 volumio volumio[1165]: } Sep 15 08:49:00 volumio volumio[1165]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 08:49:00 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 08:49:00 volumio sudo[1738]: pam_unix(sudo:session): session closed for user root Sep 15 08:49:00 volumio volumio[1612]: ....................................................++++ Sep 15 08:49:00 volumio volumio[1612]: e is 65537 (0x010001) Sep 15 08:49:00 volumio volumio[1612]: writing RSA key Sep 15 08:49:01 volumio sudo[1765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 08:48 Sep 15 08:49:01 volumio sudo[1765]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"