-- Logs begin at Wed 2025-12-31 05:08:56 UTC, end at Wed 2025-12-31 05:10:45 UTC. -- Dec 31 05:09:00 volumio4 kernel: bcmgenet: Skipping UMAC reset Dec 31 05:09:00 volumio4 kernel: bcmgenet fd580000.genet: configuring instance for external RGMII (no delay) Dec 31 05:09:00 volumio4 kernel: brcmfmac: power management disabled Dec 31 05:09:00 volumio4 networking[357]: Configuring network interfaces...done. Dec 31 05:09:00 volumio4 systemd[1]: Started LSB: Raise network interfaces.. Dec 31 05:09:00 volumio4 systemd[1]: Starting ifup for wlan0... Dec 31 05:09:00 volumio4 systemd[1]: Started ifup for wlan0. Dec 31 05:09:00 volumio4 systemd[1]: Starting ifup for eth0... Dec 31 05:09:00 volumio4 ifup[498]: /sbin/ifup: interface wlan0 already configured Dec 31 05:09:00 volumio4 systemd[1]: Started ifup for eth0. Dec 31 05:09:00 volumio4 systemd[1]: Starting Network. Dec 31 05:09:00 volumio4 systemd[1]: Reached target Network. Dec 31 05:09:00 volumio4 systemd[1]: Starting Network is Online. Dec 31 05:09:00 volumio4 systemd[1]: Reached target Network is Online. Dec 31 05:09:00 volumio4 systemd[1]: Starting LSB: RPC portmapper replacement... Dec 31 05:09:00 volumio4 ifup[499]: dhcpcd[521]: version 6.0.5 starting Dec 31 05:09:00 volumio4 dhcpcd[521]: version 6.0.5 starting Dec 31 05:09:00 volumio4 ifup[499]: dhcpcd[521]: eth0: waiting for carrier Dec 31 05:09:00 volumio4 dhcpcd[521]: eth0: waiting for carrier Dec 31 05:09:00 volumio4 rpcbind[501]: Starting rpcbind daemon.... Dec 31 05:09:00 volumio4 systemd[1]: Started LSB: RPC portmapper replacement. Dec 31 05:09:00 volumio4 systemd[1]: Starting RPC Port Mapper. Dec 31 05:09:00 volumio4 systemd[1]: Reached target RPC Port Mapper. Dec 31 05:09:00 volumio4 systemd[1]: Starting LSB: NFS support files common to client and server... Dec 31 05:09:00 volumio4 rpc.statd[547]: Version 1.2.8 starting Dec 31 05:09:00 volumio4 sm-notify[548]: Version 1.2.8 starting Dec 31 05:09:00 volumio4 kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Dec 31 05:09:01 volumio4 nfs-common[541]: Starting NFS common utilities: statd idmapd. Dec 31 05:09:01 volumio4 systemd[1]: Started LSB: NFS support files common to client and server. Dec 31 05:09:01 volumio4 systemd[1]: Starting System Initialization. Dec 31 05:09:01 volumio4 systemd[1]: Reached target System Initialization. Dec 31 05:09:01 volumio4 systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. Dec 31 05:09:01 volumio4 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Dec 31 05:09:01 volumio4 systemd[1]: Starting mpd.socket. Dec 31 05:09:01 volumio4 systemd[1]: Listening on mpd.socket. Dec 31 05:09:01 volumio4 systemd[1]: Starting D-Bus System Message Bus Socket. Dec 31 05:09:01 volumio4 systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 31 05:09:01 volumio4 systemd[1]: Starting Sockets. Dec 31 05:09:01 volumio4 systemd[1]: Reached target Sockets. Dec 31 05:09:01 volumio4 systemd[1]: Starting Daily Cleanup of Temporary Directories. Dec 31 05:09:01 volumio4 systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 31 05:09:01 volumio4 systemd[1]: Starting Timers. Dec 31 05:09:01 volumio4 systemd[1]: Reached target Timers. Dec 31 05:09:01 volumio4 systemd[1]: Started Manage Sound Card State (restore and store). Dec 31 05:09:01 volumio4 systemd[1]: Starting Restore Sound Card State... Dec 31 05:09:01 volumio4 systemd[1]: Starting Basic System. Dec 31 05:09:01 volumio4 systemd[1]: Reached target Basic System. Dec 31 05:09:01 volumio4 systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... Dec 31 05:09:01 volumio4 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Dec 31 05:09:01 volumio4 systemd[1]: Starting cd.service... Dec 31 05:09:01 volumio4 systemd[1]: Started cd.service. Dec 31 05:09:01 volumio4 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Dec 31 05:09:01 volumio4 systemd[1]: Starting Volumio Iptables Module... Dec 31 05:09:01 volumio4 systemd[1]: Started Volumio Iptables Module. Dec 31 05:09:01 volumio4 systemd[1]: Starting mount disks automatically with standby... Dec 31 05:09:01 volumio4 systemd[1]: Started mount disks automatically with standby. Dec 31 05:09:01 volumio4 systemd[1]: Starting UPnP Renderer front-end to MPD... Dec 31 05:09:01 volumio4 systemd[1]: Started UPnP Renderer front-end to MPD. Dec 31 05:09:01 volumio4 systemd[1]: Starting volumio-remote-updater.service... Dec 31 05:09:01 volumio4 systemd[1]: Started volumio-remote-updater.service. Dec 31 05:09:01 volumio4 systemd[1]: Starting Volumio SSH enabler... Dec 31 05:09:01 volumio4 systemd[1]: Started Volumio SSH enabler. Dec 31 05:09:01 volumio4 systemd[1]: Starting Wireless Services... Dec 31 05:09:01 volumio4 systemd[1]: Starting Xbindrc... Dec 31 05:09:01 volumio4 systemd[1]: Started Xbindrc. Dec 31 05:09:01 volumio4 systemd[1]: Starting OpenBSD Secure Shell server... Dec 31 05:09:01 volumio4 volumio[562]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Dec 31 05:09:01 volumio4 systemd[1]: Starting /etc/rc.local Compatibility... Dec 31 05:09:01 volumio4 systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. Dec 31 05:09:01 volumio4 systemd[1]: Starting Login Service... Dec 31 05:09:01 volumio4 systemd[1]: Starting LSB: start Winbind daemon... Dec 31 05:09:01 volumio4 systemd[1]: Starting LSB: start Samba daemons for the AD DC... Dec 31 05:09:01 volumio4 systemd[1]: Starting LSB: Start NTP daemon... Dec 31 05:09:01 volumio4 systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... Dec 31 05:09:01 volumio4 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Dec 31 05:09:01 volumio4 systemd[1]: Starting LSB: Brings up/down network automatically... Dec 31 05:09:01 volumio4 systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... Dec 31 05:09:01 volumio4 systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... Dec 31 05:09:01 volumio4 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 31 05:09:01 volumio4 systemd[1]: Starting Music Player Daemon... Dec 31 05:09:01 volumio4 kernel: uart-pl011 fe201000.serial: no DMA platform data Dec 31 05:09:01 volumio4 systemd[1]: Starting D-Bus System Message Bus... Dec 31 05:09:01 volumio4 kernel: bcmgenet fd580000.genet eth0: Link is Down Dec 31 05:09:01 volumio4 systemd[1]: Started D-Bus System Message Bus. Dec 31 05:09:01 volumio4 btuart[563]: Patch not found for BCM4345C0, continue anyway Dec 31 05:09:01 volumio4 dhcpcd[619]: Not running dhcpcd because there is aleady an interface specific instance ... failed! Dec 31 05:09:01 volumio4 dhcpcd[619]: /var/run/dhcpcd-eth0.pid ... failed! Dec 31 05:09:01 volumio4 ifplugd(eth0)[667]: ifplugd 0.28 initializing. Dec 31 05:09:01 volumio4 ifplugd(eth0)[667]: Using interface eth0/DC:A6:32:71:3F:69 with driver (version: v2.0) Dec 31 05:09:01 volumio4 ifplugd(eth0)[667]: Using detection mode: SIOCETHTOOL Dec 31 05:09:01 volumio4 ifplugd(eth0)[667]: Initialization complete, link beat not detected. Dec 31 05:09:01 volumio4 loadcpufreq[605]: Loading cpufreq kernel modules...done (none). Dec 31 05:09:01 volumio4 avahi-daemon[620]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). Dec 31 05:09:01 volumio4 avahi-daemon[620]: Successfully dropped root privileges. Dec 31 05:09:01 volumio4 avahi-daemon[620]: avahi-daemon 0.6.31 starting up. Dec 31 05:09:01 volumio4 ifplugd[610]: Network Interface Plugging Daemon...start eth0...done. Dec 31 05:09:01 volumio4 ntpd[657]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) Dec 31 05:09:01 volumio4 ntp[596]: Starting NTP server: ntpd. Dec 31 05:09:02 volumio4 avahi-daemon[620]: Successfully called chroot(). Dec 31 05:09:02 volumio4 avahi-daemon[620]: Successfully dropped remaining capabilities. Dec 31 05:09:02 volumio4 avahi-daemon[620]: Loading service file /services/volumio.service. Dec 31 05:09:02 volumio4 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 31 05:09:02 volumio4 avahi-daemon[620]: Network interface enumeration completed. Dec 31 05:09:02 volumio4 avahi-daemon[620]: Registering HINFO record with values 'ARMV7L'/'LINUX'. Dec 31 05:09:02 volumio4 systemd[1]: Starting Permit User Sessions... Dec 31 05:09:02 volumio4 systemd[1]: Started Restore Sound Card State. Dec 31 05:09:02 volumio4 systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Dec 31 05:09:02 volumio4 avahi-daemon[620]: Server startup complete. Host name is volumio4.local. Local service cookie is 3172069400. Dec 31 05:09:02 volumio4 avahi-daemon[620]: Service "Volumio4" (/services/volumio.service) successfully established. Dec 31 05:09:02 volumio4 systemd[1]: Unit cd.service entered failed state. Dec 31 05:09:02 volumio4 systemd[1]: Started /etc/rc.local Compatibility. Dec 31 05:09:02 volumio4 systemd[1]: Started LSB: Start NTP daemon. Dec 31 05:09:02 volumio4 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Dec 31 05:09:02 volumio4 systemd[1]: Started LSB: Brings up/down network automatically. Dec 31 05:09:02 volumio4 systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. Dec 31 05:09:02 volumio4 systemd[1]: dhcpcd.service: control process exited, code=exited status=6 Dec 31 05:09:02 volumio4 systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. Dec 31 05:09:02 volumio4 systemd[1]: Unit dhcpcd.service entered failed state. Dec 31 05:09:02 volumio4 systemd[1]: Started Permit User Sessions. Dec 31 05:09:02 volumio4 ntpd[688]: proto: precision = 1.148 usec Dec 31 05:09:02 volumio4 ntpd[688]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Dec 31 05:09:02 volumio4 ntpd[688]: Listen and drop on 1 v6wildcard :: UDP 123 Dec 31 05:09:02 volumio4 ntpd[688]: Listen normally on 2 lo 127.0.0.1 UDP 123 Dec 31 05:09:02 volumio4 ntpd[688]: peers refreshed Dec 31 05:09:02 volumio4 ntpd[688]: Listening on routing socket on fd #19 for interface updates Dec 31 05:09:02 volumio4 systemd[1]: Started OpenBSD Secure Shell server. Dec 31 05:09:02 volumio4 sshd[693]: Server listening on 0.0.0.0 port 22. Dec 31 05:09:02 volumio4 sshd[693]: Server listening on :: port 22. Dec 31 05:09:02 volumio4 systemd[1]: cd.service holdoff time over, scheduling restart. Dec 31 05:09:02 volumio4 kernel: Bluetooth: Core ver 2.22 Dec 31 05:09:02 volumio4 kernel: NET: Registered protocol family 31 Dec 31 05:09:02 volumio4 kernel: Bluetooth: HCI device and connection manager initialized Dec 31 05:09:02 volumio4 kernel: Bluetooth: HCI socket layer initialized Dec 31 05:09:02 volumio4 kernel: Bluetooth: L2CAP socket layer initialized Dec 31 05:09:02 volumio4 kernel: Bluetooth: SCO socket layer initialized Dec 31 05:09:02 volumio4 kernel: Bluetooth: HCI UART driver ver 2.3 Dec 31 05:09:02 volumio4 kernel: Bluetooth: HCI UART protocol H4 registered Dec 31 05:09:02 volumio4 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Dec 31 05:09:02 volumio4 kernel: Bluetooth: HCI UART protocol Broadcom registered Dec 31 05:09:02 volumio4 systemd[1]: Stopping cd.service... Dec 31 05:09:02 volumio4 systemd[1]: Starting cd.service... Dec 31 05:09:02 volumio4 systemd-logind[590]: New seat seat0. Dec 31 05:09:02 volumio4 btuart[563]: bcm43xx_init Dec 31 05:09:02 volumio4 btuart[563]: Set BDADDR UART: b8:27:eb:4e:7d:03 Dec 31 05:09:02 volumio4 btuart[563]: Set Controller UART speed to 3000000 bit/s Dec 31 05:09:02 volumio4 btuart[563]: Device setup complete Dec 31 05:09:02 volumio4 sudo[580]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Dec 31 05:09:02 volumio4 systemd-udevd[698]: failed to execute '/usr/bin/hciconfig' '/usr/bin/hciconfig hci0 up': No such file or directory Dec 31 05:09:02 volumio4 systemd[1]: Started cd.service. Dec 31 05:09:02 volumio4 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Dec 31 05:09:02 volumio4 systemd[1]: Starting Hold until boot process finishes up... Dec 31 05:09:02 volumio4 systemd[1]: Starting Terminate Plymouth Boot Screen... Dec 31 05:09:02 volumio4 systemd[1]: Started Configure Bluetooth Modems connected by UART. Dec 31 05:09:02 volumio4 volumio[695]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Dec 31 05:09:02 volumio4 systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Dec 31 05:09:02 volumio4 systemd[1]: Unit cd.service entered failed state. Dec 31 05:09:02 volumio4 volumio-remote-updater[567]: Error: No active session Dec 31 05:09:02 volumio4 systemd[1]: Received SIGRTMIN+21 from PID 249 (plymouthd). Dec 31 05:09:02 volumio4 volumio-remote-updater[567]: [2025-12-31 05:09:02] [info] asio async_connect error: system:111 (Connection refused) Dec 31 05:09:02 volumio4 volumio-remote-updater[567]: [2025-12-31 05:09:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 31 05:09:02 volumio4 volumio-remote-updater[567]: [2025-12-31 05:09:02] [error] handle_connect error: Underlying Transport Error Dec 31 05:09:02 volumio4 systemd[1]: Started Hold until boot process finishes up. Dec 31 05:09:02 volumio4 systemd[1]: Started Terminate Plymouth Boot Screen. Dec 31 05:09:02 volumio4 systemd[1]: Started Login Service. Dec 31 05:09:02 volumio4 systemd[1]: Starting Getty on tty1... Dec 31 05:09:02 volumio4 dbus[637]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' Dec 31 05:09:02 volumio4 systemd[1]: Started Getty on tty1. Dec 31 05:09:02 volumio4 systemd[1]: Starting Login Prompts. Dec 31 05:09:02 volumio4 systemd[1]: Reached target Login Prompts. Dec 31 05:09:02 volumio4 sudo[580]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:09:02 volumio4 systemd[1]: cd.service holdoff time over, scheduling restart. Dec 31 05:09:02 volumio4 sudo[580]: pam_unix(sudo:session): session closed for user root Dec 31 05:09:02 volumio4 systemd[1]: Stopping cd.service... Dec 31 05:09:02 volumio4 systemd[1]: Starting cd.service... Dec 31 05:09:02 volumio4 systemd[1]: Started cd.service. Dec 31 05:09:02 volumio4 systemd[1]: Starting Disk Manager (legacy version)... Dec 31 05:09:02 volumio4 cpufrequtils[701]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 31 05:09:02 volumio4 systemd[1]: Started LSB: set CPUFreq kernel parameters. Dec 31 05:09:02 volumio4 volumio[725]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Dec 31 05:09:02 volumio4 systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Dec 31 05:09:02 volumio4 systemd[1]: Unit cd.service entered failed state. Dec 31 05:09:02 volumio4 volumio[566]: Could not open config: /tmp/upmpdcli.conf Dec 31 05:09:02 volumio4 systemd[1]: cd.service holdoff time over, scheduling restart. Dec 31 05:09:02 volumio4 systemd[1]: Stopping cd.service... Dec 31 05:09:02 volumio4 systemd[1]: Starting cd.service... Dec 31 05:09:02 volumio4 systemd[1]: Started cd.service. Dec 31 05:09:02 volumio4 systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill1... Dec 31 05:09:02 volumio4 systemd[1]: Starting Bluetooth service... Dec 31 05:09:02 volumio4 volumio[736]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Dec 31 05:09:02 volumio4 systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Dec 31 05:09:02 volumio4 systemd[1]: Unit cd.service entered failed state. Dec 31 05:09:02 volumio4 systemd[1]: Started Load/Save RF Kill Switch Status of rfkill1. Dec 31 05:09:02 volumio4 systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE Dec 31 05:09:02 volumio4 systemd[1]: Unit upmpdcli.service entered failed state. Dec 31 05:09:02 volumio4 dbus[637]: [system] Successfully activated service 'org.freedesktop.UDisks' Dec 31 05:09:02 volumio4 systemd[1]: Started Disk Manager (legacy version). Dec 31 05:09:03 volumio4 systemd[1]: cd.service holdoff time over, scheduling restart. Dec 31 05:09:03 volumio4 systemd[1]: Stopping cd.service... Dec 31 05:09:03 volumio4 systemd[1]: Starting cd.service... Dec 31 05:09:03 volumio4 systemd[1]: Started cd.service. Dec 31 05:09:03 volumio4 volumio[743]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Dec 31 05:09:03 volumio4 systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Dec 31 05:09:03 volumio4 systemd[1]: Unit cd.service entered failed state. Dec 31 05:09:03 volumio4 bluetoothd[738]: Bluetooth daemon 5.23 Dec 31 05:09:03 volumio4 systemd[1]: cd.service holdoff time over, scheduling restart. Dec 31 05:09:03 volumio4 systemd[1]: Stopping cd.service... Dec 31 05:09:03 volumio4 systemd[1]: Starting cd.service... Dec 31 05:09:03 volumio4 systemd[1]: cd.service start request repeated too quickly, refusing to start. Dec 31 05:09:03 volumio4 systemd[1]: Failed to start cd.service. Dec 31 05:09:03 volumio4 systemd[1]: Unit cd.service entered failed state. Dec 31 05:09:03 volumio4 dbus[637]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' Dec 31 05:09:03 volumio4 systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... Dec 31 05:09:03 volumio4 bluetoothd[738]: Starting SDP server Dec 31 05:09:03 volumio4 bluetoothd[738]: Excluding (cli) sap Dec 31 05:09:03 volumio4 systemd[1]: Started Bluetooth service. Dec 31 05:09:03 volumio4 systemd[1]: Starting Bluetooth. Dec 31 05:09:03 volumio4 systemd[1]: Reached target Bluetooth. Dec 31 05:09:03 volumio4 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 31 05:09:03 volumio4 kernel: Bluetooth: BNEP filters: protocol multicast Dec 31 05:09:03 volumio4 kernel: Bluetooth: BNEP socket layer initialized Dec 31 05:09:03 volumio4 bluetoothd[738]: Bluetooth management interface 1.14 initialized Dec 31 05:09:03 volumio4 dbus[637]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' Dec 31 05:09:03 volumio4 systemd[1]: Starting Hostname Service... Dec 31 05:09:03 volumio4 polkitd[746]: started daemon version 0.105 using authority implementation `local' version `0.105' Dec 31 05:09:03 volumio4 dbus[637]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Dec 31 05:09:03 volumio4 systemd-hostnamed[751]: Warning: nss-myhostname is not installed. Changing the local hostname might make it unresolveable. Please install nss-myhostname! Dec 31 05:09:03 volumio4 dbus[637]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 31 05:09:03 volumio4 udisks-glue[565]: Device file /dev/mmcblk0 inserted Dec 31 05:09:03 volumio4 systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. Dec 31 05:09:03 volumio4 systemd[1]: Started Hostname Service. Dec 31 05:09:03 volumio4 haveged[561]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K Dec 31 05:09:03 volumio4 haveged[561]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244 Dec 31 05:09:03 volumio4 haveged[561]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00042 Dec 31 05:09:03 volumio4 haveged[561]: haveged: fills: 0, generated: 0 Dec 31 05:09:03 volumio4 udisks-glue[565]: Device file /dev/sda inserted Dec 31 05:09:03 volumio4 udisks-glue[565]: Device file /dev/sda1 inserted Dec 31 05:09:03 volumio4 udisks-glue[565]: Trying to automount /dev/sda1... Dec 31 05:09:04 volumio4 kernel: random: crng init done Dec 31 05:09:04 volumio4 kernel: random: 7 urandom warning(s) missed due to ratelimiting Dec 31 05:09:04 volumio4 kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 31 05:09:04 volumio4 udisks-glue[565]: Successfully automounted /dev/sda1 at /media/ESD-USB Dec 31 05:09:04 volumio4 udisks-glue[565]: Device file /dev/mmcblk0p3 inserted Dec 31 05:09:04 volumio4 udisks-glue[565]: Device file /dev/sda1 mounted at /media/ESD-USB Dec 31 05:09:04 volumio4 systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Dec 31 05:09:04 volumio4 systemd[1]: Stopping Xbindrc... Dec 31 05:09:04 volumio4 systemd[1]: Starting Xbindrc... Dec 31 05:09:04 volumio4 systemd[1]: Started Xbindrc. Dec 31 05:09:04 volumio4 dhcpcd[521]: eth0: carrier acquired Dec 31 05:09:04 volumio4 kernel: bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 31 05:09:04 volumio4 ifup[499]: dhcpcd[521]: eth0: carrier acquired Dec 31 05:09:04 volumio4 dhcpcd[521]: eth0: soliciting an IPv6 router Dec 31 05:09:04 volumio4 ifup[499]: dhcpcd[521]: eth0: soliciting an IPv6 router Dec 31 05:09:04 volumio4 ifplugd(eth0)[667]: Link beat detected. Dec 31 05:09:04 volumio4 ntpd[688]: Deferring DNS for 0.debian.pool.ntp.org 1 Dec 31 05:09:04 volumio4 ntpd[688]: Deferring DNS for 1.debian.pool.ntp.org 1 Dec 31 05:09:04 volumio4 ntpd[688]: Deferring DNS for 2.debian.pool.ntp.org 1 Dec 31 05:09:04 volumio4 ntpd[688]: Deferring DNS for 3.debian.pool.ntp.org 1 Dec 31 05:09:04 volumio4 dhcpcd[521]: eth0: soliciting a DHCP lease Dec 31 05:09:04 volumio4 ntpd[772]: signal_no_reset: signal 17 had flags 4000000 Dec 31 05:09:04 volumio4 ifup[499]: dhcpcd[521]: eth0: soliciting a DHCP lease Dec 31 05:09:04 volumio4 ifplugd(eth0)[667]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 31 05:09:04 volumio4 systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Dec 31 05:09:04 volumio4 systemd[1]: Stopping Xbindrc... Dec 31 05:09:04 volumio4 systemd[1]: Starting Xbindrc... Dec 31 05:09:04 volumio4 systemd[1]: Started Xbindrc. Dec 31 05:09:04 volumio4 ifplugd(eth0)[667]: client: /sbin/ifup: interface eth0 already configured Dec 31 05:09:04 volumio4 ifplugd(eth0)[667]: Program executed successfully. Dec 31 05:09:05 volumio4 systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Dec 31 05:09:05 volumio4 systemd[1]: Stopping Xbindrc... Dec 31 05:09:05 volumio4 systemd[1]: Starting Xbindrc... Dec 31 05:09:05 volumio4 systemd[1]: Started Xbindrc. Dec 31 05:09:05 volumio4 dhcpcd[521]: eth0: offered 192.168.0.4 from 192.168.0.1 Dec 31 05:09:05 volumio4 ifup[499]: dhcpcd[521]: eth0: offered 192.168.0.4 from 192.168.0.1 Dec 31 05:09:05 volumio4 systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Dec 31 05:09:05 volumio4 systemd[1]: Stopping Xbindrc... Dec 31 05:09:05 volumio4 systemd[1]: Starting Xbindrc... Dec 31 05:09:05 volumio4 systemd[1]: Started Xbindrc. Dec 31 05:09:06 volumio4 systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Dec 31 05:09:06 volumio4 systemd[1]: Stopping Xbindrc... Dec 31 05:09:06 volumio4 systemd[1]: Starting Xbindrc... Dec 31 05:09:06 volumio4 systemd[1]: xbindkeysrc.service start request repeated too quickly, refusing to start. Dec 31 05:09:06 volumio4 systemd[1]: Failed to start Xbindrc. Dec 31 05:09:06 volumio4 systemd[1]: Unit xbindkeysrc.service entered failed state. Dec 31 05:09:06 volumio4 ntpd_intres[772]: host name not found: 0.debian.pool.ntp.org Dec 31 05:09:06 volumio4 ntpd_intres[772]: host name not found: 1.debian.pool.ntp.org Dec 31 05:09:06 volumio4 ntpd_intres[772]: host name not found: 2.debian.pool.ntp.org Dec 31 05:09:06 volumio4 ntpd_intres[772]: host name not found: 3.debian.pool.ntp.org Dec 31 05:09:07 volumio4 volumio-remote-updater[567]: [2025-12-31 05:09:07] [info] asio async_connect error: system:111 (Connection refused) Dec 31 05:09:07 volumio4 volumio-remote-updater[567]: [2025-12-31 05:09:07] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 31 05:09:07 volumio4 volumio-remote-updater[567]: [2025-12-31 05:09:07] [error] handle_connect error: Underlying Transport Error Dec 31 05:09:07 volumio4 systemd[1]: Started LSB: start Samba daemons for the AD DC. Dec 31 05:09:07 volumio4 wireless.js[733]: WIRELESS DAEMON: start Dec 31 05:09:07 volumio4 wireless.js[733]: WIRELESS: Loaded configuration Dec 31 05:09:07 volumio4 wireless.js[733]: Cleaning previous... Dec 31 05:09:07 volumio4 systemd[1]: Stopped hotspot.service. Dec 31 05:09:07 volumio4 winbind[591]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Dec 31 05:09:07 volumio4 winbind[591]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Dec 31 05:09:07 volumio4 winbind[591]: Unable to setup corepath for winbindd: No such file or directory Dec 31 05:09:07 volumio4 winbind[591]: mkdir failed on directory /var/log/samba/cores: No such file or directory Dec 31 05:09:07 volumio4 winbind[591]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Dec 31 05:09:07 volumio4 winbind[591]: Unable to setup corepath for winbindd: No such file or directory Dec 31 05:09:07 volumio4 winbind[591]: [2025/12/31 05:09:07, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:07 volumio4 winbind[591]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Dec 31 05:09:07 volumio4 winbind[591]: [2025/12/31 05:09:07, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:07 volumio4 winbind[591]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Dec 31 05:09:07 volumio4 winbind[591]: [2025/12/31 05:09:07, 0] ../source3/winbindd/winbindd.c:1549(main) Dec 31 05:09:07 volumio4 winbind[591]: winbindd version 4.2.14-Debian started. Dec 31 05:09:07 volumio4 winbind[591]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Dec 31 05:09:07 volumio4 winbind[591]: [2025/12/31 05:09:07, 0] ../lib/util/util.c:220(directory_create_or_exist) Dec 31 05:09:07 volumio4 winbind[591]: mkdir failed on directory /var/log/samba/cores: No such file or directory Dec 31 05:09:07 volumio4 winbind[591]: [2025/12/31 05:09:07, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Dec 31 05:09:07 volumio4 winbind[591]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Dec 31 05:09:07 volumio4 winbind[591]: [2025/12/31 05:09:07, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Dec 31 05:09:07 volumio4 winbind[591]: Unable to setup corepath for winbindd: No such file or directory Dec 31 05:09:07 volumio4 sudo[817]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 31 05:09:07 volumio4 winbindd[810]: [2025/12/31 05:09:07.935310, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:07 volumio4 winbindd[810]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Dec 31 05:09:07 volumio4 winbindd[810]: [2025/12/31 05:09:07.936695, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:07 volumio4 winbindd[810]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Dec 31 05:09:07 volumio4 nmbd[813]: [2025/12/31 05:09:07.941835, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:07 volumio4 nmbd[813]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Dec 31 05:09:07 volumio4 nmbd[813]: [2025/12/31 05:09:07.944078, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:07 volumio4 nmbd[813]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Dec 31 05:09:07 volumio4 sudo[817]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:09:07 volumio4 nmbd[821]: [2025/12/31 05:09:07.954225, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) Dec 31 05:09:07 volumio4 nmbd[821]: started asyncdns process 822 Dec 31 05:09:07 volumio4 nmbd[821]: [2025/12/31 05:09:07.960391, 0] ../lib/util/become_daemon.c:135(daemon_status) Dec 31 05:09:07 volumio4 nmbd[821]: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 31 05:09:07 volumio4 systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). Dec 31 05:09:07 volumio4 systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Dec 31 05:09:07 volumio4 systemd[1]: Started LSB: start Winbind daemon. Dec 31 05:09:07 volumio4 winbindd[825]: [2025/12/31 05:09:07.984116, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Dec 31 05:09:07 volumio4 winbindd[825]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 31 05:09:07 volumio4 sudo[817]: pam_unix(sudo:session): session closed for user root Dec 31 05:09:07 volumio4 winbind[591]: [2025/12/31 05:09:07.935310, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:07 volumio4 winbind[591]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Dec 31 05:09:07 volumio4 winbind[591]: [2025/12/31 05:09:07.936695, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:07 volumio4 winbind[591]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Dec 31 05:09:07 volumio4 nmbd[601]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Dec 31 05:09:07 volumio4 nmbd[601]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Dec 31 05:09:07 volumio4 nmbd[601]: Unable to setup corepath for nmbd: No such file or directory Dec 31 05:09:07 volumio4 nmbd[601]: [2025/12/31 05:09:07, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:07 volumio4 nmbd[601]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Dec 31 05:09:07 volumio4 nmbd[601]: [2025/12/31 05:09:07, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:07 volumio4 nmbd[601]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Dec 31 05:09:07 volumio4 nmbd[601]: [2025/12/31 05:09:07, 0] ../source3/nmbd/nmbd.c:908(main) Dec 31 05:09:08 volumio4 nmbd[601]: nmbd version 4.2.14-Debian started. Dec 31 05:09:08 volumio4 nmbd[601]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Dec 31 05:09:08 volumio4 nmbd[601]: [2025/12/31 05:09:07.941835, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:08 volumio4 nmbd[601]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Dec 31 05:09:08 volumio4 nmbd[601]: [2025/12/31 05:09:07.944078, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:08 volumio4 nmbd[601]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Dec 31 05:09:08 volumio4 winbind[591]: . Dec 31 05:09:08 volumio4 nmbd[601]: . Dec 31 05:09:08 volumio4 winbindd[825]: [2025/12/31 05:09:08.027912, 0] ../lib/util/become_daemon.c:124(daemon_ready) Dec 31 05:09:08 volumio4 winbindd[825]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 31 05:09:08 volumio4 winbindd[835]: [2025/12/31 05:09:08.038244, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:08 volumio4 winbindd[835]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO4': No such file or directory Dec 31 05:09:08 volumio4 sudo[834]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 31 05:09:08 volumio4 sudo[834]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:09:08 volumio4 sudo[834]: pam_unix(sudo:session): session closed for user root Dec 31 05:09:08 volumio4 wireless.js[733]: Stopped aP Dec 31 05:09:08 volumio4 wireless.js[733]: Start wireless flow Dec 31 05:09:08 volumio4 wireless.js[733]: Stopped hotspot (if there).. Dec 31 05:09:08 volumio4 sudo[850]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 31 05:09:08 volumio4 sudo[850]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:09:08 volumio4 sudo[850]: pam_unix(sudo:session): session closed for user root Dec 31 05:09:08 volumio4 sudo[858]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 31 05:09:08 volumio4 sudo[858]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:09:08 volumio4 sudo[858]: pam_unix(sudo:session): session closed for user root Dec 31 05:09:08 volumio4 wireless.js[733]: DHCP IP Dec 31 05:09:09 volumio4 wireless.js[733]: Start ap Dec 31 05:09:09 volumio4 sudo[869]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Dec 31 05:09:09 volumio4 sudo[869]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:09:09 volumio4 dhcpcd[876]: version 6.0.5 starting Dec 31 05:09:09 volumio4 kernel: brcmfmac: power management disabled Dec 31 05:09:09 volumio4 wpa_supplicant[868]: Successfully initialized wpa_supplicant Dec 31 05:09:09 volumio4 smbd[824]: Starting SMB/CIFS daemon: smbd[2025/12/31 05:09:09, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:09 volumio4 smbd[824]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 31 05:09:09 volumio4 smbd[824]: [2025/12/31 05:09:09, 0] ../lib/util/util.c:220(directory_create_or_exist) Dec 31 05:09:09 volumio4 smbd[824]: mkdir failed on directory /var/log/samba/cores: No such file or directory Dec 31 05:09:09 volumio4 smbd[824]: [2025/12/31 05:09:09, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Dec 31 05:09:09 volumio4 smbd[824]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Dec 31 05:09:09 volumio4 smbd[824]: [2025/12/31 05:09:09, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Dec 31 05:09:09 volumio4 smbd[824]: Unable to setup corepath for smbd: No such file or directory Dec 31 05:09:09 volumio4 smbd[824]: [2025/12/31 05:09:09, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:09 volumio4 smbd[824]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 31 05:09:09 volumio4 smbd[824]: [2025/12/31 05:09:09, 0] ../source3/smbd/server.c:1241(main) Dec 31 05:09:09 volumio4 smbd[824]: smbd version 4.2.14-Debian started. Dec 31 05:09:09 volumio4 smbd[824]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Dec 31 05:09:09 volumio4 smbd[863]: [2025/12/31 05:09:09.228431, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:09 volumio4 smbd[863]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 31 05:09:09 volumio4 smbd[863]: [2025/12/31 05:09:09.230142, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:09 volumio4 smbd[863]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 31 05:09:09 volumio4 smbd[863]: [2025/12/31 05:09:09.230457, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:09 volumio4 smbd[863]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 31 05:09:09 volumio4 smbd[824]: [2025/12/31 05:09:09.228431, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:09 volumio4 smbd[824]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 31 05:09:09 volumio4 smbd[824]: [2025/12/31 05:09:09.230142, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:09 volumio4 smbd[824]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 31 05:09:09 volumio4 smbd[824]: [2025/12/31 05:09:09.230457, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:09 volumio4 smbd[824]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 31 05:09:09 volumio4 smbd[824]: . Dec 31 05:09:09 volumio4 systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Dec 31 05:09:09 volumio4 dhcpcd[876]: eth0: soliciting an IPv6 router Dec 31 05:09:09 volumio4 dhcpcd[876]: eth0: soliciting a DHCP lease Dec 31 05:09:09 volumio4 dhcpcd[876]: wlan0: waiting for carrier Dec 31 05:09:09 volumio4 dhcpcd[876]: eth0: offered 192.168.0.4 from 192.168.0.1 Dec 31 05:09:09 volumio4 winbindd[910]: [2025/12/31 05:09:09.339616, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:09 volumio4 winbindd[910]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Dec 31 05:09:09 volumio4 winbindd[911]: [2025/12/31 05:09:09.452251, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:09 volumio4 winbindd[911]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory Dec 31 05:09:09 volumio4 smbd[901]: [2025/12/31 05:09:09.511623, 0] ../lib/util/become_daemon.c:124(daemon_ready) Dec 31 05:09:09 volumio4 smbd[901]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 31 05:09:09 volumio4 smbd[912]: [2025/12/31 05:09:09.520881, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:09 volumio4 smbd[912]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 31 05:09:09 volumio4 systemd[1]: Started Music Player Daemon. Dec 31 05:09:09 volumio4 udisks-glue[565]: Updating DB (#1) ... Dec 31 05:09:09 volumio4 udisks-glue[565]: volume: 5% repeat: off random: off single: off consume: off Dec 31 05:09:09 volumio4 dhcpcd[521]: eth0: leased 192.168.0.4 for 14400 seconds Dec 31 05:09:09 volumio4 dhcpcd[521]: eth0: adding host route to 192.168.0.4 via 127.0.0.1 Dec 31 05:09:09 volumio4 dhcpcd[521]: eth0: adding route to 192.168.0.0/24 Dec 31 05:09:09 volumio4 avahi-daemon[620]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.4. Dec 31 05:09:09 volumio4 avahi-daemon[620]: New relevant interface eth0.IPv4 for mDNS. Dec 31 05:09:09 volumio4 dhcpcd[521]: eth0: adding default route via 192.168.0.1 Dec 31 05:09:09 volumio4 avahi-daemon[620]: Registering new address record for 192.168.0.4 on eth0.IPv4. Dec 31 05:09:09 volumio4 ifup[499]: dhcpcd[521]: eth0: leased 192.168.0.4 for 14400 seconds Dec 31 05:09:09 volumio4 ifup[499]: dhcpcd[521]: eth0: adding host route to 192.168.0.4 via 127.0.0.1 Dec 31 05:09:09 volumio4 ifup[499]: dhcpcd[521]: eth0: adding route to 192.168.0.0/24 Dec 31 05:09:09 volumio4 ifup[499]: dhcpcd[521]: eth0: adding default route via 192.168.0.1 Dec 31 05:09:09 volumio4 dhcpcd[521]: forked to background, child pid 937 Dec 31 05:09:09 volumio4 ifup[499]: dhcpcd[521]: forked to background, child pid 937 Dec 31 05:09:10 volumio4 wireless.js[733]: trying... Dec 31 05:09:10 volumio4 sudo[949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 31 05:09:10 volumio4 sudo[949]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:09:10 volumio4 sudo[949]: pam_unix(sudo:session): session closed for user root Dec 31 05:09:10 volumio4 systemd[1]: Reloading OpenBSD Secure Shell server. Dec 31 05:09:10 volumio4 sshd[693]: Received SIGHUP; restarting. Dec 31 05:09:10 volumio4 systemd[1]: Reloaded OpenBSD Secure Shell server. Dec 31 05:09:10 volumio4 sshd[693]: Server listening on 0.0.0.0 port 22. Dec 31 05:09:10 volumio4 sshd[693]: Server listening on :: port 22. Dec 31 05:09:11 volumio4 wireless.js[733]: trying... Dec 31 05:09:11 volumio4 sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 31 05:09:11 volumio4 sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:09:11 volumio4 ntpd[688]: Listen normally on 3 eth0 192.168.0.4 UDP 123 Dec 31 05:09:11 volumio4 ntpd[688]: peers refreshed Dec 31 05:09:11 volumio4 sudo[1024]: pam_unix(sudo:session): session closed for user root Dec 31 05:09:11 volumio4 wpa_supplicant[908]: wlan0: Trying to associate with SSID 'aterm-6ce489-a' Dec 31 05:09:12 volumio4 wireless.js[733]: trying... Dec 31 05:09:12 volumio4 sudo[1033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 31 05:09:12 volumio4 sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:09:12 volumio4 sudo[1033]: pam_unix(sudo:session): session closed for user root Dec 31 05:09:12 volumio4 smbd[1041]: [2025/12/31 05:09:12.353869, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:09:12 volumio4 smbd[1041]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 31 05:09:12 volumio4 nmbd[821]: [2025/12/31 05:09:12.967835, 0] ../lib/util/become_daemon.c:124(daemon_ready) Dec 31 05:09:12 volumio4 nmbd[821]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 31 05:09:13 volumio4 ntpd_intres[772]: DNS 0.debian.pool.ntp.org -> 162.159.200.123 Dec 31 05:09:13 volumio4 wireless.js[733]: trying... Dec 31 05:09:13 volumio4 sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 31 05:09:13 volumio4 ntpd_intres[772]: DNS 1.debian.pool.ntp.org -> 129.250.35.251 Dec 31 05:09:13 volumio4 ntpd_intres[772]: DNS 2.debian.pool.ntp.org -> 139.64.134.57 Dec 31 05:09:13 volumio4 sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:09:13 volumio4 sudo[1044]: pam_unix(sudo:session): session closed for user root Dec 31 05:09:13 volumio4 ntpd_intres[772]: DNS 3.debian.pool.ntp.org -> 160.25.135.55 Dec 31 05:09:14 volumio4 wireless.js[733]: trying... Dec 31 05:09:14 volumio4 sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 31 05:09:14 volumio4 sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:09:14 volumio4 sudo[1053]: pam_unix(sudo:session): session closed for user root Dec 31 05:09:14 volumio4 wpa_supplicant[908]: wlan0: Associated with c2:25:a2:c9:fe:e6 Dec 31 05:09:14 volumio4 wpa_supplicant[908]: wlan0: WPA: Key negotiation completed with c2:25:a2:c9:fe:e6 [PTK=CCMP GTK=CCMP] Dec 31 05:09:14 volumio4 wpa_supplicant[908]: wlan0: CTRL-EVENT-CONNECTED - Connection to c2:25:a2:c9:fe:e6 completed [id=0 id_str=] Dec 31 05:09:14 volumio4 dhcpcd[876]: wlan0: carrier acquired Dec 31 05:09:14 volumio4 wpa_supplicant[908]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Dec 31 05:09:14 volumio4 dhcpcd[876]: wlan0: soliciting an IPv6 router Dec 31 05:09:14 volumio4 dhcpcd[876]: wlan0: soliciting a DHCP lease Dec 31 05:09:14 volumio4 volumio-remote-updater[567]: [2025-12-31 05:09:14] [info] asio async_connect error: system:111 (Connection refused) Dec 31 05:09:14 volumio4 volumio-remote-updater[567]: [2025-12-31 05:09:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 31 05:09:14 volumio4 volumio-remote-updater[567]: [2025-12-31 05:09:14] [error] handle_connect error: Underlying Transport Error Dec 31 05:09:15 volumio4 dhcpcd[876]: eth0: leased 192.168.0.4 for 14400 seconds Dec 31 05:09:15 volumio4 dhcpcd[876]: eth0: adding host route to 192.168.0.4 via 127.0.0.1 Dec 31 05:09:15 volumio4 dhcpcd[876]: eth0: adding route to 192.168.0.0/24 Dec 31 05:09:15 volumio4 dhcpcd[937]: eth0: removing route to 192.168.0.0/24 Dec 31 05:09:15 volumio4 dhcpcd[876]: eth0: adding default route via 192.168.0.1 Dec 31 05:09:15 volumio4 dhcpcd[937]: eth0: removing default route via 192.168.0.1 Dec 31 05:09:15 volumio4 dhcpcd[876]: forked to background, child pid 1100 Dec 31 05:09:15 volumio4 sudo[869]: pam_unix(sudo:session): session closed for user root Dec 31 05:09:15 volumio4 dhcpcd[1100]: eth0: removing route to 192.168.0.0/24 Dec 31 05:09:15 volumio4 dhcpcd[1100]: eth0: removing default route via 192.168.0.1 Dec 31 05:09:15 volumio4 wireless.js[733]: trying... Dec 31 05:09:15 volumio4 sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 31 05:09:15 volumio4 sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:09:15 volumio4 sudo[1105]: pam_unix(sudo:session): session closed for user root Dec 31 05:09:15 volumio4 dhcpcd[1100]: wlan0: offered 192.168.0.20 from 192.168.0.1 Dec 31 05:09:16 volumio4 wireless.js[733]: trying... Dec 31 05:09:16 volumio4 sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 31 05:09:16 volumio4 sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:09:16 volumio4 sudo[1114]: pam_unix(sudo:session): session closed for user root Dec 31 05:09:17 volumio4 wireless.js[733]: trying... Dec 31 05:09:17 volumio4 sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 31 05:09:17 volumio4 sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:09:17 volumio4 sudo[1123]: pam_unix(sudo:session): session closed for user root Dec 31 05:09:18 volumio4 wireless.js[733]: trying... Dec 31 05:09:18 volumio4 sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 31 05:09:18 volumio4 sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:09:18 volumio4 sudo[1132]: pam_unix(sudo:session): session closed for user root Dec 31 05:09:19 volumio4 wireless.js[733]: trying... Dec 31 05:09:19 volumio4 sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 31 05:09:19 volumio4 sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:09:19 volumio4 sudo[1141]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:08 volumio4 systemd[1]: Time has been changed Dec 31 05:10:09 volumio4 dhcpcd[1100]: wlan0: leased 192.168.0.20 for 14400 seconds Dec 31 05:10:09 volumio4 avahi-daemon[620]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.20. Dec 31 05:10:09 volumio4 avahi-daemon[620]: New relevant interface wlan0.IPv4 for mDNS. Dec 31 05:10:09 volumio4 dhcpcd[1100]: eth0: adding route to 192.168.0.0/24 Dec 31 05:10:09 volumio4 avahi-daemon[620]: Registering new address record for 192.168.0.20 on wlan0.IPv4. Dec 31 05:10:09 volumio4 dhcpcd[1100]: eth0: adding default route via 192.168.0.1 Dec 31 05:10:09 volumio4 dhcpcd[1100]: wlan0: adding host route to 192.168.0.20 via 127.0.0.1 Dec 31 05:10:09 volumio4 dhcpcd[1100]: wlan0: adding route to 192.168.0.0/24 Dec 31 05:10:09 volumio4 dhcpcd[1100]: wlan0: adding default route via 192.168.0.1 Dec 31 05:10:09 volumio4 wireless.js[733]: trying... Dec 31 05:10:09 volumio4 sudo[1158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 31 05:10:09 volumio4 sudo[1158]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:09 volumio4 sudo[1158]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:09 volumio4 wireless.js[733]: Connected to: ----aterm-6ce489-a Dec 31 05:10:09 volumio4 wireless.js[733]: ---- Dec 31 05:10:09 volumio4 sudo[1180]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 31 05:10:09 volumio4 sudo[1180]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:09 volumio4 sudo[1180]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:09 volumio4 dhcpcd[1100]: eth0: removing route to 192.168.0.0/24 Dec 31 05:10:09 volumio4 dhcpcd[1100]: eth0: removing default route via 192.168.0.1 Dec 31 05:10:09 volumio4 wireless.js[733]: ... joined AP, wlan0 IPv4 is 192.168.0.20, ipV6 is undefined Dec 31 05:10:09 volumio4 wireless.js[733]: It's done! AP Dec 31 05:10:09 volumio4 systemd[1]: Started Wireless Services. Dec 31 05:10:09 volumio4 systemd[1]: Starting Volumio Backend Module... Dec 31 05:10:09 volumio4 systemd[1]: Started Volumio Backend Module. Dec 31 05:10:09 volumio4 systemd[1]: Starting Volumio Streaming Daemon... Dec 31 05:10:09 volumio4 systemd[1]: Started Volumio Streaming Daemon. Dec 31 05:10:09 volumio4 systemd[1]: Starting Multi-User System. Dec 31 05:10:09 volumio4 systemd[1]: Reached target Multi-User System. Dec 31 05:10:09 volumio4 systemd[1]: Starting Graphical Interface. Dec 31 05:10:09 volumio4 systemd[1]: Reached target Graphical Interface. Dec 31 05:10:09 volumio4 systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 31 05:10:09 volumio4 systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 31 05:10:09 volumio4 systemd[1]: Startup finished in 8.781s (kernel) + 25.767s (userspace) = 34.549s. Dec 31 05:10:10 volumio4 volumio-streaming-daemon[1190]: ############################ Dec 31 05:10:10 volumio4 volumio-streaming-daemon[1190]: # Volumio Streaming Daemon # Dec 31 05:10:10 volumio4 volumio-streaming-daemon[1190]: # Running on port 7777 # Dec 31 05:10:10 volumio4 volumio-streaming-daemon[1190]: ############################ Dec 31 05:10:10 volumio4 ntpd[688]: Listen normally on 4 wlan0 192.168.0.20 UDP 123 Dec 31 05:10:10 volumio4 ntpd[688]: peers refreshed Dec 31 05:10:11 volumio4 smbd[1204]: [2025/12/31 05:10:11.910970, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 31 05:10:11 volumio4 smbd[1204]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 31 05:10:13 volumio4 volumio[1189]: info: ------------------------------------------- Dec 31 05:10:13 volumio4 volumio[1189]: info: ----- Volumio2 ---- Dec 31 05:10:13 volumio4 volumio[1189]: info: ------------------------------------------- Dec 31 05:10:13 volumio4 volumio[1189]: info: ----- System startup ---- Dec 31 05:10:13 volumio4 volumio[1189]: info: ------------------------------------------- Dec 31 05:10:13 volumio4 volumio[1189]: info: MYVOLUMIO Environment detected Dec 31 05:10:13 volumio4 volumio-remote-updater[567]: [2025-12-31 05:10:13] [connect] Successful connection Dec 31 05:10:15 volumio4 volumio[1189]: info: Plugin folders cleanup Dec 31 05:10:15 volumio4 volumio[1189]: info: Scanning into folder /volumio/app/plugins/ Dec 31 05:10:15 volumio4 volumio[1189]: info: Scanning category audio_interface Dec 31 05:10:15 volumio4 volumio[1189]: info: Scanning category miscellanea Dec 31 05:10:15 volumio4 volumio[1189]: info: Scanning category music_service Dec 31 05:10:15 volumio4 volumio[1189]: info: Scanning category plugins.json Dec 31 05:10:15 volumio4 volumio[1189]: info: Scanning category system_controller Dec 31 05:10:15 volumio4 volumio[1189]: info: Scanning category user_interface Dec 31 05:10:15 volumio4 volumio[1189]: info: Scanning into folder /data/plugins/ Dec 31 05:10:15 volumio4 volumio[1189]: info: Scanning category music_service Dec 31 05:10:15 volumio4 volumio[1189]: info: Plugin folders cleanup completed Dec 31 05:10:15 volumio4 volumio[1189]: info: ------------------------------------------- Dec 31 05:10:15 volumio4 volumio[1189]: info: ----- Core plugins startup ---- Dec 31 05:10:15 volumio4 volumio[1189]: info: ------------------------------------------- Dec 31 05:10:15 volumio4 volumio[1189]: info: Loading plugins from folder /volumio/app/plugins/ Dec 31 05:10:15 volumio4 volumio[1189]: info: Adding plugin upnp to MyMusic Plugins Dec 31 05:10:15 volumio4 volumio[1189]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 31 05:10:15 volumio4 volumio[1189]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 31 05:10:15 volumio4 volumio[1189]: info: Loading plugins from folder /data/plugins/ Dec 31 05:10:15 volumio4 volumio[1189]: info: Loading plugin "system"... Dec 31 05:10:15 volumio4 volumio[1189]: info: Loading plugin "appearance"... Dec 31 05:10:16 volumio4 volumio[1189]: info: Loading plugin "network"... Dec 31 05:10:17 volumio4 volumio[1189]: info: Loading plugin "services"... Dec 31 05:10:17 volumio4 volumio[1189]: info: Loading plugin "alsa_controller"... Dec 31 05:10:17 volumio4 volumio[1189]: info: Loading plugin "wizard"... Dec 31 05:10:17 volumio4 volumio[1189]: info: Loading plugin "volumio_command_line_client"... Dec 31 05:10:17 volumio4 volumio[1189]: info: Loading plugin "upnp"... Dec 31 05:10:17 volumio4 volumio[1189]: info: [1767157817655] Starting Upmpd Daemon Dec 31 05:10:17 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 31 05:10:17 volumio4 volumio[1189]: info: Loading plugin "my_music"... Dec 31 05:10:17 volumio4 volumio[1189]: info: Loading plugin "mpd"... Dec 31 05:10:18 volumio4 sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 31 05:10:18 volumio4 volumio[1189]: info: Loading plugin "upnp_browser"... Dec 31 05:10:18 volumio4 sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:18 volumio4 sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 31 05:10:18 volumio4 sudo[1223]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:18 volumio4 sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:18 volumio4 systemd[1]: Stopping Music Player Daemon... Dec 31 05:10:18 volumio4 systemd[1]: Starting Music Player Daemon... Dec 31 05:10:19 volumio4 volumio[1189]: info: Loading plugin "networkfs"... Dec 31 05:10:19 volumio4 sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=roadstar,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //LS210D9A3/share\FLAC /mnt/NAS/FLAC_4T Dec 31 05:10:19 volumio4 sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:19 volumio4 sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //LS210D9A3/Music\from_AD850 /mnt/NAS/from_AD850 Dec 31 05:10:19 volumio4 sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:19 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 31 05:10:19 volumio4 volumio[1189]: info: Loading plugin "alarm-clock"... Dec 31 05:10:19 volumio4 sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //LS210D9A3/Music\Streaming /mnt/NAS/Streaming Dec 31 05:10:19 volumio4 sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=roadstar,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //LS210D9A3/Radio_data /mnt/NAS/Radio_data Dec 31 05:10:19 volumio4 sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:19 volumio4 sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //VOLUMIO/usb\HD-LBU3\FLAC /mnt/NAS/USB Dec 31 05:10:19 volumio4 sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:19 volumio4 sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:19 volumio4 kernel: FS-Cache: Netfs 'cifs' registered for caching Dec 31 05:10:19 volumio4 kernel: Key type cifs.spnego registered Dec 31 05:10:19 volumio4 kernel: Key type cifs.idmap registered Dec 31 05:10:19 volumio4 kernel: FS-Cache: Duplicate cookie detected Dec 31 05:10:19 volumio4 kernel: FS-Cache: O-cookie c=928e4ae2 [p=06429139 fl=222 nc=0 na=1] Dec 31 05:10:19 volumio4 kernel: FS-Cache: O-cookie d=43d5b38f n=b9a05d7d Dec 31 05:10:19 volumio4 kernel: FS-Cache: O-key=[8] '020001bdc0a80013' Dec 31 05:10:19 volumio4 kernel: FS-Cache: N-cookie c=0dbc5f62 [p=06429139 fl=2 nc=0 na=1] Dec 31 05:10:19 volumio4 kernel: FS-Cache: N-cookie d=43d5b38f n=8cd2698f Dec 31 05:10:19 volumio4 kernel: FS-Cache: N-key=[8] '020001bdc0a80013' Dec 31 05:10:19 volumio4 kernel: FS-Cache: Duplicate cookie detected Dec 31 05:10:19 volumio4 kernel: FS-Cache: O-cookie c=928e4ae2 [p=06429139 fl=222 nc=0 na=1] Dec 31 05:10:19 volumio4 kernel: FS-Cache: O-cookie d=43d5b38f n=b9a05d7d Dec 31 05:10:19 volumio4 kernel: FS-Cache: O-key=[8] '020001bdc0a80013' Dec 31 05:10:19 volumio4 kernel: FS-Cache: N-cookie c=4f1df49b [p=06429139 fl=2 nc=0 na=1] Dec 31 05:10:19 volumio4 kernel: FS-Cache: N-cookie d=43d5b38f n=375a3770 Dec 31 05:10:19 volumio4 kernel: FS-Cache: N-key=[8] '020001bdc0a80013' Dec 31 05:10:19 volumio4 kernel: FS-Cache: Duplicate cookie detected Dec 31 05:10:19 volumio4 kernel: FS-Cache: O-cookie c=928e4ae2 [p=06429139 fl=222 nc=0 na=1] Dec 31 05:10:19 volumio4 kernel: FS-Cache: O-cookie d=43d5b38f n=b9a05d7d Dec 31 05:10:19 volumio4 kernel: FS-Cache: O-key=[8] '020001bdc0a80013' Dec 31 05:10:19 volumio4 kernel: FS-Cache: N-cookie c=4f1df49b [p=06429139 fl=2 nc=0 na=1] Dec 31 05:10:19 volumio4 kernel: FS-Cache: N-cookie d=43d5b38f n=803180f3 Dec 31 05:10:19 volumio4 kernel: FS-Cache: N-key=[8] '020001bdc0a80013' Dec 31 05:10:20 volumio4 volumio[1189]: info: Loading plugin "airplay_emulation"... Dec 31 05:10:20 volumio4 volumio[1189]: info: Starting Shairport Sync Dec 31 05:10:20 volumio4 volumio[1189]: info: Loading plugin "last_100"... Dec 31 05:10:20 volumio4 volumio[1189]: info: Loading plugin "webradio"... Dec 31 05:10:20 volumio4 sudo[1252]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:20 volumio4 volumio[1189]: info: Loading plugin "i2s_dacs"... Dec 31 05:10:20 volumio4 sudo[1242]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:20 volumio4 volumio[1189]: info: I2S DAC not set, start Auto-detection Dec 31 05:10:20 volumio4 sudo[1248]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:20 volumio4 sudo[1240]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:20 volumio4 sudo[1257]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:20 volumio4 volumio[1189]: info: Loading plugin "volumiodiscovery"... Dec 31 05:10:20 volumio4 volumio[1189]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 31 05:10:20 volumio4 node[1189]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 31 05:10:20 volumio4 node[1189]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 31 05:10:20 volumio4 node[1189]: *** WARNING *** For more information see Dec 31 05:10:20 volumio4 node[1189]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 31 05:10:20 volumio4 node[1189]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 31 05:10:20 volumio4 node[1189]: *** WARNING *** For more information see Dec 31 05:10:20 volumio4 volumio[1189]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 31 05:10:20 volumio4 volumio[1189]: *** WARNING *** For more information see Dec 31 05:10:20 volumio4 volumio[1189]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 31 05:10:20 volumio4 volumio[1189]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 31 05:10:20 volumio4 volumio[1189]: *** WARNING *** For more information see Dec 31 05:10:20 volumio4 volumio[1189]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 31 05:10:20 volumio4 volumio[1189]: Discovery: StartAdv! undefined Dec 31 05:10:20 volumio4 volumio[1189]: Discovery: Started advertising... Volumio4 - undefined Dec 31 05:10:20 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 31 05:10:20 volumio4 volumio[1189]: info: Loading plugin "outputs"... Dec 31 05:10:20 volumio4 volumio[1189]: info: Loading plugin "albumart"... Dec 31 05:10:20 volumio4 volumio[1189]: info: Plugin example_plugin is not enabled Dec 31 05:10:20 volumio4 volumio[1189]: info: Loading plugin "inputs"... Dec 31 05:10:20 volumio4 volumio[1189]: info: Loading plugin "updater_comm"... Dec 31 05:10:20 volumio4 volumio[1189]: info: Plugin mpdemulation is not enabled Dec 31 05:10:21 volumio4 volumio[1189]: info: Loading plugin "rest_api"... Dec 31 05:10:21 volumio4 volumio[1189]: info: Loading plugin "websocket"... Dec 31 05:10:21 volumio4 volumio[1189]: info: Loading plugin "lms"... Dec 31 05:10:21 volumio4 volumio[1189]: Forking 3 albumart workers Dec 31 05:10:21 volumio4 volumio[1189]: info: Loading plugin "squeezelite"... Dec 31 05:10:22 volumio4 volumio[1189]: info: Squeezelite initiated Dec 31 05:10:22 volumio4 volumio[1189]: info: Loading plugin "volspotconnect2"... Dec 31 05:10:22 volumio4 volumio[1189]: Starting albumart workers Dec 31 05:10:22 volumio4 volumio[1189]: Starting albumart workers Dec 31 05:10:22 volumio4 volumio[1189]: Starting albumart workers Dec 31 05:10:23 volumio4 volumio[1189]: info: ___________ START PLUGINS ___________ Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 31 05:10:23 volumio4 volumio[1189]: info: [1767157823509] CoreMusicLibrary::Adding element Media Servers Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 31 05:10:23 volumio4 volumio[1189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 31 05:10:23 volumio4 volumio[1189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 31 05:10:23 volumio4 volumio[1189]: info: [1767157823691] CoreMusicLibrary::Adding element Last_100 Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 31 05:10:23 volumio4 volumio[1189]: info: [1767157823695] CoreMusicLibrary::Adding element Webradio Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 31 05:10:23 volumio4 sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Dec 31 05:10:23 volumio4 volumio[1189]: [SpotifyConnect] Creating VLS config file Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 05:10:23 volumio4 volumio[1189]: [SpotifyConnect] Starting metadata listener Dec 31 05:10:23 volumio4 sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:23 volumio4 sudo[1438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 31 05:10:23 volumio4 sudo[1438]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:23 volumio4 volumio[1189]: info: ------------------------------------------- Dec 31 05:10:23 volumio4 volumio[1189]: info: ----- MyVolumio plugins startup ---- Dec 31 05:10:23 volumio4 volumio[1189]: info: ------------------------------------------- Dec 31 05:10:23 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 31 05:10:23 volumio4 systemd[1]: Starting Logitech Media Server Daemon... Dec 31 05:10:23 volumio4 volumio[1189]: info: Loading i18n strings for locale ja Dec 31 05:10:23 volumio4 sudo[1446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Dec 31 05:10:23 volumio4 volumio[1189]: Updating browse sources language Dec 31 05:10:23 volumio4 volumio[1189]: Cannot find translation for sourceMedia Servers Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 31 05:10:23 volumio4 sudo[1446]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::initPlayerControls Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 05:10:23 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 05:10:24 volumio4 volumio[1189]: info: BOOT COMPLETED Dec 31 05:10:24 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 31 05:10:24 volumio4 volumio[1189]: info: CoreCommandRouter::Close All Modals sent Dec 31 05:10:24 volumio4 volumio[1189]: info: CoreCommandRouter::Close All Modals sent Dec 31 05:10:24 volumio4 volumio[1189]: Express server listening on port 3000 Dec 31 05:10:24 volumio4 volumio[1189]: Volumio Calling Home Dec 31 05:10:24 volumio4 systemd[1]: Started Logitech Media Server Daemon. Dec 31 05:10:24 volumio4 sudo[1436]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:24 volumio4 systemd[1]: Reloading. Dec 31 05:10:24 volumio4 volumio[1189]: info: CoreStateMachine::resetVolumioState Dec 31 05:10:24 volumio4 volumio[1189]: info: CoreStateMachine::getcurrentVolume Dec 31 05:10:24 volumio4 volumio[1189]: info: CoreCommandRouter::volumioRetrievevolume Dec 31 05:10:24 volumio4 systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Dec 31 05:10:24 volumio4 systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Dec 31 05:10:24 volumio4 systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Dec 31 05:10:24 volumio4 systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Dec 31 05:10:24 volumio4 sudo[1438]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:24 volumio4 systemd[1]: Starting Volspotconnect2 Daemon... Dec 31 05:10:24 volumio4 systemd[1]: Started Volspotconnect2 Daemon. Dec 31 05:10:24 volumio4 sudo[1446]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:24 volumio4 volumio[1489]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Dec 31 05:10:24 volumio4 volumio[1489]: Reading Config from "volspotify.toml" Dec 31 05:10:24 volumio4 volumio[1489]: [Vollibrespot] : Using Alsa backend with device: plughw:5 Dec 31 05:10:24 volumio4 volumio[1489]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Dec 31 05:10:24 volumio4 volumio[1189]: info: Setting Device type: Raspberry PI Dec 31 05:10:24 volumio4 volumio[1189]: info: logitechmediaserver started Dec 31 05:10:24 volumio4 volumio[1189]: info: Cannot mount NAS USB at system boot, trial number 1 ,retrying in 5 seconds Dec 31 05:10:24 volumio4 volumio[1189]: info: MPD Permissions set Dec 31 05:10:24 volumio4 sudo[1496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Dec 31 05:10:25 volumio4 sudo[1496]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:25 volumio4 sudo[1496]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:25 volumio4 volumio[1189]: info: VolumeController:: Volume=20 Mute =false Dec 31 05:10:25 volumio4 volumio[1189]: info: CoreStateMachine::pushState Dec 31 05:10:25 volumio4 volumio[1189]: info: CoreStateMachine::getState Dec 31 05:10:25 volumio4 volumio[1189]: info: CorePlayQueue::getTrack 0 Dec 31 05:10:25 volumio4 volumio[1189]: info: CoreCommandRouter::volumioPushState Dec 31 05:10:25 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 31 05:10:25 volumio4 volumio[1189]: info: CoreStateMachine::updateTrackBlock Dec 31 05:10:25 volumio4 volumio[1189]: info: CorePlayQueue::getTrackBlock Dec 31 05:10:25 volumio4 volumio[1189]: info: CoreCommandRouter::volumioRetrievevolume Dec 31 05:10:25 volumio4 volumio[1189]: [SpotifyConnect] Vollibrespot Daemon service started! Dec 31 05:10:25 volumio4 volumio[1189]: SpotifyConnect: 1519.457ms Dec 31 05:10:25 volumio4 volumio[1189]: info: squeezelite reloaded Dec 31 05:10:25 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 31 05:10:25 volumio4 sudo[1506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart squeezelite Dec 31 05:10:25 volumio4 sudo[1506]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:25 volumio4 systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Dec 31 05:10:25 volumio4 systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Dec 31 05:10:25 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 31 05:10:25 volumio4 sudo[1506]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:25 volumio4 volumio[1189]: info: No input data Dec 31 05:10:25 volumio4 volumio[1189]: info: Dec 31 05:10:25 volumio4 volumio[1189]: ---------------------------- Client requests Volume 5 Dec 31 05:10:25 volumio4 volumio[1189]: info: VolumeController::SetAlsaVolume5 Dec 31 05:10:25 volumio4 volumio[1189]: info: CoreStateMachine::pushState Dec 31 05:10:25 volumio4 volumio[1189]: info: CoreStateMachine::getState Dec 31 05:10:25 volumio4 volumio[1189]: info: CorePlayQueue::getTrack 0 Dec 31 05:10:25 volumio4 volumio[1189]: info: CoreCommandRouter::volumioPushState Dec 31 05:10:25 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 31 05:10:26 volumio4 volumio[1189]: info: squeezelite started Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 31 05:10:26 volumio4 volumio[1189]: info: Reloading queue from file Dec 31 05:10:26 volumio4 volumio[1189]: info: Setting volume on startup at 5 Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreStateMachine::setRepeat true single undefined Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreStateMachine::pushState Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreStateMachine::getState Dec 31 05:10:26 volumio4 volumio[1189]: info: CorePlayQueue::getTrack 0 Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreCommandRouter::volumioPushState Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreStateMachine::setRandom true Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreStateMachine::pushState Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreStateMachine::getState Dec 31 05:10:26 volumio4 volumio[1189]: info: CorePlayQueue::getTrack 0 Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreCommandRouter::volumioPushState Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 31 05:10:26 volumio4 volumio[1189]: info: VolumeController:: Volume=20 Mute =false Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreStateMachine::pushState Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreStateMachine::getState Dec 31 05:10:26 volumio4 volumio[1189]: info: CorePlayQueue::getTrack 0 Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreCommandRouter::volumioPushState Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreCommandRouter::volumioGetState Dec 31 05:10:26 volumio4 volumio[1189]: info: CoreStateMachine::getState Dec 31 05:10:26 volumio4 volumio[1189]: info: CorePlayQueue::getTrack 0 Dec 31 05:10:26 volumio4 volumio[1189]: info: Starting Shairport Sync Dec 31 05:10:26 volumio4 volumio[1189]: info: Starting Shairport Sync Dec 31 05:10:26 volumio4 sudo[1530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 31 05:10:26 volumio4 sudo[1530]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:26 volumio4 sudo[1532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 31 05:10:26 volumio4 sudo[1532]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:26 volumio4 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Dec 31 05:10:26 volumio4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 31 05:10:26 volumio4 sudo[1530]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:26 volumio4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 31 05:10:26 volumio4 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Dec 31 05:10:26 volumio4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 31 05:10:26 volumio4 sudo[1532]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:26 volumio4 sudo[1550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 31 05:10:26 volumio4 volumio[1189]: Discovery: adding 05cf207e-851f-40f7-bd13-7736f7df1613 Dec 31 05:10:26 volumio4 volumio[1189]: info: mDNS: Found device Volumio2 Dec 31 05:10:26 volumio4 sudo[1550]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:26 volumio4 sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 31 05:10:26 volumio4 sudo[1550]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:27 volumio4 sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:27 volumio4 volumio[1189]: Discovery: this is already registered, 05cf207e-851f-40f7-bd13-7736f7df1613 Dec 31 05:10:27 volumio4 volumio[1189]: info: mDNS: Found device Volumio2 Dec 31 05:10:27 volumio4 sudo[1561]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:27 volumio4 volumio[1189]: Discovery: adding 17fa8bd9-fb23-4a8d-8b33-7deb790f8012 Dec 31 05:10:27 volumio4 volumio[1189]: info: mDNS: Found device Volumio4 Dec 31 05:10:27 volumio4 sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 31 05:10:27 volumio4 volumio[1189]: info: Shairport-Sync Started Dec 31 05:10:27 volumio4 volumio[1189]: Error adding Membership: Error: addMembership EINVAL Dec 31 05:10:27 volumio4 volumio[1189]: info: Shairport-Sync Started Dec 31 05:10:27 volumio4 sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:27 volumio4 sudo[1579]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:27 volumio4 volumio[1189]: Discovery: this is already registered, 17fa8bd9-fb23-4a8d-8b33-7deb790f8012 Dec 31 05:10:27 volumio4 volumio[1189]: info: mDNS: Found device Volumio4 Dec 31 05:10:27 volumio4 sudo[1588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 31 05:10:27 volumio4 sudo[1588]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:27 volumio4 sudo[1588]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:27 volumio4 volumio[1189]: info: CoreCommandRouter::volumioGetVisibleSources Dec 31 05:10:27 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 31 05:10:27 volumio4 volumio[1189]: info: CoreCommandRouter::volumioGetState Dec 31 05:10:27 volumio4 volumio[1189]: info: CoreStateMachine::getState Dec 31 05:10:27 volumio4 volumio[1189]: info: CorePlayQueue::getTrack 0 Dec 31 05:10:27 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 31 05:10:27 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 05:10:27 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 31 05:10:27 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 31 05:10:27 volumio4 sudo[1597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 31 05:10:27 volumio4 sudo[1597]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:27 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 31 05:10:27 volumio4 volumio[1189]: info: Listing playlists Dec 31 05:10:27 volumio4 sudo[1602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 31 05:10:27 volumio4 squeezeboxserver[1466]: Your locale was detected as C, you may have problems with non-Latin filenames. Consider changing your LANG variable to the correct locale, i.e. en_US.utf8 Dec 31 05:10:27 volumio4 sudo[1597]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:27 volumio4 sudo[1602]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:27 volumio4 sudo[1602]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:27 volumio4 sudo[1611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 31 05:10:27 volumio4 sudo[1611]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:27 volumio4 sudo[1611]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:27 volumio4 sudo[1622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 31 05:10:27 volumio4 sudo[1618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 31 05:10:27 volumio4 sudo[1618]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:27 volumio4 sudo[1622]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:27 volumio4 volumio[1189]: info: CoreCommandRouter::volumioGetState Dec 31 05:10:27 volumio4 volumio[1189]: info: CoreStateMachine::getState Dec 31 05:10:27 volumio4 volumio[1189]: info: CorePlayQueue::getTrack 0 Dec 31 05:10:27 volumio4 sudo[1622]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:27 volumio4 sudo[1618]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:28 volumio4 sudo[1642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 31 05:10:28 volumio4 sudo[1642]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:28 volumio4 sudo[1642]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:28 volumio4 sudo[1651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 31 05:10:28 volumio4 sudo[1651]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:28 volumio4 systemd[1]: Starting UPnP Renderer front-end to MPD... Dec 31 05:10:28 volumio4 systemd[1]: Started UPnP Renderer front-end to MPD. Dec 31 05:10:28 volumio4 sudo[1651]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:28 volumio4 volumio[1189]: info: Upmpdcli Daemon Started Dec 31 05:10:28 volumio4 volumio[1189]: info: CoreCommandRouter::volumioGetVisibleSources Dec 31 05:10:28 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 31 05:10:28 volumio4 volumio[1189]: info: CoreCommandRouter::volumioGetState Dec 31 05:10:28 volumio4 volumio[1189]: info: CoreStateMachine::getState Dec 31 05:10:28 volumio4 volumio[1189]: info: CorePlayQueue::getTrack 0 Dec 31 05:10:28 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 31 05:10:28 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 31 05:10:28 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 31 05:10:28 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 31 05:10:28 volumio4 sudo[1661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 31 05:10:28 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 31 05:10:28 volumio4 volumio[1189]: info: Listing playlists Dec 31 05:10:28 volumio4 sudo[1661]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:28 volumio4 sudo[1661]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:28 volumio4 sudo[1664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 31 05:10:28 volumio4 volumio[1189]: info: No input data Dec 31 05:10:28 volumio4 sudo[1664]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:28 volumio4 sudo[1664]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:28 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 31 05:10:28 volumio4 volumio-remote-updater[567]: [2025-12-31 05:10:28] [connect] Successful connection Dec 31 05:10:28 volumio4 volumio-remote-updater[567]: [2025-12-31 05:10:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1767157828 101 Dec 31 05:10:29 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 31 05:10:29 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 31 05:10:30 volumio4 sudo[1679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //VOLUMIO/usb\HD-LBU3\FLAC /mnt/NAS/USB Dec 31 05:10:30 volumio4 sudo[1679]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:30 volumio4 sudo[1679]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:30 volumio4 volumio[1189]: info: Cannot mount NAS USB at system boot, trial number 2 ,retrying in 5 seconds Dec 31 05:10:30 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 31 05:10:32 volumio4 volumio[1189]: info: Adding plugin bluetooth to MyMusic Plugins Dec 31 05:10:32 volumio4 volumio[1189]: info: Adding plugin cd_controller to MyMusic Plugins Dec 31 05:10:32 volumio4 volumio[1189]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 31 05:10:32 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 31 05:10:35 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 31 05:10:36 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:31.8095] main::init (387) Starting Logitech Media Server (v7.9.1, 1522157629, Fri Mar 30 12:27:11 CEST 2018) perl 5.020002 - arm-linux-gnueabihf-thread-multi-64int Dec 31 05:10:36 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:33.0660] Slim::Utils::SQLiteHelper::postConnect (374) Optimizing DB because of missing or empty sqlite_stat1 table Dec 31 05:10:36 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:33.0950] Slim::Schema::forceCommit (2149) Warning: Trying to commit transactions before DB is initialized! Dec 31 05:10:36 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:33.4703] Slim::Utils::SQLiteHelper::postConnect (374) Optimizing DB because of missing or empty sqlite_stat1 table Dec 31 05:10:36 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:33.4972] Slim::Schema::forceCommit (2149) Warning: Trying to commit transactions before DB is initialized! Dec 31 05:10:36 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:35.4516] Slim::Utils::SQLiteHelper::postConnect (374) Optimizing DB because of missing or empty sqlite_stat1 table Dec 31 05:10:36 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:35.4796] Slim::Schema::forceCommit (2149) Warning: Trying to commit transactions before DB is initialized! Dec 31 05:10:36 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:36.0714] main::checkDataSource (1110) Warning: Schema updated or no media found in the database, initiating scan. Dec 31 05:10:37 volumio4 squeezeboxserver[1466]: Your locale was detected as C, you may have problems with non-Latin filenames. Consider changing your LANG variable to the correct locale, i.e. en_US.utf8 Dec 31 05:10:37 volumio4 volumio[1189]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 31 05:10:37 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 31 05:10:37 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 31 05:10:37 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 31 05:10:37 volumio4 volumio[1189]: info: Starting MyVolumio Remote Streaming Endpoints Dec 31 05:10:37 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 31 05:10:37 volumio4 volumio[1189]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 31 05:10:37 volumio4 volumio[1189]: info: Streaming services startup Dec 31 05:10:37 volumio4 volumio[1189]: info: Starting Streaming Daemon Dec 31 05:10:37 volumio4 volumio[1189]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 31 05:10:37 volumio4 sudo[1691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 31 05:10:37 volumio4 sudo[1691]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:37 volumio4 systemd[1]: Stopping Volumio Streaming Daemon... Dec 31 05:10:37 volumio4 systemd[1]: Starting Volumio Streaming Daemon... Dec 31 05:10:37 volumio4 sudo[1691]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:37 volumio4 systemd[1]: Started Volumio Streaming Daemon. Dec 31 05:10:37 volumio4 volumio[1189]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.699&uuid=d96ee36e0aa665108e6163c0a39f2d9a" http://updates.volumio.org:7070/downloader-v1/track-device Dec 31 05:10:37 volumio4 volumio[1189]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 31 05:10:37 volumio4 volumio[1189]: Dload Upload Total Spent Left Speed Dec 31 05:10:37 volumio4 volumio[1189]: [235B blob data] Dec 31 05:10:37 volumio4 volumio[1189]: retrying in 5 seconds, trial 0 Dec 31 05:10:37 volumio4 volumio[1189]: Volumio Calling Home Dec 31 05:10:37 volumio4 volumio-streaming-daemon[1697]: ############################ Dec 31 05:10:37 volumio4 volumio-streaming-daemon[1697]: # Volumio Streaming Daemon # Dec 31 05:10:37 volumio4 volumio-streaming-daemon[1697]: # Running on port 7777 # Dec 31 05:10:37 volumio4 volumio-streaming-daemon[1697]: ############################ Dec 31 05:10:38 volumio4 volumio[1189]: info: No input data Dec 31 05:10:38 volumio4 volumio[1189]: info: No input data Dec 31 05:10:38 volumio4 volumio[1189]: info: No input data Dec 31 05:10:38 volumio4 sudo[1709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //VOLUMIO/usb\HD-LBU3\FLAC /mnt/NAS/USB Dec 31 05:10:38 volumio4 volumio[1189]: info: No input data Dec 31 05:10:38 volumio4 volumio[1189]: info: No input data Dec 31 05:10:38 volumio4 volumio[1189]: info: No input data Dec 31 05:10:38 volumio4 sudo[1709]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:38 volumio4 volumio[1189]: info: CoreCommandRouter::volumioGetQueue Dec 31 05:10:38 volumio4 volumio[1189]: info: CoreStateMachine::getQueue Dec 31 05:10:38 volumio4 volumio[1189]: info: CorePlayQueue::getQueue Dec 31 05:10:38 volumio4 volumio[1189]: info: No input data Dec 31 05:10:38 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 31 05:10:38 volumio4 volumio[1189]: info: CURURI: playlists Dec 31 05:10:38 volumio4 volumio[1189]: info: Listing playlists Dec 31 05:10:38 volumio4 volumio[1189]: info: No input data Dec 31 05:10:38 volumio4 volumio[1189]: info: No input data Dec 31 05:10:38 volumio4 volumio[1189]: error: MyVolumio Custom Token format not valid, refreshing it Dec 31 05:10:38 volumio4 sudo[1709]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:38 volumio4 volumio[1189]: info: Cannot mount NAS USB at system boot, trial number 3 ,retrying in 5 seconds Dec 31 05:10:39 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:39.2583] main::main (205) Starting Logitech Media Server scanner (v7.9.1, 1522157629, Fri Mar 30 12:27:11 CEST 2018) perl 5.020002 Dec 31 05:10:39 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:39.9784] Slim::Schema::forceCommit (2149) Warning: Trying to commit transactions before DB is initialized! Dec 31 05:10:40 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 31 05:10:40 volumio4 volumio[1189]: info: CURURI: playlists/ハッチ＆達郎 Dec 31 05:10:40 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:40.5353] Slim::Music::Import::runImporter (511) Starting Slim::Media::MediaFolderScan scan Dec 31 05:10:40 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:40.5365] Slim::Music::Import::endImporter (643) Completed Slim::Media::MediaFolderScan Scan in 0 seconds. Dec 31 05:10:40 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:40.5541] Slim::Music::Import::runImporter (511) Starting Slim::Plugin::FullTextSearch::Plugin scan Dec 31 05:10:40 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:40.5575] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (396) Starting fulltext index build Dec 31 05:10:40 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:40.5584] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (400) Initialize fulltext table Dec 31 05:10:40 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:40.5652] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (406) Create fulltext index for tracks Dec 31 05:10:40 volumio4 volumio[1189]: info: Fetching Streaming Services browse cache Dec 31 05:10:40 volumio4 volumio[1189]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Dec 31 05:10:40 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 31 05:10:40 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:40.9131] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (416) Create fulltext index for albums Dec 31 05:10:40 volumio4 volumio[1189]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Dec 31 05:10:40 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 31 05:10:40 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:40.9284] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (425) Create fulltext index for contributors Dec 31 05:10:40 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:40.9428] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (435) Create fulltext index for playlists Dec 31 05:10:40 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:40.9687] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (470) Optimize fulltext index Dec 31 05:10:41 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:41.0833] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (485) Fulltext index build done! Dec 31 05:10:41 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:41.0844] Slim::Music::Import::endImporter (643) Completed Slim::Plugin::FullTextSearch::Plugin Scan in 0 seconds. Dec 31 05:10:41 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:41.0883] Slim::Music::Artwork::precacheAllArtwork (619) Starting precacheArtwork for 0 albums Dec 31 05:10:41 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:41.0892] Slim::Music::Import::endImporter (643) Completed precacheArtwork Scan in 0 seconds. Dec 31 05:10:41 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:41.1052] Slim::Music::Import::runScanPostProcessing (435) Starting Database optimization. Dec 31 05:10:41 volumio4 squeezeboxserver[1466]: [25-12-31 05:10:41.2652] Slim::Music::Import::endImporter (643) Completed dbOptimize Scan in 0 seconds. Dec 31 05:10:41 volumio4 volumio[1189]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 31 05:10:41 volumio4 volumio[1189]: info: MYVOLUMIO: Adding device Dec 31 05:10:41 volumio4 volumio[1189]: info: MYVOLUMIO: Evaluating Server Dec 31 05:10:43 volumio4 volumio[1189]: info: MyVolumio status changed Dec 31 05:10:43 volumio4 volumio[1189]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Dec 31 05:10:43 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 31 05:10:43 volumio4 volumio[1189]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Dec 31 05:10:43 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 31 05:10:43 volumio4 volumio[1189]: info: Streaming services startup Dec 31 05:10:43 volumio4 volumio[1189]: info: Re-Starting Streaming Daemon Dec 31 05:10:43 volumio4 volumio[1189]: info: Removing browser output: myVolumio user plan is not superstar Dec 31 05:10:43 volumio4 volumio[1189]: info: Removing audio output: Dec 31 05:10:43 volumio4 volumio[1189]: info: Stoppping Tunnel 1 Dec 31 05:10:43 volumio4 sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 31 05:10:43 volumio4 sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:43 volumio4 systemd[1]: Stopped MyVolumio SSH Tunnel. Dec 31 05:10:43 volumio4 sudo[1744]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:43 volumio4 volumio[1189]: info: Setting Geolocation for MyVolumio to us2 Dec 31 05:10:43 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 31 05:10:43 volumio4 sudo[1753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //VOLUMIO/usb\HD-LBU3\FLAC /mnt/NAS/USB Dec 31 05:10:43 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 31 05:10:43 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 31 05:10:43 volumio4 volumio-streaming-daemon[1697]: Restarting daemon Dec 31 05:10:43 volumio4 sudo[1753]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:43 volumio4 volumio[1189]: info: Remote SSH Stopped Dec 31 05:10:44 volumio4 volumio[1189]: info: Play playlist ハッチ＆達郎 Dec 31 05:10:44 volumio4 volumio[1189]: info: CoreCommandRouter::volumioClearQueue Dec 31 05:10:44 volumio4 volumio[1189]: info: CoreStateMachine::ClearQueue Dec 31 05:10:44 volumio4 volumio[1189]: info: CoreStateMachine::stop Dec 31 05:10:44 volumio4 volumio[1189]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 31 05:10:44 volumio4 volumio[1189]: UNSET VOLATILE Dec 31 05:10:44 volumio4 volumio[1189]: info: CorePlayQueue::clearPlayQueue Dec 31 05:10:44 volumio4 volumio[1189]: info: CorePlayQueue::saveQueue Dec 31 05:10:44 volumio4 volumio[1189]: info: CoreStateMachine::pushEmptyState Dec 31 05:10:44 volumio4 volumio[1189]: info: CoreCommandRouter::volumioPushState Dec 31 05:10:44 volumio4 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 31 05:10:44 volumio4 volumio[1189]: info: CoreCommandRouter::volumioPushQueue Dec 31 05:10:44 volumio4 sudo[1761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 31 05:10:44 volumio4 volumio[1189]: info: CoreCommandRouter::volumioAddQueueItems Dec 31 05:10:44 volumio4 volumio[1189]: info: CoreStateMachine::addQueueItems Dec 31 05:10:44 volumio4 volumio[1189]: info: CorePlayQueue::addQueueItems Dec 31 05:10:44 volumio4 volumio[1189]: info: Adding Item to queue: INTERNAL/all_MP3_ハッチハッチェル/みの石滝オールスターズ/新しい町・愛を注ごう/愛を注ごう.mp3 Dec 31 05:10:44 volumio4 volumio[1189]: info: Exploding uri INTERNAL/all_MP3_ハッチハッチェル/みの石滝オールスターズ/新しい町・愛を注ごう/愛を注ごう.mp3 in service mpd Dec 31 05:10:44 volumio4 sudo[1761]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 31 05:10:44 volumio4 volumio[1189]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 31 05:10:44 volumio4 sudo[1761]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:44 volumio4 volumio[1189]: TypeError: Cannot read property 'then' of undefined Dec 31 05:10:44 volumio4 volumio[1189]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2608:23) Dec 31 05:10:44 volumio4 volumio[1189]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2384:23) Dec 31 05:10:44 volumio4 volumio[1189]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1326:22) Dec 31 05:10:44 volumio4 volumio[1189]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:151:58) Dec 31 05:10:44 volumio4 volumio[1189]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:249:24) Dec 31 05:10:44 volumio4 volumio[1189]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:463:27) Dec 31 05:10:44 volumio4 volumio[1189]: at /volumio/app/playlistManager.js:764:40 Dec 31 05:10:44 volumio4 volumio[1189]: at /volumio/node_modules/fs-extra/node_modules/jsonfile/index.js:46:5 Dec 31 05:10:44 volumio4 volumio[1189]: at /volumio/node_modules/graceful-fs/graceful-fs.js:90:16 Dec 31 05:10:44 volumio4 volumio[1189]: at FSReqWrap.readFileAfterClose [as oncomplete] (fs.js:511:3) Dec 31 05:10:44 volumio4 volumio[1189]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 31 05:10:44 volumio4 sudo[1753]: pam_unix(sudo:session): session closed for user root Dec 31 05:10:45 volumio4 sudo[1780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-31 05:09 Dec 31 05:10:45 volumio4 sudo[1780]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="80e3a7415791a01dac7cd7cc2cc0a3d3fc65ba9d" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="e32e25d68d93383fe73e2a451c032b2661c71694" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Jan 20 19:41:22 CET 2020" VOLUMIO_VERSION="2.699" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="5faefe8928fcf34b6263aec4aa82825a"