-- Logs begin at Thu 2019-02-14 12:12:00 EET, end at Sun 2025-03-02 15:00:55 EET. -- Mar 02 14:59:00 volumio01.larpa.arpa systemd-udevd[352]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Condition check resulted in 7800 being skipped. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Created slice system-bthelper.slice. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Starting Show Plymouth Boot Screen... Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Condition check resulted in Huge Pages File System being skipped. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Mounting /boot... Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Started File System Check Daemon to report status. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Mounted /boot. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: plymouth-start.service: Succeeded. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Started Show Plymouth Boot Screen. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Starting Load/Save RF Kill Switch Status... Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Reached target Local File Systems. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Starting Preprocess NFS configuration... Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Starting Raise network interfaces... Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Starting Create Volatile Files and Directories... Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Started ifup for eth0. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Started ifup for wlan0. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Received SIGRTMIN+20 from PID 192 (plymouthd). Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: plymouth-read-write.service: Succeeded. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Started Load/Save RF Kill Switch Status. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: nfs-config.service: Succeeded. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Started Preprocess NFS configuration. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Reached target NFS client services. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Started Create Volatile Files and Directories. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Starting Update UTMP about System Boot/Shutdown... Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Starting RPC bind portmap service... Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Started Update UTMP about System Boot/Shutdown. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Reached target System Initialization. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Started Daily apt download activities. Mar 02 14:59:00 volumio01.larpa.arpa kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Listening on D-Bus System Message Bus Socket. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Started Daily Cleanup of Temporary Directories. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Listening on triggerhappy.socket. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Reached target Sockets. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Reached target Basic System. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Mar 02 14:59:00 volumio01.larpa.arpa kernel: 8021q: 802.1Q VLAN Support v1.8 Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Starting triggerhappy global hotkey daemon... Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Started UPnP Renderer front-end to MPD. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Started Volumio Log Rotation Service. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Started Volumio Iptables Module. Mar 02 14:59:00 volumio01.larpa.arpa systemd[1]: Started D-Bus System Message Bus. Mar 02 14:59:00 volumio01.larpa.arpa thd[567]: Unable to parse trigger line: Mar 02 14:59:00 volumio01.larpa.arpa thd[567]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Mar 02 14:59:00 volumio01.larpa.arpa thd[567]: Unable to parse trigger line: Mar 02 14:59:00 volumio01.larpa.arpa thd[567]: Found socket passed from systemd Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Starting WPA supplicant... Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Started volumio-remote-updater.service. Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Starting Login Service... Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Starting Configure Bluetooth Modems connected by UART... Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Started Manage Sound Card State (restore and store). Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Mar 02 14:59:01 volumio01.larpa.arpa alsactl[598]: alsactl 1.1.8 daemon started Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Starting Wireless Services... Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Starting Save/Restore Sound Card State... Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Starting dhcpcd on all interfaces... Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Started Daily apt upgrade and clean activities. Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Reached target Timers. Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Started RPC bind portmap service. Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Started triggerhappy global hotkey daemon. Mar 02 14:59:01 volumio01.larpa.arpa dhcpcd[603]: Not running dhcpcd because /etc/network/interfaces Mar 02 14:59:01 volumio01.larpa.arpa dhcpcd[603]: defines some interfaces that will use a Mar 02 14:59:01 volumio01.larpa.arpa dhcpcd[603]: DHCP client or static address Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Started Save/Restore Sound Card State. Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Failed to start dhcpcd on all interfaces. Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Reached target Sound Card. Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Reached target RPC Port Mapper. Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Reached target Remote File Systems (Pre). Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Reached target Remote File Systems. Mar 02 14:59:01 volumio01.larpa.arpa systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Mar 02 14:59:01 volumio01.larpa.arpa systemd-logind[595]: New seat seat0. Mar 02 14:59:01 volumio01.larpa.arpa sh[496]: wlan0=wlan0 Mar 02 14:59:01 volumio01.larpa.arpa avahi-daemon[599]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Mar 02 14:59:01 volumio01.larpa.arpa avahi-daemon[599]: Successfully dropped root privileges. Mar 02 14:59:01 volumio01.larpa.arpa avahi-daemon[599]: avahi-daemon 0.7 starting up. Mar 02 14:59:01 volumio01.larpa.arpa sh[495]: eth0: waiting for carrier Mar 02 14:59:01 volumio01.larpa.arpa kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Mar 02 14:59:01 volumio01.larpa.arpa kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Mar 02 14:59:01 volumio01.larpa.arpa dhcpcd[540]: eth0: waiting for carrier Mar 02 14:59:01 volumio01.larpa.arpa volumio-remote-updater[594]: Error: No active session Mar 02 14:59:01 volumio01.larpa.arpa volumio-remote-updater[594]: [2025-03-02 14:59:01] [info] asio async_connect error: system:111 (Connection refused) Mar 02 14:59:01 volumio01.larpa.arpa volumio-remote-updater[594]: [2025-03-02 14:59:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 02 14:59:01 volumio01.larpa.arpa volumio-remote-updater[594]: [2025-03-02 14:59:01] [error] handle_connect error: Underlying Transport Error Mar 02 14:59:02 volumio01.larpa.arpa volumio[578]: Could not open config: /tmp/upmpdcli.conf Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Started Login Service. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: hciuart.service: Succeeded. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Started Configure Bluetooth Modems connected by UART. Mar 02 14:59:02 volumio01.larpa.arpa avahi-daemon[599]: Successfully called chroot(). Mar 02 14:59:02 volumio01.larpa.arpa avahi-daemon[599]: Successfully dropped remaining capabilities. Mar 02 14:59:02 volumio01.larpa.arpa wpa_supplicant[593]: Successfully initialized wpa_supplicant Mar 02 14:59:02 volumio01.larpa.arpa avahi-daemon[599]: Loading service file /services/volumio.service. Mar 02 14:59:02 volumio01.larpa.arpa avahi-daemon[599]: Network interface enumeration completed. Mar 02 14:59:02 volumio01.larpa.arpa avahi-daemon[599]: Server startup complete. Host name is volumio01.local. Local service cookie is 2509082110. Mar 02 14:59:02 volumio01.larpa.arpa avahi-daemon[599]: Service "Volumio01.larpa.arpa" (/services/volumio.service) successfully established. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Started Raise network interfaces. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Started Avahi mDNS/DNS-SD Stack. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Started WPA supplicant. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Reached target Network. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Starting Network Time Service... Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Starting Permit User Sessions... Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Reached target Network is Online. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Starting Samba NMB Daemon... Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Starting Daily apt download activities... Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Starting /etc/rc.local Compatibility... Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Starting LSB: Brings up/down network automatically... Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Starting Raspberry Pi bluetooth helper... Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Started Permit User Sessions. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Started /etc/rc.local Compatibility. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Starting Hold until boot process finishes up... Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Starting Terminate Plymouth Boot Screen... Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Received SIGRTMIN+21 from PID 192 (plymouthd). Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: plymouth-quit.service: Succeeded. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Started Terminate Plymouth Boot Screen. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: plymouth-quit-wait.service: Succeeded. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Started Hold until boot process finishes up. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Received SIGRTMIN+21 from PID 192 (n/a). Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Started Getty on tty1. Mar 02 14:59:02 volumio01.larpa.arpa systemd[1]: Reached target Login Prompts. Mar 02 14:59:03 volumio01.larpa.arpa loadcpufreq[628]: Loading cpufreq kernel modules...done (none). Mar 02 14:59:03 volumio01.larpa.arpa systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Mar 02 14:59:03 volumio01.larpa.arpa systemd[1]: Starting LSB: set CPUFreq kernel parameters... Mar 02 14:59:03 volumio01.larpa.arpa bthelper[711]: Raspberry Pi BDADDR already set Mar 02 14:59:03 volumio01.larpa.arpa systemd[1]: Started Raspberry Pi bluetooth helper. Mar 02 14:59:03 volumio01.larpa.arpa ntpd[686]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 02 14:59:03 volumio01.larpa.arpa ntpd[686]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Mar 02 14:59:03 volumio01.larpa.arpa ntpd[754]: proto: precision = 1.406 usec (-19) Mar 02 14:59:03 volumio01.larpa.arpa ifplugd(eth0)[762]: ifplugd 0.28 initializing. Mar 02 14:59:03 volumio01.larpa.arpa ifplugd(eth0)[762]: Using interface eth0/B8:27:EB:1A:5C:28 with driver (version: 6.1.69-v7+) Mar 02 14:59:03 volumio01.larpa.arpa systemd[1]: Started Network Time Service. Mar 02 14:59:03 volumio01.larpa.arpa ifplugd(eth0)[762]: Using detection mode: SIOCETHTOOL Mar 02 14:59:03 volumio01.larpa.arpa ifplugd(eth0)[762]: Initialization complete, link beat not detected. Mar 02 14:59:03 volumio01.larpa.arpa systemd[1]: iptables.service: Succeeded. Mar 02 14:59:03 volumio01.larpa.arpa ntpd[754]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 02 14:59:03 volumio01.larpa.arpa ntpd[754]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 02 14:59:03 volumio01.larpa.arpa ntpd[754]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 65 days ago Mar 02 14:59:03 volumio01.larpa.arpa ntpd[754]: Listen and drop on 0 v6wildcard [::]:123 Mar 02 14:59:03 volumio01.larpa.arpa ntpd[754]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 02 14:59:03 volumio01.larpa.arpa ntpd[754]: Listen normally on 2 lo 127.0.0.1:123 Mar 02 14:59:03 volumio01.larpa.arpa ntpd[754]: Listening on routing socket on fd #19 for interface updates Mar 02 14:59:03 volumio01.larpa.arpa ntpd[754]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 02 14:59:03 volumio01.larpa.arpa ntpd[754]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 02 14:59:03 volumio01.larpa.arpa ifplugd[705]: Network Interface Plugging Daemon...start eth0...done. Mar 02 14:59:03 volumio01.larpa.arpa systemd[1]: Started LSB: Brings up/down network automatically. Mar 02 14:59:03 volumio01.larpa.arpa systemd[1]: Starting Bluetooth service... Mar 02 14:59:03 volumio01.larpa.arpa cpufrequtils[745]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Mar 02 14:59:03 volumio01.larpa.arpa systemd[1]: Started LSB: set CPUFreq kernel parameters. Mar 02 14:59:03 volumio01.larpa.arpa haveged[545]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Mar 02 14:59:03 volumio01.larpa.arpa haveged[545]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Mar 02 14:59:03 volumio01.larpa.arpa haveged[545]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00292 Mar 02 14:59:03 volumio01.larpa.arpa haveged[545]: haveged: fills: 0, generated: 0 Mar 02 14:59:03 volumio01.larpa.arpa bluetoothd[767]: Bluetooth daemon 5.50 Mar 02 14:59:03 volumio01.larpa.arpa bluetoothd[767]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Mar 02 14:59:03 volumio01.larpa.arpa systemd[1]: Started Bluetooth service. Mar 02 14:59:03 volumio01.larpa.arpa systemd[1]: Reached target Bluetooth. Mar 02 14:59:03 volumio01.larpa.arpa bluetoothd[767]: Starting SDP server Mar 02 14:59:03 volumio01.larpa.arpa bluetoothd[767]: Excluding (cli) sap Mar 02 14:59:03 volumio01.larpa.arpa kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Mar 02 14:59:03 volumio01.larpa.arpa kernel: Bluetooth: BNEP filters: protocol multicast Mar 02 14:59:03 volumio01.larpa.arpa kernel: Bluetooth: BNEP socket layer initialized Mar 02 14:59:03 volumio01.larpa.arpa bluetoothd[767]: Bluetooth management interface 1.22 initialized Mar 02 14:59:03 volumio01.larpa.arpa dbus-daemon[586]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=767 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Mar 02 14:59:03 volumio01.larpa.arpa kernel: Bluetooth: MGMT ver 1.22 Mar 02 14:59:04 volumio01.larpa.arpa bluetoothd[767]: Failed to set privacy: Rejected (0x0b) Mar 02 14:59:04 volumio01.larpa.arpa systemd[1]: Starting Hostname Service... Mar 02 14:59:04 volumio01.larpa.arpa ntpd[754]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 02 14:59:04 volumio01.larpa.arpa dbus-daemon[586]: [system] Successfully activated service 'org.freedesktop.hostname1' Mar 02 14:59:04 volumio01.larpa.arpa systemd[1]: Started Hostname Service. Mar 02 14:59:04 volumio01.larpa.arpa nmbd[712]: [2025/03/02 14:59:04.528146, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Mar 02 14:59:04 volumio01.larpa.arpa nmbd[712]: started asyncdns process 812 Mar 02 14:59:04 volumio01.larpa.arpa nmbd[712]: [2025/03/02 14:59:04.530720, 0] ../lib/util/become_daemon.c:149(daemon_status) Mar 02 14:59:04 volumio01.larpa.arpa nmbd[712]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Mar 02 14:59:04 volumio01.larpa.arpa nmbd[712]: [2025/03/02 14:59:04.530897, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Mar 02 14:59:04 volumio01.larpa.arpa nmbd[712]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Mar 02 14:59:04 volumio01.larpa.arpa systemd[1]: apt-daily.service: Succeeded. Mar 02 14:59:04 volumio01.larpa.arpa systemd[1]: Started Daily apt download activities. Mar 02 14:59:04 volumio01.larpa.arpa wireless.js[600]: Cleaning previous... Mar 02 14:59:05 volumio01.larpa.arpa sudo[839]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 02 14:59:05 volumio01.larpa.arpa sudo[839]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:05 volumio01.larpa.arpa sudo[839]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:05 volumio01.larpa.arpa sudo[841]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 02 14:59:05 volumio01.larpa.arpa sudo[841]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:05 volumio01.larpa.arpa ntpd[754]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 02 14:59:05 volumio01.larpa.arpa systemd[1]: systemd-rfkill.service: Succeeded. Mar 02 14:59:05 volumio01.larpa.arpa sudo[841]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:05 volumio01.larpa.arpa wireless.js[600]: Stopped aP Mar 02 14:59:05 volumio01.larpa.arpa sudo[848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 02 14:59:05 volumio01.larpa.arpa sudo[848]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:05 volumio01.larpa.arpa sudo[848]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:05 volumio01.larpa.arpa kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 02 14:59:05 volumio01.larpa.arpa sudo[850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 02 14:59:05 volumio01.larpa.arpa sudo[850]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:05 volumio01.larpa.arpa sudo[850]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:05 volumio01.larpa.arpa sudo[857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 02 14:59:05 volumio01.larpa.arpa sudo[857]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:05 volumio01.larpa.arpa sudo[857]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:05 volumio01.larpa.arpa sudo[859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 02 14:59:05 volumio01.larpa.arpa sudo[859]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:06 volumio01.larpa.arpa ntpd[754]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 02 14:59:06 volumio01.larpa.arpa volumio-remote-updater[594]: [2025-03-02 14:59:06] [info] asio async_connect error: system:111 (Connection refused) Mar 02 14:59:06 volumio01.larpa.arpa volumio-remote-updater[594]: [2025-03-02 14:59:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 02 14:59:06 volumio01.larpa.arpa volumio-remote-updater[594]: [2025-03-02 14:59:06] [error] handle_connect error: Underlying Transport Error Mar 02 14:59:07 volumio01.larpa.arpa ntpd[754]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 02 14:59:08 volumio01.larpa.arpa bthelper[711]: Changing power off succeeded Mar 02 14:59:08 volumio01.larpa.arpa bthelper[711]: [CHG] Controller B8:27:EB:B0:F6:82 Class: 0x0000041c Mar 02 14:59:08 volumio01.larpa.arpa bthelper[711]: Changing power on succeeded Mar 02 14:59:08 volumio01.larpa.arpa sudo[859]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:08 volumio01.larpa.arpa wireless.js[600]: SETTING APPROPRIATE REG DOMAIN: EU Mar 02 14:59:08 volumio01.larpa.arpa sudo[871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 02 14:59:08 volumio01.larpa.arpa sudo[871]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:08 volumio01.larpa.arpa sudo[871]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:08 volumio01.larpa.arpa sudo[873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set EU Mar 02 14:59:08 volumio01.larpa.arpa sudo[873]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:08 volumio01.larpa.arpa sudo[873]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:08 volumio01.larpa.arpa wireless.js[600]: SUCCESSFULLY SET NEW REGDOMAIN: EU Mar 02 14:59:08 volumio01.larpa.arpa wireless.js[600]: Start wireless flow Mar 02 14:59:08 volumio01.larpa.arpa wireless.js[600]: Stopped hotspot (if there).. Mar 02 14:59:08 volumio01.larpa.arpa systemd-udevd[868]: Process '/sbin/crda' failed with exit code 255. Mar 02 14:59:08 volumio01.larpa.arpa sudo[879]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 02 14:59:08 volumio01.larpa.arpa sudo[879]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:08 volumio01.larpa.arpa sudo[879]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:08 volumio01.larpa.arpa sudo[881]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 02 14:59:08 volumio01.larpa.arpa sudo[881]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:09 volumio01.larpa.arpa sudo[881]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:09 volumio01.larpa.arpa wireless.js[600]: DHCP IP Mar 02 14:59:09 volumio01.larpa.arpa wireless.js[600]: Start ap Mar 02 14:59:09 volumio01.larpa.arpa wpa_supplicant[883]: Successfully initialized wpa_supplicant Mar 02 14:59:09 volumio01.larpa.arpa sudo[884]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 02 14:59:09 volumio01.larpa.arpa sudo[884]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:09 volumio01.larpa.arpa dhcpcd[885]: dev: loaded udev Mar 02 14:59:09 volumio01.larpa.arpa kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 02 14:59:09 volumio01.larpa.arpa dhcpcd-run-hooks[903]: wlan0: starting wpa_supplicant Mar 02 14:59:09 volumio01.larpa.arpa dhcpcd-run-hooks[908]: wlan0: failed to start wpa_supplicant Mar 02 14:59:09 volumio01.larpa.arpa dhcpcd-run-hooks[909]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Mar 02 14:59:09 volumio01.larpa.arpa dhcpcd[885]: wlan0: connected to Access Point `' Mar 02 14:59:09 volumio01.larpa.arpa dhcpcd[885]: no interfaces have a carrier Mar 02 14:59:09 volumio01.larpa.arpa dhcpcd[885]: forked to background, child pid 919 Mar 02 14:59:09 volumio01.larpa.arpa dhcpcd[919]: eth0: waiting for carrier Mar 02 14:59:09 volumio01.larpa.arpa dhcpcd[919]: wlan0: waiting for carrier Mar 02 14:59:09 volumio01.larpa.arpa sudo[884]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:10 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:10 volumio01.larpa.arpa sudo[921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:10 volumio01.larpa.arpa sudo[921]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:10 volumio01.larpa.arpa sudo[921]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:11 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:11 volumio01.larpa.arpa sudo[924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:11 volumio01.larpa.arpa sudo[924]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:11 volumio01.larpa.arpa sudo[924]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:11 volumio01.larpa.arpa wpa_supplicant[907]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Mar 02 14:59:12 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:12 volumio01.larpa.arpa sudo[927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:12 volumio01.larpa.arpa sudo[927]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:12 volumio01.larpa.arpa sudo[927]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:12 volumio01.larpa.arpa wpa_supplicant[907]: wlan0: Trying to associate with SSID 'Sakura' Mar 02 14:59:13 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:13 volumio01.larpa.arpa sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:13 volumio01.larpa.arpa sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:13 volumio01.larpa.arpa sudo[930]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:14 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:14 volumio01.larpa.arpa sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:14 volumio01.larpa.arpa volumio-remote-updater[594]: [2025-03-02 14:59:14] [info] asio async_connect error: system:111 (Connection refused) Mar 02 14:59:14 volumio01.larpa.arpa volumio-remote-updater[594]: [2025-03-02 14:59:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 02 14:59:14 volumio01.larpa.arpa volumio-remote-updater[594]: [2025-03-02 14:59:14] [error] handle_connect error: Underlying Transport Error Mar 02 14:59:14 volumio01.larpa.arpa sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:14 volumio01.larpa.arpa sudo[933]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:15 volumio01.larpa.arpa wpa_supplicant[907]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Mar 02 14:59:15 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:15 volumio01.larpa.arpa sudo[936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:15 volumio01.larpa.arpa sudo[936]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:15 volumio01.larpa.arpa sudo[936]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:16 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:16 volumio01.larpa.arpa sudo[939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:16 volumio01.larpa.arpa sudo[939]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:16 volumio01.larpa.arpa sudo[939]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:17 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:17 volumio01.larpa.arpa sudo[942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:17 volumio01.larpa.arpa sudo[942]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:17 volumio01.larpa.arpa sudo[942]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:18 volumio01.larpa.arpa wpa_supplicant[907]: wlan0: Trying to associate with SSID 'Sakura' Mar 02 14:59:18 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:18 volumio01.larpa.arpa sudo[945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:18 volumio01.larpa.arpa sudo[945]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:18 volumio01.larpa.arpa sudo[945]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:19 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:19 volumio01.larpa.arpa sudo[948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:19 volumio01.larpa.arpa sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:19 volumio01.larpa.arpa sudo[948]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:20 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:20 volumio01.larpa.arpa sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:20 volumio01.larpa.arpa sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:20 volumio01.larpa.arpa sudo[951]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:21 volumio01.larpa.arpa dhcpcd[919]: wlan0: carrier acquired Mar 02 14:59:21 volumio01.larpa.arpa dhcpcd[919]: wlan0: connected to Access Point `Sakura' Mar 02 14:59:21 volumio01.larpa.arpa wpa_supplicant[907]: wlan0: Associated with dc:b7:ac:2c:03:52 Mar 02 14:59:21 volumio01.larpa.arpa wpa_supplicant[907]: wlan0: CTRL-EVENT-CONNECTED - Connection to dc:b7:ac:2c:03:52 completed [id=0 id_str=] Mar 02 14:59:21 volumio01.larpa.arpa wpa_supplicant[907]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 02 14:59:21 volumio01.larpa.arpa wpa_supplicant[907]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=FI Mar 02 14:59:21 volumio01.larpa.arpa dhcpcd[919]: DUID 00:01:00:01:2c:2b:35:b4:b8:27:eb:4f:09:7d Mar 02 14:59:21 volumio01.larpa.arpa dhcpcd[919]: wlan0: IAID eb:4f:09:7d Mar 02 14:59:21 volumio01.larpa.arpa dhcpcd[919]: wlan0: adding address fe80::925e:490e:ee0c:4a1d Mar 02 14:59:21 volumio01.larpa.arpa dhcpcd[919]: ipv6_addaddr1: Permission denied Mar 02 14:59:21 volumio01.larpa.arpa dhcpcd[919]: wlan0: carrier lost Mar 02 14:59:21 volumio01.larpa.arpa dhcpcd[919]: wlan0: carrier acquired Mar 02 14:59:21 volumio01.larpa.arpa dhcpcd[919]: wlan0: IAID eb:4f:09:7d Mar 02 14:59:21 volumio01.larpa.arpa dhcpcd[919]: wlan0: soliciting an IPv6 router Mar 02 14:59:21 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:21 volumio01.larpa.arpa sudo[966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:21 volumio01.larpa.arpa sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:21 volumio01.larpa.arpa sudo[966]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:22 volumio01.larpa.arpa dhcpcd[919]: wlan0: rebinding lease of 192.168.2.50 Mar 02 14:59:22 volumio01.larpa.arpa dhcpcd[919]: wlan0: probing address 192.168.2.50/24 Mar 02 14:59:22 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:22 volumio01.larpa.arpa sudo[969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:22 volumio01.larpa.arpa sudo[969]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:22 volumio01.larpa.arpa sudo[969]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:23 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:23 volumio01.larpa.arpa sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:23 volumio01.larpa.arpa sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:23 volumio01.larpa.arpa sudo[972]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:24 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:24 volumio01.larpa.arpa volumio-remote-updater[594]: [2025-03-02 14:59:24] [info] asio async_connect error: system:111 (Connection refused) Mar 02 14:59:24 volumio01.larpa.arpa volumio-remote-updater[594]: [2025-03-02 14:59:24] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 02 14:59:24 volumio01.larpa.arpa volumio-remote-updater[594]: [2025-03-02 14:59:24] [error] handle_connect error: Underlying Transport Error Mar 02 14:59:24 volumio01.larpa.arpa sudo[975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:24 volumio01.larpa.arpa sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:24 volumio01.larpa.arpa sudo[975]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:25 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:25 volumio01.larpa.arpa sudo[978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:25 volumio01.larpa.arpa sudo[978]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:25 volumio01.larpa.arpa sudo[978]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:26 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:26 volumio01.larpa.arpa sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:26 volumio01.larpa.arpa sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:26 volumio01.larpa.arpa sudo[981]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:27 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:27 volumio01.larpa.arpa sudo[984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:27 volumio01.larpa.arpa sudo[984]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:27 volumio01.larpa.arpa sudo[984]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:27 volumio01.larpa.arpa dhcpcd[919]: wlan0: leased 192.168.2.50 for 7200 seconds Mar 02 14:59:27 volumio01.larpa.arpa avahi-daemon[599]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.50. Mar 02 14:59:27 volumio01.larpa.arpa dhcpcd[919]: wlan0: adding route to 192.168.2.0/24 Mar 02 14:59:27 volumio01.larpa.arpa dhcpcd[919]: wlan0: adding default route via 192.168.2.1 Mar 02 14:59:27 volumio01.larpa.arpa avahi-daemon[599]: New relevant interface wlan0.IPv4 for mDNS. Mar 02 14:59:27 volumio01.larpa.arpa avahi-daemon[599]: Registering new address record for 192.168.2.50 on wlan0.IPv4. Mar 02 14:59:28 volumio01.larpa.arpa wireless.js[600]: trying... Mar 02 14:59:28 volumio01.larpa.arpa sudo[1008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 02 14:59:28 volumio01.larpa.arpa sudo[1008]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:28 volumio01.larpa.arpa sudo[1008]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:28 volumio01.larpa.arpa wireless.js[600]: Connected to: ----Sakura Mar 02 14:59:28 volumio01.larpa.arpa wireless.js[600]: ---- Mar 02 14:59:28 volumio01.larpa.arpa sudo[1011]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 14:59:28 volumio01.larpa.arpa sudo[1011]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:28 volumio01.larpa.arpa sudo[1011]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:28 volumio01.larpa.arpa wireless.js[600]: ... joined AP, wlan0 IPv4 is 192.168.2.50, ipV6 is undefined Mar 02 14:59:28 volumio01.larpa.arpa wireless.js[600]: It's done! AP Mar 02 14:59:28 volumio01.larpa.arpa systemd[1]: Started Wireless Services. Mar 02 14:59:28 volumio01.larpa.arpa kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 02 14:59:28 volumio01.larpa.arpa systemd[1]: Started Volumio Backend Module. Mar 02 14:59:28 volumio01.larpa.arpa systemd[1]: Started Volumio Cpu Tweaker. Mar 02 14:59:28 volumio01.larpa.arpa volumio-cpu-tweak[1017]: Setting RT Priority for mpd Mar 02 14:59:28 volumio01.larpa.arpa volumio-cpu-tweak[1017]: pid 35's current scheduling policy: SCHED_OTHER Mar 02 14:59:28 volumio01.larpa.arpa volumio-cpu-tweak[1017]: pid 35's current scheduling priority: 0 Mar 02 14:59:28 volumio01.larpa.arpa volumio-cpu-tweak[1017]: Setting MPD Affinity Mar 02 14:59:28 volumio01.larpa.arpa volumio-cpu-tweak[1017]: pid 3's current affinity mask: f Mar 02 14:59:28 volumio01.larpa.arpa volumio-cpu-tweak[1017]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Mar 02 14:59:28 volumio01.larpa.arpa volumio-cpu-tweak[1017]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 02 14:59:28 volumio01.larpa.arpa systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 02 14:59:29 volumio01.larpa.arpa ntpd[754]: Listen normally on 3 wlan0 192.168.2.50:123 Mar 02 14:59:29 volumio01.larpa.arpa ntpd[754]: new interface(s) found: waking up resolver Mar 02 14:59:29 volumio01.larpa.arpa nmbd[712]: [2025/03/02 14:59:29.539892, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 02 14:59:29 volumio01.larpa.arpa systemd[1]: Started Samba NMB Daemon. Mar 02 14:59:29 volumio01.larpa.arpa nmbd[712]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 02 14:59:29 volumio01.larpa.arpa systemd[1]: Starting Samba Winbind Daemon... Mar 02 14:59:29 volumio01.larpa.arpa winbindd[1033]: [2025/03/02 14:59:29.923588, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 02 14:59:29 volumio01.larpa.arpa winbindd[1033]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 02 14:59:29 volumio01.larpa.arpa systemd[1]: Started Samba Winbind Daemon. Mar 02 14:59:29 volumio01.larpa.arpa winbindd[1033]: [2025/03/02 14:59:29.950115, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 02 14:59:29 volumio01.larpa.arpa winbindd[1033]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 02 14:59:29 volumio01.larpa.arpa systemd[1]: Starting Samba SMB Daemon... Mar 02 14:59:30 volumio01.larpa.arpa systemd[1]: systemd-fsckd.service: Succeeded. Mar 02 14:59:30 volumio01.larpa.arpa smbd[1038]: [2025/03/02 14:59:30.500670, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 02 14:59:30 volumio01.larpa.arpa smbd[1038]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 02 14:59:30 volumio01.larpa.arpa systemd[1]: Started Samba SMB Daemon. Mar 02 14:59:30 volumio01.larpa.arpa systemd[1]: Reached target Multi-User System. Mar 02 14:59:30 volumio01.larpa.arpa systemd[1]: Reached target Graphical Interface. Mar 02 14:59:30 volumio01.larpa.arpa systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 02 14:59:30 volumio01.larpa.arpa systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 02 14:59:30 volumio01.larpa.arpa systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 02 14:59:30 volumio01.larpa.arpa systemd[1]: Startup finished in 15.661s (kernel) + 36.737s (userspace) = 52.399s. Mar 02 14:59:31 volumio01.larpa.arpa volumio[1016]: info: ------------------------------------------- Mar 02 14:59:31 volumio01.larpa.arpa volumio[1016]: info: ----- Volumio3 ---- Mar 02 14:59:31 volumio01.larpa.arpa volumio[1016]: info: ------------------------------------------- Mar 02 14:59:31 volumio01.larpa.arpa volumio[1016]: info: ----- System startup ---- Mar 02 14:59:31 volumio01.larpa.arpa volumio[1016]: info: ------------------------------------------- Mar 02 14:59:31 volumio01.larpa.arpa sh[495]: timed out Mar 02 14:59:31 volumio01.larpa.arpa dhcpcd[540]: timed out Mar 02 14:59:31 volumio01.larpa.arpa sh[495]: dhcpcd exited Mar 02 14:59:31 volumio01.larpa.arpa dhcpcd[540]: dhcpcd exited Mar 02 14:59:31 volumio01.larpa.arpa sh[495]: ifup: failed to bring up eth0 Mar 02 14:59:31 volumio01.larpa.arpa systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 02 14:59:31 volumio01.larpa.arpa systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: MYVOLUMIO Environment detected Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Plugin folders cleanup Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Scanning into folder /volumio/app/plugins/ Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Scanning category audio_interface Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Scanning category miscellanea Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Scanning category music_service Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Scanning category plugins.json Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Scanning category system_controller Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Scanning category user_interface Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Scanning into folder /data/plugins/ Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Plugin folders cleanup completed Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: ------------------------------------------- Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: ----- Core plugins startup ---- Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: ------------------------------------------- Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Loading plugins from folder /volumio/app/plugins/ Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Adding plugin upnp to MyMusic Plugins Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Loading plugins from folder /data/plugins/ Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "system"... Mar 02 14:59:32 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "appearance"... Mar 02 14:59:34 volumio01.larpa.arpa systemd[1]: systemd-hostnamed.service: Succeeded. Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "network"... Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: Refreshing Cached IP Addresses Mar 02 14:59:34 volumio01.larpa.arpa sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 02 14:59:34 volumio01.larpa.arpa sudo[1064]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "services"... Mar 02 14:59:34 volumio01.larpa.arpa sudo[1064]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:34 volumio01.larpa.arpa sudo[1066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 14:59:34 volumio01.larpa.arpa sudo[1066]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "alsa_controller"... Mar 02 14:59:34 volumio01.larpa.arpa sudo[1066]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:34 volumio01.larpa.arpa sudo[1069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 02 14:59:34 volumio01.larpa.arpa sudo[1069]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:34 volumio01.larpa.arpa volumio-remote-updater[594]: [2025-03-02 14:59:34] [connect] Successful connection Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "wizard"... Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "networkfs"... Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: Starting Udev Watcher for removable devices Mar 02 14:59:34 volumio01.larpa.arpa sudo[1077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=roon,password=5^+YZsL6cNt2xKm,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.18/mnt/Music /mnt/NAS/Music Mar 02 14:59:34 volumio01.larpa.arpa sudo[1077]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: Ignoring mount for partition: boot Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: Ignoring mount for partition: volumio Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: Ignoring mount for partition: volumio_data Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "volumio_command_line_client"... Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "upnp"... Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: [1740920374693] Starting Upmpd Daemon Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "my_music"... Mar 02 14:59:34 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "mpd"... Mar 02 14:59:34 volumio01.larpa.arpa kernel: Key type cifs.spnego registered Mar 02 14:59:34 volumio01.larpa.arpa kernel: Key type cifs.idmap registered Mar 02 14:59:34 volumio01.larpa.arpa kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Mar 02 14:59:34 volumio01.larpa.arpa kernel: CIFS: Attempting to mount \\192.168.1.18\mnt Mar 02 14:59:35 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "upnp_browser"... Mar 02 14:59:35 volumio01.larpa.arpa sudo[1077]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:35 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "alarm-clock"... Mar 02 14:59:35 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "airplay_emulation"... Mar 02 14:59:35 volumio01.larpa.arpa volumio[1016]: info: Starting Shairport Sync Mar 02 14:59:35 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "last_100"... Mar 02 14:59:35 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "webradio"... Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "i2s_dacs"... Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: I2S DAC not set, start Auto-detection Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "volumiodiscovery"... Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: *** WARNING *** For more information see Mar 02 14:59:36 volumio01.larpa.arpa node[1016]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: *** WARNING *** For more information see Mar 02 14:59:36 volumio01.larpa.arpa node[1016]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 02 14:59:36 volumio01.larpa.arpa node[1016]: *** WARNING *** For more information see Mar 02 14:59:36 volumio01.larpa.arpa node[1016]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 02 14:59:36 volumio01.larpa.arpa node[1016]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 02 14:59:36 volumio01.larpa.arpa node[1016]: *** WARNING *** For more information see Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: Discovery: Started advertising with name: Volumio01.larpa.arpa Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "outputs"... Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "albumart"... Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: Plugin example_plugin is not enabled Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "inputs"... Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "updater_comm"... Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: Plugin mpdemulation is not enabled Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "rest_api"... Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: Loading plugin "websocket"... Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: Starting Socket.io Server version 2.3.0 Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: Loading i18n strings for locale en Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: Updating browse sources language Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: Forking 3 albumart workers Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::initPlayerControls Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: Express server listening on port 3000 Mar 02 14:59:36 volumio01.larpa.arpa volumio[1016]: [Metrics] WebUI: 6s 349.54ms Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::resetVolumioState Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::getcurrentVolume Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioRetrievevolume Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: verbose: New Socket.io Connection to 192.168.2.50 from 192.168.1.99 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Edg/133.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Mar 02 14:59:37 volumio01.larpa.arpa volumio-remote-updater[594]: [2025-03-02 14:59:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740920374 101 Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: Reloading queue from file Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: Setting Device type: Raspberry PI Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: VolumeController:: Volume=41 Mute =false Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::pushState Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioPushState Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::updateTrackBlock Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrackBlock Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioRetrievevolume Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::setRepeat null single undefined Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::pushState Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioPushState Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::setRandom null Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::pushState Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioPushState Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: verbose: New Socket.io Connection to 192.168.2.50 from 192.168.1.99 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Edg/133.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: Completed loading Core Plugins Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: Preparing to generate the ALSA configuration file Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: Starting albumart workers Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: VolumeController:: Volume=41 Mute =false Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::pushState Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioPushState Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: Starting albumart workers Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioGetState Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: Starting albumart workers Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioGetVisibleSources Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: Listing playlists Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioGetQueue Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::getQueue Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getQueue Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: Asound.conf file written Mar 02 14:59:37 volumio01.larpa.arpa sudo[1145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 02 14:59:37 volumio01.larpa.arpa sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:37 volumio01.larpa.arpa sudo[1145]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: Output device has changed, restarting MPD Mar 02 14:59:37 volumio01.larpa.arpa sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 02 14:59:37 volumio01.larpa.arpa sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: Output device has changed, restarting Shairport Sync Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:37 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa sudo[1150]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:38 volumio01.larpa.arpa sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 02 14:59:38 volumio01.larpa.arpa sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: ___________ START PLUGINS ___________ Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: ControllerMpd::onStart: Initializing MPD Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: Creating MPD Configuration file Mar 02 14:59:38 volumio01.larpa.arpa systemd[1]: Listening on mpd.socket. Mar 02 14:59:38 volumio01.larpa.arpa systemd[1]: Starting Music Player Daemon... Mar 02 14:59:38 volumio01.larpa.arpa sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: [1740920378171] CoreMusicLibrary::Adding element Media Servers Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 02 14:59:38 volumio01.larpa.arpa sudo[1069]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:38 volumio01.larpa.arpa sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:38 volumio01.larpa.arpa sudo[1163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 02 14:59:38 volumio01.larpa.arpa sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:38 volumio01.larpa.arpa sudo[1160]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:38 volumio01.larpa.arpa systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 02 14:59:38 volumio01.larpa.arpa systemd[1]: mpd.service: Succeeded. Mar 02 14:59:38 volumio01.larpa.arpa systemd[1]: Stopped Music Player Daemon. Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa systemd[1]: Starting Music Player Daemon... Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: [1740920378426] CoreMusicLibrary::Adding element Last_100 Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: [1740920378431] CoreMusicLibrary::Adding element Webradio Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 02 14:59:38 volumio01.larpa.arpa sudo[1174]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: Initializing BBC Radios Mar 02 14:59:38 volumio01.larpa.arpa sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:38 volumio01.larpa.arpa sudo[1174]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 02 14:59:38 volumio01.larpa.arpa sudo[1174]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: Volumio Calling Home Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: Discovery: adding 28450d4d-5b40-49a7-b00c-6c4249ecf579 Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: Discovery: Found device Volumio01.larpa.arpa Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioGetState Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: MPD Permissions set Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: MPD Permissions set Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: Volumio called home Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:38 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioGetState Mar 02 14:59:39 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 14:59:39 volumio01.larpa.arpa volumio[1016]: info: Starting Shairport Sync Mar 02 14:59:39 volumio01.larpa.arpa volumio[1016]: info: Starting Shairport Sync Mar 02 14:59:39 volumio01.larpa.arpa volumio[1016]: info: Starting Shairport Sync Mar 02 14:59:39 volumio01.larpa.arpa sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 02 14:59:39 volumio01.larpa.arpa sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:39 volumio01.larpa.arpa sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 02 14:59:39 volumio01.larpa.arpa sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:39 volumio01.larpa.arpa sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 02 14:59:39 volumio01.larpa.arpa sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:39 volumio01.larpa.arpa systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 02 14:59:39 volumio01.larpa.arpa systemd[1]: shairport-sync.service: Succeeded. Mar 02 14:59:39 volumio01.larpa.arpa systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 02 14:59:39 volumio01.larpa.arpa systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 02 14:59:39 volumio01.larpa.arpa sudo[1221]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:39 volumio01.larpa.arpa sudo[1223]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:39 volumio01.larpa.arpa sudo[1225]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:39 volumio01.larpa.arpa volumio[1016]: info: Shairport-Sync Started Mar 02 14:59:39 volumio01.larpa.arpa volumio[1016]: Error adding Membership: Error: addMembership EINVAL Mar 02 14:59:39 volumio01.larpa.arpa volumio[1016]: info: Shairport-Sync Started Mar 02 14:59:39 volumio01.larpa.arpa volumio[1016]: info: Shairport-Sync Started Mar 02 14:59:41 volumio01.larpa.arpa mpd[1200]: Mar 02 14:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 02 14:59:41 volumio01.larpa.arpa systemd[1]: Started Music Player Daemon. Mar 02 14:59:41 volumio01.larpa.arpa sudo[1152]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:41 volumio01.larpa.arpa sudo[1163]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:41 volumio01.larpa.arpa volumio[1016]: info: Completed starting Core Plugins Mar 02 14:59:41 volumio01.larpa.arpa volumio[1016]: info: ------------------------------------------- Mar 02 14:59:41 volumio01.larpa.arpa volumio[1016]: info: ----- MyVolumio plugins startup ---- Mar 02 14:59:41 volumio01.larpa.arpa volumio[1016]: info: ------------------------------------------- Mar 02 14:59:41 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 02 14:59:41 volumio01.larpa.arpa volumio[1016]: error: MPD error: The expression evaluated to a falsy value: Mar 02 14:59:41 volumio01.larpa.arpa volumio[1016]: assert.ok(self.idling) Mar 02 14:59:41 volumio01.larpa.arpa volumio[1016]: error: The expression evaluated to a falsy value: Mar 02 14:59:41 volumio01.larpa.arpa volumio[1016]: assert.ok(self.idling) Mar 02 14:59:41 volumio01.larpa.arpa volumio[1016]: info: MPD running with PID1200 Mar 02 14:59:41 volumio01.larpa.arpa volumio[1016]: ,establishing connection Mar 02 14:59:41 volumio01.larpa.arpa volumio[1016]: error: updateQueue error: null Mar 02 14:59:41 volumio01.larpa.arpa volumio[1016]: error: updateQueue error: null Mar 02 14:59:44 volumio01.larpa.arpa sudo[1238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 02 14:59:44 volumio01.larpa.arpa sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:44 volumio01.larpa.arpa sudo[1238]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:44 volumio01.larpa.arpa sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 14:59:44 volumio01.larpa.arpa sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:44 volumio01.larpa.arpa sudo[1240]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:44 volumio01.larpa.arpa sudo[1244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 02 14:59:44 volumio01.larpa.arpa sudo[1244]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:44 volumio01.larpa.arpa systemd[1]: Started UPnP Renderer front-end to MPD. Mar 02 14:59:44 volumio01.larpa.arpa sudo[1244]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:44 volumio01.larpa.arpa volumio[1016]: info: Upmpdcli Daemon Started Mar 02 14:59:44 volumio01.larpa.arpa volumio[1246]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 02 14:59:45 volumio01.larpa.arpa volumio[1246]: .++++ Mar 02 14:59:46 volumio01.larpa.arpa volumio[1016]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: Adding plugin bluetooth to MyMusic Plugins Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: Adding plugin multiroom to MyMusic Plugins Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: Adding plugin metavolumio to MyMusic Plugins Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: Adding plugin cd_controller to MyMusic Plugins Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 02 14:59:49 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 02 14:59:50 volumio01.larpa.arpa volumio[1246]: .........................................++++ Mar 02 14:59:50 volumio01.larpa.arpa volumio[1246]: e is 65537 (0x010001) Mar 02 14:59:50 volumio01.larpa.arpa volumio[1246]: writing RSA key Mar 02 14:59:51 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 02 14:59:51 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 02 14:59:51 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:51 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:51 volumio01.larpa.arpa volumio[1016]: info: Starting MyVolumio Remote Streaming Endpoints Mar 02 14:59:51 volumio01.larpa.arpa volumio[1016]: info: MyVolumio login type: Token Mar 02 14:59:51 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 02 14:59:51 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 02 14:59:52 volumio01.larpa.arpa volumio[1016]: info: Starting Streaming Service Transparent Proxy Mar 02 14:59:52 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 02 14:59:52 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 02 14:59:52 volumio01.larpa.arpa volumio[1016]: info: Streaming services startup Mar 02 14:59:52 volumio01.larpa.arpa volumio[1016]: info: Starting Streaming Daemon Mar 02 14:59:52 volumio01.larpa.arpa sudo[1284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 02 14:59:52 volumio01.larpa.arpa sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:52 volumio01.larpa.arpa sudo[1284]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:52 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 02 14:59:52 volumio01.larpa.arpa volumio[1016]: error: Cannot start Volumio Streaming Daemon Mar 02 14:59:52 volumio01.larpa.arpa volumio[1016]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 02 14:59:52 volumio01.larpa.arpa volumio[1016]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 02 14:59:53 volumio01.larpa.arpa volumio[1016]: error: MyVolumio Custom Token format not valid, refreshing it Mar 02 14:59:53 volumio01.larpa.arpa volumio[1016]: STREAMING PROXY: Starting server on port 3245 Mar 02 14:59:53 volumio01.larpa.arpa volumio[1016]: Node JS runtime: 14 Mar 02 14:59:53 volumio01.larpa.arpa volumio[1016]: info: MyVolumio login type: Token Mar 02 14:59:54 volumio01.larpa.arpa volumio[1016]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 02 14:59:54 volumio01.larpa.arpa volumio[1016]: info: MyVolumio token set successfully Mar 02 14:59:54 volumio01.larpa.arpa volumio[1016]: info: MYVOLUMIO: Adding device Mar 02 14:59:54 volumio01.larpa.arpa volumio[1016]: info: MYVOLUMIO: Evaluating Server Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: MyVolumio Plan changed: premium Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: Removing browser output: myVolumio user plan is not superstar Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: Removing audio output: Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: MYVOLUMIO: Adding device Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: MYVOLUMIO: Evaluating Server Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: Remote config written successfully Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: Starting Tunnel 1 Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: Starting Tunnel Connection Checker Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: Completed starting MyVolumio Plugin Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: MYVolumio Device enabled Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: MyVolumio status changed Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: Streaming services startup Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: Starting Streaming Daemon Mar 02 14:59:55 volumio01.larpa.arpa sudo[1324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 02 14:59:55 volumio01.larpa.arpa sudo[1324]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: Setting Geolocation for MyVolumio to eu4 Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:55 volumio01.larpa.arpa sudo[1324]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: error: Cannot start Volumio Streaming Daemon Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: error: [MyVolumio PluginManager] Cache data is invalid! Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: Setting Geolocation for MyVolumio to eu4 Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:55 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:56 volumio01.larpa.arpa volumio[1016]: info: Successfully Added MyVolumio device Mar 02 14:59:56 volumio01.larpa.arpa volumio[1016]: info: Successfully Added MyVolumio device Mar 02 14:59:56 volumio01.larpa.arpa volumio[1016]: info: Updating MyVolumio device info Mar 02 14:59:56 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:56 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:56 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:56 volumio01.larpa.arpa volumio[1016]: info: Updating MyVolumio device info Mar 02 14:59:56 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:56 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 02 14:59:57 volumio01.larpa.arpa volumio[1016]: info: [1740920397140] Starting BluetoothController Mar 02 14:59:57 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 02 14:59:58 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 02 14:59:58 volumio01.larpa.arpa sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 02 14:59:58 volumio01.larpa.arpa sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:58 volumio01.larpa.arpa sudo[1327]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:58 volumio01.larpa.arpa volumio[1016]: info: MRS: MultiRoom plugin initialized Mar 02 14:59:58 volumio01.larpa.arpa volumio[1016]: info: MRS: STOPPING SNAPCLIENT Mar 02 14:59:58 volumio01.larpa.arpa volumio[1016]: info: MRS: Snap server stop Mar 02 14:59:58 volumio01.larpa.arpa sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 02 14:59:58 volumio01.larpa.arpa sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:58 volumio01.larpa.arpa volumio[1016]: info: MRS: STOPPING volumioStreaming Mar 02 14:59:58 volumio01.larpa.arpa sudo[1345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 02 14:59:58 volumio01.larpa.arpa sudo[1345]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:58 volumio01.larpa.arpa sudo[1349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 02 14:59:58 volumio01.larpa.arpa sudo[1349]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:58 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 02 14:59:58 volumio01.larpa.arpa sudo[1343]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:59 volumio01.larpa.arpa sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 02 14:59:59 volumio01.larpa.arpa sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:59 volumio01.larpa.arpa sudo[1352]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:59 volumio01.larpa.arpa sudo[1345]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:59 volumio01.larpa.arpa sudo[1349]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Preparing to generate the ALSA configuration file Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Reading ALSA contributions from plugins. Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Successfully Updated MyVolumio device Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: MRS: Removed streaming files Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: MRS: volumioStreaming STOPPED Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: MRS: SNAPSERVER STOPPED Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: MRS: SNAPCLIENT STOPPED Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Asound.conf file written Mar 02 14:59:59 volumio01.larpa.arpa sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 02 14:59:59 volumio01.larpa.arpa sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:59 volumio01.larpa.arpa sudo[1355]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Output device has changed, restarting MPD Mar 02 14:59:59 volumio01.larpa.arpa sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 02 14:59:59 volumio01.larpa.arpa sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Output device has changed, restarting Shairport Sync Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 14:59:59 volumio01.larpa.arpa sudo[1360]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:59 volumio01.larpa.arpa sudo[1362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 02 14:59:59 volumio01.larpa.arpa sudo[1362]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:59 volumio01.larpa.arpa systemd[1]: Stopping Music Player Daemon... Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: MRS: Pushing multiroomSync output for this device Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: MRS: Pushing multiroomSync output Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Adding audio output: Mar 02 14:59:59 volumio01.larpa.arpa sudo[1371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio01.larpa.arpa Mar 02 14:59:59 volumio01.larpa.arpa sudo[1371]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Adding audio output: Mar 02 14:59:59 volumio01.larpa.arpa sudo[1371]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Adding METAVOLUMIO REST API Endpoints Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Preparing CD Folders Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Adding CD REST API Endpoints Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Starting UDEV Watcher for CD Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Detecting CD presence with UDEV Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Mar 02 14:59:59 volumio01.larpa.arpa systemd[1]: mpd.service: Succeeded. Mar 02 14:59:59 volumio01.larpa.arpa systemd[1]: Stopped Music Player Daemon. Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Adding inputs REST Endpoints Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Scanning Audio Inputs Mar 02 14:59:59 volumio01.larpa.arpa systemd[1]: Starting Music Player Daemon... Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Checking against Known Cards name Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Adding Server instance for streaming Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: error: Hi Res Audio Failed Login: Missing Login Data Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Refreshing TIDAL token Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Mar 02 14:59:59 volumio01.larpa.arpa sudo[1380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 02 14:59:59 volumio01.larpa.arpa sudo[1380]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Stopping AccessToken refresher cron for QOBUZ Mar 02 14:59:59 volumio01.larpa.arpa sudo[1376]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 02 14:59:59 volumio01.larpa.arpa sudo[1376]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:59:59 volumio01.larpa.arpa sudo[1380]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:59 volumio01.larpa.arpa sudo[1376]: pam_unix(sudo:session): session closed for user root Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: AccessToken refresher cron started for QOBUZ Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Adding QOBUZ REST API Endpoints Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Successfully Updated MyVolumio device Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: Bluetooth name changed to Volumio01.larpa.arpa Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: MPD Permissions set Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 14:59:59 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 15:00:00 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 15:00:00 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 15:00:00 volumio01.larpa.arpa volumio[1016]: info: Starting Shairport Sync Mar 02 15:00:00 volumio01.larpa.arpa sudo[1390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 02 15:00:00 volumio01.larpa.arpa sudo[1390]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 15:00:00 volumio01.larpa.arpa systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 02 15:00:00 volumio01.larpa.arpa systemd[1]: shairport-sync.service: Succeeded. Mar 02 15:00:00 volumio01.larpa.arpa systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 02 15:00:00 volumio01.larpa.arpa systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 02 15:00:00 volumio01.larpa.arpa sudo[1390]: pam_unix(sudo:session): session closed for user root Mar 02 15:00:00 volumio01.larpa.arpa volumio[1016]: info: Shairport-Sync Started Mar 02 15:00:00 volumio01.larpa.arpa sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 02 15:00:00 volumio01.larpa.arpa sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 15:00:00 volumio01.larpa.arpa systemd[1]: Started MyVolumio SSH Tunnel. Mar 02 15:00:00 volumio01.larpa.arpa sudo[1400]: pam_unix(sudo:session): session closed for user root Mar 02 15:00:00 volumio01.larpa.arpa volumio[1016]: info: Remote SSH Started Mar 02 15:00:00 volumio01.larpa.arpa autossh[1403]: port set to 0, monitoring disabled Mar 02 15:00:00 volumio01.larpa.arpa autossh[1403]: starting ssh (count 1) Mar 02 15:00:00 volumio01.larpa.arpa autossh[1403]: ssh child pid is 1406 Mar 02 15:00:00 volumio01.larpa.arpa volumio[1016]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 02 15:00:00 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioGetState Mar 02 15:00:00 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 15:00:00 volumio01.larpa.arpa volumio[1016]: info: Access Token successfully retrieved Mar 02 15:00:01 volumio01.larpa.arpa volumio[1016]: info: Successfully retrieved User Session From TIDAL Mar 02 15:00:01 volumio01.larpa.arpa volumio[1016]: info: Successfully retrieved User Subscription From TIDAL Mar 02 15:00:01 volumio01.larpa.arpa volumio[1016]: info: Adding TIDAL to Browse Sources Mar 02 15:00:01 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 02 15:00:01 volumio01.larpa.arpa volumio[1016]: info: [1740920401300] CoreMusicLibrary::Adding element TIDAL Mar 02 15:00:01 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 02 15:00:01 volumio01.larpa.arpa volumio[1016]: Cannot find translation for source TIDAL Mar 02 15:00:01 volumio01.larpa.arpa volumio[1016]: info: Stopping AccessToken refresher cron Mar 02 15:00:01 volumio01.larpa.arpa mpd[1384]: Mar 02 15:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 02 15:00:01 volumio01.larpa.arpa volumio[1016]: info: AccessToken refresher cron started Mar 02 15:00:01 volumio01.larpa.arpa volumio[1016]: info: Adding TIDAL REST API Endpoints Mar 02 15:00:01 volumio01.larpa.arpa systemd[1]: Started Music Player Daemon. Mar 02 15:00:01 volumio01.larpa.arpa sudo[1362]: pam_unix(sudo:session): session closed for user root Mar 02 15:00:01 volumio01.larpa.arpa volumio[1016]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 02 15:00:01 volumio01.larpa.arpa volumio[1016]: error: updateQueue error: null Mar 02 15:00:02 volumio01.larpa.arpa sudo[1448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 02 15:00:02 volumio01.larpa.arpa sudo[1448]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 15:00:02 volumio01.larpa.arpa systemd[1]: Started Volumio Bluetooth Module. Mar 02 15:00:02 volumio01.larpa.arpa sudo[1448]: pam_unix(sudo:session): session closed for user root Mar 02 15:00:02 volumio01.larpa.arpa volumio[1016]: info: Volumio BT Module successfully started Mar 02 15:00:02 volumio01.larpa.arpa sudo[1451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Mar 02 15:00:02 volumio01.larpa.arpa sudo[1451]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 15:00:02 volumio01.larpa.arpa volumiobt[1450]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Mar 02 15:00:02 volumio01.larpa.arpa sudo[1451]: pam_unix(sudo:session): session closed for user root Mar 02 15:00:02 volumio01.larpa.arpa volumio[1016]: info: TidalConnect service stoped! Mar 02 15:00:02 volumio01.larpa.arpa volumio[1016]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 02 15:00:02 volumio01.larpa.arpa volumio[1016]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 02 15:00:02 volumio01.larpa.arpa sudo[1463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 02 15:00:02 volumio01.larpa.arpa sudo[1463]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 15:00:03 volumio01.larpa.arpa systemd[1]: Started Volumio Tidal Connect Service. Mar 02 15:00:03 volumio01.larpa.arpa sudo[1463]: pam_unix(sudo:session): session closed for user root Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: Executing endpoint tc_getconfig Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 02 15:00:03 volumio01.larpa.arpa vtcs[1465]: STARTING TidalConnect services, version: 1.4.0.34 Mar 02 15:00:03 volumio01.larpa.arpa vtcs[1465]: STARTED TidalConnect services. Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: Executing endpoint tc_connect Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: Connecting to TidalConnect Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::servicePushState Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::pushState Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioPushState Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: MRS: Pushing multiroomSync output update for this device Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: MRS: Pushing multiroomSync output Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::servicePushState Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::pushState Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioPushState Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: MRS: Pushing multiroomSync output update for this device Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: MRS: Pushing multiroomSync output Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Mar 02 15:00:03 volumio01.larpa.arpa sudo[1484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Mar 02 15:00:03 volumio01.larpa.arpa sudo[1484]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 15:00:03 volumio01.larpa.arpa volumiobt[1450]: pulseaudio: no process found Mar 02 15:00:03 volumio01.larpa.arpa sudo[1484]: pam_unix(sudo:session): session closed for user root Mar 02 15:00:03 volumio01.larpa.arpa dbus-daemon[586]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.19' (uid=1000 pid=1488 comm="/usr/bin/pulseaudio --high-priority=true ") Mar 02 15:00:03 volumio01.larpa.arpa systemd[1]: Starting RealtimeKit Scheduling Policy Service... Mar 02 15:00:03 volumio01.larpa.arpa volumio[1016]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 02 15:00:03 volumio01.larpa.arpa dbus-daemon[586]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Mar 02 15:00:03 volumio01.larpa.arpa systemd[1]: Started RealtimeKit Scheduling Policy Service. Mar 02 15:00:03 volumio01.larpa.arpa rtkit-daemon[1489]: Successfully called chroot. Mar 02 15:00:03 volumio01.larpa.arpa rtkit-daemon[1489]: Successfully dropped privileges. Mar 02 15:00:03 volumio01.larpa.arpa rtkit-daemon[1489]: Successfully limited resources. Mar 02 15:00:03 volumio01.larpa.arpa rtkit-daemon[1489]: Running. Mar 02 15:00:03 volumio01.larpa.arpa rtkit-daemon[1489]: Canary thread running. Mar 02 15:00:03 volumio01.larpa.arpa rtkit-daemon[1489]: Watchdog thread running. Mar 02 15:00:03 volumio01.larpa.arpa dbus-daemon[586]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.20' (uid=0 pid=1489 comm="/usr/lib/rtkit/rtkit-daemon ") Mar 02 15:00:04 volumio01.larpa.arpa systemd[1]: Starting Authorization Manager... Mar 02 15:00:04 volumio01.larpa.arpa volumio[1016]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 02 15:00:04 volumio01.larpa.arpa polkitd[1492]: started daemon version 0.105 using authority implementation `local' version `0.105' Mar 02 15:00:04 volumio01.larpa.arpa dbus-daemon[586]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Mar 02 15:00:04 volumio01.larpa.arpa systemd[1]: Started Authorization Manager. Mar 02 15:00:04 volumio01.larpa.arpa pulseaudio[1488]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 02 15:00:04 volumio01.larpa.arpa pulseaudio[1488]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: Applying permissions Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: Setting BT discoverable and pairable Mar 02 15:00:04 volumio01.larpa.arpa bluetoothd[767]: Endpoint registered: sender=:1.40 path=/MediaEndpoint/A2DPSource Mar 02 15:00:04 volumio01.larpa.arpa bluetoothd[767]: Endpoint registered: sender=:1.40 path=/MediaEndpoint/A2DPSink Mar 02 15:00:04 volumio01.larpa.arpa pulseaudio[1488]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Mar 02 15:00:04 volumio01.larpa.arpa kernel: Bluetooth: RFCOMM TTY layer initialized Mar 02 15:00:04 volumio01.larpa.arpa kernel: Bluetooth: RFCOMM socket layer initialized Mar 02 15:00:04 volumio01.larpa.arpa kernel: Bluetooth: RFCOMM ver 1.11 Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [CHG] Controller B8:27:EB:B0:F6:82 UUIDs: 00001112-0000-1000-8000-00805f9b34fb Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [91B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [55B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [117B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [bluetooth]# -e power on Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: Invalid command in menu main: -e Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: Use "help" for a list of available commands in a menu. Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: Use "menu " if you want to enter any submenu. Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: Use "back" if you want to return to menu main. Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [bluetooth]# agent on Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: Agent is already registered Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [bluetooth]# discoverable on Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [bluetooth]# pairable on Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [bluetooth]# agent NoInputNoOutput Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: Agent is already registered Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [bluetooth]# default-agent Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [bluetooth]# quit Mar 02 15:00:04 volumio01.larpa.arpa volumiobt[1450]: [59B blob data] Mar 02 15:00:04 volumio01.larpa.arpa volumio[1016]: info: MRS: Getting audio outputs on start Mar 02 15:00:04 volumio01.larpa.arpa volumio[1016]: info: MRS: Requesting all other devices output Mar 02 15:00:06 volumio01.larpa.arpa volumio[1016]: info: TidalConnect service started! Mar 02 15:00:06 volumio01.larpa.arpa volumio[1016]: info: BOOT COMPLETED Mar 02 15:00:06 volumio01.larpa.arpa volumio[1016]: [Metrics] CommandRouter: 34s 559.82ms Mar 02 15:00:06 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumiosetStartupVolume Mar 02 15:00:06 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 15:00:06 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 15:00:06 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::Close All Modals sent Mar 02 15:00:06 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::Close All Modals sent Mar 02 15:00:06 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 02 15:00:06 volumio01.larpa.arpa volumio[1016]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 02 15:00:06 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioGetState Mar 02 15:00:06 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 15:00:07 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 02 15:00:07 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 02 15:00:07 volumio01.larpa.arpa volumio[1016]: xcb_connection_has_error() returned true Mar 02 15:00:07 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 02 15:00:08 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 02 15:00:08 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 15:00:08 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 02 15:00:08 volumio01.larpa.arpa ntpd[754]: Soliciting pool server 135.181.151.153 Mar 02 15:00:08 volumio01.larpa.arpa volumio-remote-updater[594]: No test mode Mar 02 15:00:08 volumio01.larpa.arpa volumio-remote-updater[594]: No alpha test mode Mar 02 15:00:08 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 02 15:00:08 volumio01.larpa.arpa volumio[1016]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

    • Improved translations
    • Fix for Allo Piano DAC on RPi with kernel 6.6.x

 

NEW ADDITIONS

    • Add support for Raspberry Pi Display V2
    • Bump kernel to 6.6.62

 

","title":"Update v3.785","updateavailable":true} Mar 02 15:00:08 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 02 15:00:09 volumio01.larpa.arpa ntpd[754]: Soliciting pool server 65.109.81.187 Mar 02 15:00:10 volumio01.larpa.arpa ntpd[754]: Soliciting pool server 95.216.138.141 Mar 02 15:00:10 volumio01.larpa.arpa ntpd[754]: Soliciting pool server 162.159.200.123 Mar 02 15:00:11 volumio01.larpa.arpa ntpd[754]: Soliciting pool server 37.27.186.231 Mar 02 15:00:11 volumio01.larpa.arpa ntpd[754]: Soliciting pool server 37.27.51.34 Mar 02 15:00:11 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 15:00:11 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 15:00:11 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 02 15:00:11 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 02 15:00:11 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 02 15:00:11 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioGetBrowseSources Mar 02 15:00:11 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 02 15:00:11 volumio01.larpa.arpa ntpd[754]: Soliciting pool server 135.181.216.186 Mar 02 15:00:12 volumio01.larpa.arpa ntpd[754]: Soliciting pool server 95.217.220.251 Mar 02 15:00:12 volumio01.larpa.arpa ntpd[754]: Soliciting pool server 37.27.202.175 Mar 02 15:00:12 volumio01.larpa.arpa ntpd[754]: Soliciting pool server 65.109.9.242 Mar 02 15:00:13 volumio01.larpa.arpa ntpd[754]: Soliciting pool server 95.216.71.38 Mar 02 15:00:13 volumio01.larpa.arpa ntpd[754]: Soliciting pool server 95.217.153.166 Mar 02 15:00:13 volumio01.larpa.arpa ntpd[754]: Soliciting pool server 65.109.1.5 Mar 02 15:00:14 volumio01.larpa.arpa ntpd[754]: Soliciting pool server 2a01:4f9:c012:46b2::123 Mar 02 15:00:39 volumio01.larpa.arpa ntpd[754]: receive: Unexpected origin timestamp 0xeb6ed4e0.3488d403 does not match aorg 0000000000.00000000 from server@37.27.51.34 xmt 0xeb6ed4f7.2a54d2e2 Mar 02 15:00:39 volumio01.larpa.arpa ntpd[754]: receive: Unexpected origin timestamp 0xeb6ed4e0.348b9aee does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xeb6ed4f7.2a506966 Mar 02 15:00:39 volumio01.larpa.arpa ntpd[754]: receive: Unexpected origin timestamp 0xeb6ed4e0.3487cbd1 does not match aorg 0000000000.00000000 from server@135.181.216.186 xmt 0xeb6ed4f7.2a527770 Mar 02 15:00:39 volumio01.larpa.arpa ntpd[754]: receive: Unexpected origin timestamp 0xeb6ed4e0.34848461 does not match aorg 0000000000.00000000 from server@65.109.1.5 xmt 0xeb6ed4f7.2907446e Mar 02 15:00:39 volumio01.larpa.arpa ntpd[754]: receive: Unexpected origin timestamp 0xeb6ed4e0.348da4e6 does not match aorg 0000000000.00000000 from server@135.181.151.153 xmt 0xeb6ed4f7.2a5d366c Mar 02 15:00:39 volumio01.larpa.arpa ntpd[754]: receive: Unexpected origin timestamp 0xeb6ed4e0.34868465 does not match aorg 0000000000.00000000 from server@37.27.202.175 xmt 0xeb6ed4f7.2a593a76 Mar 02 15:00:39 volumio01.larpa.arpa ntpd[754]: receive: Unexpected origin timestamp 0xeb6ed4e0.348a1fc9 does not match aorg 0000000000.00000000 from server@37.27.186.231 xmt 0xeb6ed4f7.2a53c878 Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: CURURI: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: Preload queue cleared Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: Preloading song: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 01 Intro.flac Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: Preloading song: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 02 Silent Pictures.flac Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: Preloading song: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 03 Coming Down From Outer Space.flac Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: Preloading song: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 04 Laser Headlights.flac Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: Preloading song: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 05 Red River.flac Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: Preloading song: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 06 Infinite Skies.flac Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: Preloading song: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 07 Center Of Everywhere.flac Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: Preloading song: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 08 Outro.flac Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: Exploding uri music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 01 Intro.flac in service mpd Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: ALBUMART /albumart?cacheid=787&web=Acid%20King/Middle%20of%20Nowhere%2C%20Center%20of%20Everywhere/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAcid%20King%20-%20Middle%20of%20Nowhere-%20Center%20of%20Everywhere%2FAcid%20King%20-%20Middle%20of%20Nowhere%2C%20Center%20of%20Everywhere%20-%2001%20Intro.flac&metadata=false Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: URI /mnt/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 01 Intro.flac Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: Exploding uri music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 02 Silent Pictures.flac in service mpd Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: ALBUMART /albumart?cacheid=787&web=Acid%20King/Middle%20of%20Nowhere%2C%20Center%20of%20Everywhere/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAcid%20King%20-%20Middle%20of%20Nowhere-%20Center%20of%20Everywhere%2FAcid%20King%20-%20Middle%20of%20Nowhere%2C%20Center%20of%20Everywhere%20-%2002%20Silent%20Pictures.flac&metadata=false Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: URI /mnt/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 02 Silent Pictures.flac Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: Exploding uri music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 03 Coming Down From Outer Space.flac in service mpd Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: ALBUMART /albumart?cacheid=787&web=Acid%20King/Middle%20of%20Nowhere%2C%20Center%20of%20Everywhere/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAcid%20King%20-%20Middle%20of%20Nowhere-%20Center%20of%20Everywhere%2FAcid%20King%20-%20Middle%20of%20Nowhere%2C%20Center%20of%20Everywhere%20-%2003%20Coming%20Down%20From%20Outer%20Space.flac&metadata=false Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: URI /mnt/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 03 Coming Down From Outer Space.flac Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: Exploding uri music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 04 Laser Headlights.flac in service mpd Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: ALBUMART /albumart?cacheid=787&web=Acid%20King/Middle%20of%20Nowhere%2C%20Center%20of%20Everywhere/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAcid%20King%20-%20Middle%20of%20Nowhere-%20Center%20of%20Everywhere%2FAcid%20King%20-%20Middle%20of%20Nowhere%2C%20Center%20of%20Everywhere%20-%2004%20Laser%20Headlights.flac&metadata=false Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: URI /mnt/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 04 Laser Headlights.flac Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: Exploding uri music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 05 Red River.flac in service mpd Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: ALBUMART /albumart?cacheid=787&web=Acid%20King/Middle%20of%20Nowhere%2C%20Center%20of%20Everywhere/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAcid%20King%20-%20Middle%20of%20Nowhere-%20Center%20of%20Everywhere%2FAcid%20King%20-%20Middle%20of%20Nowhere%2C%20Center%20of%20Everywhere%20-%2005%20Red%20River.flac&metadata=false Mar 02 15:00:39 volumio01.larpa.arpa volumio[1016]: info: URI /mnt/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 05 Red River.flac Mar 02 15:00:40 volumio01.larpa.arpa volumio[1016]: info: Exploding uri music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 06 Infinite Skies.flac in service mpd Mar 02 15:00:40 volumio01.larpa.arpa volumio[1016]: info: ALBUMART /albumart?cacheid=787&web=Acid%20King/Middle%20of%20Nowhere%2C%20Center%20of%20Everywhere/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAcid%20King%20-%20Middle%20of%20Nowhere-%20Center%20of%20Everywhere%2FAcid%20King%20-%20Middle%20of%20Nowhere%2C%20Center%20of%20Everywhere%20-%2006%20Infinite%20Skies.flac&metadata=false Mar 02 15:00:40 volumio01.larpa.arpa volumio[1016]: info: URI /mnt/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 06 Infinite Skies.flac Mar 02 15:00:40 volumio01.larpa.arpa volumio[1016]: info: Exploding uri music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 07 Center Of Everywhere.flac in service mpd Mar 02 15:00:40 volumio01.larpa.arpa volumio[1016]: info: ALBUMART /albumart?cacheid=787&web=Acid%20King/Middle%20of%20Nowhere%2C%20Center%20of%20Everywhere/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAcid%20King%20-%20Middle%20of%20Nowhere-%20Center%20of%20Everywhere%2FAcid%20King%20-%20Middle%20of%20Nowhere%2C%20Center%20of%20Everywhere%20-%2007%20Center%20Of%20Everywhere.flac&metadata=false Mar 02 15:00:40 volumio01.larpa.arpa volumio[1016]: info: URI /mnt/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 07 Center Of Everywhere.flac Mar 02 15:00:40 volumio01.larpa.arpa volumio[1016]: info: Exploding uri music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 08 Outro.flac in service mpd Mar 02 15:00:40 volumio01.larpa.arpa volumio[1016]: info: ALBUMART /albumart?cacheid=787&web=Acid%20King/Middle%20of%20Nowhere%2C%20Center%20of%20Everywhere/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FAcid%20King%20-%20Middle%20of%20Nowhere-%20Center%20of%20Everywhere%2FAcid%20King%20-%20Middle%20of%20Nowhere%2C%20Center%20of%20Everywhere%20-%2008%20Outro.flac&metadata=false Mar 02 15:00:40 volumio01.larpa.arpa volumio[1016]: info: URI /mnt/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 08 Outro.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Preload queue cleared Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::ClearQueue Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::stop Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::clearPlayQueue Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::saveQueue Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioPushQueue Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::addQueueItems Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::addQueueItems Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Preload queue cleared Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Adding Item to queue: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 01 Intro.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Using cached record of: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 01 Intro.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Adding Item to queue: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 02 Silent Pictures.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Using cached record of: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 02 Silent Pictures.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Adding Item to queue: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 03 Coming Down From Outer Space.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Using cached record of: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 03 Coming Down From Outer Space.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Adding Item to queue: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 04 Laser Headlights.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Using cached record of: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 04 Laser Headlights.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Adding Item to queue: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 05 Red River.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Using cached record of: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 05 Red River.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Adding Item to queue: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 06 Infinite Skies.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Using cached record of: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 06 Infinite Skies.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Adding Item to queue: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 07 Center Of Everywhere.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Using cached record of: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 07 Center Of Everywhere.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Adding Item to queue: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 08 Outro.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Using cached record of: music-library/NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 08 Outro.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioPushQueue Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::saveQueue Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::updateTrackBlock Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrackBlock Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioPlay Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::play index 0 Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::stop Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::play index undefined Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::startPlaybackTimer Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 01 Intro.flac Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: sendMpdCommand stop took 6 milliseconds Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::sendMpdCommand clear Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: ---------------------------- MPD announces system playlist update Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Ignoring MPD Status Update Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: sendMpdCommand clear took 3 milliseconds Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 01 Intro.flac" Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: error: updateQueue error: null Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: ---------------------------- MPD announces system playlist update Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Ignoring MPD Status Update Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: ------------------------------ 7ms Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: sendMpdCommand add "NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 01 Intro.flac" took 4 milliseconds Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::sendMpdCommand play Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: ------------------------------ 6ms Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: sendMpdCommand play took 4 milliseconds Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: ---------------------------- MPD announces state update: player Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: ControllerMpd::getState Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: ---------------------------- MPD announces state update: player Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: sendMpdCommand status took 18 milliseconds Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: ControllerMpd::getState Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::parseState Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: sendMpdCommand status took 5 milliseconds Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::parseState Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::parseTrackInfo Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: ControllerMpd::pushState Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::servicePushState Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Intro","artist":"Acid King","album":"Middle of Nowhere, Center of Everywhere","uri":"NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 01 Intro.flac","trackType":"flac"} Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: CURRENT POSITION 0 Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::syncState stateService play Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::syncState currentStatus stop Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: ------------------------------ 37ms Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::parseTrackInfo Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: ControllerMpd::pushState Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::servicePushState Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Intro","artist":"Acid King","album":"Middle of Nowhere, Center of Everywhere","uri":"NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 01 Intro.flac","trackType":"flac"} Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: verbose: CURRENT POSITION 0 Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::syncState stateService play Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::syncState currentStatus play Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: Received an update from plugin. extracting info from payload Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::pushState Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioPushState Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: MRS: Pushing multiroomSync output update for this device Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: MRS: Pushing multiroomSync output Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::pushState Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioPushState Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: MRS: Pushing multiroomSync output update for this device Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: MRS: Pushing multiroomSync output Mar 02 15:00:41 volumio01.larpa.arpa volumio[1016]: info: ------------------------------ 83ms Mar 02 15:00:44 volumio01.larpa.arpa volumiossh-tunnel[1402]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Mar 02 15:00:44 volumio01.larpa.arpa wpa_supplicant[907]: RRM: Ignoring radio measurement request: Not RRM network Mar 02 15:00:48 volumio01.larpa.arpa volumio[1016]: verbose: New Socket.io Connection to 192.168.2.50 from 192.168.1.99 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Edg/133.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Mar 02 15:00:48 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioGetState Mar 02 15:00:48 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 15:00:48 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 02 15:00:48 volumio01.larpa.arpa volumio[1016]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 02 15:00:48 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioGetVisibleSources Mar 02 15:00:48 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 02 15:00:48 volumio01.larpa.arpa volumio[1016]: info: Listing playlists Mar 02 15:00:48 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioGetQueue Mar 02 15:00:48 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::getQueue Mar 02 15:00:48 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getQueue Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: Pausing Remote Device: http://192.168.2.50 Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: ---------------------------- Client requests Volumio pause Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioPause Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::pause Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::stPlaybackTimer Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::servicePause Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::servicePause Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: ControllerMpd::pause Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::sendMpdCommand pause Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: ---------------------------- MPD announces state update: player Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: sendMpdCommand pause took 3 milliseconds Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: ControllerMpd::getState Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: sendMpdCommand status took 5 milliseconds Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::parseState Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: Done pausing: Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: verbose: ControllerMpd::parseTrackInfo Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: ControllerMpd::pushState Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::servicePushState Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14597,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"608 Kbps","isStreaming":false,"title":"Intro","artist":"Acid King","album":"Middle of Nowhere, Center of Everywhere","uri":"NAS/Music/Acid King - Middle of Nowhere- Center of Everywhere/Acid King - Middle of Nowhere, Center of Everywhere - 01 Intro.flac","trackType":"flac"} Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: verbose: CURRENT POSITION 0 Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::syncState stateService pause Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::syncState currentStatus pause Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::pushState Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: CorePlayQueue::getTrack 0 Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::volumioPushState Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: MRS: Pushing multiroomSync output update for this device Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: MRS: Pushing multiroomSync output Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: CoreStateMachine::stPlaybackTimer Mar 02 15:00:54 volumio01.larpa.arpa volumio[1016]: info: ------------------------------ 43ms Mar 02 15:00:55 volumio01.larpa.arpa volumio[1016]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Mar 02 15:00:55 volumio01.larpa.arpa volumio[1016]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Mar 02 15:00:55 volumio01.larpa.arpa volumio[1016]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 02 15:00:55 volumio01.larpa.arpa volumio[1016]: TypeError: Cannot read property 'then' of undefined Mar 02 15:00:55 volumio01.larpa.arpa volumio[1016]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Mar 02 15:00:55 volumio01.larpa.arpa volumio[1016]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2260:30) Mar 02 15:00:55 volumio01.larpa.arpa volumio[1016]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1442:26) Mar 02 15:00:55 volumio01.larpa.arpa volumio[1016]: at Socket.emit (events.js:315:20) Mar 02 15:00:55 volumio01.larpa.arpa volumio[1016]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 02 15:00:55 volumio01.larpa.arpa volumio[1016]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 02 15:00:55 volumio01.larpa.arpa volumio[1016]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 02 15:00:55 volumio01.larpa.arpa sudo[1522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-02 14:59 Mar 02 15:00:55 volumio01.larpa.arpa sudo[1522]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"