-- 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"