-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sun 2022-12-04 19:48:02 UTC. -- Dec 04 19:47:00 4aw-volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Dec 04 19:47:00 4aw-volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Dec 04 19:47:00 4aw-volumio systemd[1]: Started ifup for eth0. Dec 04 19:47:00 4aw-volumio systemd[1]: Started ifup for wlan0. Dec 04 19:47:00 4aw-volumio systemd[1]: Starting Raise network interfaces... Dec 04 19:47:00 4aw-volumio systemd[1]: Starting Create Volatile Files and Directories... Dec 04 19:47:00 4aw-volumio systemd[1]: Starting Preprocess NFS configuration... Dec 04 19:47:00 4aw-volumio systemd[1]: plymouth-read-write.service: Succeeded. Dec 04 19:47:00 4aw-volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Dec 04 19:47:00 4aw-volumio systemd[1]: Received SIGRTMIN+20 from PID 233 (plymouthd). Dec 04 19:47:00 4aw-volumio systemd[1]: nfs-config.service: Succeeded. Dec 04 19:47:00 4aw-volumio systemd[1]: Started Preprocess NFS configuration. Dec 04 19:47:00 4aw-volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Dec 04 19:47:00 4aw-volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Dec 04 19:47:00 4aw-volumio systemd[1]: Reached target NFS client services. Dec 04 19:47:00 4aw-volumio systemd[1]: Started Create Volatile Files and Directories. Dec 04 19:47:00 4aw-volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Dec 04 19:47:00 4aw-volumio systemd[1]: Starting RPC bind portmap service... Dec 04 19:47:00 4aw-volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Dec 04 19:47:00 4aw-volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Dec 04 19:47:00 4aw-volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Dec 04 19:47:00 4aw-volumio systemd[1]: Reached target System Initialization. Dec 04 19:47:00 4aw-volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 04 19:47:00 4aw-volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 04 19:47:00 4aw-volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Dec 04 19:47:00 4aw-volumio systemd[1]: apt-daily.timer: Not using persistent file timestamp Thu 2024-03-28 19:48:17 UTC as it is in the future. Dec 04 19:47:00 4aw-volumio systemd[1]: Started Daily apt download activities. Dec 04 19:47:00 4aw-volumio systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Thu 2024-03-28 19:48:17 UTC as it is in the future. Dec 04 19:47:00 4aw-volumio systemd[1]: Started Daily apt upgrade and clean activities. Dec 04 19:47:00 4aw-volumio systemd[1]: Reached target Timers. Dec 04 19:47:00 4aw-volumio systemd[1]: Listening on triggerhappy.socket. Dec 04 19:47:00 4aw-volumio systemd[1]: Listening on mpd.socket. Dec 04 19:47:00 4aw-volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 04 19:47:00 4aw-volumio systemd[1]: Reached target Sockets. Dec 04 19:47:00 4aw-volumio systemd[1]: Reached target Basic System. Dec 04 19:47:00 4aw-volumio systemd[1]: Started Manage Sound Card State (restore and store). Dec 04 19:47:00 4aw-volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Dec 04 19:47:00 4aw-volumio systemd[1]: Started UPnP Renderer front-end to MPD. Dec 04 19:47:00 4aw-volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Dec 04 19:47:00 4aw-volumio alsactl[465]: alsactl 1.1.8 daemon started Dec 04 19:47:00 4aw-volumio systemd[1]: Starting Wireless Services... Dec 04 19:47:00 4aw-volumio systemd[1]: Starting Configure Bluetooth Modems connected by UART... Dec 04 19:47:00 4aw-volumio systemd[1]: Started Volumio Iptables Module. Dec 04 19:47:00 4aw-volumio systemd[1]: Starting Save/Restore Sound Card State... Dec 04 19:47:00 4aw-volumio systemd[1]: Started volumio-remote-updater.service. Dec 04 19:47:00 4aw-volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Dec 04 19:47:00 4aw-volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 04 19:47:00 4aw-volumio systemd[1]: Starting Login Service... Dec 04 19:47:00 4aw-volumio systemd[1]: Started D-Bus System Message Bus. Dec 04 19:47:00 4aw-volumio systemd[1]: Starting WPA supplicant... Dec 04 19:47:00 4aw-volumio systemd[1]: Starting dhcpcd on all interfaces... Dec 04 19:47:00 4aw-volumio systemd[1]: Starting triggerhappy global hotkey daemon... Dec 04 19:47:00 4aw-volumio systemd[1]: Started RPC bind portmap service. Dec 04 19:47:00 4aw-volumio systemd[1]: Started Save/Restore Sound Card State. Dec 04 19:47:01 4aw-volumio ifup[359]: ifup: waiting for lock on /run/network/ifstate.wlan0 Dec 04 19:47:01 4aw-volumio systemd[1]: Reached target Sound Card. Dec 04 19:47:01 4aw-volumio systemd[1]: Reached target Remote File Systems (Pre). Dec 04 19:47:01 4aw-volumio systemd[1]: Reached target Remote File Systems. Dec 04 19:47:01 4aw-volumio sh[357]: eth0=eth0 Dec 04 19:47:01 4aw-volumio sh[358]: wlan0=wlan0 Dec 04 19:47:01 4aw-volumio thd[484]: Unable to parse trigger line: Dec 04 19:47:01 4aw-volumio thd[484]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Dec 04 19:47:01 4aw-volumio thd[484]: Unable to parse trigger line: Dec 04 19:47:01 4aw-volumio thd[484]: Found socket passed from systemd Dec 04 19:47:01 4aw-volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Dec 04 19:47:01 4aw-volumio systemd[1]: Reached target RPC Port Mapper. Dec 04 19:47:01 4aw-volumio systemd[1]: Started triggerhappy global hotkey daemon. Dec 04 19:47:01 4aw-volumio kernel: uart-pl011 3f201000.serial: no DMA platform data Dec 04 19:47:01 4aw-volumio systemd-logind[479]: New seat seat0. Dec 04 19:47:01 4aw-volumio dhcpcd[483]: dev: loaded udev Dec 04 19:47:01 4aw-volumio avahi-daemon[478]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Dec 04 19:47:01 4aw-volumio avahi-daemon[478]: Successfully dropped root privileges. Dec 04 19:47:01 4aw-volumio avahi-daemon[478]: avahi-daemon 0.7 starting up. Dec 04 19:47:01 4aw-volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Dec 04 19:47:01 4aw-volumio kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Dec 04 19:47:01 4aw-volumio dhcpcd[483]: forked to background, child pid 540 Dec 04 19:47:02 4aw-volumio systemd[1]: Started Login Service. Dec 04 19:47:02 4aw-volumio systemd[1]: Started dhcpcd on all interfaces. Dec 04 19:47:02 4aw-volumio avahi-daemon[478]: Successfully called chroot(). Dec 04 19:47:02 4aw-volumio avahi-daemon[478]: Successfully dropped remaining capabilities. Dec 04 19:47:02 4aw-volumio avahi-daemon[478]: Loading service file /services/volumio.service. Dec 04 19:47:02 4aw-volumio avahi-daemon[478]: Network interface enumeration completed. Dec 04 19:47:02 4aw-volumio avahi-daemon[478]: Server startup complete. Host name is 4aw-volumio.local. Local service cookie is 2331829144. Dec 04 19:47:02 4aw-volumio avahi-daemon[478]: Service "4AW Volumio" (/services/volumio.service) successfully established. Dec 04 19:47:02 4aw-volumio systemd[1]: Started Raise network interfaces. Dec 04 19:47:02 4aw-volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 04 19:47:03 4aw-volumio loadcpufreq[510]: Loading cpufreq kernel modules...done (none). Dec 04 19:47:03 4aw-volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Dec 04 19:47:03 4aw-volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Dec 04 19:47:03 4aw-volumio haveged[410]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Dec 04 19:47:03 4aw-volumio haveged[410]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Dec 04 19:47:03 4aw-volumio haveged[410]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99653 Dec 04 19:47:03 4aw-volumio haveged[410]: haveged: fills: 0, generated: 0 Dec 04 19:47:03 4aw-volumio dhcpcd-run-hooks[595]: wlan0: starting wpa_supplicant Dec 04 19:47:03 4aw-volumio cpufrequtils[596]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 04 19:47:03 4aw-volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Dec 04 19:47:03 4aw-volumio systemd[1]: iptables.service: Succeeded. Dec 04 19:47:03 4aw-volumio systemd[1]: Started WPA supplicant. Dec 04 19:47:03 4aw-volumio wpa_supplicant[481]: Successfully initialized wpa_supplicant Dec 04 19:47:03 4aw-volumio systemd[1]: Reached target Network. Dec 04 19:47:03 4aw-volumio systemd[1]: Starting Music Player Daemon... Dec 04 19:47:03 4aw-volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Dec 04 19:47:03 4aw-volumio systemd[1]: Starting Permit User Sessions... Dec 04 19:47:03 4aw-volumio systemd[1]: Reached target Network is Online. Dec 04 19:47:03 4aw-volumio systemd[1]: Starting LSB: Brings up/down network automatically... Dec 04 19:47:03 4aw-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 04 19:47:03 4aw-volumio systemd[1]: Starting Samba NMB Daemon... Dec 04 19:47:03 4aw-volumio systemd[1]: Starting Network Time Service... Dec 04 19:47:03 4aw-volumio systemd[1]: Starting /etc/rc.local Compatibility... Dec 04 19:47:03 4aw-volumio systemd[1]: Started /etc/rc.local Compatibility. Dec 04 19:47:03 4aw-volumio systemd[1]: Started Permit User Sessions. Dec 04 19:47:03 4aw-volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Dec 04 19:47:03 4aw-volumio systemd[1]: Starting Hold until boot process finishes up... Dec 04 19:47:03 4aw-volumio systemd[1]: systemd-rfkill.service: Succeeded. Dec 04 19:47:03 4aw-volumio systemd[1]: Received SIGRTMIN+21 from PID 233 (plymouthd). Dec 04 19:47:03 4aw-volumio ifplugd(eth0)[643]: ifplugd 0.28 initializing. Dec 04 19:47:03 4aw-volumio ifplugd(eth0)[643]: Using interface eth0/B8:27:EB:CB:3C:0A with driver (version: 5.10.92-v7+) Dec 04 19:47:03 4aw-volumio ifplugd(eth0)[643]: Using detection mode: SIOCETHTOOL Dec 04 19:47:03 4aw-volumio ifplugd(eth0)[643]: Initialization complete, link beat not detected. Dec 04 19:47:03 4aw-volumio systemd[1]: Received SIGRTMIN+21 from PID 233 (plymouthd). Dec 04 19:47:03 4aw-volumio systemd[1]: plymouth-quit.service: Succeeded. Dec 04 19:47:03 4aw-volumio systemd[1]: Started Terminate Plymouth Boot Screen. Dec 04 19:47:03 4aw-volumio systemd[1]: plymouth-start.service: Succeeded. Dec 04 19:47:03 4aw-volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Dec 04 19:47:03 4aw-volumio systemd[1]: Started Hold until boot process finishes up. Dec 04 19:47:03 4aw-volumio systemd[1]: Started Getty on tty1. Dec 04 19:47:03 4aw-volumio systemd[1]: Reached target Login Prompts. Dec 04 19:47:04 4aw-volumio ifplugd[619]: Network Interface Plugging Daemon...start eth0...done. Dec 04 19:47:04 4aw-volumio systemd[1]: Started LSB: Brings up/down network automatically. Dec 04 19:47:04 4aw-volumio ntpd[623]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 04 19:47:04 4aw-volumio ntpd[623]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Dec 04 19:47:04 4aw-volumio systemd[1]: Started Network Time Service. Dec 04 19:47:04 4aw-volumio ntpd[648]: proto: precision = 0.625 usec (-21) Dec 04 19:47:04 4aw-volumio ntpd[648]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 04 19:47:04 4aw-volumio ntpd[648]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 04 19:47:04 4aw-volumio ntpd[648]: Listen and drop on 0 v6wildcard [::]:123 Dec 04 19:47:04 4aw-volumio ntpd[648]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 04 19:47:04 4aw-volumio ntpd[648]: Listen normally on 2 lo 127.0.0.1:123 Dec 04 19:47:04 4aw-volumio ntpd[648]: Listening on routing socket on fd #19 for interface updates Dec 04 19:47:04 4aw-volumio ntpd[648]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 04 19:47:04 4aw-volumio ntpd[648]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 04 19:47:04 4aw-volumio volumio-remote-updater[477]: Error: No active session Dec 04 19:47:04 4aw-volumio volumio-remote-updater[477]: [2022-12-04 19:47:04] [info] asio async_connect error: system:111 (Connection refused) Dec 04 19:47:04 4aw-volumio volumio-remote-updater[477]: [2022-12-04 19:47:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 04 19:47:04 4aw-volumio volumio-remote-updater[477]: [2022-12-04 19:47:04] [error] handle_connect error: Underlying Transport Error Dec 04 19:47:04 4aw-volumio dhcpcd[540]: wlan0: connected to Access Point `' Dec 04 19:47:04 4aw-volumio dhcpcd[540]: eth0: waiting for carrier Dec 04 19:47:04 4aw-volumio dhcpcd[540]: wlan0: waiting for carrier Dec 04 19:47:05 4aw-volumio volumio[470]: Could not open config: /tmp/upmpdcli.conf Dec 04 19:47:05 4aw-volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 04 19:47:05 4aw-volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 04 19:47:05 4aw-volumio ntpd[648]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 04 19:47:06 4aw-volumio ntpd[648]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 04 19:47:07 4aw-volumio nmbd[626]: [2022/12/04 19:47:07.009658, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 04 19:47:07 4aw-volumio nmbd[626]: started asyncdns process 671 Dec 04 19:47:07 4aw-volumio nmbd[626]: [2022/12/04 19:47:07.012714, 0] ../lib/util/become_daemon.c:149(daemon_status) Dec 04 19:47:07 4aw-volumio nmbd[626]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 04 19:47:07 4aw-volumio nmbd[626]: [2022/12/04 19:47:07.012978, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Dec 04 19:47:07 4aw-volumio nmbd[626]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 04 19:47:07 4aw-volumio ntpd[648]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 04 19:47:08 4aw-volumio wireless.js[473]: Cleaning previous... Dec 04 19:47:08 4aw-volumio ntpd[648]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 04 19:47:08 4aw-volumio kernel: Bluetooth: Core ver 2.22 Dec 04 19:47:08 4aw-volumio kernel: NET: Registered protocol family 31 Dec 04 19:47:08 4aw-volumio kernel: Bluetooth: HCI device and connection manager initialized Dec 04 19:47:08 4aw-volumio kernel: Bluetooth: HCI socket layer initialized Dec 04 19:47:08 4aw-volumio kernel: Bluetooth: L2CAP socket layer initialized Dec 04 19:47:08 4aw-volumio kernel: Bluetooth: SCO socket layer initialized Dec 04 19:47:08 4aw-volumio kernel: Bluetooth: HCI UART driver ver 2.3 Dec 04 19:47:08 4aw-volumio kernel: Bluetooth: HCI UART protocol H4 registered Dec 04 19:47:08 4aw-volumio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Dec 04 19:47:08 4aw-volumio kernel: Bluetooth: HCI UART protocol Broadcom registered Dec 04 19:47:08 4aw-volumio btuart[474]: bcm43xx_init Dec 04 19:47:08 4aw-volumio btuart[474]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Dec 04 19:47:08 4aw-volumio btuart[474]: Set BDADDR UART: b8:27:eb:61:96:a0 Dec 04 19:47:08 4aw-volumio btuart[474]: Set Controller UART speed to 3000000 bit/s Dec 04 19:47:08 4aw-volumio btuart[474]: Device setup complete Dec 04 19:47:08 4aw-volumio systemd[1]: Started Configure Bluetooth Modems connected by UART. Dec 04 19:47:08 4aw-volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Dec 04 19:47:08 4aw-volumio sudo[680]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 04 19:47:08 4aw-volumio sudo[680]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:08 4aw-volumio sudo[680]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:08 4aw-volumio sudo[691]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 04 19:47:08 4aw-volumio sudo[691]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:08 4aw-volumio systemd[1]: Started Load/Save RF Kill Switch Status. Dec 04 19:47:08 4aw-volumio systemd[1]: Created slice system-bthelper.slice. Dec 04 19:47:08 4aw-volumio systemd[1]: Starting Raspberry Pi bluetooth helper... Dec 04 19:47:08 4aw-volumio bthelper[693]: Raspberry Pi BDADDR already set Dec 04 19:47:08 4aw-volumio systemd[1]: Started Raspberry Pi bluetooth helper. Dec 04 19:47:08 4aw-volumio systemd[1]: Starting Bluetooth service... Dec 04 19:47:08 4aw-volumio bluetoothd[702]: Bluetooth daemon 5.50 Dec 04 19:47:08 4aw-volumio bluetoothd[702]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Dec 04 19:47:08 4aw-volumio systemd[1]: Started Bluetooth service. Dec 04 19:47:08 4aw-volumio systemd[1]: Reached target Bluetooth. Dec 04 19:47:08 4aw-volumio bluetoothd[702]: Starting SDP server Dec 04 19:47:08 4aw-volumio bluetoothd[702]: Excluding (cli) sap Dec 04 19:47:08 4aw-volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 04 19:47:08 4aw-volumio kernel: Bluetooth: BNEP filters: protocol multicast Dec 04 19:47:08 4aw-volumio kernel: Bluetooth: BNEP socket layer initialized Dec 04 19:47:08 4aw-volumio dbus-daemon[480]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=702 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Dec 04 19:47:08 4aw-volumio bluetoothd[702]: Bluetooth management interface 1.18 initialized Dec 04 19:47:08 4aw-volumio systemd[1]: Starting Hostname Service... Dec 04 19:47:09 4aw-volumio bluetoothd[702]: Failed to set privacy: Rejected (0x0b) Dec 04 19:47:09 4aw-volumio mpd[646]: Dec 04 19:47 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 04 19:47:09 4aw-volumio sudo[691]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:09 4aw-volumio wireless.js[473]: Stopped aP Dec 04 19:47:09 4aw-volumio sudo[711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 04 19:47:09 4aw-volumio sudo[711]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:09 4aw-volumio sudo[711]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:09 4aw-volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 04 19:47:09 4aw-volumio sudo[713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 04 19:47:09 4aw-volumio sudo[713]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:09 4aw-volumio mpd[646]: Dec 04 19:47 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 04 19:47:09 4aw-volumio mpd[646]: Dec 04 19:47 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 04 19:47:09 4aw-volumio dbus-daemon[480]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 04 19:47:09 4aw-volumio systemd[1]: Started Hostname Service. Dec 04 19:47:09 4aw-volumio sudo[713]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:09 4aw-volumio systemd[1]: Started Music Player Daemon. Dec 04 19:47:09 4aw-volumio sudo[722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 04 19:47:09 4aw-volumio sudo[722]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:09 4aw-volumio sudo[722]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:09 4aw-volumio sudo[724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 04 19:47:09 4aw-volumio sudo[724]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:09 4aw-volumio volumio-remote-updater[477]: [2022-12-04 19:47:09] [info] asio async_connect error: system:111 (Connection refused) Dec 04 19:47:09 4aw-volumio volumio-remote-updater[477]: [2022-12-04 19:47:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 04 19:47:09 4aw-volumio volumio-remote-updater[477]: [2022-12-04 19:47:09] [error] handle_connect error: Underlying Transport Error Dec 04 19:47:12 4aw-volumio sudo[724]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:12 4aw-volumio wireless.js[473]: Start wireless flow Dec 04 19:47:12 4aw-volumio wireless.js[473]: Stopped hotspot (if there).. Dec 04 19:47:12 4aw-volumio sudo[729]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 04 19:47:12 4aw-volumio sudo[729]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:12 4aw-volumio sudo[729]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:12 4aw-volumio sudo[731]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 04 19:47:12 4aw-volumio sudo[731]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:12 4aw-volumio sudo[731]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:12 4aw-volumio wireless.js[473]: DHCP IP Dec 04 19:47:12 4aw-volumio wireless.js[473]: Start ap Dec 04 19:47:12 4aw-volumio wpa_supplicant[733]: Successfully initialized wpa_supplicant Dec 04 19:47:12 4aw-volumio sudo[734]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Dec 04 19:47:12 4aw-volumio sudo[734]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:12 4aw-volumio dhcpcd[735]: sending commands to master dhcpcd process Dec 04 19:47:12 4aw-volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 04 19:47:12 4aw-volumio dhcpcd[540]: control command: /sbin/dhcpcd Dec 04 19:47:12 4aw-volumio sudo[734]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:12 4aw-volumio dhcpcd[540]: lo: carrier acquired Dec 04 19:47:12 4aw-volumio dhcpcd[540]: DUID 00:01:00:01:2b:1f:b5:b5:b8:27:eb:cb:3c:0a Dec 04 19:47:12 4aw-volumio dhcpcd[540]: lo: IAID 6c:6f:00:00 Dec 04 19:47:12 4aw-volumio dhcpcd[540]: lo: ipv6_start: Operation not supported Dec 04 19:47:12 4aw-volumio dhcpcd[540]: lo: using static address 127.0.0.1/8 Dec 04 19:47:12 4aw-volumio dhcpcd[540]: lo: adding route to 127.0.0.0/8 Dec 04 19:47:12 4aw-volumio dhcpcd[540]: arp_announce: Invalid argument Dec 04 19:47:13 4aw-volumio systemd[1]: systemd-rfkill.service: Succeeded. Dec 04 19:47:13 4aw-volumio wireless.js[473]: trying... Dec 04 19:47:13 4aw-volumio sudo[765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 04 19:47:13 4aw-volumio sudo[765]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:13 4aw-volumio sudo[765]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:13 4aw-volumio bthelper[693]: Changing power off succeeded Dec 04 19:47:13 4aw-volumio bthelper[693]: [CHG] Controller B8:27:EB:61:96:A0 Powered: no Dec 04 19:47:13 4aw-volumio bthelper[693]: [CHG] Controller B8:27:EB:61:96:A0 Discovering: no Dec 04 19:47:13 4aw-volumio bthelper[693]: [CHG] Controller B8:27:EB:61:96:A0 Class: 0x00000000 Dec 04 19:47:14 4aw-volumio bthelper[693]: [CHG] Controller B8:27:EB:61:96:A0 Class: 0x0000041c Dec 04 19:47:14 4aw-volumio bthelper[693]: Changing power on succeeded Dec 04 19:47:14 4aw-volumio kernel: cam1-reg: disabling Dec 04 19:47:14 4aw-volumio kernel: cam-dummy-reg: disabling Dec 04 19:47:14 4aw-volumio wireless.js[473]: trying... Dec 04 19:47:14 4aw-volumio sudo[775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 04 19:47:14 4aw-volumio sudo[775]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:14 4aw-volumio sudo[775]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:15 4aw-volumio wpa_supplicant[751]: wlan0: Trying to associate with SSID '4AW_5G' Dec 04 19:47:15 4aw-volumio wireless.js[473]: trying... Dec 04 19:47:15 4aw-volumio sudo[778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 04 19:47:15 4aw-volumio sudo[778]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:15 4aw-volumio sudo[778]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:16 4aw-volumio wireless.js[473]: trying... Dec 04 19:47:16 4aw-volumio sudo[781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 04 19:47:16 4aw-volumio sudo[781]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:16 4aw-volumio sudo[781]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:17 4aw-volumio volumio-remote-updater[477]: [2022-12-04 19:47:17] [info] asio async_connect error: system:111 (Connection refused) Dec 04 19:47:17 4aw-volumio volumio-remote-updater[477]: [2022-12-04 19:47:17] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 04 19:47:17 4aw-volumio volumio-remote-updater[477]: [2022-12-04 19:47:17] [error] handle_connect error: Underlying Transport Error Dec 04 19:47:17 4aw-volumio wireless.js[473]: trying... Dec 04 19:47:17 4aw-volumio sudo[784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 04 19:47:17 4aw-volumio sudo[784]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:17 4aw-volumio sudo[784]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:18 4aw-volumio wpa_supplicant[751]: wlan0: Associated with 30:5a:3a:60:7c:9c Dec 04 19:47:18 4aw-volumio wpa_supplicant[751]: wlan0: CTRL-EVENT-CONNECTED - Connection to 30:5a:3a:60:7c:9c completed [id=0 id_str=] Dec 04 19:47:18 4aw-volumio dhcpcd[540]: wlan0: carrier acquired Dec 04 19:47:18 4aw-volumio dhcpcd[540]: wlan0: connected to Access Point `4AW_5G' Dec 04 19:47:18 4aw-volumio wpa_supplicant[751]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 04 19:47:18 4aw-volumio wpa_supplicant[751]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=HU Dec 04 19:47:18 4aw-volumio dhcpcd[540]: wlan0: IAID eb:9e:69:5f Dec 04 19:47:18 4aw-volumio dhcpcd[540]: wlan0: adding address fe80::ba27:ebff:fe9e:695f Dec 04 19:47:18 4aw-volumio dhcpcd[540]: ipv6_addaddr1: Permission denied Dec 04 19:47:18 4aw-volumio dhcpcd[540]: wlan0: rebinding lease of 192.168.1.239 Dec 04 19:47:18 4aw-volumio dhcpcd[540]: wlan0: probing address 192.168.1.239/24 Dec 04 19:47:18 4aw-volumio wireless.js[473]: trying... Dec 04 19:47:18 4aw-volumio sudo[788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 04 19:47:18 4aw-volumio sudo[788]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:18 4aw-volumio sudo[788]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:19 4aw-volumio dhcpcd[540]: wlan0: soliciting an IPv6 router Dec 04 19:47:19 4aw-volumio wireless.js[473]: trying... Dec 04 19:47:19 4aw-volumio sudo[791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 04 19:47:19 4aw-volumio sudo[791]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:19 4aw-volumio sudo[791]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:20 4aw-volumio wireless.js[473]: trying... Dec 04 19:47:20 4aw-volumio sudo[794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 04 19:47:20 4aw-volumio sudo[794]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:20 4aw-volumio sudo[794]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:21 4aw-volumio wireless.js[473]: trying... Dec 04 19:47:21 4aw-volumio sudo[797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 04 19:47:21 4aw-volumio sudo[797]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:21 4aw-volumio sudo[797]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:22 4aw-volumio wireless.js[473]: trying... Dec 04 19:47:22 4aw-volumio sudo[800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 04 19:47:22 4aw-volumio sudo[800]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:22 4aw-volumio sudo[800]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:23 4aw-volumio dhcpcd[540]: wlan0: leased 192.168.1.239 for 86400 seconds Dec 04 19:47:23 4aw-volumio avahi-daemon[478]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.239. Dec 04 19:47:23 4aw-volumio avahi-daemon[478]: New relevant interface wlan0.IPv4 for mDNS. Dec 04 19:47:23 4aw-volumio dhcpcd[540]: wlan0: adding route to 192.168.1.0/24 Dec 04 19:47:23 4aw-volumio avahi-daemon[478]: Registering new address record for 192.168.1.239 on wlan0.IPv4. Dec 04 19:47:23 4aw-volumio dhcpcd[540]: wlan0: adding default route via 192.168.1.1 Dec 04 19:47:23 4aw-volumio wireless.js[473]: trying... Dec 04 19:47:23 4aw-volumio sudo[824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 04 19:47:23 4aw-volumio sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:23 4aw-volumio sudo[824]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:23 4aw-volumio wireless.js[473]: Connected to: ----4AW_5G Dec 04 19:47:23 4aw-volumio wireless.js[473]: ---- Dec 04 19:47:23 4aw-volumio sudo[827]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 04 19:47:23 4aw-volumio sudo[827]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:23 4aw-volumio sudo[827]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:23 4aw-volumio wireless.js[473]: ... joined AP, wlan0 IPv4 is 192.168.1.239, ipV6 is undefined Dec 04 19:47:23 4aw-volumio wireless.js[473]: It's done! AP Dec 04 19:47:23 4aw-volumio systemd[1]: Started Wireless Services. Dec 04 19:47:23 4aw-volumio systemd[1]: Started Volumio Backend Module. Dec 04 19:47:23 4aw-volumio systemd[1]: Started Volumio Streaming Daemon. Dec 04 19:47:24 4aw-volumio volumio-streaming-daemon[832]: ############################ Dec 04 19:47:24 4aw-volumio volumio-streaming-daemon[832]: # Volumio Streaming Daemon # Dec 04 19:47:24 4aw-volumio volumio-streaming-daemon[832]: # Running on port 7777 # Dec 04 19:47:24 4aw-volumio volumio-streaming-daemon[832]: ############################ Dec 04 19:47:24 4aw-volumio volumio-streaming-daemon[832]: Environment: production Dec 04 19:47:24 4aw-volumio ntpd[648]: Listen normally on 3 wlan0 192.168.1.239:123 Dec 04 19:47:24 4aw-volumio ntpd[648]: new interface(s) found: waking up resolver Dec 04 19:47:26 4aw-volumio volumio[831]: info: ------------------------------------------- Dec 04 19:47:26 4aw-volumio volumio[831]: info: ----- Volumio3 ---- Dec 04 19:47:26 4aw-volumio volumio[831]: info: ------------------------------------------- Dec 04 19:47:26 4aw-volumio volumio[831]: info: ----- System startup ---- Dec 04 19:47:26 4aw-volumio volumio[831]: info: ------------------------------------------- Dec 04 19:47:27 4aw-volumio nmbd[626]: [2022/12/04 19:47:27.021697, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 04 19:47:27 4aw-volumio systemd[1]: Started Samba NMB Daemon. Dec 04 19:47:27 4aw-volumio nmbd[626]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 04 19:47:27 4aw-volumio systemd[1]: Starting Samba Winbind Daemon... Dec 04 19:47:27 4aw-volumio nmbd[626]: [2022/12/04 19:47:27.048611, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 04 19:47:27 4aw-volumio nmbd[626]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.239 for name WORKGROUP<1d>. Dec 04 19:47:27 4aw-volumio nmbd[626]: This response was from IP 192.168.1.253, reporting an IP address of 192.168.1.253. Dec 04 19:47:27 4aw-volumio volumio-remote-updater[477]: [2022-12-04 19:47:27] [connect] Successful connection Dec 04 19:47:27 4aw-volumio winbindd[852]: [2022/12/04 19:47:27.385810, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 04 19:47:27 4aw-volumio winbindd[852]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 04 19:47:27 4aw-volumio winbindd[852]: [2022/12/04 19:47:27.583737, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 04 19:47:27 4aw-volumio winbindd[852]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 04 19:47:27 4aw-volumio systemd[1]: Started Samba Winbind Daemon. Dec 04 19:47:27 4aw-volumio systemd[1]: Starting Samba SMB Daemon... Dec 04 19:47:28 4aw-volumio volumio[831]: info: MYVOLUMIO Environment detected Dec 04 19:47:28 4aw-volumio winbindd[852]: [2022/12/04 19:47:28.310108, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Dec 04 19:47:28 4aw-volumio winbindd[852]: res_names->count = 3, expected 5 Dec 04 19:47:28 4aw-volumio systemd[1]: Started Samba SMB Daemon. Dec 04 19:47:28 4aw-volumio smbd[857]: [2022/12/04 19:47:28.363624, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 04 19:47:28 4aw-volumio smbd[857]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 04 19:47:28 4aw-volumio systemd[1]: Reached target Multi-User System. Dec 04 19:47:28 4aw-volumio systemd[1]: Reached target Graphical Interface. Dec 04 19:47:28 4aw-volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 04 19:47:28 4aw-volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 04 19:47:28 4aw-volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 04 19:47:28 4aw-volumio systemd[1]: Startup finished in 11.730s (kernel) + 36.563s (userspace) = 48.293s. Dec 04 19:47:28 4aw-volumio volumio[831]: info: Plugin folders cleanup Dec 04 19:47:28 4aw-volumio volumio[831]: info: Scanning into folder /volumio/app/plugins/ Dec 04 19:47:28 4aw-volumio volumio[831]: info: Scanning category audio_interface Dec 04 19:47:28 4aw-volumio volumio[831]: info: Scanning category miscellanea Dec 04 19:47:28 4aw-volumio volumio[831]: info: Scanning category music_service Dec 04 19:47:28 4aw-volumio volumio[831]: info: Scanning category plugins.json Dec 04 19:47:28 4aw-volumio volumio[831]: info: Scanning category system_controller Dec 04 19:47:28 4aw-volumio volumio[831]: info: Scanning category user_interface Dec 04 19:47:28 4aw-volumio volumio[831]: info: Scanning into folder /data/plugins/ Dec 04 19:47:28 4aw-volumio volumio[831]: info: Scanning category audio_interface Dec 04 19:47:28 4aw-volumio volumio[831]: info: Scanning category music_service Dec 04 19:47:28 4aw-volumio volumio[831]: info: Scanning category touch_display Dec 04 19:47:28 4aw-volumio volumio[831]: info: Scanning category user_interface Dec 04 19:47:28 4aw-volumio volumio[831]: info: Plugin folders cleanup completed Dec 04 19:47:28 4aw-volumio volumio[831]: info: ------------------------------------------- Dec 04 19:47:28 4aw-volumio volumio[831]: info: ----- Core plugins startup ---- Dec 04 19:47:28 4aw-volumio volumio[831]: info: ------------------------------------------- Dec 04 19:47:28 4aw-volumio volumio[831]: info: Loading plugins from folder /volumio/app/plugins/ Dec 04 19:47:28 4aw-volumio volumio[831]: info: Adding plugin upnp to MyMusic Plugins Dec 04 19:47:28 4aw-volumio volumio[831]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 04 19:47:28 4aw-volumio volumio[831]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 04 19:47:28 4aw-volumio volumio[831]: info: Loading plugins from folder /data/plugins/ Dec 04 19:47:28 4aw-volumio volumio[831]: info: Loading plugin "system"... Dec 04 19:47:28 4aw-volumio volumio[831]: info: Loading plugin "appearance"... Dec 04 19:47:29 4aw-volumio systemd[1]: systemd-fsckd.service: Succeeded. Dec 04 19:47:30 4aw-volumio volumio[831]: info: Loading plugin "network"... Dec 04 19:47:30 4aw-volumio volumio[831]: info: Refreshing Cached IP Addresses Dec 04 19:47:30 4aw-volumio sudo[869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 04 19:47:30 4aw-volumio sudo[869]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:30 4aw-volumio sudo[869]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:30 4aw-volumio volumio[831]: info: Loading plugin "services"... Dec 04 19:47:30 4aw-volumio sudo[871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 04 19:47:30 4aw-volumio sudo[871]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:30 4aw-volumio sudo[871]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:30 4aw-volumio volumio[831]: info: Loading plugin "alsa_controller"... Dec 04 19:47:30 4aw-volumio sudo[874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 04 19:47:30 4aw-volumio sudo[874]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:30 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 04 19:47:30 4aw-volumio volumio[831]: info: Loading plugin "wizard"... Dec 04 19:47:30 4aw-volumio volumio[831]: info: Loading plugin "volumio_command_line_client"... Dec 04 19:47:30 4aw-volumio volumio[831]: info: Loading plugin "upnp"... Dec 04 19:47:30 4aw-volumio volumio[831]: info: [1670183250365] Starting Upmpd Daemon Dec 04 19:47:30 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 04 19:47:30 4aw-volumio volumio[831]: info: Loading plugin "my_music"... Dec 04 19:47:30 4aw-volumio volumio[831]: info: Loading plugin "mpd"... Dec 04 19:47:30 4aw-volumio volumio[831]: info: Creating MPD Configuration file Dec 04 19:47:30 4aw-volumio volumio[831]: info: Loading plugin "upnp_browser"... Dec 04 19:47:30 4aw-volumio sudo[882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 04 19:47:30 4aw-volumio sudo[882]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:30 4aw-volumio sudo[882]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:30 4aw-volumio sudo[884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 04 19:47:30 4aw-volumio sudo[884]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:30 4aw-volumio systemd[1]: Stopping Music Player Daemon... Dec 04 19:47:31 4aw-volumio systemd[1]: mpd.service: Succeeded. Dec 04 19:47:31 4aw-volumio systemd[1]: Stopped Music Player Daemon. Dec 04 19:47:31 4aw-volumio systemd[1]: Starting Music Player Daemon... Dec 04 19:47:31 4aw-volumio volumio[831]: info: Loading plugin "networkfs"... Dec 04 19:47:32 4aw-volumio volumio[831]: info: Starting Udev Watcher for removable devices Dec 04 19:47:32 4aw-volumio sudo[892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=kodi,password=idok,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.240/musictest /mnt/NAS/Music_test Dec 04 19:47:32 4aw-volumio sudo[892]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:32 4aw-volumio sudo[894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=kodi,password=idok,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.240/music /mnt/NAS/music Dec 04 19:47:32 4aw-volumio sudo[894]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:32 4aw-volumio kernel: FS-Cache: Netfs 'cifs' registered for caching Dec 04 19:47:32 4aw-volumio kernel: Key type cifs.spnego registered Dec 04 19:47:32 4aw-volumio kernel: Key type cifs.idmap registered Dec 04 19:47:32 4aw-volumio kernel: CIFS: Attempting to mount //192.168.1.240/music Dec 04 19:47:32 4aw-volumio 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. Dec 04 19:47:32 4aw-volumio kernel: CIFS: Attempting to mount //192.168.1.240/musictest Dec 04 19:47:32 4aw-volumio kernel: FS-Cache: Duplicate cookie detected Dec 04 19:47:32 4aw-volumio kernel: FS-Cache: O-cookie c=71313809 [p=bac32b52 fl=222 nc=0 na=1] Dec 04 19:47:32 4aw-volumio kernel: FS-Cache: O-cookie d=d6c29f24 n=9eb119e7 Dec 04 19:47:32 4aw-volumio kernel: FS-Cache: O-key=[8] '020001bdc0a801f0' Dec 04 19:47:32 4aw-volumio kernel: FS-Cache: N-cookie c=f7ae3853 [p=bac32b52 fl=2 nc=0 na=1] Dec 04 19:47:32 4aw-volumio kernel: FS-Cache: N-cookie d=d6c29f24 n=36147ac6 Dec 04 19:47:32 4aw-volumio kernel: FS-Cache: N-key=[8] '020001bdc0a801f0' Dec 04 19:47:32 4aw-volumio volumio[831]: info: Ignoring mount for partition: boot Dec 04 19:47:32 4aw-volumio volumio[831]: info: Ignoring mount for partition: volumio Dec 04 19:47:32 4aw-volumio volumio[831]: info: Ignoring mount for partition: volumio_data Dec 04 19:47:32 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 04 19:47:32 4aw-volumio volumio[831]: info: Loading plugin "alarm-clock"... Dec 04 19:47:32 4aw-volumio sudo[892]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:32 4aw-volumio mpd[889]: Dec 04 19:47 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 04 19:47:32 4aw-volumio sudo[894]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:32 4aw-volumio mpd[889]: Dec 04 19:47 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 04 19:47:32 4aw-volumio mpd[889]: Dec 04 19:47 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 04 19:47:32 4aw-volumio systemd[1]: Started Music Player Daemon. Dec 04 19:47:32 4aw-volumio sudo[884]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:32 4aw-volumio volumio[831]: info: Plugin airplay_emulation is not enabled Dec 04 19:47:32 4aw-volumio volumio[831]: info: Loading plugin "last_100"... Dec 04 19:47:32 4aw-volumio volumio[831]: info: Loading plugin "webradio"... Dec 04 19:47:32 4aw-volumio volumio[831]: info: Loading plugin "i2s_dacs"... Dec 04 19:47:32 4aw-volumio volumio[831]: info: I2S DAC not set, start Auto-detection Dec 04 19:47:32 4aw-volumio volumio[831]: info: Loading plugin "volumiodiscovery"... Dec 04 19:47:32 4aw-volumio volumio[831]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 04 19:47:32 4aw-volumio volumio[831]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 04 19:47:32 4aw-volumio volumio[831]: *** WARNING *** For more information see Dec 04 19:47:32 4aw-volumio node[831]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 04 19:47:32 4aw-volumio volumio[831]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 04 19:47:32 4aw-volumio volumio[831]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 04 19:47:32 4aw-volumio volumio[831]: *** WARNING *** For more information see Dec 04 19:47:32 4aw-volumio node[831]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 04 19:47:32 4aw-volumio node[831]: *** WARNING *** For more information see Dec 04 19:47:32 4aw-volumio node[831]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 04 19:47:32 4aw-volumio node[831]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 04 19:47:32 4aw-volumio node[831]: *** WARNING *** For more information see Dec 04 19:47:32 4aw-volumio volumio[831]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 04 19:47:32 4aw-volumio volumio[831]: info: Discovery: Started advertising with name: 4AW Volumio Dec 04 19:47:33 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 04 19:47:33 4aw-volumio volumio[831]: info: Loading plugin "spop"... Dec 04 19:47:33 4aw-volumio sudo[874]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:34 4aw-volumio volumio[831]: info: Loading plugin "outputs"... Dec 04 19:47:34 4aw-volumio volumio[831]: info: Loading plugin "albumart"... Dec 04 19:47:34 4aw-volumio volumio[831]: info: Plugin example_plugin is not enabled Dec 04 19:47:34 4aw-volumio volumio[831]: info: Loading plugin "inputs"... Dec 04 19:47:34 4aw-volumio volumio[831]: info: Loading plugin "updater_comm"... Dec 04 19:47:34 4aw-volumio volumio[831]: info: Plugin mpdemulation is not enabled Dec 04 19:47:34 4aw-volumio volumio[831]: info: Loading plugin "rest_api"... Dec 04 19:47:34 4aw-volumio volumio[831]: info: Loading plugin "websocket"... Dec 04 19:47:34 4aw-volumio volumio[831]: info: Loading plugin "fusiondsp"... Dec 04 19:47:35 4aw-volumio volumio[831]: Forking 3 albumart workers Dec 04 19:47:35 4aw-volumio volumio[831]: info: Applying required configuration parameters for plugin fusiondsp Dec 04 19:47:35 4aw-volumio volumio[831]: info: Loading plugin "touch_display"... Dec 04 19:47:35 4aw-volumio volumio[831]: Starting albumart workers Dec 04 19:47:35 4aw-volumio volumio[831]: Starting albumart workers Dec 04 19:47:35 4aw-volumio volumio[831]: Starting albumart workers Dec 04 19:47:36 4aw-volumio volumio[831]: info: Applying required configuration parameters for plugin touch_display Dec 04 19:47:36 4aw-volumio volumio[831]: info: Loading i18n strings for locale en Dec 04 19:47:36 4aw-volumio volumio[831]: Updating browse sources language Dec 04 19:47:36 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 04 19:47:36 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 04 19:47:36 4aw-volumio volumio[831]: info: CoreCommandRouter::initPlayerControls Dec 04 19:47:36 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:36 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:36 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:36 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:36 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:36 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:36 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:36 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:36 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 04 19:47:36 4aw-volumio volumio[831]: Express server listening on port 3000 Dec 04 19:47:36 4aw-volumio volumio[831]: [Metrics] WebUI: 11s 302.92ms Dec 04 19:47:36 4aw-volumio volumio[831]: info: CoreStateMachine::resetVolumioState Dec 04 19:47:36 4aw-volumio volumio[831]: info: CoreStateMachine::getcurrentVolume Dec 04 19:47:36 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioRetrievevolume Dec 04 19:47:36 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 04 19:47:37 4aw-volumio volumio[831]: info: MPD Permissions set Dec 04 19:47:37 4aw-volumio volumio[831]: verbose: New Socket.io Connection to 192.168.1.239 from 192.168.1.240 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 1 Dec 04 19:47:37 4aw-volumio volumio[831]: verbose: New Socket.io Connection to 192.168.1.239 from 192.168.1.240 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 2 Dec 04 19:47:37 4aw-volumio volumio[831]: verbose: New Socket.io Connection to 192.168.1.239 from 192.168.1.240 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 3 Dec 04 19:47:37 4aw-volumio volumio[831]: verbose: New Socket.io Connection to 192.168.1.239 from 192.168.1.240 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 4 Dec 04 19:47:37 4aw-volumio volumio[831]: verbose: New Socket.io Connection to 192.168.1.239 from 192.168.1.240 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 5 Dec 04 19:47:37 4aw-volumio volumio[831]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Dec 04 19:47:37 4aw-volumio volumio[831]: info: MPD running with PID889 Dec 04 19:47:37 4aw-volumio volumio[831]: ,establishing connection Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:37 4aw-volumio volumio[831]: info: Reloading queue from file Dec 04 19:47:37 4aw-volumio volumio[831]: error: updateQueue error: null Dec 04 19:47:37 4aw-volumio volumio[831]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 04 19:47:37 4aw-volumio volumio[831]: info: VolumeController:: Volume=20 Mute =false Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreStateMachine::pushState Dec 04 19:47:37 4aw-volumio volumio[831]: info: CorePlayQueue::getTrack 0 Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioPushState Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreStateMachine::updateTrackBlock Dec 04 19:47:37 4aw-volumio volumio[831]: info: CorePlayQueue::getTrackBlock Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioRetrievevolume Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreStateMachine::setRepeat false single undefined Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreStateMachine::pushState Dec 04 19:47:37 4aw-volumio volumio[831]: info: CorePlayQueue::getTrack 0 Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioPushState Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreStateMachine::setRandom null Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreStateMachine::pushState Dec 04 19:47:37 4aw-volumio volumio[831]: info: CorePlayQueue::getTrack 0 Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioPushState Dec 04 19:47:37 4aw-volumio volumio[831]: info: Setting Device type: Raspberry PI Dec 04 19:47:37 4aw-volumio volumio[831]: info: Completed loading Core Plugins Dec 04 19:47:37 4aw-volumio volumio[831]: info: Preparing to generate the ALSA configuration file Dec 04 19:47:37 4aw-volumio volumio[831]: info: Discovery: adding fb53e21d-3ac3-41fe-a31f-920e703b3557 Dec 04 19:47:37 4aw-volumio volumio[831]: info: Discovery: Found device 4AW Volumio Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioGetState Dec 04 19:47:37 4aw-volumio volumio[831]: info: CorePlayQueue::getTrack 0 Dec 04 19:47:37 4aw-volumio volumio[831]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 04 19:47:37 4aw-volumio volumio[831]: info: Reading ALSA contributions from plugins. Dec 04 19:47:37 4aw-volumio volumio[831]: info: VolumeController:: Volume=20 Mute =false Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreStateMachine::pushState Dec 04 19:47:37 4aw-volumio volumio[831]: info: CorePlayQueue::getTrack 0 Dec 04 19:47:37 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioPushState Dec 04 19:47:37 4aw-volumio volumio[831]: error: updateQueue error: null Dec 04 19:47:38 4aw-volumio volumio[831]: info: Asound.conf file unchanged, so no further update is needed Dec 04 19:47:38 4aw-volumio volumio[831]: info: Output device has changed, restarting MPD Dec 04 19:47:38 4aw-volumio sudo[1008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 04 19:47:38 4aw-volumio sudo[1008]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:38 4aw-volumio volumio[831]: info: ___________ START PLUGINS ___________ Dec 04 19:47:38 4aw-volumio sudo[1008]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 04 19:47:38 4aw-volumio volumio[831]: info: [1670183258057] CoreMusicLibrary::Adding element Media Servers Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 04 19:47:38 4aw-volumio volumio[831]: Cannot find translation for sourceMedia Servers Dec 04 19:47:38 4aw-volumio sudo[1011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 04 19:47:38 4aw-volumio sudo[1011]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 04 19:47:38 4aw-volumio volumio[831]: info: [1670183258084] CoreMusicLibrary::Adding element Last_100 Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 04 19:47:38 4aw-volumio volumio[831]: Cannot find translation for sourceMedia Servers Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 04 19:47:38 4aw-volumio volumio[831]: info: [1670183258087] CoreMusicLibrary::Adding element Webradio Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 04 19:47:38 4aw-volumio volumio[831]: Cannot find translation for sourceMedia Servers Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 04 19:47:38 4aw-volumio volumio[831]: info: Checking for old spotify connect plugin installed Dec 04 19:47:38 4aw-volumio systemd[1]: Stopping Music Player Daemon... Dec 04 19:47:38 4aw-volumio volumio[831]: info: Loading i18n strings for locale en Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:38 4aw-volumio volumio[831]: info: mixtype--------------------- Hardware Dec 04 19:47:38 4aw-volumio volumio[831]: info: Loading i18n strings for locale en Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 04 19:47:38 4aw-volumio systemd[1]: mpd.service: Succeeded. Dec 04 19:47:38 4aw-volumio systemd[1]: Stopped Music Player Daemon. Dec 04 19:47:38 4aw-volumio volumio[831]: info: Volumio Calling Home Dec 04 19:47:38 4aw-volumio systemd[1]: Starting Music Player Daemon... Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 04 19:47:38 4aw-volumio volumio[831]: [SpotifyConnect] Creating VLS config file Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:38 4aw-volumio volumio[831]: [SpotifyConnect] Starting metadata listener Dec 04 19:47:38 4aw-volumio volumio[831]: info: Preparing to generate the ALSA configuration file Dec 04 19:47:38 4aw-volumio sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Dec 04 19:47:38 4aw-volumio sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:38 4aw-volumio systemd[1]: Started Volspotconnect2 Daemon. Dec 04 19:47:38 4aw-volumio sudo[1019]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:38 4aw-volumio sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 04 19:47:38 4aw-volumio sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:38 4aw-volumio systemd[1]: Reloading. Dec 04 19:47:38 4aw-volumio volumio[1023]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Dec 04 19:47:38 4aw-volumio volumio[1023]: Reading Config from "/tmp/volspotify.toml" Dec 04 19:47:38 4aw-volumio volumio[1023]: Malformed config key: missing field `Authentication` Dec 04 19:47:38 4aw-volumio volumio[831]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 04 19:47:38 4aw-volumio volumio[831]: info: Reading ALSA contributions from plugins. Dec 04 19:47:38 4aw-volumio volumio[831]: [SpotifyConnect] Vollibrespot Daemon service started! Dec 04 19:47:38 4aw-volumio volumio[831]: [Metrics] SpotifyConnect: 0s 356.74ms Dec 04 19:47:38 4aw-volumio volumio[831]: info: MPD Permissions set Dec 04 19:47:38 4aw-volumio volumio[831]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Dec 04 19:47:38 4aw-volumio volumio[831]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Dec 04 19:47:38 4aw-volumio volumio[831]: info: Volumio called home Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:38 4aw-volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 04 19:47:38 4aw-volumio volumio[831]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Dec 04 19:47:38 4aw-volumio volumio[831]: info: Asound.conf file unchanged, so no further update is needed Dec 04 19:47:38 4aw-volumio volumio[831]: info: Output device has changed, restarting MPD Dec 04 19:47:38 4aw-volumio sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 04 19:47:38 4aw-volumio sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:38 4aw-volumio sudo[1044]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:38 4aw-volumio volumio[831]: [SpotifyConnect] Creating VLS config file Dec 04 19:47:38 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:39 4aw-volumio volumio[831]: [SpotifyConnect] Restarting Vollibrespot Daemon Dec 04 19:47:39 4aw-volumio sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 04 19:47:39 4aw-volumio sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:39 4aw-volumio sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Dec 04 19:47:39 4aw-volumio sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:39 4aw-volumio volumio[831]: info: MPD Permissions set Dec 04 19:47:39 4aw-volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioGetState Dec 04 19:47:39 4aw-volumio volumio[831]: info: CorePlayQueue::getTrack 0 Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:39 4aw-volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE Dec 04 19:47:39 4aw-volumio sudo[1022]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:39 4aw-volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Dec 04 19:47:39 4aw-volumio volumio[831]: info: touch_display: systemctl daemon-reload succeeded. Dec 04 19:47:39 4aw-volumio systemd[1]: Stopped Volspotconnect2 Daemon. Dec 04 19:47:39 4aw-volumio sudo[1058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Dec 04 19:47:39 4aw-volumio systemd[1]: Started Volspotconnect2 Daemon. Dec 04 19:47:39 4aw-volumio sudo[1058]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:39 4aw-volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 04 19:47:39 4aw-volumio systemd[1]: mpd.service: Succeeded. Dec 04 19:47:39 4aw-volumio systemd[1]: Stopped Music Player Daemon. Dec 04 19:47:39 4aw-volumio sudo[1050]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:39 4aw-volumio systemd[1]: Starting Music Player Daemon... Dec 04 19:47:39 4aw-volumio volumio[831]: [SpotifyConnect] Vollibrespot Daemon service restarted! Dec 04 19:47:39 4aw-volumio volumio[1059]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Dec 04 19:47:39 4aw-volumio volumio[1059]: Reading Config from "/tmp/volspotify.toml" Dec 04 19:47:39 4aw-volumio sudo[1058]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:39 4aw-volumio volumio[831]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Dec 04 19:47:39 4aw-volumio volumio[831]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Dec 04 19:47:39 4aw-volumio volumio[831]: info: Completed starting Core Plugins Dec 04 19:47:39 4aw-volumio volumio[831]: info: ------------------------------------------- Dec 04 19:47:39 4aw-volumio volumio[831]: info: ----- MyVolumio plugins startup ---- Dec 04 19:47:39 4aw-volumio volumio[831]: info: ------------------------------------------- Dec 04 19:47:39 4aw-volumio volumio[831]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 04 19:47:39 4aw-volumio volumio[1059]: [Vollibrespot] : Using Alsa backend with device: volumio Dec 04 19:47:39 4aw-volumio volumio[831]: verbose: New Socket.io Connection to 192.168.1.239 from 192.168.1.240 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 9 Dec 04 19:47:39 4aw-volumio volumio[831]: error: error Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioGetState Dec 04 19:47:39 4aw-volumio volumio[831]: info: CorePlayQueue::getTrack 0 Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 04 19:47:39 4aw-volumio volumio[831]: info: Listing playlists Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioGetVisibleSources Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioGetQueue Dec 04 19:47:39 4aw-volumio volumio[831]: info: CoreStateMachine::getQueue Dec 04 19:47:39 4aw-volumio volumio[831]: info: CorePlayQueue::getQueue Dec 04 19:47:39 4aw-volumio systemd[1]: systemd-hostnamed.service: Succeeded. Dec 04 19:47:39 4aw-volumio volumio[1059]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Dec 04 19:47:39 4aw-volumio volumio[1059]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Dec 04 19:47:39 4aw-volumio volumio[1059]: [Vollibrespot] : Authenticated as "f4fmwcdvks73mjqdjmzzcc0n9" ! Dec 04 19:47:39 4aw-volumio volumio[1059]: [Vollibrespot] : Using alsa sink Dec 04 19:47:40 4aw-volumio volumio[1059]: [Vollibrespot] : Metadata pipe established Dec 04 19:47:40 4aw-volumio volumio[1059]: [Vollibrespot] : Event: Volume { volume_to_mixer: 44564 } Dec 04 19:47:40 4aw-volumio volumio[1059]: [Vollibrespot] : Country: "NL" Dec 04 19:47:40 4aw-volumio volumio[831]: [SpotifyConnect] Volume Spotify: 68.00030518043793 Volumio: 68 Dec 04 19:47:40 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:40 4aw-volumio volumio[831]: info: AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Dec 04 19:47:40 4aw-volumio volumio[831]: info: FusionDsp loaded Dec 04 19:47:40 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 04 19:47:40 4aw-volumio sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 04 19:47:40 4aw-volumio sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:40 4aw-volumio volumio[831]: info: Reporting Fusion DSP Enabled Dec 04 19:47:40 4aw-volumio volumio[831]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Dec 04 19:47:40 4aw-volumio systemd[1]: Started FusionDsp Daemon. Dec 04 19:47:40 4aw-volumio sudo[1078]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:40 4aw-volumio volumio[831]: error: File size not found in array! Dec 04 19:47:40 4aw-volumio volumio[831]: info: eq1: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 25 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.1 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq2: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 40 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.1 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq3: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 63 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.1 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq4: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 100 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.1 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq5: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 160 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.1 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq6: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 250 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.1 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq7: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 400 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.1 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq8: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 630 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.1 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq9: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 1000 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.1 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq10: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 1600 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.1 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq11: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 2500 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.1 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq12: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 4000 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.1 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq13: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 6300 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.1 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq14: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 10000 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.1 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq15: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 16000 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.1 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq16: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 25 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.11 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq17: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 40 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.11 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq18: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 63 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.11 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq19: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 100 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.11 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq20: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 160 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.11 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq21: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 250 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.11 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq22: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 400 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.11 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq23: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 630 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.11 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq24: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 1000 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.11 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq25: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 1600 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.11 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq26: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 2500 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.11 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq27: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 4000 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.11 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq28: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 6300 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.11 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq29: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 10000 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.11 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: eq30: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Biquad Dec 04 19:47:40 4aw-volumio volumio[831]: parameters: Dec 04 19:47:40 4aw-volumio volumio[831]: type: Peaking Dec 04 19:47:40 4aw-volumio volumio[831]: freq: 16000 Dec 04 19:47:40 4aw-volumio volumio[831]: q: 1.11 Dec 04 19:47:40 4aw-volumio volumio[831]: gain: 0 Dec 04 19:47:40 4aw-volumio volumio[831]: error: WebSocket error: [object Object] Dec 04 19:47:40 4aw-volumio sudo[1082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 04 19:47:40 4aw-volumio sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 04 19:47:40 4aw-volumio sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:40 4aw-volumio sudo[1082]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:40 4aw-volumio sudo[1084]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:40 4aw-volumio sudo[1082]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:40 4aw-volumio sudo[1088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 04 19:47:40 4aw-volumio sudo[1088]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 04 19:47:40 4aw-volumio systemd[1]: Started UPnP Renderer front-end to MPD. Dec 04 19:47:40 4aw-volumio sudo[1088]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:40 4aw-volumio volumio[831]: info: Upmpdcli Daemon Started Dec 04 19:47:40 4aw-volumio mpd[1070]: Dec 04 19:47 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 04 19:47:40 4aw-volumio mpd[1070]: Dec 04 19:47 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 04 19:47:40 4aw-volumio mpd[1070]: Dec 04 19:47 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 04 19:47:40 4aw-volumio systemd[1]: Started Music Player Daemon. Dec 04 19:47:40 4aw-volumio sudo[1047]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:40 4aw-volumio sudo[1011]: pam_unix(sudo:session): session closed for user root Dec 04 19:47:40 4aw-volumio volumio[831]: error: MPD error: The expression evaluated to a falsy value: Dec 04 19:47:40 4aw-volumio volumio[831]: assert.ok(self.idling) Dec 04 19:47:40 4aw-volumio volumio[831]: error: updateQueue error: null Dec 04 19:47:40 4aw-volumio volumio[1090]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 04 19:47:42 4aw-volumio volumio-remote-updater[477]: [2022-12-04 19:47:42] [connect] Successful connection Dec 04 19:47:42 4aw-volumio volumio-remote-updater[477]: [2022-12-04 19:47:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1670183262 101 Dec 04 19:47:42 4aw-volumio volumio[831]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 11 Dec 04 19:47:42 4aw-volumio volumio[831]: info: Checking Spotify Web API Dec 04 19:47:43 4aw-volumio volumio[1059]: [Vollibrespot] : ReqToken Dec 04 19:47:43 4aw-volumio volumio[831]: info: New Spotify Access Token Received Dec 04 19:47:43 4aw-volumio volumio[831]: info: Initializing Spotify Web API Dec 04 19:47:43 4aw-volumio volumio[831]: info: Initliazing Spotify Browsing Facility Dec 04 19:47:43 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 04 19:47:43 4aw-volumio volumio[831]: info: [1670183263055] CoreMusicLibrary::Adding element Spotify Dec 04 19:47:43 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 04 19:47:43 4aw-volumio volumio[831]: Cannot find translation for sourceMedia Servers Dec 04 19:47:43 4aw-volumio volumio[831]: Cannot find translation for sourceSpotify Dec 04 19:47:43 4aw-volumio volumio[831]: (node:831) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Dec 04 19:47:43 4aw-volumio volumio[831]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 04 19:47:43 4aw-volumio volumio[831]: (node:831) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Dec 04 19:47:43 4aw-volumio volumio[831]: error: Failed to retrieve spotify devices lists: WebapiError: certificate is not yet valid Dec 04 19:47:43 4aw-volumio volumio[831]: error: Failed to retrieve user informations: WebapiError: certificate is not yet valid Dec 04 19:47:45 4aw-volumio volumio[831]: info: Checking Spotify Web API Dec 04 19:47:45 4aw-volumio volumio[831]: error: updateQueue error: null Dec 04 19:47:50 4aw-volumio volumio[1090]: ..................................................................................++++ Dec 04 19:47:55 4aw-volumio volumio[1090]: ......................................++++ Dec 04 19:47:55 4aw-volumio volumio[1090]: e is 65537 (0x010001) Dec 04 19:47:55 4aw-volumio volumio[1090]: writing RSA key Dec 04 19:47:59 4aw-volumio volumio[831]: error: MyVolumio Plugin failed to start in a timely fashion Dec 04 19:47:59 4aw-volumio volumio[831]: info: BOOT COMPLETED Dec 04 19:47:59 4aw-volumio volumio[831]: [Metrics] CommandRouter: 32s 775.52ms Dec 04 19:47:59 4aw-volumio volumio[831]: info: CoreCommandRouter::volumiosetStartupVolume Dec 04 19:47:59 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 04 19:47:59 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 04 19:47:59 4aw-volumio volumio[831]: info: CoreCommandRouter::Close All Modals sent Dec 04 19:47:59 4aw-volumio volumio[831]: info: CoreCommandRouter::Close All Modals sent Dec 04 19:48:00 4aw-volumio volumio[831]: info: Clearing queue after UPNP request Dec 04 19:48:00 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 04 19:48:00 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 04 19:48:00 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 04 19:48:00 4aw-volumio volumio[831]: info: CoreStateMachine::ClearQueue Dec 04 19:48:00 4aw-volumio volumio[831]: info: CoreStateMachine::stop Dec 04 19:48:00 4aw-volumio volumio[831]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 04 19:48:00 4aw-volumio volumio[831]: info: CorePlayQueue::clearPlayQueue Dec 04 19:48:00 4aw-volumio volumio[831]: info: CorePlayQueue::saveQueue Dec 04 19:48:00 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioPushState Dec 04 19:48:00 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioPushQueue Dec 04 19:48:00 4aw-volumio volumio[831]: info: Dec 04 19:48:00 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:00 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:00 4aw-volumio volumio[831]: info: Dec 04 19:48:00 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:00 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:00 4aw-volumio volumio[831]: info: Dec 04 19:48:00 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:00 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:00 4aw-volumio volumio[831]: error: updateQueue error: null Dec 04 19:48:00 4aw-volumio volumio[831]: error: updateQueue error: null Dec 04 19:48:00 4aw-volumio volumio[831]: error: updateQueue error: null Dec 04 19:48:00 4aw-volumio volumio[831]: info: ------------------------------ 10ms Dec 04 19:48:00 4aw-volumio volumio[831]: info: ------------------------------ 8ms Dec 04 19:48:00 4aw-volumio volumio[831]: info: ------------------------------ 6ms Dec 04 19:48:00 4aw-volumio volumio[831]: info: Starting UPNP Playback Dec 04 19:48:00 4aw-volumio volumio[831]: info: Preparing playback through UPNP Dec 04 19:48:00 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioGetState Dec 04 19:48:00 4aw-volumio volumio[831]: info: CorePlayQueue::getTrack 0 Dec 04 19:48:00 4aw-volumio volumio[831]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 8ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 6ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 5ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 7ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 6ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 4ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 7ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 5ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 4ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 7ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 5ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 3ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 7ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 6ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 4ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 7ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 4ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 4ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: Clearing queue after UPNP request Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces state update: player Dec 04 19:48:01 4aw-volumio volumio[831]: info: ControllerMpd::getState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces state update: player Dec 04 19:48:01 4aw-volumio volumio[831]: info: ControllerMpd::getState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces state update: player Dec 04 19:48:01 4aw-volumio volumio[831]: info: ControllerMpd::getState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Dec 04 19:48:01 4aw-volumio volumio[831]: info: sendMpdCommand status took 26 milliseconds Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::parseState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces state update: player Dec 04 19:48:01 4aw-volumio volumio[831]: info: ControllerMpd::getState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Dec 04 19:48:01 4aw-volumio volumio[831]: info: sendMpdCommand status took 31 milliseconds Dec 04 19:48:01 4aw-volumio volumio[831]: info: sendMpdCommand status took 29 milliseconds Dec 04 19:48:01 4aw-volumio volumio[831]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::parseState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::parseState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::parseTrackInfo Dec 04 19:48:01 4aw-volumio volumio[831]: info: ControllerMpd::pushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::servicePushState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: In UPNP mode Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"575 Kbps","isStreaming":false,"title":"No Can Do","artist":"Mark Knopfler","album":"Golden Heart","uri":"http://192.168.1.240:10328/ItemID=6420.flac","trackType":"flac"} Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: CURRENT POSITION 0 Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::syncState stateService play Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::syncState currentStatus stop Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 58ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces state update: player Dec 04 19:48:01 4aw-volumio volumio[831]: info: ControllerMpd::getState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces system playlist update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Ignoring MPD Status Update Dec 04 19:48:01 4aw-volumio volumio[831]: info: Dec 04 19:48:01 4aw-volumio volumio[831]: ---------------------------- MPD announces state update: player Dec 04 19:48:01 4aw-volumio volumio[831]: info: ControllerMpd::getState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand status Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 38ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: sendMpdCommand status took 35 milliseconds Dec 04 19:48:01 4aw-volumio volumio[831]: info: sendMpdCommand playlistinfo took 31 milliseconds Dec 04 19:48:01 4aw-volumio volumio[831]: info: sendMpdCommand playlistinfo took 29 milliseconds Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 16ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: sendMpdCommand status took 13 milliseconds Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 13ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: sendMpdCommand status took 9 milliseconds Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::parseState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::parseTrackInfo Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::parseTrackInfo Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::parseState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::parseState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 04 19:48:01 4aw-volumio volumio[831]: info: ControllerMpd::pushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::servicePushState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: In UPNP mode Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":320,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"No Can Do","artist":"Mark Knopfler","album":"Golden Heart","uri":"http://192.168.1.240:10328/ItemID=6420.flac","trackType":"flac"} Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: CURRENT POSITION 0 Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::syncState stateService play Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::syncState currentStatus play Dec 04 19:48:01 4aw-volumio volumio[831]: info: Received an update from plugin. extracting info from payload Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::pushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioPushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: ControllerMpd::pushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::servicePushState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: In UPNP mode Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"No Can Do","artist":"Mark Knopfler","album":"Golden Heart","uri":"http://192.168.1.240:10328/ItemID=6420.flac","trackType":"flac"} Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: CURRENT POSITION 0 Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::syncState stateService play Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::syncState currentStatus play Dec 04 19:48:01 4aw-volumio volumio[831]: info: Received an update from plugin. extracting info from payload Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::pushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioPushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 130ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 128ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: sendMpdCommand playlistinfo took 60 milliseconds Dec 04 19:48:01 4aw-volumio volumio[831]: info: sendMpdCommand playlistinfo took 58 milliseconds Dec 04 19:48:01 4aw-volumio volumio[831]: info: sendMpdCommand playlistinfo took 57 milliseconds Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::parseTrackInfo Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::parseTrackInfo Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::parseTrackInfo Dec 04 19:48:01 4aw-volumio volumio[831]: info: ControllerMpd::pushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::servicePushState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: In UPNP mode Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"No Can Do","artist":"Mark Knopfler","album":"Golden Heart","uri":"http://192.168.1.240:10328/ItemID=6420.flac","trackType":"flac"} Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: CURRENT POSITION 0 Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::syncState stateService play Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::syncState currentStatus play Dec 04 19:48:01 4aw-volumio volumio[831]: info: Received an update from plugin. extracting info from payload Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::pushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioPushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: ControllerMpd::pushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::servicePushState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: In UPNP mode Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"No Can Do","artist":"Mark Knopfler","album":"Golden Heart","uri":"http://192.168.1.240:10328/ItemID=6420.flac","trackType":"flac"} Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: CURRENT POSITION 0 Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::syncState stateService play Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::syncState currentStatus play Dec 04 19:48:01 4aw-volumio volumio[831]: info: Received an update from plugin. extracting info from payload Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::pushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioPushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: ControllerMpd::pushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::servicePushState Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: In UPNP mode Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"No Can Do","artist":"Mark Knopfler","album":"Golden Heart","uri":"http://192.168.1.240:10328/ItemID=6420.flac","trackType":"flac"} Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: CURRENT POSITION 0 Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::syncState stateService play Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::syncState currentStatus play Dec 04 19:48:01 4aw-volumio volumio[831]: info: Received an update from plugin. extracting info from payload Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::pushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioPushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 161ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 137ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: ------------------------------ 132ms Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioGetQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::getQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CorePlayQueue::getQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioGetQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::getQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CorePlayQueue::getQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioGetQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::getQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CorePlayQueue::getQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioGetQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::getQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CorePlayQueue::getQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioGetQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::getQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CorePlayQueue::getQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::ClearQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::stop Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::pushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: CorePlayQueue::getTrack 0 Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioPushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::stPlaybackTimer Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::updateTrackBlock Dec 04 19:48:01 4aw-volumio volumio[831]: info: CorePlayQueue::getTrackBlock Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreStateMachine::serviceStop Dec 04 19:48:01 4aw-volumio volumio[831]: info: CorePlayQueue::getTrack 0 Dec 04 19:48:01 4aw-volumio volumio[831]: info: ControllerMpd::stop Dec 04 19:48:01 4aw-volumio volumio[831]: verbose: ControllerMpd::sendMpdCommand stop Dec 04 19:48:01 4aw-volumio volumio[831]: info: CorePlayQueue::clearPlayQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CorePlayQueue::saveQueue Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioPushState Dec 04 19:48:01 4aw-volumio volumio[831]: info: CoreCommandRouter::volumioPushQueue Dec 04 19:48:02 4aw-volumio volumio[831]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 04 19:48:02 4aw-volumio volumio[831]: Error: certificate is not yet valid Dec 04 19:48:02 4aw-volumio volumio[831]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Dec 04 19:48:02 4aw-volumio volumio[831]: at TLSSocket.emit (events.js:315:20) Dec 04 19:48:02 4aw-volumio volumio[831]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Dec 04 19:48:02 4aw-volumio volumio[831]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Dec 04 19:48:02 4aw-volumio volumio[831]: code: 'CERT_NOT_YET_VALID' Dec 04 19:48:02 4aw-volumio volumio[831]: } Dec 04 19:48:02 4aw-volumio volumio[831]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 04 19:48:02 4aw-volumio sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-12-04 19:47 Dec 04 19:48:02 4aw-volumio sudo[1196]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="8e6823482d6af342172ec9fdc1c15b0dd02653d2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 04 Dec 2022 08:41:29 PM CET" VOLUMIO_VERSION="3.396" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d460c75d04818d0f55c67627fa362668"