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