-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Thu 2023-12-14 21:01:13 UTC. --
Dec 14 21:00:00 volumio systemd[1]: Starting triggerhappy global hotkey daemon...
Dec 14 21:00:00 volumio systemd[1]: Started D-Bus System Message Bus.
Dec 14 21:00:00 volumio systemd[1]: Starting WPA supplicant...
Dec 14 21:00:00 volumio systemd[1]: Starting Configure Bluetooth Modems connected by UART...
Dec 14 21:00:00 volumio systemd[1]: Starting Login Service...
Dec 14 21:00:00 volumio systemd[1]: Starting dhcpcd on all interfaces...
Dec 14 21:00:00 volumio systemd[1]: Started Save/Restore Sound Card State.
Dec 14 21:00:00 volumio systemd[1]: Reached target Sound Card.
Dec 14 21:00:00 volumio thd[465]: Unable to parse trigger line:
Dec 14 21:00:00 volumio thd[465]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle
Dec 14 21:00:00 volumio thd[465]: Unable to parse trigger line:
Dec 14 21:00:00 volumio dhcpcd[471]: Not running dhcpcd because /etc/network/interfaces
Dec 14 21:00:00 volumio dhcpcd[471]: defines some interfaces that will use a
Dec 14 21:00:00 volumio dhcpcd[471]: DHCP client or static address
Dec 14 21:00:00 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Dec 14 21:00:00 volumio sh[384]: eth0: waiting for carrier
Dec 14 21:00:00 volumio dhcpcd[432]: eth0: waiting for carrier
Dec 14 21:00:00 volumio kernel: 8021q: adding VLAN 0 to HW filter on device eth0
Dec 14 21:00:00 volumio kernel: uart-pl011 3f201000.serial: no DMA platform data
Dec 14 21:00:01 volumio systemd-logind[469]: New seat seat0.
Dec 14 21:00:01 volumio avahi-daemon[462]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113).
Dec 14 21:00:01 volumio avahi-daemon[462]: Successfully dropped root privileges.
Dec 14 21:00:01 volumio avahi-daemon[462]: avahi-daemon 0.7 starting up.
Dec 14 21:00:01 volumio thd[465]: Found socket passed from systemd
Dec 14 21:00:01 volumio systemd[1]: Started RPC bind portmap service.
Dec 14 21:00:01 volumio avahi-daemon[462]: Successfully called chroot().
Dec 14 21:00:01 volumio systemd[1]: Started triggerhappy global hotkey daemon.
Dec 14 21:00:01 volumio avahi-daemon[462]: Successfully dropped remaining capabilities.
Dec 14 21:00:01 volumio systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED
Dec 14 21:00:01 volumio systemd[1]: dhcpcd.service: Failed with result 'exit-code'.
Dec 14 21:00:01 volumio systemd[1]: Failed to start dhcpcd on all interfaces.
Dec 14 21:00:01 volumio avahi-daemon[462]: No service file found in /etc/avahi/services.
Dec 14 21:00:01 volumio avahi-daemon[462]: Network interface enumeration completed.
Dec 14 21:00:01 volumio avahi-daemon[462]: Server startup complete. Host name is volumio.local. Local service cookie is 3075460200.
Dec 14 21:00:01 volumio systemd[1]: Started Login Service.
Dec 14 21:00:01 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Dec 14 21:00:01 volumio sh[389]: wlan0=wlan0
Dec 14 21:00:01 volumio systemd[1]: Reached target Remote File Systems (Pre).
Dec 14 21:00:01 volumio systemd[1]: Reached target Remote File Systems.
Dec 14 21:00:01 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling...
Dec 14 21:00:01 volumio systemd[1]: Reached target RPC Port Mapper.
Dec 14 21:00:02 volumio systemd[1]: Started Raise network interfaces.
Dec 14 21:00:02 volumio haveged[449]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K
Dec 14 21:00:02 volumio haveged[449]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488
Dec 14 21:00:02 volumio haveged[449]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00191
Dec 14 21:00:02 volumio haveged[449]: haveged: fills: 0, generated: 0
Dec 14 21:00:02 volumio loadcpufreq[549]: Loading cpufreq kernel modules...done (none).
Dec 14 21:00:02 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling.
Dec 14 21:00:02 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters...
Dec 14 21:00:02 volumio systemd[1]: iptables.service: Succeeded.
Dec 14 21:00:02 volumio cpufrequtils[594]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done.
Dec 14 21:00:02 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters.
Dec 14 21:00:03 volumio systemd[1]: Started WPA supplicant.
Dec 14 21:00:03 volumio wpa_supplicant[467]: Successfully initialized wpa_supplicant
Dec 14 21:00:03 volumio systemd[1]: Reached target Network.
Dec 14 21:00:03 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped.
Dec 14 21:00:03 volumio systemd[1]: Starting Permit User Sessions...
Dec 14 21:00:03 volumio systemd[1]: Starting Network Time Service...
Dec 14 21:00:03 volumio systemd[1]: Reached target Network is Online.
Dec 14 21:00:03 volumio systemd[1]: Starting LSB: Brings up/down network automatically...
Dec 14 21:00:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Dec 14 21:00:03 volumio systemd[1]: Starting /etc/rc.local Compatibility...
Dec 14 21:00:03 volumio systemd[1]: Starting Music Player Daemon...
Dec 14 21:00:03 volumio systemd[1]: Starting Samba NMB Daemon...
Dec 14 21:00:03 volumio systemd[1]: Started Permit User Sessions.
Dec 14 21:00:03 volumio systemd[1]: Started /etc/rc.local Compatibility.
Dec 14 21:00:03 volumio systemd[1]: Starting Hold until boot process finishes up...
Dec 14 21:00:03 volumio systemd[1]: Starting Terminate Plymouth Boot Screen...
Dec 14 21:00:04 volumio systemd[1]: plymouth-quit-wait.service: Succeeded.
Dec 14 21:00:04 volumio systemd[1]: Started Hold until boot process finishes up.
Dec 14 21:00:04 volumio systemd[1]: plymouth-start.service: Succeeded.
Dec 14 21:00:04 volumio kernel: Under-voltage detected! (0x00050005)
Dec 14 21:00:04 volumio systemd[1]: plymouth-quit.service: Succeeded.
Dec 14 21:00:04 volumio systemd[1]: Started Terminate Plymouth Boot Screen.
Dec 14 21:00:04 volumio systemd[1]: Received SIGRTMIN+21 from PID 241 (n/a).
Dec 14 21:00:04 volumio systemd[1]: Received SIGRTMIN+21 from PID 241 (n/a).
Dec 14 21:00:04 volumio systemd[1]: Started Getty on tty1.
Dec 14 21:00:04 volumio systemd[1]: Reached target Login Prompts.
Dec 14 21:00:04 volumio ifplugd(eth0)[639]: ifplugd 0.28 initializing.
Dec 14 21:00:04 volumio ifplugd(eth0)[639]: Using interface eth0/B8:27:EB:3E:81:D2 with driver (version: 5.10.92-v7+)
Dec 14 21:00:04 volumio ifplugd(eth0)[639]: Using detection mode: SIOCETHTOOL
Dec 14 21:00:04 volumio ifplugd(eth0)[639]: Initialization complete, link beat not detected.
Dec 14 21:00:04 volumio systemd[1]: systemd-rfkill.service: Succeeded.
Dec 14 21:00:04 volumio ifplugd[615]: Network Interface Plugging Daemon...start eth0...done.
Dec 14 21:00:04 volumio systemd[1]: Started LSB: Brings up/down network automatically.
Dec 14 21:00:05 volumio volumio-remote-updater[463]: Error: No active session
Dec 14 21:00:05 volumio volumio-remote-updater[463]: [2023-12-14 21:00:05] [info] asio async_connect error: system:111 (Connection refused)
Dec 14 21:00:05 volumio volumio-remote-updater[463]: [2023-12-14 21:00:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Dec 14 21:00:05 volumio volumio-remote-updater[463]: [2023-12-14 21:00:05] [error] handle_connect error: Underlying Transport Error
Dec 14 21:00:05 volumio ntpd[614]: ntpd 4.2.8p12@1.3728-o (1): Starting
Dec 14 21:00:05 volumio ntpd[614]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103
Dec 14 21:00:05 volumio systemd[1]: Started Network Time Service.
Dec 14 21:00:05 volumio ntpd[648]: proto: precision = 1.459 usec (-19)
Dec 14 21:00:05 volumio ntpd[648]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
Dec 14 21:00:05 volumio ntpd[648]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
Dec 14 21:00:05 volumio ntpd[648]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 352 days ago
Dec 14 21:00:05 volumio ntpd[648]: Listen and drop on 0 v6wildcard [::]:123
Dec 14 21:00:05 volumio ntpd[648]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Dec 14 21:00:05 volumio ntpd[648]: Listen normally on 2 lo 127.0.0.1:123
Dec 14 21:00:05 volumio ntpd[648]: Listening on routing socket on fd #19 for interface updates
Dec 14 21:00:05 volumio ntpd[648]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Dec 14 21:00:05 volumio ntpd[648]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Dec 14 21:00:06 volumio volumio[461]: Could not open config: /tmp/upmpdcli.conf
Dec 14 21:00:06 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Dec 14 21:00:06 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Dec 14 21:00:06 volumio ntpd[648]: error resolving pool 0.debian.pool.ntp.org: System error (-11)
Dec 14 21:00:07 volumio ntpd[648]: error resolving pool 1.debian.pool.ntp.org: System error (-11)
Dec 14 21:00:08 volumio ntpd[648]: error resolving pool 2.debian.pool.ntp.org: System error (-11)
Dec 14 21:00:08 volumio kernel: Bluetooth: Core ver 2.22
Dec 14 21:00:08 volumio kernel: NET: Registered protocol family 31
Dec 14 21:00:08 volumio kernel: Bluetooth: HCI device and connection manager initialized
Dec 14 21:00:08 volumio kernel: Bluetooth: HCI socket layer initialized
Dec 14 21:00:08 volumio kernel: Bluetooth: L2CAP socket layer initialized
Dec 14 21:00:08 volumio kernel: Bluetooth: SCO socket layer initialized
Dec 14 21:00:08 volumio kernel: Bluetooth: HCI UART driver ver 2.3
Dec 14 21:00:08 volumio kernel: Bluetooth: HCI UART protocol H4 registered
Dec 14 21:00:08 volumio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered
Dec 14 21:00:08 volumio kernel: Bluetooth: HCI UART protocol Broadcom registered
Dec 14 21:00:08 volumio btuart[468]: bcm43xx_init
Dec 14 21:00:08 volumio btuart[468]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd
Dec 14 21:00:08 volumio btuart[468]: Set BDADDR UART: b8:27:eb:94:2b:78
Dec 14 21:00:08 volumio btuart[468]: Set Controller UART speed to 3000000 bit/s
Dec 14 21:00:08 volumio btuart[468]: Device setup complete
Dec 14 21:00:08 volumio systemd[1]: Started Configure Bluetooth Modems connected by UART.
Dec 14 21:00:08 volumio systemd[1]: Starting Load/Save RF Kill Switch Status...
Dec 14 21:00:08 volumio systemd-rfkill[663]: Failed to parse state file /var/lib/systemd/rfkill/platform-soc:bluetooth: Invalid argument
Dec 14 21:00:09 volumio systemd[1]: Started Load/Save RF Kill Switch Status.
Dec 14 21:00:09 volumio systemd[1]: Created slice system-bthelper.slice.
Dec 14 21:00:09 volumio systemd[1]: Starting Raspberry Pi bluetooth helper...
Dec 14 21:00:09 volumio nmbd[626]: [2023/12/14 21:00:09.159793, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns)
Dec 14 21:00:09 volumio nmbd[626]: started asyncdns process 665
Dec 14 21:00:09 volumio nmbd[626]: [2023/12/14 21:00:09.164046, 0] ../lib/util/become_daemon.c:149(daemon_status)
Dec 14 21:00:09 volumio nmbd[626]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...
Dec 14 21:00:09 volumio nmbd[626]: [2023/12/14 21:00:09.164387, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets)
Dec 14 21:00:09 volumio nmbd[626]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6).
Dec 14 21:00:09 volumio bthelper[664]: Raspberry Pi BDADDR already set
Dec 14 21:00:09 volumio systemd[1]: Started Raspberry Pi bluetooth helper.
Dec 14 21:00:09 volumio systemd[1]: Starting Bluetooth service...
Dec 14 21:00:09 volumio ntpd[648]: error resolving pool 3.debian.pool.ntp.org: System error (-11)
Dec 14 21:00:09 volumio bluetoothd[674]: Bluetooth daemon 5.50
Dec 14 21:00:09 volumio bluetoothd[674]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf
Dec 14 21:00:09 volumio systemd[1]: Started Bluetooth service.
Dec 14 21:00:09 volumio systemd[1]: Reached target Bluetooth.
Dec 14 21:00:09 volumio bluetoothd[674]: Starting SDP server
Dec 14 21:00:09 volumio bluetoothd[674]: Excluding (cli) sap
Dec 14 21:00:09 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Dec 14 21:00:09 volumio kernel: Bluetooth: BNEP filters: protocol multicast
Dec 14 21:00:09 volumio kernel: Bluetooth: BNEP socket layer initialized
Dec 14 21:00:09 volumio dbus-daemon[466]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=674 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ")
Dec 14 21:00:09 volumio bluetoothd[674]: Bluetooth management interface 1.18 initialized
Dec 14 21:00:09 volumio systemd[1]: Starting Hostname Service...
Dec 14 21:00:09 volumio bluetoothd[674]: Failed to set privacy: Rejected (0x0b)
Dec 14 21:00:10 volumio dbus-daemon[466]: [system] Successfully activated service 'org.freedesktop.hostname1'
Dec 14 21:00:10 volumio systemd[1]: Started Hostname Service.
Dec 14 21:00:10 volumio volumio-remote-updater[463]: [2023-12-14 21:00:10] [info] asio async_connect error: system:111 (Connection refused)
Dec 14 21:00:10 volumio volumio-remote-updater[463]: [2023-12-14 21:00:10] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Dec 14 21:00:10 volumio volumio-remote-updater[463]: [2023-12-14 21:00:10] [error] handle_connect error: Underlying Transport Error
Dec 14 21:00:10 volumio wireless.js[460]: Cleaning previous...
Dec 14 21:00:11 volumio sudo[686]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Dec 14 21:00:11 volumio sudo[686]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:11 volumio sudo[686]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:11 volumio sudo[688]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Dec 14 21:00:11 volumio sudo[688]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:12 volumio sudo[688]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:12 volumio wireless.js[460]: Stopped aP
Dec 14 21:00:12 volumio sudo[695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Dec 14 21:00:12 volumio sudo[695]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:12 volumio sudo[695]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:12 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Dec 14 21:00:12 volumio sudo[697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get
Dec 14 21:00:12 volumio sudo[697]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:12 volumio sudo[697]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:12 volumio sudo[704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Dec 14 21:00:12 volumio sudo[704]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:12 volumio sudo[704]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:12 volumio sudo[706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan
Dec 14 21:00:12 volumio sudo[706]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:12 volumio mpd[642]: Dec 14 21:00 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 14 21:00:12 volumio mpd[642]: Dec 14 21:00 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Dec 14 21:00:12 volumio mpd[642]: Dec 14 21:00 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Dec 14 21:00:12 volumio systemd[1]: Started Music Player Daemon.
Dec 14 21:00:13 volumio systemd[1]: systemd-rfkill.service: Succeeded.
Dec 14 21:00:14 volumio bthelper[664]: Changing power off succeeded
Dec 14 21:00:14 volumio bthelper[664]: [CHG] Controller B8:27:EB:94:2B:78 Class: 0x0000041c
Dec 14 21:00:14 volumio bthelper[664]: Changing power on succeeded
Dec 14 21:00:15 volumio sudo[706]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:15 volumio wireless.js[460]: Start wireless flow
Dec 14 21:00:15 volumio wireless.js[460]: Stopped hotspot (if there)..
Dec 14 21:00:15 volumio sudo[720]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Dec 14 21:00:15 volumio sudo[720]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:15 volumio sudo[720]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:15 volumio sudo[722]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Dec 14 21:00:15 volumio sudo[722]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:15 volumio kernel: cam1-reg: disabling
Dec 14 21:00:15 volumio kernel: cam-dummy-reg: disabling
Dec 14 21:00:15 volumio sudo[722]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:15 volumio wireless.js[460]: DHCP IP
Dec 14 21:00:15 volumio wireless.js[460]: Start ap
Dec 14 21:00:15 volumio wpa_supplicant[724]: Successfully initialized wpa_supplicant
Dec 14 21:00:15 volumio sudo[725]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd
Dec 14 21:00:15 volumio sudo[725]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:15 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Dec 14 21:00:15 volumio dhcpcd[726]: dev: loaded udev
Dec 14 21:00:15 volumio dhcpcd-run-hooks[744]: wlan0: starting wpa_supplicant
Dec 14 21:00:16 volumio dhcpcd-run-hooks[749]: wlan0: failed to start wpa_supplicant
Dec 14 21:00:16 volumio dhcpcd-run-hooks[750]: wlan0: Successfully initialized wpa_supplicant
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Ma
Dec 14 21:00:16 volumio dhcpcd[726]: wlan0: connected to Access Point `'
Dec 14 21:00:16 volumio dhcpcd[726]: no interfaces have a carrier
Dec 14 21:00:16 volumio dhcpcd[726]: forked to background, child pid 760
Dec 14 21:00:16 volumio dhcpcd[760]: eth0: waiting for carrier
Dec 14 21:00:16 volumio dhcpcd[760]: wlan0: waiting for carrier
Dec 14 21:00:16 volumio sudo[725]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:16 volumio kernel: Voltage normalised (0x00000000)
Dec 14 21:00:16 volumio wireless.js[460]: trying...
Dec 14 21:00:16 volumio sudo[763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 14 21:00:16 volumio sudo[763]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:16 volumio sudo[763]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:17 volumio wireless.js[460]: trying...
Dec 14 21:00:17 volumio sudo[766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 14 21:00:17 volumio sudo[766]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:17 volumio sudo[766]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:17 volumio volumio-remote-updater[463]: [2023-12-14 21:00:17] [info] asio async_connect error: system:111 (Connection refused)
Dec 14 21:00:17 volumio volumio-remote-updater[463]: [2023-12-14 21:00:17] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Dec 14 21:00:17 volumio volumio-remote-updater[463]: [2023-12-14 21:00:17] [error] handle_connect error: Underlying Transport Error
Dec 14 21:00:18 volumio wpa_supplicant[748]: wlan0: Trying to associate with SSID 'Swoop2406221 5Ghz'
Dec 14 21:00:18 volumio wireless.js[460]: trying...
Dec 14 21:00:18 volumio sudo[769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 14 21:00:18 volumio sudo[769]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:18 volumio sudo[769]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:19 volumio wireless.js[460]: trying...
Dec 14 21:00:19 volumio sudo[772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 14 21:00:19 volumio sudo[772]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:19 volumio sudo[772]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:20 volumio wireless.js[460]: trying...
Dec 14 21:00:20 volumio sudo[775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 14 21:00:20 volumio sudo[775]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:20 volumio sudo[775]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:20 volumio kernel: Under-voltage detected! (0x00050005)
Dec 14 21:00:20 volumio dhcpcd[760]: wlan0: carrier acquired
Dec 14 21:00:20 volumio dhcpcd[760]: wlan0: connected to Access Point `Swoop2406221 5Ghz'
Dec 14 21:00:20 volumio wpa_supplicant[748]: wlan0: Associated with dc:2c:6e:69:9c:49
Dec 14 21:00:20 volumio wpa_supplicant[748]: wlan0: CTRL-EVENT-CONNECTED - Connection to dc:2c:6e:69:9c:49 completed [id=0 id_str=]
Dec 14 21:00:20 volumio wpa_supplicant[748]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Dec 14 21:00:20 volumio dhcpcd[760]: DUID 00:01:00:01:2a:c9:6b:22:b8:27:eb:6b:d4:87
Dec 14 21:00:20 volumio dhcpcd[760]: wlan0: IAID eb:6b:d4:87
Dec 14 21:00:20 volumio dhcpcd[760]: wlan0: adding address fe80::f60e:e93b:8c92:99b0
Dec 14 21:00:20 volumio dhcpcd[760]: ipv6_addaddr1: Permission denied
Dec 14 21:00:20 volumio dhcpcd[760]: wlan0: carrier lost
Dec 14 21:00:20 volumio dhcpcd[760]: wlan0: carrier acquired
Dec 14 21:00:20 volumio dhcpcd[760]: wlan0: IAID eb:6b:d4:87
Dec 14 21:00:21 volumio wireless.js[460]: trying...
Dec 14 21:00:21 volumio sudo[790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 14 21:00:21 volumio sudo[790]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:21 volumio sudo[790]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:21 volumio dhcpcd[760]: wlan0: soliciting an IPv6 router
Dec 14 21:00:21 volumio dhcpcd[760]: wlan0: rebinding lease of 192.168.1.108
Dec 14 21:00:21 volumio dhcpcd[760]: wlan0: probing address 192.168.1.108/24
Dec 14 21:00:22 volumio wireless.js[460]: trying...
Dec 14 21:00:22 volumio sudo[793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 14 21:00:22 volumio sudo[793]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:22 volumio sudo[793]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:23 volumio wireless.js[460]: trying...
Dec 14 21:00:23 volumio sudo[796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 14 21:00:23 volumio sudo[796]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:23 volumio sudo[796]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:24 volumio wireless.js[460]: trying...
Dec 14 21:00:24 volumio sudo[799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 14 21:00:24 volumio sudo[799]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:24 volumio sudo[799]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:24 volumio kernel: Voltage normalised (0x00000000)
Dec 14 21:00:25 volumio wireless.js[460]: trying...
Dec 14 21:00:25 volumio sudo[802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 14 21:00:25 volumio sudo[802]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:25 volumio sudo[802]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:26 volumio wireless.js[460]: trying...
Dec 14 21:00:26 volumio sudo[805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 14 21:00:26 volumio sudo[805]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:26 volumio sudo[805]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:27 volumio dhcpcd[760]: wlan0: leased 192.168.1.108 for 86400 seconds
Dec 14 21:00:27 volumio avahi-daemon[462]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.108.
Dec 14 21:00:27 volumio dhcpcd[760]: wlan0: adding route to 192.168.1.0/24
Dec 14 21:00:27 volumio dhcpcd[760]: wlan0: adding default route via 192.168.1.1
Dec 14 21:00:27 volumio avahi-daemon[462]: New relevant interface wlan0.IPv4 for mDNS.
Dec 14 21:00:27 volumio avahi-daemon[462]: Registering new address record for 192.168.1.108 on wlan0.IPv4.
Dec 14 21:00:27 volumio wireless.js[460]: trying...
Dec 14 21:00:27 volumio volumio-remote-updater[463]: [2023-12-14 21:00:27] [info] asio async_connect error: system:111 (Connection refused)
Dec 14 21:00:27 volumio volumio-remote-updater[463]: [2023-12-14 21:00:27] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Dec 14 21:00:27 volumio volumio-remote-updater[463]: [2023-12-14 21:00:27] [error] handle_connect error: Underlying Transport Error
Dec 14 21:00:27 volumio sudo[828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 14 21:00:27 volumio sudo[828]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:27 volumio sudo[828]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:27 volumio wireless.js[460]: Connected to: ----Swoop2406221 5Ghz
Dec 14 21:00:27 volumio wireless.js[460]: ----
Dec 14 21:00:27 volumio sudo[831]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 14 21:00:27 volumio sudo[831]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:27 volumio sudo[831]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:27 volumio wireless.js[460]: ... joined AP, wlan0 IPv4 is 192.168.1.108, ipV6 is undefined
Dec 14 21:00:27 volumio wireless.js[460]: It's done! AP
Dec 14 21:00:27 volumio systemd[1]: Started Wireless Services.
Dec 14 21:00:27 volumio systemd[1]: Started Volumio Backend Module.
Dec 14 21:00:27 volumio systemd[1]: Started Volumio Streaming Daemon.
Dec 14 21:00:28 volumio volumio-streaming-daemon[836]: ############################
Dec 14 21:00:28 volumio volumio-streaming-daemon[836]: # Volumio Streaming Daemon #
Dec 14 21:00:28 volumio volumio-streaming-daemon[836]: # Running on port 7777 #
Dec 14 21:00:28 volumio volumio-streaming-daemon[836]: ############################
Dec 14 21:00:28 volumio volumio-streaming-daemon[836]: Environment: production
Dec 14 21:00:28 volumio ntpd[648]: Listen normally on 3 wlan0 192.168.1.108:123
Dec 14 21:00:28 volumio ntpd[648]: new interface(s) found: waking up resolver
Dec 14 21:00:28 volumio kernel: Under-voltage detected! (0x00050005)
Dec 14 21:00:29 volumio nmbd[626]: [2023/12/14 21:00:29.182646, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Dec 14 21:00:29 volumio systemd[1]: Started Samba NMB Daemon.
Dec 14 21:00:29 volumio nmbd[626]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Dec 14 21:00:29 volumio systemd[1]: Starting Samba Winbind Daemon...
Dec 14 21:00:29 volumio systemd[1]: systemd-fsckd.service: Succeeded.
Dec 14 21:00:29 volumio winbindd[848]: [2023/12/14 21:00:29.838096, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache)
Dec 14 21:00:29 volumio winbindd[848]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Dec 14 21:00:29 volumio winbindd[848]: [2023/12/14 21:00:29.944068, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Dec 14 21:00:29 volumio systemd[1]: Started Samba Winbind Daemon.
Dec 14 21:00:29 volumio winbindd[848]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
Dec 14 21:00:29 volumio systemd[1]: Starting Samba SMB Daemon...
Dec 14 21:00:30 volumio sh[384]: timed out
Dec 14 21:00:30 volumio dhcpcd[432]: timed out
Dec 14 21:00:30 volumio sh[384]: dhcpcd exited
Dec 14 21:00:30 volumio dhcpcd[432]: dhcpcd exited
Dec 14 21:00:30 volumio sh[384]: ifup: failed to bring up eth0
Dec 14 21:00:30 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE
Dec 14 21:00:30 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'.
Dec 14 21:00:31 volumio winbindd[848]: [2023/12/14 21:00:31.021685, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv)
Dec 14 21:00:31 volumio winbindd[848]: res_names->count = 3, expected 4
Dec 14 21:00:31 volumio smbd[853]: [2023/12/14 21:00:31.051238, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Dec 14 21:00:31 volumio systemd[1]: Started Samba SMB Daemon.
Dec 14 21:00:31 volumio smbd[853]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Dec 14 21:00:31 volumio systemd[1]: Reached target Multi-User System.
Dec 14 21:00:31 volumio systemd[1]: Reached target Graphical Interface.
Dec 14 21:00:31 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
Dec 14 21:00:31 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Dec 14 21:00:31 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
Dec 14 21:00:31 volumio systemd[1]: Startup finished in 11.156s (kernel) + 38.951s (userspace) = 50.107s.
Dec 14 21:00:33 volumio volumio[835]: info: -------------------------------------------
Dec 14 21:00:33 volumio volumio[835]: info: ----- Volumio3 ----
Dec 14 21:00:33 volumio volumio[835]: info: -------------------------------------------
Dec 14 21:00:33 volumio volumio[835]: info: ----- System startup ----
Dec 14 21:00:33 volumio volumio[835]: info: -------------------------------------------
Dec 14 21:00:35 volumio volumio[835]: info: MYVOLUMIO Environment detected
Dec 14 21:00:35 volumio volumio[835]: info: Plugin folders cleanup
Dec 14 21:00:35 volumio volumio[835]: info: Scanning into folder /volumio/app/plugins/
Dec 14 21:00:35 volumio volumio[835]: info: Scanning category audio_interface
Dec 14 21:00:35 volumio volumio[835]: info: Scanning category miscellanea
Dec 14 21:00:35 volumio volumio[835]: info: Scanning category music_service
Dec 14 21:00:35 volumio volumio[835]: info: Scanning category plugins.json
Dec 14 21:00:35 volumio volumio[835]: info: Scanning category system_controller
Dec 14 21:00:35 volumio volumio[835]: info: Scanning category user_interface
Dec 14 21:00:35 volumio volumio[835]: info: Scanning into folder /data/plugins/
Dec 14 21:00:35 volumio volumio[835]: info: Plugin folders cleanup completed
Dec 14 21:00:35 volumio volumio[835]: info: -------------------------------------------
Dec 14 21:00:35 volumio volumio[835]: info: ----- Core plugins startup ----
Dec 14 21:00:35 volumio volumio[835]: info: -------------------------------------------
Dec 14 21:00:35 volumio volumio[835]: info: Loading plugins from folder /volumio/app/plugins/
Dec 14 21:00:35 volumio volumio[835]: info: Adding plugin upnp to MyMusic Plugins
Dec 14 21:00:35 volumio volumio[835]: info: Adding plugin airplay_emulation to MyMusic Plugins
Dec 14 21:00:35 volumio volumio[835]: info: Adding plugin upnp_browser to MyMusic Plugins
Dec 14 21:00:35 volumio volumio[835]: info: Loading plugins from folder /data/plugins/
Dec 14 21:00:35 volumio volumio[835]: info: Loading plugin "system"...
Dec 14 21:00:35 volumio volumio[835]: info: Loading plugin "appearance"...
Dec 14 21:00:37 volumio volumio-remote-updater[463]: [2023-12-14 21:00:37] [connect] Successful connection
Dec 14 21:00:38 volumio volumio[835]: info: Loading plugin "network"...
Dec 14 21:00:38 volumio volumio[835]: info: Refreshing Cached IP Addresses
Dec 14 21:00:38 volumio sudo[874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 14 21:00:38 volumio sudo[874]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:38 volumio sudo[874]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:38 volumio sudo[876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 14 21:00:38 volumio sudo[876]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:38 volumio volumio[835]: info: Loading plugin "services"...
Dec 14 21:00:38 volumio sudo[876]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:38 volumio volumio[835]: info: Loading plugin "alsa_controller"...
Dec 14 21:00:38 volumio sudo[879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Dec 14 21:00:38 volumio sudo[879]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:38 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 14 21:00:38 volumio volumio[835]: info: Loading plugin "wizard"...
Dec 14 21:00:38 volumio volumio[835]: info: Loading plugin "volumio_command_line_client"...
Dec 14 21:00:38 volumio volumio[835]: info: Loading plugin "upnp"...
Dec 14 21:00:38 volumio volumio[835]: info: [1702587638277] Starting Upmpd Daemon
Dec 14 21:00:38 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 14 21:00:38 volumio volumio[835]: info: Loading plugin "my_music"...
Dec 14 21:00:38 volumio volumio[835]: info: Loading plugin "mpd"...
Dec 14 21:00:39 volumio volumio[835]: info: Creating MPD Configuration file
Dec 14 21:00:39 volumio volumio[835]: info: Loading plugin "upnp_browser"...
Dec 14 21:00:39 volumio sudo[885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 14 21:00:39 volumio sudo[885]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:39 volumio sudo[885]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:39 volumio sudo[887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 14 21:00:39 volumio sudo[887]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:39 volumio systemd[1]: Stopping Music Player Daemon...
Dec 14 21:00:39 volumio systemd[1]: mpd.service: Succeeded.
Dec 14 21:00:39 volumio systemd[1]: Stopped Music Player Daemon.
Dec 14 21:00:39 volumio systemd[1]: Starting Music Player Daemon...
Dec 14 21:00:40 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
Dec 14 21:00:40 volumio volumio[835]: info: Loading plugin "networkfs"...
Dec 14 21:00:41 volumio volumio[835]: info: Starting Udev Watcher for removable devices
Dec 14 21:00:41 volumio volumio[835]: info: Ignoring mount for partition: boot
Dec 14 21:00:41 volumio volumio[835]: info: Ignoring mount for partition: volumio
Dec 14 21:00:41 volumio volumio[835]: info: Ignoring mount for partition: volumio_data
Dec 14 21:00:41 volumio volumio[835]: info: Mounting Device KINGSTON
Dec 14 21:00:41 volumio sudo[900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/KINGSTON -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Dec 14 21:00:41 volumio sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:41 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
Dec 14 21:00:41 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
Dec 14 21:00:41 volumio sudo[900]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:41 volumio sudo[879]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:41 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 14 21:00:41 volumio volumio[835]: info: Loading plugin "alarm-clock"...
Dec 14 21:00:42 volumio mpd[894]: Dec 14 21:00 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 14 21:00:42 volumio mpd[894]: Dec 14 21:00 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Dec 14 21:00:42 volumio mpd[894]: Dec 14 21:00 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Dec 14 21:00:42 volumio systemd[1]: Started Music Player Daemon.
Dec 14 21:00:42 volumio volumio[835]: info: Loading plugin "airplay_emulation"...
Dec 14 21:00:42 volumio sudo[887]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:42 volumio volumio[835]: info: Starting Shairport Sync
Dec 14 21:00:42 volumio volumio[835]: info: Loading plugin "last_100"...
Dec 14 21:00:42 volumio volumio[835]: info: Loading plugin "webradio"...
Dec 14 21:00:42 volumio volumio[835]: info: Loading plugin "i2s_dacs"...
Dec 14 21:00:42 volumio volumio[835]: info: Loading plugin "volumiodiscovery"...
Dec 14 21:00:42 volumio volumio[835]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Dec 14 21:00:42 volumio volumio[835]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 14 21:00:42 volumio volumio[835]: *** WARNING *** For more information see
Dec 14 21:00:42 volumio node[835]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Dec 14 21:00:42 volumio volumio[835]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Dec 14 21:00:42 volumio volumio[835]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 14 21:00:42 volumio volumio[835]: *** WARNING *** For more information see
Dec 14 21:00:42 volumio node[835]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 14 21:00:42 volumio node[835]: *** WARNING *** For more information see
Dec 14 21:00:42 volumio node[835]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Dec 14 21:00:42 volumio node[835]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 14 21:00:42 volumio node[835]: *** WARNING *** For more information see
Dec 14 21:00:42 volumio volumio[835]: info: Applying required configuration parameters for plugin volumiodiscovery
Dec 14 21:00:42 volumio volumio[835]: info: Discovery: Started advertising with name: Volumio
Dec 14 21:00:42 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 14 21:00:42 volumio volumio[835]: info: Loading plugin "outputs"...
Dec 14 21:00:42 volumio volumio[835]: info: Loading plugin "albumart"...
Dec 14 21:00:42 volumio volumio[835]: info: Plugin example_plugin is not enabled
Dec 14 21:00:42 volumio volumio[835]: info: Loading plugin "inputs"...
Dec 14 21:00:43 volumio volumio[835]: info: Loading plugin "updater_comm"...
Dec 14 21:00:43 volumio volumio[835]: info: Plugin mpdemulation is not enabled
Dec 14 21:00:43 volumio volumio[835]: info: Loading plugin "rest_api"...
Dec 14 21:00:43 volumio volumio[835]: info: Loading plugin "websocket"...
Dec 14 21:00:43 volumio volumio[835]: info: Loading i18n strings for locale en
Dec 14 21:00:43 volumio volumio[835]: Updating browse sources language
Dec 14 21:00:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 14 21:00:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 14 21:00:43 volumio volumio[835]: info: CoreCommandRouter::initPlayerControls
Dec 14 21:00:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 14 21:00:43 volumio volumio[835]: Express server listening on port 3000
Dec 14 21:00:43 volumio volumio[835]: [Metrics] WebUI: 12s 230.93ms
Dec 14 21:00:43 volumio volumio[835]: info: CoreStateMachine::resetVolumioState
Dec 14 21:00:43 volumio volumio[835]: info: CoreStateMachine::getcurrentVolume
Dec 14 21:00:43 volumio volumio[835]: info: CoreCommandRouter::volumioRetrievevolume
Dec 14 21:00:43 volumio volumio[835]: info: CoreStateMachine::pushState
Dec 14 21:00:43 volumio volumio[835]: info: CorePlayQueue::getTrack 0
Dec 14 21:00:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 14 21:00:43 volumio volumio[835]: info: CoreCommandRouter::volumioPushState
Dec 14 21:00:43 volumio volumio[835]: Forking 3 albumart workers
Dec 14 21:00:43 volumio volumio[835]: info: MPD Permissions set
Dec 14 21:00:44 volumio volumio[835]: info: MPD running with PID894
Dec 14 21:00:44 volumio volumio[835]: ,establishing connection
Dec 14 21:00:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 14 21:00:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 14 21:00:44 volumio volumio[835]: info: Reloading queue from file
Dec 14 21:00:44 volumio volumio[835]: error: updateQueue error: null
Dec 14 21:00:44 volumio volumio[835]: info: CoreStateMachine::setRepeat null single undefined
Dec 14 21:00:44 volumio volumio[835]: info: CoreStateMachine::pushState
Dec 14 21:00:44 volumio volumio[835]: info: CorePlayQueue::getTrack 0
Dec 14 21:00:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 14 21:00:44 volumio volumio[835]: info: CoreCommandRouter::volumioPushState
Dec 14 21:00:44 volumio volumio[835]: info: CoreStateMachine::setRandom null
Dec 14 21:00:44 volumio volumio[835]: info: CoreStateMachine::pushState
Dec 14 21:00:44 volumio volumio[835]: info: CorePlayQueue::getTrack 0
Dec 14 21:00:44 volumio volumio[835]: info: CoreCommandRouter::volumioPushState
Dec 14 21:00:44 volumio volumio[835]: info: Setting Device type: Raspberry PI
Dec 14 21:00:44 volumio volumio[835]: info: Completed loading Core Plugins
Dec 14 21:00:44 volumio volumio[835]: info: Preparing to generate the ALSA configuration file
Dec 14 21:00:45 volumio volumio[835]: error: updateQueue error: null
Dec 14 21:00:45 volumio volumio[835]: info: Asound.conf file unchanged, so no further update is needed
Dec 14 21:00:45 volumio volumio[835]: info: Output device has changed, restarting MPD
Dec 14 21:00:45 volumio volumio[835]: info: Output device has changed, restarting Shairport Sync
Dec 14 21:00:45 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:45 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 14 21:00:45 volumio sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 14 21:00:45 volumio sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:45 volumio sudo[951]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:45 volumio sudo[953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 14 21:00:45 volumio sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:45 volumio volumio[835]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 14 21:00:45 volumio volumio[835]: info: ___________ START PLUGINS ___________
Dec 14 21:00:45 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 14 21:00:45 volumio volumio[835]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 14 21:00:45 volumio volumio[835]: info: [1702587645316] CoreMusicLibrary::Adding element Media Servers
Dec 14 21:00:45 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 14 21:00:45 volumio volumio[835]: Cannot find translation for sourceMedia Servers
Dec 14 21:00:45 volumio systemd[1]: Stopping Music Player Daemon...
Dec 14 21:00:45 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:45 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 14 21:00:45 volumio systemd[1]: mpd.service: Succeeded.
Dec 14 21:00:45 volumio systemd[1]: Stopped Music Player Daemon.
Dec 14 21:00:45 volumio systemd[1]: Starting Music Player Daemon...
Dec 14 21:00:45 volumio volumio[835]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 14 21:00:45 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:45 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 14 21:00:45 volumio volumio[835]: Starting albumart workers
Dec 14 21:00:45 volumio volumio[835]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 14 21:00:45 volumio volumio[835]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 14 21:00:45 volumio volumio[835]: info: [1702587645664] CoreMusicLibrary::Adding element Last_100
Dec 14 21:00:45 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 14 21:00:45 volumio volumio[835]: Cannot find translation for sourceMedia Servers
Dec 14 21:00:45 volumio volumio[835]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 14 21:00:45 volumio volumio[835]: info: [1702587645675] CoreMusicLibrary::Adding element Webradio
Dec 14 21:00:45 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 14 21:00:45 volumio volumio[835]: Cannot find translation for sourceMedia Servers
Dec 14 21:00:45 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 14 21:00:45 volumio volumio[835]: info: Volumio Calling Home
Dec 14 21:00:45 volumio volumio[835]: Starting albumart workers
Dec 14 21:00:45 volumio volumio[835]: info: Completed starting Core Plugins
Dec 14 21:00:45 volumio volumio[835]: info: -------------------------------------------
Dec 14 21:00:45 volumio volumio[835]: info: ----- MyVolumio plugins startup ----
Dec 14 21:00:45 volumio volumio[835]: info: -------------------------------------------
Dec 14 21:00:46 volumio volumio[835]: info: [MyVolumio PluginManager] Fetching plans data....
Dec 14 21:00:46 volumio volumio[835]: Starting albumart workers
Dec 14 21:00:46 volumio volumio[835]: info: Discovery: adding 733c8d46-3c17-40a7-bfab-8752fd8c4f21
Dec 14 21:00:46 volumio volumio[835]: info: Discovery: Found device Volumio
Dec 14 21:00:46 volumio volumio[835]: info: CoreCommandRouter::volumioGetState
Dec 14 21:00:46 volumio volumio[835]: info: CorePlayQueue::getTrack 0
Dec 14 21:00:46 volumio volumio[835]: info: MPD Permissions set
Dec 14 21:00:46 volumio volumio[835]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1
Dec 14 21:00:46 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:46 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:46 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:46 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:46 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:46 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:46 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:46 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:46 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 14 21:00:46 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:00:46 volumio volumio[835]: info: Starting Shairport Sync
Dec 14 21:00:46 volumio volumio[835]: info: Starting Shairport Sync
Dec 14 21:00:46 volumio volumio[835]: info: Starting Shairport Sync
Dec 14 21:00:46 volumio sudo[976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 14 21:00:46 volumio sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Dec 14 21:00:47 volumio systemd[1]: shairport-sync.service: Succeeded.
Dec 14 21:00:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Dec 14 21:00:47 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Dec 14 21:00:47 volumio sudo[976]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:47 volumio sudo[979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 14 21:00:47 volumio volumio[835]: info: Volumio called home
Dec 14 21:00:47 volumio sudo[979]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:47 volumio sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 14 21:00:47 volumio sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Dec 14 21:00:47 volumio systemd[1]: shairport-sync.service: Succeeded.
Dec 14 21:00:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Dec 14 21:00:47 volumio volumio[835]: info: Shairport-Sync Started
Dec 14 21:00:47 volumio volumio[835]: Error adding Membership: Error: addMembership EINVAL
Dec 14 21:00:47 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Dec 14 21:00:47 volumio sudo[981]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Dec 14 21:00:47 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Dec 14 21:00:47 volumio systemd[1]: shairport-sync.service: Succeeded.
Dec 14 21:00:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Dec 14 21:00:47 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Dec 14 21:00:47 volumio sudo[979]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:47 volumio volumio[835]: info: CoreCommandRouter::volumioGetState
Dec 14 21:00:47 volumio volumio[835]: info: CorePlayQueue::getTrack 0
Dec 14 21:00:47 volumio volumio[835]: info: Shairport-Sync Started
Dec 14 21:00:47 volumio volumio[835]: info: Shairport-Sync Started
Dec 14 21:00:48 volumio sudo[1001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 14 21:00:48 volumio sudo[1001]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:48 volumio sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 14 21:00:48 volumio sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:48 volumio sudo[1001]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:48 volumio sudo[1002]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:48 volumio sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Dec 14 21:00:48 volumio sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:48 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Dec 14 21:00:48 volumio sudo[1006]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:48 volumio volumio[835]: info: Upmpdcli Daemon Started
Dec 14 21:00:48 volumio mpd[970]: Dec 14 21:00 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 14 21:00:48 volumio mpd[970]: Dec 14 21:00 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Dec 14 21:00:48 volumio mpd[970]: Dec 14 21:00 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Dec 14 21:00:48 volumio systemd[1]: Started Music Player Daemon.
Dec 14 21:00:48 volumio sudo[953]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:48 volumio volumio[835]: error: updateQueue error: null
Dec 14 21:00:49 volumio volumio[1008]: Generating RSA private key, 4096 bit long modulus (2 primes)
Dec 14 21:00:49 volumio sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 14 21:00:49 volumio sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:49 volumio sudo[1043]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:49 volumio sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 14 21:00:49 volumio sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:00:49 volumio sudo[1045]: pam_unix(sudo:session): session closed for user root
Dec 14 21:00:49 volumio volumio[835]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.85 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Total Clients: 2
Dec 14 21:00:49 volumio volumio[835]: info: CoreCommandRouter::volumioGetState
Dec 14 21:00:49 volumio volumio[835]: info: CorePlayQueue::getTrack 0
Dec 14 21:00:49 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Dec 14 21:00:49 volumio volumio[835]: info: CoreCommandRouter::volumioGetVisibleSources
Dec 14 21:00:49 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 14 21:00:49 volumio volumio[835]: info: Listing playlists
Dec 14 21:00:49 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Dec 14 21:00:49 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Dec 14 21:00:49 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Dec 14 21:00:49 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 14 21:00:52 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 14 21:00:52 volumio volumio[1008]: ...........++++
Dec 14 21:00:52 volumio volumio-remote-updater[463]: [2023-12-14 21:00:52] [connect] Successful connection
Dec 14 21:00:52 volumio volumio-remote-updater[463]: [2023-12-14 21:00:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1702587652 101
Dec 14 21:00:52 volumio volumio[835]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3
Dec 14 21:00:55 volumio volumio[1008]: ........++++
Dec 14 21:00:55 volumio volumio[1008]: e is 65537 (0x010001)
Dec 14 21:00:55 volumio volumio[1008]: writing RSA key
Dec 14 21:00:55 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 14 21:00:55 volumio volumio[835]: info: CURURI: music-library
Dec 14 21:00:55 volumio volumio[835]: info: CoreCommandRouter::volumioGetQueue
Dec 14 21:00:55 volumio volumio[835]: info: CoreStateMachine::getQueue
Dec 14 21:00:55 volumio volumio[835]: info: CorePlayQueue::getQueue
Dec 14 21:00:56 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 14 21:00:56 volumio volumio[835]: info: CURURI: music-library/USB
Dec 14 21:00:57 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 14 21:00:57 volumio volumio[835]: info: CURURI: music-library/USB/KINGSTON
Dec 14 21:01:02 volumio kernel: Voltage normalised (0x00000000)
Dec 14 21:01:05 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 14 21:01:05 volumio volumio[835]: info: CURURI: music-library/USB/KINGSTON/Daft Punk
Dec 14 21:01:06 volumio volumio[835]: error: MyVolumio Plugin failed to start in a timely fashion
Dec 14 21:01:06 volumio volumio[835]: info: BOOT COMPLETED
Dec 14 21:01:06 volumio volumio[835]: [Metrics] CommandRouter: 32s 916.65ms
Dec 14 21:01:06 volumio volumio[835]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 14 21:01:06 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 14 21:01:06 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 14 21:01:06 volumio volumio[835]: info: CoreCommandRouter::Close All Modals sent
Dec 14 21:01:06 volumio volumio[835]: info: CoreCommandRouter::Close All Modals sent
Dec 14 21:01:07 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 14 21:01:07 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 14 21:01:07 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 14 21:01:07 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 14 21:01:07 volumio volumio[835]: info: CURURI: music-library/USB/KINGSTON/Daft Punk/TRON LEGACY
Dec 14 21:01:08 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 14 21:01:08 volumio volumio[835]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Dec 14 21:01:08 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Dec 14 21:01:08 volumio volumio[835]: info: Received Get System Version
Dec 14 21:01:08 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 14 21:01:08 volumio volumio[835]: info: Received Get System Info
Dec 14 21:01:08 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 14 21:01:08 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 14 21:01:08 volumio volumio[835]: info: Discovery: Getting this device information
Dec 14 21:01:08 volumio volumio[835]: info: CoreCommandRouter::volumioGetState
Dec 14 21:01:08 volumio volumio[835]: info: CorePlayQueue::getTrack 0
Dec 14 21:01:08 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 14 21:01:11 volumio volumio[835]: info: CoreCommandRouter::volumioReplaceandPlayItems
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::ClearQueue
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::stop
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 14 21:01:11 volumio volumio[835]: info: CorePlayQueue::clearPlayQueue
Dec 14 21:01:11 volumio volumio[835]: info: CorePlayQueue::saveQueue
Dec 14 21:01:11 volumio volumio[835]: info: CoreCommandRouter::volumioPushState
Dec 14 21:01:11 volumio volumio[835]: info: CoreCommandRouter::volumioPushQueue
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::addQueueItems
Dec 14 21:01:11 volumio volumio[835]: info: CorePlayQueue::addQueueItems
Dec 14 21:01:11 volumio volumio[835]: info: Adding Item to queue: music-library/USB/KINGSTON/Daft Punk/TRON LEGACY
Dec 14 21:01:11 volumio volumio[835]: info: Exploding uri music-library/USB/KINGSTON/Daft Punk/TRON LEGACY in service mpd
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F1%20-%20DAFT%20PUNK%20-%20Overture.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/1 - DAFT PUNK - Overture.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F10%20-%20DAFT%20PUNK%20-%20Adagio%20for%20TRON.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/10 - DAFT PUNK - Adagio for TRON.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F11%20-%20DAFT%20PUNK%20-%20Nocturn.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/11 - DAFT PUNK - Nocturn.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F12%20-%20DAFT%20PUNK%20-%20End%20of%20Line.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/12 - DAFT PUNK - End of Line.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F13%20-%20DAFT%20PUNK%20-%20Derezzed.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/13 - DAFT PUNK - Derezzed.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F14%20-%20DAFT%20PUNK%20-%20Fall.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/14 - DAFT PUNK - Fall.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F15%20-%20DAFT%20PUNK%20-%20Solar%20Sailor.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/15 - DAFT PUNK - Solar Sailor.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F16%20-%20DAFT%20PUNK%20-%20Rectifier.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/16 - DAFT PUNK - Rectifier.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F17%20-%20DAFT%20PUNK%20-%20Dic%20Wars.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/17 - DAFT PUNK - Dic Wars.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F18%20-%20DAFT%20PUNK%20-%20C.L.U..wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/18 - DAFT PUNK - C.L.U..wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F19%20-%20DAFT%20PUNK%20-%20Arrival.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/19 - DAFT PUNK - Arrival.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F2%20-%20DAFT%20PUNK%20-%20The%20Grid.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/2 - DAFT PUNK - The Grid.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F20%20-%20DAFT%20PUNK%20-%20Flynn%20Lives.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/20 - DAFT PUNK - Flynn Lives.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F21%20-%20DAFT%20PUNK%20-%20TRON%20Legacy-End%20Titles.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/21 - DAFT PUNK - TRON Legacy-End Titles.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F22%20-%20DAFT%20PUNK%20-%20Finale.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/22 - DAFT PUNK - Finale.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=//extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F23%20-%20DAFT%20PUNK%20-%20Bonus.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/23 - DAFT PUNK - Bonus.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F3%20-%20DAFT%20PUNK%20-%20The%20Son%20of%20Flynn.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/3 - DAFT PUNK - The Son of Flynn.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F4%20-%20DAFT%20PUNK%20-%20Recognizer.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/4 - DAFT PUNK - Recognizer.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F5%20-%20DAFT%20PUNK%20-%20Armory.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/5 - DAFT PUNK - Armory.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F6%20-%20DAFT%20PUNK%20-%20Arena.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/6 - DAFT PUNK - Arena.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F7%20-%20DAFT%20PUNK%20-%20Rinzler.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/7 - DAFT PUNK - Rinzler.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F8%20-%20DAFT%20PUNK%20-%20The%20Game%20Has%20Changed.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/8 - DAFT PUNK - The Game Has Changed.wav
Dec 14 21:01:11 volumio volumio[835]: info: ALBUMART /albumart?cacheid=345&web=DAFT%20PUNK/TRON%20LEGACY/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FDaft%20Punk%2FTRON%20LEGACY%2F9%20-%20DAFT%20PUNK%20-%20Outlands.wav&metadata=false
Dec 14 21:01:11 volumio volumio[835]: info: URI /mnt/USB/KINGSTON/Daft Punk/TRON LEGACY/9 - DAFT PUNK - Outlands.wav
Dec 14 21:01:11 volumio volumio[835]: info: CorePlayQueue::saveQueue
Dec 14 21:01:11 volumio volumio[835]: info: CoreCommandRouter::volumioPushQueue
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock
Dec 14 21:01:11 volumio volumio[835]: info: CorePlayQueue::getTrackBlock
Dec 14 21:01:11 volumio volumio[835]: info: CoreCommandRouter::volumioPlay
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::play index 0
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::stop
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::play index undefined
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 14 21:01:11 volumio volumio[835]: info: CorePlayQueue::getTrack 0
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::startPlaybackTimer
Dec 14 21:01:11 volumio volumio[835]: info: CorePlayQueue::getTrack 0
Dec 14 21:01:11 volumio volumio[835]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/Daft Punk/TRON LEGACY/1 - DAFT PUNK - Overture.wav
Dec 14 21:01:11 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop
Dec 14 21:01:11 volumio volumio[835]: info: sendMpdCommand stop took 17 milliseconds
Dec 14 21:01:11 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand clear
Dec 14 21:01:11 volumio volumio[835]: info:
Dec 14 21:01:11 volumio volumio[835]: ---------------------------- MPD announces system playlist update
Dec 14 21:01:11 volumio volumio[835]: info: Ignoring MPD Status Update
Dec 14 21:01:11 volumio volumio[835]: info: sendMpdCommand clear took 13 milliseconds
Dec 14 21:01:11 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/Daft Punk/TRON LEGACY/1 - DAFT PUNK - Overture.wav"
Dec 14 21:01:11 volumio volumio[835]: error: updateQueue error: null
Dec 14 21:01:11 volumio volumio[835]: info:
Dec 14 21:01:11 volumio volumio[835]: ---------------------------- MPD announces system playlist update
Dec 14 21:01:11 volumio volumio[835]: info: Ignoring MPD Status Update
Dec 14 21:01:11 volumio volumio[835]: info: ------------------------------ 24ms
Dec 14 21:01:11 volumio volumio[835]: info: sendMpdCommand add "USB/KINGSTON/Daft Punk/TRON LEGACY/1 - DAFT PUNK - Overture.wav" took 19 milliseconds
Dec 14 21:01:11 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand play
Dec 14 21:01:11 volumio volumio[835]: info: ------------------------------ 24ms
Dec 14 21:01:11 volumio volumio[835]: info: sendMpdCommand play took 14 milliseconds
Dec 14 21:01:11 volumio volumio[835]: info:
Dec 14 21:01:11 volumio volumio[835]: ---------------------------- MPD announces state update: player
Dec 14 21:01:11 volumio volumio[835]: info: ControllerMpd::getState
Dec 14 21:01:11 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status
Dec 14 21:01:11 volumio volumio[835]: info:
Dec 14 21:01:11 volumio volumio[835]: ---------------------------- MPD announces state update: player
Dec 14 21:01:11 volumio volumio[835]: info: ControllerMpd::getState
Dec 14 21:01:11 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status
Dec 14 21:01:11 volumio volumio[835]: info: sendMpdCommand status took 15 milliseconds
Dec 14 21:01:11 volumio volumio[835]: verbose: ControllerMpd::parseState
Dec 14 21:01:11 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 14 21:01:11 volumio volumio[835]: info: sendMpdCommand status took 15 milliseconds
Dec 14 21:01:11 volumio volumio[835]: info: sendMpdCommand playlistinfo took 7 milliseconds
Dec 14 21:01:11 volumio volumio[835]: verbose: ControllerMpd::parseState
Dec 14 21:01:11 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 14 21:01:11 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo
Dec 14 21:01:11 volumio volumio[835]: info: ControllerMpd::pushState
Dec 14 21:01:11 volumio volumio[835]: info: CoreCommandRouter::servicePushState
Dec 14 21:01:11 volumio volumio[835]: info: CorePlayQueue::getTrack 0
Dec 14 21:01:11 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":148,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Overture","artist":"DAFT PUNK","album":"TRON LEGACY","uri":"USB/KINGSTON/Daft Punk/TRON LEGACY/1 - DAFT PUNK - Overture.wav","trackType":"wav"}
Dec 14 21:01:11 volumio volumio[835]: verbose: CURRENT POSITION 0
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::syncState stateService play
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus stop
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::pushState
Dec 14 21:01:11 volumio volumio[835]: info: CorePlayQueue::getTrack 0
Dec 14 21:01:11 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 14 21:01:11 volumio volumio[835]: info: CoreCommandRouter::volumioPushState
Dec 14 21:01:11 volumio volumio[835]: info: ------------------------------ 89ms
Dec 14 21:01:11 volumio volumio[835]: info: sendMpdCommand playlistinfo took 52 milliseconds
Dec 14 21:01:11 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo
Dec 14 21:01:11 volumio volumio[835]: info: ControllerMpd::pushState
Dec 14 21:01:11 volumio volumio[835]: info: CoreCommandRouter::servicePushState
Dec 14 21:01:11 volumio volumio[835]: info: CorePlayQueue::getTrack 0
Dec 14 21:01:11 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":148,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Overture","artist":"DAFT PUNK","album":"TRON LEGACY","uri":"USB/KINGSTON/Daft Punk/TRON LEGACY/1 - DAFT PUNK - Overture.wav","trackType":"wav"}
Dec 14 21:01:11 volumio volumio[835]: verbose: CURRENT POSITION 0
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::syncState stateService play
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play
Dec 14 21:01:11 volumio volumio[835]: info: Received an update from plugin. extracting info from payload
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::pushState
Dec 14 21:01:11 volumio volumio[835]: info: CorePlayQueue::getTrack 0
Dec 14 21:01:11 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 14 21:01:11 volumio volumio[835]: info: CoreCommandRouter::volumioPushState
Dec 14 21:01:11 volumio volumio[835]: info: CoreStateMachine::pushState
Dec 14 21:01:11 volumio volumio[835]: info: CorePlayQueue::getTrack 0
Dec 14 21:01:11 volumio volumio[835]: info: CoreCommandRouter::volumioPushState
Dec 14 21:01:12 volumio volumio[835]: info: ------------------------------ 125ms
Dec 14 21:01:12 volumio ntpd[648]: Soliciting pool server 220.158.215.21
Dec 14 21:01:12 volumio ntpd[648]: Soliciting pool server 129.250.35.250
Dec 14 21:01:13 volumio volumio[835]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 14 21:01:13 volumio volumio[835]: Error: certificate is not yet valid
Dec 14 21:01:13 volumio volumio[835]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34)
Dec 14 21:01:13 volumio volumio[835]: at TLSSocket.emit (events.js:315:20)
Dec 14 21:01:13 volumio volumio[835]: at TLSSocket._finishInit (_tls_wrap.js:932:8)
Dec 14 21:01:13 volumio volumio[835]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) {
Dec 14 21:01:13 volumio volumio[835]: code: 'CERT_NOT_YET_VALID'
Dec 14 21:01:13 volumio volumio[835]: }
Dec 14 21:01:13 volumio volumio[835]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 14 21:01:13 volumio ntpd[648]: Soliciting pool server 129.250.35.251
Dec 14 21:01:13 volumio ntpd[648]: Soliciting pool server 119.18.6.37
Dec 14 21:01:13 volumio sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-12-14 21:00
Dec 14 21:01:13 volumio sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 14 21:01:13 volumio ntpd[648]: Soliciting pool server 194.195.249.28
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="85acf7bbbceb3e3dec447156a39a88033f921210"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST"
VOLUMIO_VERSION="3.378"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"