-- Logs begin at Fri 2025-06-13 13:33:57 CST, end at Fri 2025-06-13 13:35:55 CST. -- Jun 13 13:34:00 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jun 13 13:34:00 volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jun 13 13:34:00 volumio systemd[1]: Starting RPC bind portmap service... Jun 13 13:34:00 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jun 13 13:34:00 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Jun 13 13:34:00 volumio systemd[1]: Reached target System Initialization. Jun 13 13:34:00 volumio systemd[1]: Listening on triggerhappy.socket. Jun 13 13:34:00 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Jun 13 13:34:00 volumio systemd[1]: Started Daily apt download activities. Jun 13 13:34:00 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Jun 13 13:34:00 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jun 13 13:34:00 volumio systemd[1]: Started Daily man-db regeneration. Jun 13 13:34:00 volumio systemd[1]: Reached target Timers. Jun 13 13:34:00 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jun 13 13:34:00 volumio systemd[1]: Reached target Sockets. Jun 13 13:34:00 volumio systemd[1]: Reached target Basic System. Jun 13 13:34:00 volumio systemd[1]: Starting Login Service... Jun 13 13:34:00 volumio systemd[1]: Starting Volumio Time Update Utility... Jun 13 13:34:00 volumio systemd[1]: Started Volumio Iptables Module. Jun 13 13:34:00 volumio systemd[1]: Started MPD Monitor Service. Jun 13 13:34:00 volumio systemd[1]: Started D-Bus System Message Bus. Jun 13 13:34:00 volumio volumio-time-update[772]: volumio-time-update-util: Fetching time from Volumio... Jun 13 13:34:00 volumio systemd[1]: Starting WPA supplicant... Jun 13 13:34:00 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jun 13 13:34:00 volumio systemd[1]: Started Manage Sound Card State (restore and store). Jun 13 13:34:00 volumio mpd_monitor.sh[777]: MPD Monitor Service: Starting MPD Monitor Service Jun 13 13:34:00 volumio alsactl[782]: alsactl 1.1.8 daemon started Jun 13 13:34:00 volumio systemd[1]: Started volumio-remote-updater.service. Jun 13 13:34:00 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 13 13:34:00 volumio systemd[1]: Starting Save/Restore Sound Card State... Jun 13 13:34:00 volumio systemd[1]: Starting triggerhappy global hotkey daemon... Jun 13 13:34:00 volumio systemd[1]: Starting Bluetooth service... Jun 13 13:34:00 volumio systemd[1]: Starting My Shell Script... Jun 13 13:34:00 volumio systemd[1]: Starting Wireless Services... Jun 13 13:34:00 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jun 13 13:34:00 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jun 13 13:34:00 volumio kernel: usbcore: registered new interface driver snd-usb-audio Jun 13 13:34:00 volumio systemd-logind[763]: New seat seat0. Jun 13 13:34:00 volumio systemd[1]: Started Volumio Log Rotation Service. Jun 13 13:34:00 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jun 13 13:34:00 volumio systemd[1]: Starting DHCP Client Daemon... Jun 13 13:34:00 volumio systemd[1]: Started RPC bind portmap service. Jun 13 13:34:00 volumio systemd[1]: Started Save/Restore Sound Card State. Jun 13 13:34:00 volumio thd[790]: Unable to parse trigger line: Jun 13 13:34:00 volumio thd[790]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jun 13 13:34:00 volumio thd[790]: Unable to parse trigger line: Jun 13 13:34:00 volumio thd[790]: Found socket passed from systemd Jun 13 13:34:00 volumio systemd-logind[763]: Watching system buttons on /dev/input/event1 (UsbHID SingWon-CTP-V1.18B) Jun 13 13:34:00 volumio systemd-logind[763]: Watching system buttons on /dev/input/event5 (gpio-keys) Jun 13 13:34:00 volumio mpd_monitor.sh[777]: MPD error: Cannot assign requested address Jun 13 13:34:00 volumio mpd_monitor.sh[777]: MPD Monitor Service: MPD Appears to be inactive, restarting Jun 13 13:34:00 volumio mpd_monitor.sh[777]: mpd: no process found Jun 13 13:34:00 volumio avahi-daemon[796]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Jun 13 13:34:00 volumio avahi-daemon[796]: Successfully dropped root privileges. Jun 13 13:34:00 volumio avahi-daemon[796]: avahi-daemon 0.7 starting up. Jun 13 13:34:00 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Jun 13 13:34:00 volumio haveged[751]: haveged: ver: 1.9.1; arch: x86; vend: GenuineIntel; build: (gcc 8.3.0 ITV); collect: 128K Jun 13 13:34:00 volumio haveged[751]: haveged: cpu: (L4 VC); data: 24K (L4 V); inst: 32K (L4 V); idx: 24/40; sz: 31410/52825 Jun 13 13:34:00 volumio haveged[751]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00322 Jun 13 13:34:00 volumio haveged[751]: haveged: fills: 0, generated: 0 Jun 13 13:34:00 volumio bluetoothd[791]: Bluetooth daemon 5.50 Jun 13 13:34:00 volumio bluetoothd[791]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jun 13 13:34:00 volumio systemd[1]: Started triggerhappy global hotkey daemon. Jun 13 13:34:00 volumio systemd[1]: Listening on mpd.socket. Jun 13 13:34:00 volumio systemd[1]: Reached target Sound Card. Jun 13 13:34:00 volumio systemd[1]: Reached target RPC Port Mapper. Jun 13 13:34:00 volumio systemd[1]: Reached target Remote File Systems (Pre). Jun 13 13:34:00 volumio systemd[1]: Reached target Remote File Systems. Jun 13 13:34:00 volumio sh[733]: wlan0=wlan0 Jun 13 13:34:00 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jun 13 13:34:00 volumio volumio-remote-updater[786]: Error: No active session Jun 13 13:34:00 volumio volumio-remote-updater[786]: [2025-06-13 13:34:00] [info] asio async_connect error: system:111 (Connection refused) Jun 13 13:34:00 volumio volumio-remote-updater[786]: [2025-06-13 13:34:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 13 13:34:00 volumio volumio-remote-updater[786]: [2025-06-13 13:34:00] [error] handle_connect error: Underlying Transport Error Jun 13 13:34:00 volumio dhcpcd[800]: no interfaces have a carrier Jun 13 13:34:00 volumio dhcpcd[800]: no interfaces have a carrier Jun 13 13:34:00 volumio dhcpcd[843]: wlan0: waiting for carrier Jun 13 13:34:00 volumio dhcpcd[800]: forked to background, child pid 843 Jun 13 13:34:00 volumio dhcpcd[800]: forked to background, child pid 843 Jun 13 13:34:00 volumio volumio[787]: Could not open config: /tmp/upmpdcli.conf Jun 13 13:34:00 volumio dbus-daemon[778]: [system] Successfully activated service 'org.freedesktop.systemd1' Jun 13 13:34:00 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jun 13 13:34:00 volumio avahi-daemon[796]: Successfully called chroot(). Jun 13 13:34:00 volumio avahi-daemon[796]: Successfully dropped remaining capabilities. Jun 13 13:34:00 volumio systemd[1]: Started Login Service. Jun 13 13:34:00 volumio wpa_supplicant[780]: Successfully initialized wpa_supplicant Jun 13 13:34:00 volumio systemd-udevd[524]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Jun 13 13:34:01 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jun 13 13:34:01 volumio kernel: Bluetooth: BNEP filters: protocol multicast Jun 13 13:34:01 volumio kernel: Bluetooth: BNEP socket layer initialized Jun 13 13:34:01 volumio kernel: Bluetooth: MGMT ver 1.22 Jun 13 13:34:00 volumio bluetoothd[791]: Starting SDP server Jun 13 13:34:01 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Jun 13 13:34:01 volumio volumio-time-update[772]: volumio-time-update-util: Date not found in response Jun 13 13:34:01 volumio volumio-time-update[772]: volumio-time-update-util: Retrying in 5 seconds... Jun 13 13:34:00 volumio bluetoothd[791]: Excluding (cli) sap Jun 13 13:34:01 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 13 13:34:01 volumio avahi-daemon[796]: No service file found in /etc/avahi/services. Jun 13 13:34:01 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 13 13:34:01 volumio avahi-daemon[796]: Network interface enumeration completed. Jun 13 13:34:01 volumio avahi-daemon[796]: Server startup complete. Host name is volumio.local. Local service cookie is 2086005098. Jun 13 13:34:01 volumio bluetoothd[791]: Bluetooth management interface 1.22 initialized Jun 13 13:34:01 volumio dbus-daemon[778]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=791 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jun 13 13:34:01 volumio systemd[1]: Started DHCP Client Daemon. Jun 13 13:34:01 volumio systemd[1]: iptables.service: Succeeded. Jun 13 13:34:01 volumio systemd[1]: Started WPA supplicant. Jun 13 13:34:01 volumio systemd[1]: Started Bluetooth service. Jun 13 13:34:01 volumio systemd[1]: Starting Hostname Service... Jun 13 13:34:01 volumio systemd[1]: Reached target Bluetooth. Jun 13 13:34:01 volumio systemd[1]: Starting Raise network interfaces... Jun 13 13:34:01 volumio loadcpufreq[828]: Loading cpufreq kernel modules...done (acpi-cpufreq). Jun 13 13:34:01 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jun 13 13:34:01 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jun 13 13:34:01 volumio dbus-daemon[778]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 13 13:34:01 volumio systemd[1]: Started Hostname Service. Jun 13 13:34:01 volumio cpufrequtils[919]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Jun 13 13:34:01 volumio systemd[1]: Started Raise network interfaces. Jun 13 13:34:01 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Jun 13 13:34:01 volumio systemd[1]: Reached target Network. Jun 13 13:34:01 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jun 13 13:34:01 volumio systemd[1]: Starting Music Player Daemon... Jun 13 13:34:01 volumio systemd[1]: Starting OpenBSD Secure Shell server... Jun 13 13:34:01 volumio systemd[1]: Starting Permit User Sessions... Jun 13 13:34:01 volumio systemd[1]: Starting Network Time Service... Jun 13 13:34:01 volumio systemd[1]: Reached target Network is Online. Jun 13 13:34:01 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Jun 13 13:34:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 13 13:34:01 volumio systemd[1]: Starting Samba NMB Daemon... Jun 13 13:34:01 volumio systemd[1]: Starting /etc/rc.local Compatibility... Jun 13 13:34:01 volumio systemd[1]: Started Permit User Sessions. Jun 13 13:34:01 volumio systemd[1]: Started /etc/rc.local Compatibility. Jun 13 13:34:01 volumio systemd[1]: Starting Hold until boot process finishes up... Jun 13 13:34:01 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Jun 13 13:34:01 volumio systemd[1]: Received SIGRTMIN+21 from PID 370 (plymouthd). Jun 13 13:34:01 volumio systemd[1]: Received SIGRTMIN+21 from PID 370 (plymouthd). Jun 13 13:34:01 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Jun 13 13:34:01 volumio systemd[1]: Started Hold until boot process finishes up. Jun 13 13:34:01 volumio sshd[976]: Server listening on 0.0.0.0 port 22. Jun 13 13:34:01 volumio sshd[976]: Server listening on :: port 22. Jun 13 13:34:01 volumio systemd[1]: Started OpenBSD Secure Shell server. Jun 13 13:34:01 volumio systemd[1]: plymouth-quit.service: Succeeded. Jun 13 13:34:01 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Jun 13 13:34:01 volumio ifplugd[961]: Network Interface Plugging Daemon...skip eth0...done. Jun 13 13:34:01 volumio systemd[1]: Started Getty on tty1. Jun 13 13:34:01 volumio systemd[1]: Reached target Login Prompts. Jun 13 13:34:01 volumio systemd[1]: Started LSB: Brings up/down network automatically. Jun 13 13:34:01 volumio ntpd[960]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 13 13:34:01 volumio ntpd[960]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 13 13:34:01 volumio systemd[1]: Started Network Time Service. Jun 13 13:34:01 volumio ntpd[979]: proto: precision = 0.217 usec (-22) Jun 13 13:34:01 volumio ntpd[979]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 13 13:34:01 volumio ntpd[979]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 13 13:34:01 volumio ntpd[979]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 899 days ago Jun 13 13:34:01 volumio ntpd[979]: Listen and drop on 0 v6wildcard [::]:123 Jun 13 13:34:01 volumio ntpd[979]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 13 13:34:01 volumio ntpd[979]: Listen normally on 2 lo 127.0.0.1:123 Jun 13 13:34:01 volumio ntpd[979]: Listening on routing socket on fd #19 for interface updates Jun 13 13:34:01 volumio ntpd[979]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 13 13:34:01 volumio ntpd[979]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 13 13:34:01 volumio sudo[957]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 13 13:34:01 volumio sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:01 volumio sudo[957]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 13 13:34:01 volumio sudo[957]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:02 volumio wireless.js[795]: Cleaning previous... Jun 13 13:34:02 volumio nmbd[968]: [2025/06/13 13:34:02.196004, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 13 13:34:02 volumio nmbd[968]: started asyncdns process 991 Jun 13 13:34:02 volumio nmbd[968]: [2025/06/13 13:34:02.197734, 0] ../lib/util/become_daemon.c:149(daemon_status) Jun 13 13:34:02 volumio nmbd[968]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jun 13 13:34:02 volumio nmbd[968]: [2025/06/13 13:34:02.197877, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jun 13 13:34:02 volumio nmbd[968]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jun 13 13:34:02 volumio sudo[993]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 13 13:34:02 volumio sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:02 volumio sudo[993]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:02 volumio sudo[995]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 13 13:34:02 volumio sudo[995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:02 volumio ntpd[979]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 13 13:34:02 volumio sudo[995]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:02 volumio wireless.js[795]: Stopped aP Jun 13 13:34:02 volumio sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 13 13:34:02 volumio sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:02 volumio sudo[1002]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:02 volumio sudo[1004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 13 13:34:02 volumio sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:03 volumio sudo[1004]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:03 volumio sudo[1011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 13 13:34:03 volumio sudo[1011]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:03 volumio sudo[1011]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:03 volumio sudo[1013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 13 13:34:03 volumio sudo[1013]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:03 volumio ntpd[979]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 13 13:34:03 volumio mpd[984]: Jun 13 13:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 13 13:34:04 volumio systemd[1]: Started Music Player Daemon. Jun 13 13:34:04 volumio mpd_monitor.sh[777]: MPD Monitor Service: MPD restarted due to no mpc output. Jun 13 13:34:04 volumio ntpd[979]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 13 13:34:04 volumio systemd[1]: systemd-rfkill.service: Succeeded. Jun 13 13:34:05 volumio ntpd[979]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 13 13:34:05 volumio volumio-remote-updater[786]: [2025-06-13 13:34:05] [info] asio async_connect error: system:111 (Connection refused) Jun 13 13:34:05 volumio volumio-remote-updater[786]: [2025-06-13 13:34:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 13 13:34:05 volumio volumio-remote-updater[786]: [2025-06-13 13:34:05] [error] handle_connect error: Underlying Transport Error Jun 13 13:34:06 volumio volumio-time-update[772]: volumio-time-update-util: Fetching time from Volumio... Jun 13 13:34:06 volumio volumio-time-update[772]: volumio-time-update-util: Date not found in response Jun 13 13:34:06 volumio volumio-time-update[772]: volumio-time-update-util: Retrying in 5 seconds... Jun 13 13:34:06 volumio sudo[1013]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:06 volumio wireless.js[795]: SETTING APPROPRIATE REG DOMAIN: CN Jun 13 13:34:06 volumio sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 13 13:34:06 volumio sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:06 volumio sudo[1026]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:06 volumio sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Jun 13 13:34:06 volumio sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:06 volumio sudo[1028]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:06 volumio wireless.js[795]: SUCCESSFULLY SET NEW REGDOMAIN: CN Jun 13 13:34:06 volumio wireless.js[795]: Start wireless flow Jun 13 13:34:06 volumio wireless.js[795]: Stopped hotspot (if there).. Jun 13 13:34:06 volumio sudo[1033]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 13 13:34:06 volumio sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:06 volumio sudo[1033]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:06 volumio sudo[1035]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 13 13:34:06 volumio sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:07 volumio sudo[1035]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:07 volumio wireless.js[795]: DHCP IP Jun 13 13:34:07 volumio wpa_supplicant[1037]: Successfully initialized wpa_supplicant Jun 13 13:34:07 volumio wireless.js[795]: Start ap Jun 13 13:34:07 volumio sudo[1038]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 13 13:34:07 volumio sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:07 volumio dhcpcd[1039]: sending commands to master dhcpcd process Jun 13 13:34:07 volumio dhcpcd[843]: control command: /sbin/dhcpcd Jun 13 13:34:07 volumio sudo[1038]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:08 volumio wireless.js[795]: trying... Jun 13 13:34:08 volumio sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 13 13:34:08 volumio sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:08 volumio sudo[1044]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:09 volumio wireless.js[795]: trying... Jun 13 13:34:09 volumio sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 13 13:34:09 volumio sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:09 volumio sudo[1047]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:10 volumio wireless.js[795]: trying... Jun 13 13:34:10 volumio sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 13 13:34:10 volumio sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:10 volumio sudo[1050]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:10 volumio wpa_supplicant[1042]: wlan0: Trying to associate with SSID 'HUAWEI-040L9B-ZJJ_5G' Jun 13 13:34:11 volumio volumio-time-update[772]: volumio-time-update-util: Fetching time from Volumio... Jun 13 13:34:11 volumio volumio-time-update[772]: volumio-time-update-util: Date not found in response Jun 13 13:34:11 volumio volumio-time-update[772]: volumio-time-update-util: Retrying in 5 seconds... Jun 13 13:34:11 volumio wpa_supplicant[1042]: wlan0: Associated with 20:da:22:7a:15:b8 Jun 13 13:34:11 volumio wpa_supplicant[1042]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 13 13:34:11 volumio wpa_supplicant[1042]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Jun 13 13:34:11 volumio wpa_supplicant[1042]: wlan0: WPA: Key negotiation completed with 20:da:22:7a:15:b8 [PTK=CCMP GTK=CCMP] Jun 13 13:34:11 volumio wpa_supplicant[1042]: wlan0: CTRL-EVENT-CONNECTED - Connection to 20:da:22:7a:15:b8 completed [id=0 id_str=] Jun 13 13:34:11 volumio dhcpcd[843]: wlan0: carrier acquired Jun 13 13:34:11 volumio dhcpcd[843]: DUID 00:04:bc:b5:f9:ed:61:b8:7f:f3:00:8b:10:08:b1:ff:17:dc Jun 13 13:34:11 volumio dhcpcd[843]: wlan0: IAID b1:ff:17:db Jun 13 13:34:11 volumio dhcpcd[843]: wlan0: adding address fe80::1208:b1ff:feff:17db Jun 13 13:34:11 volumio dhcpcd[843]: ipv6_addaddr1: Permission denied Jun 13 13:34:11 volumio dhcpcd[843]: wlan0: probing address 192.168.3.123/24 Jun 13 13:34:11 volumio wireless.js[795]: trying... Jun 13 13:34:11 volumio sudo[1059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 13 13:34:11 volumio sudo[1059]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:11 volumio sudo[1059]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:11 volumio dhcpcd[843]: wlan0: soliciting an IPv6 router Jun 13 13:34:12 volumio wireless.js[795]: trying... Jun 13 13:34:12 volumio sudo[1062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 13 13:34:12 volumio sudo[1062]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:12 volumio sudo[1062]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:13 volumio wireless.js[795]: trying... Jun 13 13:34:13 volumio sudo[1065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 13 13:34:13 volumio sudo[1065]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:13 volumio sudo[1065]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:13 volumio volumio-remote-updater[786]: [2025-06-13 13:34:13] [info] asio async_connect error: system:111 (Connection refused) Jun 13 13:34:13 volumio volumio-remote-updater[786]: [2025-06-13 13:34:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 13 13:34:13 volumio volumio-remote-updater[786]: [2025-06-13 13:34:13] [error] handle_connect error: Underlying Transport Error Jun 13 13:34:14 volumio wireless.js[795]: trying... Jun 13 13:34:14 volumio sudo[1068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 13 13:34:14 volumio sudo[1068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:14 volumio sudo[1068]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:15 volumio wireless.js[795]: trying... Jun 13 13:34:15 volumio sudo[1071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 13 13:34:15 volumio sudo[1071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:15 volumio sudo[1071]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:15 volumio dhcpcd[843]: wlan0: using static address 192.168.3.123/24 Jun 13 13:34:15 volumio avahi-daemon[796]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.123. Jun 13 13:34:15 volumio dhcpcd[843]: wlan0: adding route to 192.168.3.0/24 Jun 13 13:34:15 volumio dhcpcd[843]: wlan0: adding default route via 192.168.3.1 Jun 13 13:34:15 volumio avahi-daemon[796]: New relevant interface wlan0.IPv4 for mDNS. Jun 13 13:34:15 volumio avahi-daemon[796]: Registering new address record for 192.168.3.123 on wlan0.IPv4. Jun 13 13:34:16 volumio volumio-time-update[772]: volumio-time-update-util: Fetching time from Volumio... Jun 13 13:34:16 volumio wireless.js[795]: trying... Jun 13 13:34:16 volumio sudo[1096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 13 13:34:16 volumio sudo[1096]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:16 volumio sudo[1096]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:16 volumio wireless.js[795]: Connected to: ----HUAWEI-040L9B-ZJJ_5G Jun 13 13:34:16 volumio wireless.js[795]: ---- Jun 13 13:34:16 volumio sudo[1099]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 13 13:34:16 volumio sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:16 volumio sudo[1099]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:16 volumio wireless.js[795]: ... joined AP, wlan0 IPv4 is 192.168.3.123, ipV6 is undefined Jun 13 13:34:16 volumio wireless.js[795]: It's done! AP Jun 13 13:34:16 volumio systemd[1]: Started Wireless Services. Jun 13 13:34:16 volumio systemd[1]: Started Volumio Backend Module. Jun 13 13:34:16 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Jun 13 13:34:16 volumio systemd[1]: Started Start Volumio Kiosk. Jun 13 13:34:16 volumio systemd[1]: Started Volumio Cpu Tweaker. Jun 13 13:34:16 volumio ntpd[979]: Listen normally on 3 wlan0 192.168.3.123:123 Jun 13 13:34:16 volumio ntpd[979]: new interface(s) found: waking up resolver Jun 13 13:34:16 volumio volumio-cpu-tweak[1107]: Setting RT Priority for mpd Jun 13 13:34:16 volumio volumio-cpu-tweak[1107]: Setting MPD Affinity Jun 13 13:34:16 volumio volumio-cpu-tweak[1107]: pid 984's current affinity mask: f Jun 13 13:34:16 volumio volumio-cpu-tweak[1107]: pid 984's new affinity mask: 3 Jun 13 13:34:16 volumio volumio-cpu-tweak[1107]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 13 13:34:16 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Jun 13 13:34:16 volumio startx[1106]: X.Org X Server 1.20.4 Jun 13 13:34:16 volumio startx[1106]: X Protocol Version 11, Revision 0 Jun 13 13:34:16 volumio startx[1106]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Jun 13 13:34:16 volumio startx[1106]: Current Operating System: Linux volumio 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Jun 13 13:34:16 volumio startx[1106]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=c9dc0ff7-9f12-407d-b73b-c0acff562c46 bootpart=UUID=9E04-BB0F datapart=UUID=874c43f3-ff55-470f-8154-f337435b4463 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 Jun 13 13:34:16 volumio startx[1106]: Build Date: 18 December 2021 09:05:36AM Jun 13 13:34:16 volumio startx[1106]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Jun 13 13:34:16 volumio startx[1106]: Current version of pixman: 0.36.0 Jun 13 13:34:16 volumio startx[1106]: Before reporting problems, check http://wiki.x.org Jun 13 13:34:16 volumio startx[1106]: to make sure that you have the latest version. Jun 13 13:34:16 volumio startx[1106]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 13 13:34:16 volumio startx[1106]: (++) from command line, (!!) notice, (II) informational, Jun 13 13:34:16 volumio startx[1106]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 13 13:34:16 volumio startx[1106]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Jun 13 13:34:16 2025 Jun 13 13:34:16 volumio startx[1106]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 13 13:34:17 volumio kernel: broken atomic modeset userspace detected, disabling atomic Jun 13 13:34:17 volumio nmbd[968]: [2025/06/13 13:34:17.335934, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 13 13:34:17 volumio nmbd[968]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 13 13:34:17 volumio systemd[1]: Started Samba NMB Daemon. Jun 13 13:34:17 volumio systemd[1]: Starting Samba Winbind Daemon... Jun 13 13:34:17 volumio volumio-time-update[772]: volumio-time-update-util: Setting system time to: 2025-06-13 13:34:17 Jun 13 13:34:17 volumio sudo[1237]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-13 13:34:17 Jun 13 13:34:17 volumio sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:17 volumio dbus-daemon[778]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.8' (uid=0 pid=1243 comm="timedatectl set-time 2025-06-13 13:34:17 ") Jun 13 13:34:17 volumio winbindd[1213]: [2025/06/13 13:34:17.619385, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 13 13:34:17 volumio winbindd[1213]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 13 13:34:17 volumio winbindd[1213]: [2025/06/13 13:34:17.633784, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 13 13:34:17 volumio winbindd[1213]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 13 13:34:17 volumio systemd[1]: Starting Time & Date Service... Jun 13 13:34:17 volumio systemd[1]: Started Samba Winbind Daemon. Jun 13 13:34:17 volumio systemd[1]: Starting Samba SMB Daemon... Jun 13 13:34:17 volumio systemd[1]: soundcard-init.service: Succeeded. Jun 13 13:34:17 volumio dbus-daemon[778]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 13 13:34:17 volumio systemd[1]: Started Time & Date Service. Jun 13 13:34:17 volumio systemd-timedated[1256]: Changed local time to Fri Jun 13 13:34:17 2025 Jun 13 13:34:17 volumio sudo[1237]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:17 volumio volumio-time-update[772]: volumio-time-update-util: System time updated successfully. Jun 13 13:34:17 volumio systemd[1]: Started Volumio Time Update Utility. Jun 13 13:34:17 volumio startx[1106]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Jun 13 13:34:17 volumio smbd[1265]: [2025/06/13 13:34:17.322896, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 13 13:34:17 volumio systemd[1]: Started Samba SMB Daemon. Jun 13 13:34:17 volumio smbd[1265]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 13 13:34:18 volumio volumio[1104]: info: ------------------------------------------- Jun 13 13:34:18 volumio volumio[1104]: info: ----- Volumio3 ---- Jun 13 13:34:18 volumio volumio[1104]: info: ------------------------------------------- Jun 13 13:34:18 volumio volumio[1104]: info: ----- System startup ---- Jun 13 13:34:18 volumio volumio[1104]: info: ------------------------------------------- Jun 13 13:34:18 volumio sudo[1324]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xrandr -d :0 -o right Jun 13 13:34:18 volumio sudo[1324]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:18 volumio kernel: i915 0000:00:02.0: vgaarb: VGA decodes changed: olddecodes=io+mem,decodes=io+mem:owns=io+mem Jun 13 13:34:18 volumio sudo[1324]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:18 volumio sudo[1327]: root : TTY=unknown ; PWD=/ ; USER=root ; ENV=DISPLAY=:0 ; COMMAND=/usr/bin/xinput set-prop SingWon-CTP-V1.18B Coordinate Transformation Matrix 0 1 0 -1 0 1 0 0 1 Jun 13 13:34:18 volumio sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:18 volumio sudo[1327]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:19 volumio volumio[1104]: info: MYVOLUMIO Environment detected Jun 13 13:34:19 volumio volumio[1104]: info: Plugin folders cleanup Jun 13 13:34:19 volumio volumio[1104]: info: Scanning into folder /volumio/app/plugins/ Jun 13 13:34:19 volumio volumio[1104]: info: Scanning category audio_interface Jun 13 13:34:19 volumio volumio[1104]: info: Scanning category miscellanea Jun 13 13:34:19 volumio volumio[1104]: info: Scanning category music_service Jun 13 13:34:19 volumio volumio[1104]: info: Scanning category plugins.json Jun 13 13:34:19 volumio volumio[1104]: info: Scanning category system_controller Jun 13 13:34:19 volumio volumio[1104]: info: Scanning category user_interface Jun 13 13:34:19 volumio volumio[1104]: info: Scanning into folder /data/plugins/ Jun 13 13:34:19 volumio volumio[1104]: info: Scanning category user_interface Jun 13 13:34:19 volumio volumio[1104]: info: Plugin folders cleanup completed Jun 13 13:34:19 volumio volumio[1104]: info: ------------------------------------------- Jun 13 13:34:19 volumio volumio[1104]: info: ----- Core plugins startup ---- Jun 13 13:34:19 volumio volumio[1104]: info: ------------------------------------------- Jun 13 13:34:19 volumio volumio[1104]: info: Loading plugins from folder /volumio/app/plugins/ Jun 13 13:34:19 volumio volumio[1104]: info: Adding plugin upnp to MyMusic Plugins Jun 13 13:34:19 volumio volumio[1104]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 13 13:34:19 volumio volumio[1104]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 13 13:34:19 volumio volumio[1104]: info: Loading plugins from folder /data/plugins/ Jun 13 13:34:19 volumio volumio[1104]: info: Loading plugin "system"... Jun 13 13:34:19 volumio volumio[1104]: info: Loading plugin "appearance"... Jun 13 13:34:21 volumio volumio[1104]: info: Loading plugin "network"... Jun 13 13:34:21 volumio volumio[1104]: info: Refreshing Cached IP Addresses Jun 13 13:34:21 volumio sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 13 13:34:21 volumio sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:21 volumio sudo[1358]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:21 volumio volumio[1104]: info: Loading plugin "services"... Jun 13 13:34:21 volumio volumio[1104]: info: Loading plugin "alsa_controller"... Jun 13 13:34:21 volumio sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 13 13:34:21 volumio sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:21 volumio sudo[1360]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:21 volumio sudo[1370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 13 13:34:21 volumio sudo[1370]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:21 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 13 13:34:21 volumio volumio[1104]: info: Loading plugin "wizard"... Jun 13 13:34:21 volumio volumio[1104]: info: Loading plugin "networkfs"... Jun 13 13:34:21 volumio volumio[1104]: info: Starting Udev Watcher for removable devices Jun 13 13:34:21 volumio volumio[1104]: info: Mounting Device 3762-0A9A Jun 13 13:34:22 volumio sudo[1391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/3762-0A9A -o noatime Jun 13 13:34:22 volumio sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:22 volumio mount.exfat[1403]: volume was not unmounted cleanly Jun 13 13:34:22 volumio sudo[1391]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:22 volumio volumio[1104]: WARN: volume was not unmounted cleanly. Jun 13 13:34:22 volumio volumio[1104]: info: Ignoring mount for partition: boot Jun 13 13:34:22 volumio volumio[1104]: info: Ignoring mount for partition: volumio Jun 13 13:34:22 volumio volumio[1104]: info: Ignoring mount for partition: volumio_data Jun 13 13:34:22 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 13 13:34:22 volumio volumio[1104]: info: Loading plugin "volumio_command_line_client"... Jun 13 13:34:22 volumio volumio[1104]: info: Loading plugin "upnp"... Jun 13 13:34:23 volumio volumio[1104]: info: [1749792863012] Starting Upmpd Daemon Jun 13 13:34:23 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 13 13:34:23 volumio volumio[1104]: info: Loading plugin "my_music"... Jun 13 13:34:23 volumio volumio[1104]: info: Loading plugin "mpd"... Jun 13 13:34:23 volumio volumio-remote-updater[786]: [2025-06-13 13:34:23] [connect] Successful connection Jun 13 13:34:23 volumio volumio[1104]: info: Plugin upnp_browser is not enabled Jun 13 13:34:23 volumio volumio[1104]: info: Loading plugin "alarm-clock"... Jun 13 13:34:23 volumio volumio[1104]: info: Loading plugin "airplay_emulation"... Jun 13 13:34:23 volumio volumio[1104]: info: Starting Shairport Sync Jun 13 13:34:23 volumio volumio[1104]: info: Loading plugin "last_100"... Jun 13 13:34:23 volumio volumio[1104]: info: Loading plugin "webradio"... Jun 13 13:34:24 volumio volumio[1104]: info: Loading plugin "i2s_dacs"... Jun 13 13:34:24 volumio volumio[1104]: info: I2S DAC not set, start Auto-detection Jun 13 13:34:24 volumio volumio[1104]: info: Loading plugin "volumiodiscovery"... Jun 13 13:34:24 volumio volumio[1104]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 13 13:34:24 volumio volumio[1104]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 13 13:34:24 volumio volumio[1104]: *** WARNING *** For more information see Jun 13 13:34:24 volumio node[1104]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 13 13:34:24 volumio volumio[1104]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 13 13:34:24 volumio volumio[1104]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 13 13:34:24 volumio volumio[1104]: *** WARNING *** For more information see Jun 13 13:34:24 volumio node[1104]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 13 13:34:24 volumio node[1104]: *** WARNING *** For more information see Jun 13 13:34:24 volumio node[1104]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 13 13:34:24 volumio node[1104]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 13 13:34:24 volumio node[1104]: *** WARNING *** For more information see Jun 13 13:34:24 volumio volumio[1104]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 13 13:34:24 volumio volumio[1104]: info: Discovery: Started advertising with name: Volumio Jun 13 13:34:24 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 13 13:34:24 volumio volumio[1104]: info: Loading plugin "peppy_screensaver"... Jun 13 13:34:24 volumio volumio[1104]: info: Applying required configuration parameters for plugin peppy_screensaver Jun 13 13:34:24 volumio volumio[1104]: info: Loading plugin "outputs"... Jun 13 13:34:24 volumio volumio[1104]: info: Loading plugin "albumart"... Jun 13 13:34:24 volumio volumio[1104]: info: Plugin example_plugin is not enabled Jun 13 13:34:24 volumio volumio[1104]: info: Loading plugin "inputs"... Jun 13 13:34:24 volumio volumio[1104]: info: Loading plugin "updater_comm"... Jun 13 13:34:24 volumio volumio[1104]: Forking 3 albumart workers Jun 13 13:34:25 volumio volumio[1104]: info: Plugin mpdemulation is not enabled Jun 13 13:34:25 volumio volumio[1104]: info: Loading plugin "rest_api"... Jun 13 13:34:25 volumio volumio[1104]: info: Loading plugin "websocket"... Jun 13 13:34:25 volumio volumio[1104]: info: Starting Socket.io Server version 2.3.0 Jun 13 13:34:25 volumio volumio[1104]: info: Loading i18n strings for locale zh Jun 13 13:34:25 volumio volumio[1104]: Updating browse sources language Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::initPlayerControls Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 13 13:34:25 volumio volumio[1104]: Express server listening on port 3000 Jun 13 13:34:25 volumio volumio[1104]: [Metrics] WebUI: 8s 42.23ms Jun 13 13:34:25 volumio volumio[1104]: info: Setting Device type: x86 Jun 13 13:34:25 volumio volumio[1104]: info: CoreStateMachine::resetVolumioState Jun 13 13:34:25 volumio volumio[1104]: info: CoreStateMachine::getcurrentVolume Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::volumioRetrievevolume Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:25 volumio volumio[1104]: Starting albumart workers Jun 13 13:34:25 volumio volumio[1104]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 13 13:34:25 volumio volumio[1104]: info: Completed loading Core Plugins Jun 13 13:34:25 volumio volumio[1104]: info: Preparing to generate the ALSA configuration file Jun 13 13:34:25 volumio volumio[1104]: Starting albumart workers Jun 13 13:34:25 volumio volumio[1104]: Starting albumart workers Jun 13 13:34:25 volumio volumio[1104]: info: Volumio Network Manager: Network status updated: 2 Jun 13 13:34:25 volumio volumio[1104]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 13 13:34:25 volumio volumio[1104]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jun 13 13:34:25 volumio volumio[1104]: info: Reading ALSA contributions from plugins. Jun 13 13:34:25 volumio volumio-remote-updater[786]: [2025-06-13 13:34:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749792863 101 Jun 13 13:34:25 volumio volumio[1104]: 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 Jun 13 13:34:25 volumio volumio[1104]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 13 13:34:25 volumio volumio[1104]: info: Reloading queue from file Jun 13 13:34:25 volumio volumio[1104]: info: VolumeController:: Volume=54 Mute =false Jun 13 13:34:25 volumio volumio[1104]: info: CoreStateMachine::pushState Jun 13 13:34:25 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::volumioPushState Jun 13 13:34:25 volumio volumio[1104]: info: CoreStateMachine::updateTrackBlock Jun 13 13:34:25 volumio volumio[1104]: info: CorePlayQueue::getTrackBlock Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::volumioRetrievevolume Jun 13 13:34:25 volumio volumio[1104]: info: CoreStateMachine::setRepeat false single undefined Jun 13 13:34:25 volumio volumio[1104]: info: CoreStateMachine::pushState Jun 13 13:34:25 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::volumioPushState Jun 13 13:34:25 volumio volumio[1104]: info: CoreStateMachine::setRandom false Jun 13 13:34:25 volumio volumio[1104]: info: CoreStateMachine::pushState Jun 13 13:34:25 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 13:34:25 volumio volumio[1104]: info: CoreCommandRouter::volumioPushState Jun 13 13:34:26 volumio volumio[1104]: info: VolumeController:: Volume=54 Mute =false Jun 13 13:34:26 volumio volumio[1104]: info: CoreStateMachine::pushState Jun 13 13:34:26 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::volumioPushState Jun 13 13:34:26 volumio volumio[1104]: info: Asound.conf file unchanged, so no further update is needed Jun 13 13:34:26 volumio volumio[1104]: info: Output device has changed, restarting MPD Jun 13 13:34:26 volumio sudo[1544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 13 13:34:26 volumio sudo[1544]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:26 volumio sudo[1544]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:26 volumio volumio[1104]: info: Output device has changed, restarting Shairport Sync Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:26 volumio sudo[1547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 13 13:34:26 volumio sudo[1547]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:26 volumio systemd[1]: Stopping Music Player Daemon... Jun 13 13:34:26 volumio volumio[1104]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 13 13:34:26 volumio volumio[1104]: info: ___________ START PLUGINS ___________ Jun 13 13:34:26 volumio volumio[1104]: info: ControllerMpd::onStart: Initializing MPD Jun 13 13:34:26 volumio volumio[1104]: info: Creating MPD Configuration file Jun 13 13:34:26 volumio systemd[1]: mpd.service: Succeeded. Jun 13 13:34:26 volumio systemd[1]: Stopped Music Player Daemon. Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:26 volumio sudo[1553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 13 13:34:26 volumio sudo[1553]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:26 volumio systemd[1]: Starting Music Player Daemon... Jun 13 13:34:26 volumio sudo[1553]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:26 volumio sudo[1555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 13 13:34:26 volumio sudo[1555]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:26 volumio volumio[1104]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:26 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 13 13:34:26 volumio systemd[1]: mpd.service: Succeeded. Jun 13 13:34:26 volumio systemd[1]: Stopped Music Player Daemon. Jun 13 13:34:26 volumio systemd[1]: Starting Music Player Daemon... Jun 13 13:34:26 volumio volumio[1104]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 13 13:34:26 volumio volumio[1104]: info: [1749792866370] CoreMusicLibrary::Adding element Last_100 Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 13 13:34:26 volumio volumio[1104]: info: [1749792866376] CoreMusicLibrary::Adding element Webradio Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 13 13:34:26 volumio volumio[1104]: info: Initializing BBC Radios Jun 13 13:34:26 volumio volumio[1104]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:26 volumio sudo[1563]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 13 13:34:26 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:26 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:26 volumio volumio[1104]: info: Loading i18n strings for locale zh Jun 13 13:34:26 volumio volumio[1104]: info: /tmp/myfifo created Jun 13 13:34:26 volumio volumio[1104]: info: /tmp/myfifosa created Jun 13 13:34:26 volumio sudo[1583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Jun 13 13:34:26 volumio sudo[1583]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:26 volumio sudo[1583]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:26 volumio volumio[1104]: info: snd-dummy loaded Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:26 volumio sudo[1602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/shairport-sync.conf.tmpl /volumio/app/plugins/music_service/airplay_emulation/shairport-sync.conf.tmpl Jun 13 13:34:26 volumio sudo[1602]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:26 volumio sudo[1602]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:26 volumio volumio[1104]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 13 13:34:26 volumio sudo[1370]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:26 volumio volumio[1104]: info: Volumio Calling Home Jun 13 13:34:26 volumio volumio[1104]: info: MPD Permissions set Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:26 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:26 volumio volumio[1104]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:26 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:27 volumio sudo[1626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Jun 13 13:34:27 volumio sudo[1626]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:27 volumio volumio[1104]: info: Starting Shairport Sync Jun 13 13:34:27 volumio volumio[1104]: info: Starting Shairport Sync Jun 13 13:34:27 volumio sudo[1626]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:27 volumio volumio[1104]: info: Starting Shairport Sync Jun 13 13:34:27 volumio sudo[1631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 13 13:34:27 volumio sudo[1631]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:27 volumio volumio[1104]: info: Starting Shairport Sync Jun 13 13:34:27 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 13 13:34:27 volumio sudo[1634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 13 13:34:27 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 13 13:34:27 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 13 13:34:27 volumio sudo[1634]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:27 volumio sudo[1629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 13 13:34:27 volumio sudo[1629]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:27 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:27 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 13 13:34:27 volumio sudo[1631]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:27 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 13 13:34:27 volumio sudo[1639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 13 13:34:27 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 13 13:34:27 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 13 13:34:27 volumio sudo[1639]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:27 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 13 13:34:27 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 13 13:34:27 volumio sudo[1629]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:27 volumio sudo[1634]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:27 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 13 13:34:27 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 13 13:34:27 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 13 13:34:27 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 13 13:34:27 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 13 13:34:27 volumio sudo[1639]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:27 volumio volumio[1104]: info: Shairport-Sync Started Jun 13 13:34:27 volumio volumio[1104]: Error adding Membership: Error: addMembership EINVAL Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Jun 13 13:34:27 volumio volumio[1104]: info: Shairport-Sync Started Jun 13 13:34:27 volumio volumio[1104]: info: Shairport-Sync Started Jun 13 13:34:27 volumio volumio[1104]: info: Shairport-Sync Started Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:27 volumio sudo[1644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 13 13:34:27 volumio sudo[1644]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:27 volumio sudo[1644]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:27 volumio volumio[1104]: info: MPD Permissions set Jun 13 13:34:27 volumio mpd[1573]: Jun 13 13:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 13 13:34:27 volumio volumio[1104]: info: Volumio called home Jun 13 13:34:27 volumio systemd[1]: Started Music Player Daemon. Jun 13 13:34:27 volumio sudo[1547]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:27 volumio sudo[1555]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:27 volumio volumio[1104]: info: Completed starting Core Plugins Jun 13 13:34:27 volumio volumio[1104]: info: ------------------------------------------- Jun 13 13:34:27 volumio volumio[1104]: info: ----- MyVolumio plugins startup ---- Jun 13 13:34:27 volumio volumio[1104]: info: ------------------------------------------- Jun 13 13:34:27 volumio volumio[1104]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 13 13:34:27 volumio volumio[1104]: error: MPD error: The expression evaluated to a falsy value: Jun 13 13:34:27 volumio volumio[1104]: assert.ok(self.idling) Jun 13 13:34:27 volumio volumio[1104]: error: The expression evaluated to a falsy value: Jun 13 13:34:27 volumio volumio[1104]: assert.ok(self.idling) Jun 13 13:34:27 volumio volumio[1104]: info: MPD running with PID1573 Jun 13 13:34:27 volumio volumio[1104]: ,establishing connection Jun 13 13:34:27 volumio volumio[1104]: error: updateQueue error: null Jun 13 13:34:27 volumio volumio[1104]: error: updateQueue error: null Jun 13 13:34:27 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 13 13:34:27 volumio sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 13 13:34:27 volumio sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:28 volumio systemd[1]: Stopping Music Player Daemon... Jun 13 13:34:28 volumio systemd[1]: mpd.service: Succeeded. Jun 13 13:34:28 volumio systemd[1]: Stopped Music Player Daemon. Jun 13 13:34:28 volumio systemd[1]: Starting Music Player Daemon... Jun 13 13:34:28 volumio sudo[1661]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 13 13:34:28 volumio sudo[1661]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:28 volumio sudo[1661]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:28 volumio volumio[1104]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 13 13:34:29 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 13 13:34:29 volumio mpd[1663]: Jun 13 13:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 13 13:34:29 volumio systemd[1]: Started Music Player Daemon. Jun 13 13:34:29 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 13 13:34:29 volumio sudo[1659]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:29 volumio volumio[1104]: error: updateQueue error: null Jun 13 13:34:30 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 13 13:34:30 volumio volumio[1104]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 13 13:34:32 volumio volumio[1104]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::volumioGetVisibleSources Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:32 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 13 13:34:32 volumio volumio[1104]: info: Received Get System Info Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 13:34:32 volumio volumio[1104]: info: Discovery: Getting this device information Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:32 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:32 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:32 volumio volumio[1104]: info: Listing playlists Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 13:34:32 volumio volumio[1104]: info: Received Get System Info Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 13:34:32 volumio volumio[1104]: info: Discovery: Getting this device information Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:32 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::volumioGetVisibleSources Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:32 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 13 13:34:32 volumio volumio[1104]: info: Received Get System Info Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 13:34:32 volumio volumio[1104]: info: Discovery: Getting this device information Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:32 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 13:34:32 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:32 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:32 volumio volumio[1104]: info: Listing playlists Jun 13 13:34:33 volumio sudo[1752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 13 13:34:33 volumio sudo[1752]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:33 volumio sudo[1756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 13 13:34:33 volumio sudo[1756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:33 volumio sudo[1752]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:33 volumio sudo[1756]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:33 volumio sudo[1763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 13 13:34:33 volumio sudo[1763]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:33 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 13 13:34:33 volumio sudo[1763]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:33 volumio volumio[1104]: info: Upmpdcli Daemon Started Jun 13 13:34:33 volumio volumio[1785]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 13 13:34:33 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 13 13:34:34 volumio volumio[1104]: info: Discovery: adding a4a38f5c-3c06-4b6c-addf-1f4213f4058a Jun 13 13:34:34 volumio volumio[1104]: info: Discovery: Found device Volumio Jun 13 13:34:34 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:34 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:34 volumio volumio[1785]: .........................++++ Jun 13 13:34:36 volumio volumio[1785]: .....................................................................................................++++ Jun 13 13:34:36 volumio volumio[1785]: e is 65537 (0x010001) Jun 13 13:34:36 volumio volumio[1785]: writing RSA key Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 13 13:34:37 volumio volumio[1104]: info: Adding plugin bluetooth to MyMusic Plugins Jun 13 13:34:37 volumio volumio[1104]: info: Adding plugin multiroom to MyMusic Plugins Jun 13 13:34:37 volumio volumio[1104]: info: Adding plugin metavolumio to MyMusic Plugins Jun 13 13:34:37 volumio volumio[1104]: info: Adding plugin cd_controller to MyMusic Plugins Jun 13 13:34:37 volumio volumio[1104]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 13 13:34:37 volumio volumio[1104]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 13 13:34:37 volumio volumio[1104]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 13 13:34:37 volumio volumio[1104]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 13 13:34:38 volumio volumio[1104]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 13 13:34:38 volumio volumio[1104]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 13 13:34:38 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:38 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:38 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:38 volumio volumio[1104]: info: Starting MyVolumio Remote Streaming Endpoints Jun 13 13:34:38 volumio volumio[1104]: info: MyVolumio not started Jun 13 13:34:38 volumio volumio[1104]: info: Initializing device activation check Jun 13 13:34:38 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:38 volumio volumio[1104]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 13 13:34:38 volumio volumio[1104]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 13 13:34:39 volumio volumio[1104]: info: Starting Streaming Service Transparent Proxy Jun 13 13:34:39 volumio volumio[1104]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 13 13:34:39 volumio volumio[1104]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 13 13:34:39 volumio volumio[1104]: info: Streaming services startup Jun 13 13:34:39 volumio volumio[1104]: info: Starting Streaming Daemon Jun 13 13:34:39 volumio sudo[1909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 13 13:34:39 volumio sudo[1909]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:39 volumio volumio[1104]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 13 13:34:39 volumio sudo[1909]: pam_unix(sudo:session): session closed for user root Jun 13 13:34:39 volumio volumio[1104]: error: Cannot start Volumio Streaming Daemon Jun 13 13:34:39 volumio volumio[1104]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 13 13:34:39 volumio volumio[1104]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 13 13:34:40 volumio volumio[1104]: STREAMING PROXY: Starting server on port 3245 Jun 13 13:34:40 volumio volumio[1104]: Node JS runtime: 14 Jun 13 13:34:40 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 13 13:34:47 volumio systemd[1]: systemd-timedated.service: Succeeded. Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 13:34:49 volumio volumio[1104]: info: Discovery: Getting this device information Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:49 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 13:34:49 volumio volumio[1104]: info: Discovery: Getting this device information Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:49 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 13:34:49 volumio volumio[1104]: info: Discovery: Getting this device information Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:49 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 13:34:49 volumio volumio[1104]: verbose: New Socket.io Connection to 192.168.3.123 from 192.168.3.35 UA: Mozilla/5.0 (Linux; Android 10; WLZ-AN00 Build/HUAWEIWLZ-AN00; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/114.0.5735.196 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::volumioGetVisibleSources Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:49 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 13 13:34:49 volumio volumio[1104]: info: Received Get System Info Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 13:34:49 volumio volumio[1104]: info: Discovery: Getting this device information Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:49 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 13:34:49 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:49 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:49 volumio volumio[1104]: info: Listing playlists Jun 13 13:34:50 volumio volumio[1104]: verbose: New Socket.io Connection to 192.168.3.123:3000 from 192.168.3.35 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jun 13 13:34:50 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 13 13:34:52 volumio volumio[1104]: info: CoreCommandRouter::volumioGetQueue Jun 13 13:34:52 volumio volumio[1104]: info: CoreStateMachine::getQueue Jun 13 13:34:52 volumio volumio[1104]: info: CorePlayQueue::getQueue Jun 13 13:34:53 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 13 13:34:53 volumio volumio[1104]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 13 13:34:53 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:34:53 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:34:56 volumio volumio[1104]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jun 13 13:34:56 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:56 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 13 13:34:56 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:56 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 13 13:34:57 volumio volumio-remote-updater[786]: Test mode enabled Jun 13 13:34:57 volumio volumio-remote-updater[786]: No alpha test mode Jun 13 13:34:57 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 13 13:34:57 volumio volumio[1104]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

  • Various Fixes for QOBUZ Connect
  • Various Fixes for Tidal Connect
","title":"Update v3.816","updateavailable":true} Jun 13 13:34:57 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 13 13:34:58 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:58 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:58 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 13 13:34:58 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 13 13:34:58 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 13 13:34:58 volumio volumio[1104]: info: CoreCommandRouter::volumioGetBrowseSources Jun 13 13:34:58 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 13 13:34:59 volumio systemd[1]: Started My Shell Script. Jun 13 13:34:59 volumio systemd[1]: Reached target Multi-User System. Jun 13 13:34:59 volumio systemd[1]: Reached target Graphical Interface. Jun 13 13:34:59 volumio sudo[1952]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/bash /home/volumio/scripts/peppy.sh Jun 13 13:34:59 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 13 13:34:59 volumio sudo[1952]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:34:59 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 13 13:34:59 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 13 13:34:59 volumio systemd[1]: Startup finished in 7.531s (firmware) + 3.120s (loader) + 10.468s (kernel) + 1min 3.716s (userspace) = 1min 24.836s. Jun 13 13:34:59 volumio volumio[1104]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 13 13:34:59 volumio volumio[1104]: info: Completed starting MyVolumio Plugin Jun 13 13:34:59 volumio volumio[1104]: [Metrics] CommandRouter: 41s 560.45ms Jun 13 13:34:59 volumio volumio[1104]: info: CoreCommandRouter::volumiosetStartupVolume Jun 13 13:34:59 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 13:34:59 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 13:34:59 volumio volumio[1104]: info: CoreCommandRouter::Close All Modals sent Jun 13 13:34:59 volumio volumio[1104]: info: CoreCommandRouter::Close All Modals sent Jun 13 13:35:00 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 13 13:35:00 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 13 13:35:00 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 13 13:35:02 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 13:35:02 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 13:35:02 volumio volumio[1104]: info: Discovery: Getting this device information Jun 13 13:35:02 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:35:02 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:35:02 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 13:35:04 volumio volumio[1104]: info: Preload queue cleared Jun 13 13:35:04 volumio volumio[1104]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 13 13:35:04 volumio volumio[1104]: info: CoreStateMachine::ClearQueue Jun 13 13:35:04 volumio volumio[1104]: info: CoreStateMachine::stop Jun 13 13:35:04 volumio volumio[1104]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 13 13:35:04 volumio volumio[1104]: info: CorePlayQueue::clearPlayQueue Jun 13 13:35:04 volumio volumio[1104]: info: CorePlayQueue::saveQueue Jun 13 13:35:04 volumio volumio[1104]: info: CoreCommandRouter::volumioPushQueue Jun 13 13:35:04 volumio volumio[1104]: info: CoreStateMachine::addQueueItems Jun 13 13:35:04 volumio volumio[1104]: info: CorePlayQueue::addQueueItems Jun 13 13:35:04 volumio volumio[1104]: info: Preload queue cleared Jun 13 13:35:04 volumio volumio[1104]: info: Adding Item to queue: music-library/USB/3762-0A9A/赵鹏《惊艳低音》CD1 Jun 13 13:35:04 volumio volumio[1104]: info: Exploding uri music-library/USB/3762-0A9A/赵鹏《惊艳低音》CD1 in service mpd Jun 13 13:35:04 volumio volumio[1104]: info: ALBUMART /albumart?cacheid=868&path=%2Fmnt%2FUSB%2F3762-0A9A%2F%E8%B5%B5%E9%B9%8F%E3%80%8A%E6%83%8A%E8%89%B3%E4%BD%8E%E9%9F%B3%E3%80%8BCD1%2F01%20%E6%8A%8A%E6%82%B2%E4%BC%A4%E7%95%99%E7%BB%99%E8%87%AA%E5%B7%B1.wav&metadata=false Jun 13 13:35:04 volumio volumio[1104]: info: URI /mnt/USB/3762-0A9A/赵鹏《惊艳低音》CD1/01 把悲伤留给自己.wav Jun 13 13:35:04 volumio volumio[1104]: info: ALBUMART /albumart?cacheid=868&path=%2Fmnt%2FUSB%2F3762-0A9A%2F%E8%B5%B5%E9%B9%8F%E3%80%8A%E6%83%8A%E8%89%B3%E4%BD%8E%E9%9F%B3%E3%80%8BCD1%2F02%20%E6%AE%87.wav&metadata=false Jun 13 13:35:04 volumio volumio[1104]: info: URI /mnt/USB/3762-0A9A/赵鹏《惊艳低音》CD1/02 殇.wav Jun 13 13:35:04 volumio volumio[1104]: info: ALBUMART /albumart?cacheid=868&path=%2Fmnt%2FUSB%2F3762-0A9A%2F%E8%B5%B5%E9%B9%8F%E3%80%8A%E6%83%8A%E8%89%B3%E4%BD%8E%E9%9F%B3%E3%80%8BCD1%2F03%20%E8%88%B9%E6%AD%8C.wav&metadata=false Jun 13 13:35:04 volumio volumio[1104]: info: URI /mnt/USB/3762-0A9A/赵鹏《惊艳低音》CD1/03 船歌.wav Jun 13 13:35:04 volumio volumio[1104]: info: ALBUMART /albumart?cacheid=868&path=%2Fmnt%2FUSB%2F3762-0A9A%2F%E8%B5%B5%E9%B9%8F%E3%80%8A%E6%83%8A%E8%89%B3%E4%BD%8E%E9%9F%B3%E3%80%8BCD1%2F04%20%E5%B1%B1%E8%8C%B6%E8%8A%B1.wav&metadata=false Jun 13 13:35:04 volumio volumio[1104]: info: URI /mnt/USB/3762-0A9A/赵鹏《惊艳低音》CD1/04 山茶花.wav Jun 13 13:35:04 volumio volumio[1104]: info: ALBUMART /albumart?cacheid=868&path=%2Fmnt%2FUSB%2F3762-0A9A%2F%E8%B5%B5%E9%B9%8F%E3%80%8A%E6%83%8A%E8%89%B3%E4%BD%8E%E9%9F%B3%E3%80%8BCD1%2F05%20%E7%99%BD%E6%9C%88%E5%85%89.wav&metadata=false Jun 13 13:35:04 volumio volumio[1104]: info: URI /mnt/USB/3762-0A9A/赵鹏《惊艳低音》CD1/05 白月光.wav Jun 13 13:35:04 volumio volumio[1104]: info: ALBUMART /albumart?cacheid=868&path=%2Fmnt%2FUSB%2F3762-0A9A%2F%E8%B5%B5%E9%B9%8F%E3%80%8A%E6%83%8A%E8%89%B3%E4%BD%8E%E9%9F%B3%E3%80%8BCD1%2F06%20%E5%9C%A8%E6%B0%B4%E4%B8%80%E6%96%B9.wav&metadata=false Jun 13 13:35:04 volumio volumio[1104]: info: URI /mnt/USB/3762-0A9A/赵鹏《惊艳低音》CD1/06 在水一方.wav Jun 13 13:35:04 volumio volumio[1104]: info: ALBUMART /albumart?cacheid=868&path=%2Fmnt%2FUSB%2F3762-0A9A%2F%E8%B5%B5%E9%B9%8F%E3%80%8A%E6%83%8A%E8%89%B3%E4%BD%8E%E9%9F%B3%E3%80%8BCD1%2F07%20%E4%BA%B2%E7%88%B1%E7%9A%84%E5%B0%8F%E5%AD%A9.wav&metadata=false Jun 13 13:35:04 volumio volumio[1104]: info: URI /mnt/USB/3762-0A9A/赵鹏《惊艳低音》CD1/07 亲爱的小孩.wav Jun 13 13:35:04 volumio volumio[1104]: info: ALBUMART /albumart?cacheid=868&path=%2Fmnt%2FUSB%2F3762-0A9A%2F%E8%B5%B5%E9%B9%8F%E3%80%8A%E6%83%8A%E8%89%B3%E4%BD%8E%E9%9F%B3%E3%80%8BCD1%2F08%20%E4%BD%A0%E7%9A%84%E6%A0%B7%E5%AD%90.wav&metadata=false Jun 13 13:35:04 volumio volumio[1104]: info: URI /mnt/USB/3762-0A9A/赵鹏《惊艳低音》CD1/08 你的样子.wav Jun 13 13:35:04 volumio volumio[1104]: info: ALBUMART /albumart?cacheid=868&path=%2Fmnt%2FUSB%2F3762-0A9A%2F%E8%B5%B5%E9%B9%8F%E3%80%8A%E6%83%8A%E8%89%B3%E4%BD%8E%E9%9F%B3%E3%80%8BCD1%2F09%20%E7%99%BD%E6%A1%A6%E6%9E%97.wav&metadata=false Jun 13 13:35:04 volumio volumio[1104]: info: URI /mnt/USB/3762-0A9A/赵鹏《惊艳低音》CD1/09 白桦林.wav Jun 13 13:35:04 volumio volumio[1104]: info: ALBUMART /albumart?cacheid=868&path=%2Fmnt%2FUSB%2F3762-0A9A%2F%E8%B5%B5%E9%B9%8F%E3%80%8A%E6%83%8A%E8%89%B3%E4%BD%8E%E9%9F%B3%E3%80%8BCD1%2F10%20%E7%8B%AC%E4%B8%8A%E8%A5%BF%E6%A5%BC.wav&metadata=false Jun 13 13:35:04 volumio volumio[1104]: info: URI /mnt/USB/3762-0A9A/赵鹏《惊艳低音》CD1/10 独上西楼.wav Jun 13 13:35:04 volumio volumio[1104]: info: ALBUMART /albumart?cacheid=868&path=%2Fmnt%2FUSB%2F3762-0A9A%2F%E8%B5%B5%E9%B9%8F%E3%80%8A%E6%83%8A%E8%89%B3%E4%BD%8E%E9%9F%B3%E3%80%8BCD1%2F11%20%E5%94%B1%E6%AD%8C%E7%BB%99%E4%BD%A0%E5%90%AC.wav&metadata=false Jun 13 13:35:04 volumio volumio[1104]: info: URI /mnt/USB/3762-0A9A/赵鹏《惊艳低音》CD1/11 唱歌给你听.wav Jun 13 13:35:04 volumio volumio[1104]: info: ALBUMART /albumart?cacheid=868&path=%2Fmnt%2FUSB%2F3762-0A9A%2F%E8%B5%B5%E9%B9%8F%E3%80%8A%E6%83%8A%E8%89%B3%E4%BD%8E%E9%9F%B3%E3%80%8BCD1%2F12%20%E5%A6%82%E6%9E%9C%E8%BF%99%E9%83%BD%E4%B8%8D%E7%AE%97%E7%88%B1.wav&metadata=false Jun 13 13:35:04 volumio volumio[1104]: info: URI /mnt/USB/3762-0A9A/赵鹏《惊艳低音》CD1/12 如果这都不算爱.wav Jun 13 13:35:04 volumio volumio[1104]: info: ALBUMART /albumart?cacheid=868&path=%2Fmnt%2FUSB%2F3762-0A9A%2F%E8%B5%B5%E9%B9%8F%E3%80%8A%E6%83%8A%E8%89%B3%E4%BD%8E%E9%9F%B3%E3%80%8BCD1%2F13%20%E5%89%AA%E7%88%B1.wav&metadata=false Jun 13 13:35:04 volumio volumio[1104]: info: URI /mnt/USB/3762-0A9A/赵鹏《惊艳低音》CD1/13 剪爱.wav Jun 13 13:35:04 volumio volumio[1104]: info: ALBUMART /albumart?cacheid=868&path=%2Fmnt%2FUSB%2F3762-0A9A%2F%E8%B5%B5%E9%B9%8F%E3%80%8A%E6%83%8A%E8%89%B3%E4%BD%8E%E9%9F%B3%E3%80%8BCD1%2F14%20%E9%97%AA%E4%BA%AE%E7%9A%84%E6%97%A5%E5%AD%90.wav&metadata=false Jun 13 13:35:04 volumio volumio[1104]: info: URI /mnt/USB/3762-0A9A/赵鹏《惊艳低音》CD1/14 闪亮的日子.wav Jun 13 13:35:04 volumio volumio[1104]: info: ALBUMART /albumart?cacheid=868&path=%2Fmnt%2FUSB%2F3762-0A9A%2F%E8%B5%B5%E9%B9%8F%E3%80%8A%E6%83%8A%E8%89%B3%E4%BD%8E%E9%9F%B3%E3%80%8BCD1%2F15%20%E5%BA%AD%E9%99%A2%E6%B7%B1%E6%B7%B1.wav&metadata=false Jun 13 13:35:04 volumio volumio[1104]: info: URI /mnt/USB/3762-0A9A/赵鹏《惊艳低音》CD1/15 庭院深深.wav Jun 13 13:35:04 volumio volumio[1104]: info: CoreCommandRouter::volumioPushQueue Jun 13 13:35:04 volumio volumio[1104]: info: CorePlayQueue::saveQueue Jun 13 13:35:04 volumio volumio[1104]: info: CoreStateMachine::updateTrackBlock Jun 13 13:35:04 volumio volumio[1104]: info: CorePlayQueue::getTrackBlock Jun 13 13:35:04 volumio volumio[1104]: info: CoreCommandRouter::volumioPlay Jun 13 13:35:04 volumio volumio[1104]: info: CoreStateMachine::play index 0 Jun 13 13:35:04 volumio volumio[1104]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 13 13:35:04 volumio volumio[1104]: info: CoreStateMachine::stop Jun 13 13:35:04 volumio volumio[1104]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 13 13:35:04 volumio volumio[1104]: info: CoreStateMachine::play index undefined Jun 13 13:35:04 volumio volumio[1104]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 13 13:35:04 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:35:04 volumio volumio[1104]: info: CoreStateMachine::startPlaybackTimer Jun 13 13:35:04 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:35:04 volumio volumio[1104]: verbose: ControllerMpd::clearAddPlayTracks USB/3762-0A9A/赵鹏《惊艳低音》CD1/01 把悲伤留给自己.wav Jun 13 13:35:04 volumio volumio[1104]: verbose: ControllerMpd::sendMpdCommand stop Jun 13 13:35:04 volumio volumio[1104]: info: sendMpdCommand stop took 7 milliseconds Jun 13 13:35:04 volumio volumio[1104]: verbose: ControllerMpd::sendMpdCommand clear Jun 13 13:35:04 volumio volumio[1104]: info: sendMpdCommand clear took 1 milliseconds Jun 13 13:35:04 volumio volumio[1104]: verbose: ControllerMpd::sendMpdCommand add "USB/3762-0A9A/赵鹏《惊艳低音》CD1/01 把悲伤留给自己.wav" Jun 13 13:35:04 volumio volumio[1104]: info: Jun 13 13:35:04 volumio volumio[1104]: ---------------------------- MPD announces system playlist update Jun 13 13:35:04 volumio volumio[1104]: info: Ignoring MPD Status Update Jun 13 13:35:04 volumio volumio[1104]: info: Jun 13 13:35:04 volumio volumio[1104]: ---------------------------- MPD announces system playlist update Jun 13 13:35:04 volumio volumio[1104]: info: Ignoring MPD Status Update Jun 13 13:35:04 volumio volumio[1104]: info: sendMpdCommand add "USB/3762-0A9A/赵鹏《惊艳低音》CD1/01 把悲伤留给自己.wav" took 6 milliseconds Jun 13 13:35:04 volumio volumio[1104]: info: ------------------------------ 6ms Jun 13 13:35:04 volumio volumio[1104]: verbose: ControllerMpd::sendMpdCommand play Jun 13 13:35:04 volumio volumio[1104]: info: ------------------------------ 7ms Jun 13 13:35:04 volumio volumio[1104]: info: sendMpdCommand play took 4 milliseconds Jun 13 13:35:05 volumio volumio[1104]: info: Jun 13 13:35:05 volumio volumio[1104]: ---------------------------- MPD announces state update: player Jun 13 13:35:05 volumio volumio[1104]: info: ControllerMpd::getState Jun 13 13:35:05 volumio volumio[1104]: verbose: ControllerMpd::sendMpdCommand status Jun 13 13:35:05 volumio volumio[1104]: info: sendMpdCommand status took 55 milliseconds Jun 13 13:35:05 volumio volumio[1104]: verbose: ControllerMpd::parseState Jun 13 13:35:05 volumio volumio[1104]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 13:35:05 volumio volumio[1104]: info: Jun 13 13:35:05 volumio volumio[1104]: ---------------------------- MPD announces state update: player Jun 13 13:35:05 volumio volumio[1104]: info: ControllerMpd::getState Jun 13 13:35:05 volumio volumio[1104]: verbose: ControllerMpd::sendMpdCommand status Jun 13 13:35:05 volumio volumio[1104]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 13 13:35:05 volumio volumio[1104]: verbose: ControllerMpd::parseTrackInfo Jun 13 13:35:05 volumio volumio[1104]: info: ControllerMpd::pushState Jun 13 13:35:05 volumio volumio[1104]: info: CoreCommandRouter::servicePushState Jun 13 13:35:05 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:35:05 volumio volumio[1104]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":289,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 1","artist":"δ֪??????","album":"δ֪??Ƭ?? (2014/12/10 21:22:25)","uri":"USB/3762-0A9A/赵鹏《惊艳低音》CD1/01 把悲伤留给自己.wav","trackType":"wav"} Jun 13 13:35:05 volumio volumio[1104]: verbose: CURRENT POSITION 0 Jun 13 13:35:05 volumio volumio[1104]: info: CoreStateMachine::syncState stateService play Jun 13 13:35:05 volumio volumio[1104]: info: CoreStateMachine::syncState currentStatus stop Jun 13 13:35:05 volumio volumio[1104]: info: ------------------------------ 77ms Jun 13 13:35:05 volumio volumio[1104]: info: sendMpdCommand status took 13 milliseconds Jun 13 13:35:05 volumio volumio[1104]: verbose: ControllerMpd::parseState Jun 13 13:35:05 volumio volumio[1104]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 13:35:05 volumio volumio[1104]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 13 13:35:05 volumio volumio[1104]: verbose: ControllerMpd::parseTrackInfo Jun 13 13:35:05 volumio volumio[1104]: info: ControllerMpd::pushState Jun 13 13:35:05 volumio volumio[1104]: info: CoreCommandRouter::servicePushState Jun 13 13:35:05 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:35:05 volumio volumio[1104]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":289,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 1","artist":"δ֪??????","album":"δ֪??Ƭ?? (2014/12/10 21:22:25)","uri":"USB/3762-0A9A/赵鹏《惊艳低音》CD1/01 把悲伤留给自己.wav","trackType":"wav"} Jun 13 13:35:05 volumio volumio[1104]: verbose: CURRENT POSITION 0 Jun 13 13:35:05 volumio volumio[1104]: info: CoreStateMachine::syncState stateService play Jun 13 13:35:05 volumio volumio[1104]: info: CoreStateMachine::syncState currentStatus play Jun 13 13:35:05 volumio volumio[1104]: info: Received an update from plugin. extracting info from payload Jun 13 13:35:05 volumio volumio[1104]: info: CoreStateMachine::pushState Jun 13 13:35:05 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:35:05 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 13:35:05 volumio volumio[1104]: info: CoreCommandRouter::volumioPushState Jun 13 13:35:05 volumio volumio[1104]: info: CoreStateMachine::pushState Jun 13 13:35:05 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:35:05 volumio volumio[1104]: info: CoreCommandRouter::volumioPushState Jun 13 13:35:05 volumio volumio[1104]: info: ------------------------------ 51ms Jun 13 13:35:06 volumio volumio[1104]: info: BOOT COMPLETED Jun 13 13:35:07 volumio ntpd[979]: Soliciting pool server 95.111.202.5 Jun 13 13:35:07 volumio ntpd[979]: Soliciting pool server 95.111.202.5 Jun 13 13:35:08 volumio ntpd[979]: Soliciting pool server 45.76.221.157 Jun 13 13:35:09 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:35:09 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:35:09 volumio sudo[2045]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/bin/bash run_peppymeter.sh Jun 13 13:35:09 volumio sudo[2045]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 13 13:35:09 volumio ntpd[979]: Soliciting pool server 202.118.1.81 Jun 13 13:35:09 volumio ntpd[979]: Soliciting pool server 193.182.111.142 Jun 13 13:35:10 volumio ntpd[979]: Soliciting pool server 193.182.111.14 Jun 13 13:35:10 volumio ntpd[979]: Soliciting pool server 193.182.111.143 Jun 13 13:35:10 volumio ntpd[979]: Soliciting pool server 162.159.200.1 Jun 13 13:35:11 volumio ntpd[979]: Soliciting pool server 117.80.231.60 Jun 13 13:35:11 volumio ntpd[979]: Soliciting pool server 210.28.130.4 Jun 13 13:35:12 volumio ntpd[979]: Soliciting pool server 84.16.67.12 Jun 13 13:35:12 volumio ntpd[979]: Soliciting pool server 2a03:8600::cc Jun 13 13:35:13 volumio volumio[1104]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Jun 13 13:35:13 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:35:13 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:35:18 volumio ntpd[979]: receive: Unexpected origin timestamp 0xebf63715.da12deac does not match aorg 0000000000.00000000 from server@193.182.111.143 xmt 0xebf63716.c3468f8a Jun 13 13:35:18 volumio ntpd[979]: receive: Unexpected origin timestamp 0xebf63715.da174d72 does not match aorg 0000000000.00000000 from server@193.182.111.142 xmt 0xebf63716.c2f0a9a0 Jun 13 13:35:18 volumio ntpd[979]: receive: Unexpected origin timestamp 0xebf63715.da1dbc5e does not match aorg 0000000000.00000000 from server@95.111.202.5 xmt 0xebf63716.c0502310 Jun 13 13:35:21 volumio volumio[1104]: info: VolumeController::SetAlsaVolume61 Jun 13 13:35:21 volumio volumio[1104]: info: CoreStateMachine::pushState Jun 13 13:35:21 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:35:21 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 13:35:21 volumio volumio[1104]: info: CoreCommandRouter::volumioPushState Jun 13 13:35:21 volumio volumio[1104]: info: VolumeController::SetAlsaVolume81 Jun 13 13:35:21 volumio volumio[1104]: info: CoreStateMachine::pushState Jun 13 13:35:21 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:35:21 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 13:35:21 volumio volumio[1104]: info: CoreCommandRouter::volumioPushState Jun 13 13:35:33 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 13:35:33 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 13:35:33 volumio volumio[1104]: info: Discovery: Getting this device information Jun 13 13:35:33 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Jun 13 13:35:33 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:35:33 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 13:35:40 volumio volumio[1104]: info: VolumeController::SetAlsaVolume47 Jun 13 13:35:40 volumio volumio[1104]: info: CoreStateMachine::pushState Jun 13 13:35:40 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:35:40 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 13:35:40 volumio volumio[1104]: info: CoreCommandRouter::volumioPushState Jun 13 13:35:55 volumio volumio[1104]: info: CoreCommandRouter::volumioPause Jun 13 13:35:55 volumio volumio[1104]: info: CoreStateMachine::pause Jun 13 13:35:55 volumio volumio[1104]: info: CoreStateMachine::stPlaybackTimer Jun 13 13:35:55 volumio volumio[1104]: info: CoreStateMachine::servicePause Jun 13 13:35:55 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:35:55 volumio volumio[1104]: info: CoreCommandRouter::servicePause Jun 13 13:35:55 volumio volumio[1104]: info: ControllerMpd::pause Jun 13 13:35:55 volumio volumio[1104]: verbose: ControllerMpd::sendMpdCommand pause Jun 13 13:35:55 volumio volumio[1104]: info: Jun 13 13:35:55 volumio volumio[1104]: ---------------------------- MPD announces state update: player Jun 13 13:35:55 volumio volumio[1104]: info: sendMpdCommand pause took 7 milliseconds Jun 13 13:35:55 volumio volumio[1104]: info: ControllerMpd::getState Jun 13 13:35:55 volumio volumio[1104]: verbose: ControllerMpd::sendMpdCommand status Jun 13 13:35:55 volumio volumio[1104]: info: sendMpdCommand status took 4 milliseconds Jun 13 13:35:55 volumio volumio[1104]: verbose: ControllerMpd::parseState Jun 13 13:35:55 volumio volumio[1104]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 13:35:55 volumio volumio[1104]: info: sendMpdCommand playlistinfo took 18 milliseconds Jun 13 13:35:55 volumio volumio[1104]: verbose: ControllerMpd::parseTrackInfo Jun 13 13:35:55 volumio volumio[1104]: info: ControllerMpd::pushState Jun 13 13:35:55 volumio volumio[1104]: info: CoreCommandRouter::servicePushState Jun 13 13:35:55 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:35:55 volumio volumio[1104]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":50131,"duration":289,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 1","artist":"δ֪??????","album":"δ֪??Ƭ?? (2014/12/10 21:22:25)","uri":"USB/3762-0A9A/赵鹏《惊艳低音》CD1/01 把悲伤留给自己.wav","trackType":"wav"} Jun 13 13:35:55 volumio volumio[1104]: verbose: CURRENT POSITION 0 Jun 13 13:35:55 volumio volumio[1104]: info: CoreStateMachine::syncState stateService pause Jun 13 13:35:55 volumio volumio[1104]: info: CoreStateMachine::syncState currentStatus pause Jun 13 13:35:55 volumio volumio[1104]: info: CoreStateMachine::pushState Jun 13 13:35:55 volumio volumio[1104]: info: CorePlayQueue::getTrack 0 Jun 13 13:35:55 volumio volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 13:35:55 volumio volumio[1104]: info: CoreCommandRouter::volumioPushState Jun 13 13:35:55 volumio volumio[1104]: info: CoreStateMachine::stPlaybackTimer Jun 13 13:35:55 volumio volumio[1104]: info: ------------------------------ 71ms Jun 13 13:35:55 volumio volumio[1104]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 13 13:35:55 volumio volumio[1104]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Jun 13 13:35:55 volumio volumio[1104]: at Object.readdirSync (fs.js:1048:3) Jun 13 13:35:55 volumio volumio[1104]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Jun 13 13:35:55 volumio volumio[1104]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Jun 13 13:35:55 volumio volumio[1104]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Jun 13 13:35:55 volumio volumio[1104]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Jun 13 13:35:55 volumio volumio[1104]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Jun 13 13:35:55 volumio volumio[1104]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jun 13 13:35:55 volumio volumio[1104]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Jun 13 13:35:55 volumio volumio[1104]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Jun 13 13:35:55 volumio volumio[1104]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) { Jun 13 13:35:55 volumio volumio[1104]: errno: -20, Jun 13 13:35:55 volumio volumio[1104]: syscall: 'scandir', Jun 13 13:35:55 volumio volumio[1104]: code: 'ENOTDIR', Jun 13 13:35:55 volumio volumio[1104]: path: '/tmp/peppyrunning' Jun 13 13:35:55 volumio volumio[1104]: } Jun 13 13:35:55 volumio volumio[1104]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 13 13:35:55 volumio sudo[2262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-13 13:34 Jun 13 13:35:55 volumio sudo[2262]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"