-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Mon 2021-12-20 16:04:05 UTC. -- Dec 20 16:02:59 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Dec 20 16:03:00 volumio haveged[381]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Dec 20 16:03:00 volumio haveged[381]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Dec 20 16:03:00 volumio haveged[381]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00262 Dec 20 16:03:00 volumio haveged[381]: haveged: fills: 0, generated: 0 Dec 20 16:02:59 volumio ntpd[597]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Dec 20 16:03:00 volumio systemd[1]: plymouth-start.service: Succeeded. Dec 20 16:03:00 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Dec 20 16:03:00 volumio systemd[1]: Started Hold until boot process finishes up. Dec 20 16:03:00 volumio systemd[1]: Started Network Time Service. Dec 20 16:03:00 volumio ifplugd(eth0)[627]: ifplugd 0.28 initializing. Dec 20 16:03:00 volumio ifplugd(eth0)[627]: Using interface eth0/B8:27:EB:99:46:79 with driver (version: ) Dec 20 16:03:00 volumio ifplugd(eth0)[627]: Using detection mode: SIOCETHTOOL Dec 20 16:03:00 volumio ifplugd(eth0)[627]: Initialization complete, link beat detected. Dec 20 16:03:00 volumio systemd[1]: Started Getty on tty1. Dec 20 16:03:00 volumio systemd[1]: Reached target Login Prompts. Dec 20 16:03:00 volumio ntpd[620]: proto: precision = 1.458 usec (-19) Dec 20 16:03:00 volumio ntpd[620]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 20 16:03:00 volumio ntpd[620]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 20 16:03:00 volumio ifplugd(eth0)[627]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 20 16:03:00 volumio systemd[1]: iptables.service: Succeeded. Dec 20 16:03:00 volumio ntpd[620]: Listen and drop on 0 v6wildcard [::]:123 Dec 20 16:03:00 volumio ntpd[620]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 20 16:03:00 volumio ntpd[620]: Listen normally on 2 lo 127.0.0.1:123 Dec 20 16:03:00 volumio ntpd[620]: Listening on routing socket on fd #19 for interface updates Dec 20 16:03:00 volumio ntpd[620]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 20 16:03:00 volumio ntpd[620]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 20 16:03:00 volumio ifplugd(eth0)[627]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Dec 20 16:03:00 volumio volumio-remote-updater[470]: Error: No active session Dec 20 16:03:00 volumio volumio-remote-updater[470]: [2021-12-20 16:03:00] [info] asio async_connect error: system:111 (Connection refused) Dec 20 16:03:00 volumio volumio-remote-updater[470]: [2021-12-20 16:03:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 20 16:03:00 volumio volumio-remote-updater[470]: [2021-12-20 16:03:00] [error] handle_connect error: Underlying Transport Error Dec 20 16:03:01 volumio volumio[489]: Could not open config: /tmp/upmpdcli.conf Dec 20 16:03:01 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 20 16:03:01 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 20 16:03:01 volumio ntpd[620]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 20 16:03:02 volumio ntpd[620]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 20 16:03:02 volumio systemd[1]: systemd-rfkill.service: Succeeded. Dec 20 16:03:02 volumio nmbd[612]: [2021/12/20 16:03:02.257084, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 20 16:03:02 volumio nmbd[612]: started asyncdns process 645 Dec 20 16:03:02 volumio nmbd[612]: [2021/12/20 16:03:02.262269, 0] ../lib/util/become_daemon.c:149(daemon_status) Dec 20 16:03:02 volumio nmbd[612]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 20 16:03:02 volumio nmbd[612]: [2021/12/20 16:03:02.262623, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Dec 20 16:03:02 volumio nmbd[612]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 20 16:03:03 volumio ntpd[620]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 20 16:03:03 volumio sh[366]: eth0: leased 192.168.1.47 for 604800 seconds Dec 20 16:03:03 volumio sh[366]: eth0: adding route to 192.168.1.0/24 Dec 20 16:03:03 volumio sh[366]: eth0: adding default route via 192.168.1.1 Dec 20 16:03:03 volumio dhcpcd[402]: eth0: leased 192.168.1.47 for 604800 seconds Dec 20 16:03:03 volumio avahi-daemon[479]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.47. Dec 20 16:03:03 volumio avahi-daemon[479]: New relevant interface eth0.IPv4 for mDNS. Dec 20 16:03:03 volumio avahi-daemon[479]: Registering new address record for 192.168.1.47 on eth0.IPv4. Dec 20 16:03:03 volumio dhcpcd[402]: eth0: adding route to 192.168.1.0/24 Dec 20 16:03:03 volumio dhcpcd[402]: eth0: adding default route via 192.168.1.1 Dec 20 16:03:03 volumio sh[366]: forked to background, child pid 671 Dec 20 16:03:03 volumio dhcpcd[402]: forked to background, child pid 671 Dec 20 16:03:03 volumio ifplugd(eth0)[627]: client: ifup: interface eth0 already configured Dec 20 16:03:03 volumio sh[366]: eth0=eth0 Dec 20 16:03:03 volumio ifplugd(eth0)[627]: Program executed successfully. Dec 20 16:03:03 volumio ifplugd[599]: Network Interface Plugging Daemon...start eth0...done. Dec 20 16:03:03 volumio systemd[1]: Started LSB: Brings up/down network automatically. Dec 20 16:03:04 volumio ntpd[620]: Soliciting pool server 203.159.70.33 Dec 20 16:03:05 volumio ntpd[620]: Listen normally on 3 eth0 192.168.1.47:123 Dec 20 16:03:05 volumio ntpd[620]: new interface(s) found: waking up resolver Dec 20 16:03:05 volumio wireless.js[473]: WIRELESS DAEMON: start Dec 20 16:03:05 volumio wireless.js[473]: WIRELESS: First boot Dec 20 16:03:05 volumio wireless.js[473]: Cleaning previous... Dec 20 16:03:05 volumio volumio-remote-updater[470]: [2021-12-20 16:03:05] [info] asio async_connect error: system:111 (Connection refused) Dec 20 16:03:05 volumio volumio-remote-updater[470]: [2021-12-20 16:03:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 20 16:03:05 volumio volumio-remote-updater[470]: [2021-12-20 16:03:05] [error] handle_connect error: Underlying Transport Error Dec 20 16:03:05 volumio kernel: Bluetooth: Core ver 2.22 Dec 20 16:03:05 volumio kernel: NET: Registered protocol family 31 Dec 20 16:03:05 volumio kernel: Bluetooth: HCI device and connection manager initialized Dec 20 16:03:05 volumio kernel: Bluetooth: HCI socket layer initialized Dec 20 16:03:05 volumio kernel: Bluetooth: L2CAP socket layer initialized Dec 20 16:03:05 volumio kernel: Bluetooth: SCO socket layer initialized Dec 20 16:03:05 volumio kernel: Bluetooth: HCI UART driver ver 2.3 Dec 20 16:03:05 volumio kernel: Bluetooth: HCI UART protocol H4 registered Dec 20 16:03:05 volumio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Dec 20 16:03:05 volumio btuart[471]: bcm43xx_init Dec 20 16:03:05 volumio btuart[471]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Dec 20 16:03:05 volumio btuart[471]: Set BDADDR UART: b8:27:eb:33:ec:d3 Dec 20 16:03:05 volumio btuart[471]: Set Controller UART speed to 3000000 bit/s Dec 20 16:03:05 volumio btuart[471]: Device setup complete Dec 20 16:03:05 volumio systemd[1]: Started Configure Bluetooth Modems connected by UART. Dec 20 16:03:05 volumio kernel: Bluetooth: HCI UART protocol Broadcom registered Dec 20 16:03:05 volumio sudo[701]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 20 16:03:05 volumio sudo[701]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:05 volumio sudo[701]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:05 volumio sudo[711]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 20 16:03:05 volumio sudo[711]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:05 volumio systemd[1]: Created slice system-bthelper.slice. Dec 20 16:03:05 volumio systemd[1]: Starting Raspberry Pi bluetooth helper... Dec 20 16:03:05 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Dec 20 16:03:06 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Dec 20 16:03:06 volumio bthelper[713]: Raspberry Pi BDADDR already set Dec 20 16:03:06 volumio systemd[1]: Started Raspberry Pi bluetooth helper. Dec 20 16:03:06 volumio systemd[1]: Starting Bluetooth service... Dec 20 16:03:06 volumio bluetoothd[724]: Bluetooth daemon 5.50 Dec 20 16:03:06 volumio bluetoothd[724]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Dec 20 16:03:06 volumio systemd[1]: Started Bluetooth service. Dec 20 16:03:06 volumio systemd[1]: Reached target Bluetooth. Dec 20 16:03:06 volumio bluetoothd[724]: Starting SDP server Dec 20 16:03:06 volumio bluetoothd[724]: Excluding (cli) sap Dec 20 16:03:06 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 20 16:03:06 volumio kernel: Bluetooth: BNEP filters: protocol multicast Dec 20 16:03:06 volumio kernel: Bluetooth: BNEP socket layer initialized Dec 20 16:03:06 volumio dbus-daemon[485]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=724 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Dec 20 16:03:06 volumio bluetoothd[724]: Bluetooth management interface 1.14 initialized Dec 20 16:03:06 volumio systemd[1]: Starting Hostname Service... Dec 20 16:03:06 volumio sudo[711]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:06 volumio bluetoothd[724]: Failed to set privacy: Rejected (0x0b) Dec 20 16:03:06 volumio wireless.js[473]: Stopped aP Dec 20 16:03:06 volumio mpd[642]: Dec 20 16:03 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 16:03:06 volumio mpd[642]: Dec 20 16:03 : exception: Database corrupted Dec 20 16:03:06 volumio mpd[642]: Dec 20 16:03 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 20 16:03:06 volumio mpd[642]: Dec 20 16:03 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 20 16:03:06 volumio dbus-daemon[485]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 20 16:03:06 volumio systemd[1]: Started Hostname Service. Dec 20 16:03:06 volumio systemd[1]: Started Music Player Daemon. Dec 20 16:03:06 volumio sudo[734]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 20 16:03:06 volumio sudo[734]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:06 volumio sudo[734]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:06 volumio sudo[739]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 20 16:03:06 volumio sudo[739]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:06 volumio sudo[739]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:06 volumio avahi-daemon[479]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 20 16:03:06 volumio avahi-daemon[479]: New relevant interface wlan0.IPv4 for mDNS. Dec 20 16:03:06 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 20 16:03:06 volumio avahi-daemon[479]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Dec 20 16:03:07 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Dec 20 16:03:07 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Dec 20 16:03:07 volumio dnsmasq[746]: dnsmasq: syntax check OK. Dec 20 16:03:07 volumio hostapd[745]: Configuration file: /etc/hostapd/hostapd.conf Dec 20 16:03:07 volumio hostapd[745]: wlan0: Could not connect to kernel driver Dec 20 16:03:07 volumio hostapd[745]: Using interface wlan0 with hwaddr b8:27:eb:cc:13:2c and ssid "Volumio" Dec 20 16:03:07 volumio hostapd[745]: wlan0: interface state UNINITIALIZED->ENABLED Dec 20 16:03:07 volumio hostapd[745]: wlan0: AP-ENABLED Dec 20 16:03:07 volumio dnsmasq[753]: started, version 2.80 cachesize 150 Dec 20 16:03:07 volumio dnsmasq[753]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Dec 20 16:03:07 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Dec 20 16:03:07 volumio dnsmasq-dhcp[753]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Dec 20 16:03:07 volumio dnsmasq[753]: reading /etc/resolv.conf Dec 20 16:03:07 volumio dnsmasq[753]: using nameserver 192.168.1.1#53 Dec 20 16:03:07 volumio dnsmasq[753]: using nameserver 208.67.222.222#53 Dec 20 16:03:07 volumio dnsmasq[753]: using nameserver 208.67.220.220#53 Dec 20 16:03:07 volumio dnsmasq[753]: read /etc/hosts - 5 addresses Dec 20 16:03:07 volumio nmbd[612]: [2021/12/20 16:03:07.363775, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 20 16:03:07 volumio nmbd[612]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 20 16:03:07 volumio systemd[1]: Started Samba NMB Daemon. Dec 20 16:03:07 volumio systemd[1]: Starting Samba Winbind Daemon... Dec 20 16:03:07 volumio systemd[1]: wireless.service: Succeeded. Dec 20 16:03:07 volumio systemd[1]: Started Wireless Services. Dec 20 16:03:07 volumio systemd[1]: Started Volumio Backend Module. Dec 20 16:03:07 volumio systemd[1]: Started Volumio Streaming Daemon. Dec 20 16:03:07 volumio systemd[1]: Started First Start. Dec 20 16:03:07 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Dec 20 16:03:07 volumio systemd[1]: Reached target Host and Network Name Lookups. Dec 20 16:03:07 volumio firststart.sh[764]: Volumio first start configuration script Dec 20 16:03:07 volumio firststart.sh[764]: Configuring unconfigured packages Dec 20 16:03:08 volumio firststart.sh[764]: Creating /var/log/samba/cores folder Dec 20 16:03:08 volumio winbindd[755]: [2021/12/20 16:03:08.134852, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 20 16:03:08 volumio firststart.sh[764]: Creating /boot/userconfig.txt Dec 20 16:03:08 volumio winbindd[755]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 20 16:03:08 volumio firststart.sh[764]: Removing default SSH host keys Dec 20 16:03:08 volumio volumio-streaming-daemon[763]: ############################ Dec 20 16:03:08 volumio volumio-streaming-daemon[763]: # Volumio Streaming Daemon # Dec 20 16:03:08 volumio volumio-streaming-daemon[763]: # Running on port 7777 # Dec 20 16:03:08 volumio volumio-streaming-daemon[763]: ############################ Dec 20 16:03:08 volumio firststart.sh[764]: removed '/etc/ssh/ssh_host_dsa_key' Dec 20 16:03:08 volumio firststart.sh[764]: removed '/etc/ssh/ssh_host_dsa_key.pub' Dec 20 16:03:08 volumio firststart.sh[764]: removed '/etc/ssh/ssh_host_ecdsa_key' Dec 20 16:03:08 volumio firststart.sh[764]: removed '/etc/ssh/ssh_host_ecdsa_key.pub' Dec 20 16:03:08 volumio firststart.sh[764]: removed '/etc/ssh/ssh_host_ed25519_key' Dec 20 16:03:08 volumio firststart.sh[764]: removed '/etc/ssh/ssh_host_ed25519_key.pub' Dec 20 16:03:08 volumio firststart.sh[764]: removed '/etc/ssh/ssh_host_rsa_key' Dec 20 16:03:08 volumio firststart.sh[764]: removed '/etc/ssh/ssh_host_rsa_key.pub' Dec 20 16:03:08 volumio firststart.sh[764]: Generating SSH host keys Dec 20 16:03:08 volumio volumio-streaming-daemon[763]: Environment: Dec 20 16:03:08 volumio systemd[1]: Started Samba Winbind Daemon. Dec 20 16:03:08 volumio systemd[1]: Starting Samba SMB Daemon... Dec 20 16:03:08 volumio winbindd[755]: [2021/12/20 16:03:08.269450, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 20 16:03:08 volumio winbindd[755]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 20 16:03:09 volumio ntpd[620]: Listen normally on 4 wlan0 192.168.211.1:123 Dec 20 16:03:09 volumio ntpd[620]: new interface(s) found: waking up resolver Dec 20 16:03:09 volumio smbd[786]: [2021/12/20 16:03:09.727685, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 20 16:03:09 volumio systemd[1]: Started Samba SMB Daemon. Dec 20 16:03:09 volumio smbd[786]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 20 16:03:09 volumio systemd[1]: Reached target Multi-User System. Dec 20 16:03:09 volumio systemd[1]: Reached target Graphical Interface. Dec 20 16:03:09 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 20 16:03:09 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 20 16:03:09 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 20 16:03:09 volumio systemd[1]: Startup finished in 25.122s (kernel) + 18.818s (userspace) = 43.941s. Dec 20 16:03:11 volumio firststart.sh[764]: debconf: unable to initialize frontend: Dialog Dec 20 16:03:11 volumio firststart.sh[764]: debconf: (TERM is not set, so the dialog frontend is not usable.) Dec 20 16:03:11 volumio firststart.sh[764]: debconf: falling back to frontend: Readline Dec 20 16:03:11 volumio systemd[1]: systemd-rfkill.service: Succeeded. Dec 20 16:03:11 volumio firststart.sh[764]: debconf: unable to initialize frontend: Readline Dec 20 16:03:11 volumio firststart.sh[764]: debconf: (This frontend requires a controlling tty.) Dec 20 16:03:11 volumio firststart.sh[764]: debconf: falling back to frontend: Teletype Dec 20 16:03:11 volumio bthelper[713]: Changing power off succeeded Dec 20 16:03:11 volumio bthelper[713]: [CHG] Controller B8:27:EB:33:EC:D3 Powered: no Dec 20 16:03:11 volumio bthelper[713]: [CHG] Controller B8:27:EB:33:EC:D3 Discovering: no Dec 20 16:03:11 volumio bthelper[713]: [CHG] Controller B8:27:EB:33:EC:D3 Class: 0x00000000 Dec 20 16:03:11 volumio bthelper[713]: [CHG] Controller B8:27:EB:33:EC:D3 Class: 0x0000041c Dec 20 16:03:11 volumio bthelper[713]: Changing power on succeeded Dec 20 16:03:13 volumio volumio-remote-updater[470]: [2021-12-20 16:03:13] [connect] Successful connection Dec 20 16:03:14 volumio volumio[762]: info: ------------------------------------------- Dec 20 16:03:14 volumio volumio[762]: info: ----- Volumio3 ---- Dec 20 16:03:14 volumio volumio[762]: info: ------------------------------------------- Dec 20 16:03:14 volumio volumio[762]: info: ----- System startup ---- Dec 20 16:03:14 volumio volumio[762]: info: ------------------------------------------- Dec 20 16:03:16 volumio volumio[762]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio Dec 20 16:03:16 volumio volumio[762]: info: MYVOLUMIO Environment detected Dec 20 16:03:16 volumio volumio[762]: info: Plugin folders cleanup Dec 20 16:03:16 volumio volumio[762]: info: Scanning into folder /volumio/app/plugins/ Dec 20 16:03:16 volumio volumio[762]: info: Scanning category audio_interface Dec 20 16:03:16 volumio volumio[762]: info: Scanning category miscellanea Dec 20 16:03:16 volumio volumio[762]: info: Scanning category music_service Dec 20 16:03:16 volumio volumio[762]: info: Scanning category plugins.json Dec 20 16:03:16 volumio volumio[762]: info: Scanning category system_controller Dec 20 16:03:16 volumio volumio[762]: info: Scanning category user_interface Dec 20 16:03:16 volumio volumio[762]: info: Scanning into folder /data/plugins/ Dec 20 16:03:16 volumio volumio[762]: info: Plugin folders cleanup completed Dec 20 16:03:16 volumio volumio[762]: info: ------------------------------------------- Dec 20 16:03:16 volumio volumio[762]: info: ----- Core plugins startup ---- Dec 20 16:03:16 volumio volumio[762]: info: ------------------------------------------- Dec 20 16:03:16 volumio volumio[762]: info: Loading plugins from folder /volumio/app/plugins/ Dec 20 16:03:16 volumio volumio[762]: info: Adding plugin upnp to MyMusic Plugins Dec 20 16:03:16 volumio volumio[762]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 20 16:03:16 volumio volumio[762]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 20 16:03:16 volumio volumio[762]: info: Loading plugins from folder /data/plugins/ Dec 20 16:03:16 volumio volumio[762]: info: Loading plugin "system"... Dec 20 16:03:16 volumio volumio[762]: info: No id defined. Creating one Dec 20 16:03:16 volumio volumio[762]: info: Loading plugin "appearance"... Dec 20 16:03:19 volumio volumio[762]: info: Loading plugin "network"... Dec 20 16:03:19 volumio volumio[762]: info: Refreshing Cached IP Addresses Dec 20 16:03:19 volumio sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 16:03:19 volumio sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:19 volumio sudo[919]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:19 volumio sudo[921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 16:03:19 volumio sudo[921]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:19 volumio volumio[762]: info: Loading plugin "services"... Dec 20 16:03:19 volumio sudo[921]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:19 volumio volumio[762]: info: Loading plugin "alsa_controller"... Dec 20 16:03:19 volumio sudo[924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 20 16:03:19 volumio sudo[924]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:20 volumio volumio[762]: warn: The ALSA output card is not set, defaulting to card 0 Dec 20 16:03:20 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 20 16:03:20 volumio volumio[762]: info: Found match in Cards Database: setting mixer HDMI for card HDMI Dec 20 16:03:20 volumio volumio[762]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 20 16:03:20 volumio volumio[762]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 20 16:03:20 volumio volumio[762]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 20 16:03:20 volumio volumio[762]: info: Loading plugin "wizard"... Dec 20 16:03:20 volumio volumio[762]: info: Loading plugin "volumio_command_line_client"... Dec 20 16:03:20 volumio volumio[762]: info: Loading plugin "upnp"... Dec 20 16:03:20 volumio volumio[762]: info: [1640016200531] Starting Upmpd Daemon Dec 20 16:03:20 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 16:03:20 volumio volumio[762]: info: Loading plugin "my_music"... Dec 20 16:03:20 volumio volumio[762]: info: Loading plugin "mpd"... Dec 20 16:03:21 volumio volumio[762]: info: Creating MPD Configuration file Dec 20 16:03:21 volumio volumio[762]: info: Loading plugin "upnp_browser"... Dec 20 16:03:21 volumio sudo[940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 16:03:21 volumio sudo[940]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:21 volumio sudo[942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 16:03:21 volumio sudo[942]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:21 volumio sudo[940]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:21 volumio systemd[1]: Stopping Music Player Daemon... Dec 20 16:03:21 volumio firststart.sh[764]: Creating SSH2 RSA key; this may take some time ... Dec 20 16:03:21 volumio firststart.sh[764]: 2048 SHA256:96uHoV3h7hHpr2xbZJEEZswXZuwF83kIv6zMbnKRzr0 root@volumio (RSA) Dec 20 16:03:21 volumio systemd[1]: mpd.service: Succeeded. Dec 20 16:03:21 volumio systemd[1]: Stopped Music Player Daemon. Dec 20 16:03:22 volumio systemd[1]: Starting Music Player Daemon... Dec 20 16:03:22 volumio volumio[762]: info: Loading plugin "networkfs"... Dec 20 16:03:23 volumio volumio[762]: info: Starting Udev Watcher for removable devices Dec 20 16:03:23 volumio firststart.sh[764]: Creating SSH2 DSA key; this may take some time ... Dec 20 16:03:23 volumio firststart.sh[764]: 1024 SHA256:JWup5JkLJBAMpOxlxfG7o24482MRPV07vCLDkAy8wN8 root@volumio (DSA) Dec 20 16:03:23 volumio firststart.sh[764]: Creating SSH2 ECDSA key; this may take some time ... Dec 20 16:03:23 volumio firststart.sh[764]: 256 SHA256:NJ/ZXfnvn4eyUAXy8w5JkuB5hM8QYmiOAkIvFStmyS0 root@volumio (ECDSA) Dec 20 16:03:23 volumio volumio[762]: info: Ignoring mount for partition: boot Dec 20 16:03:23 volumio volumio[762]: info: Ignoring mount for partition: volumio Dec 20 16:03:23 volumio volumio[762]: info: Ignoring mount for partition: volumio_data Dec 20 16:03:23 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 16:03:23 volumio volumio[762]: info: Loading plugin "alarm-clock"... Dec 20 16:03:23 volumio sudo[924]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:24 volumio mpd[952]: Dec 20 16:03 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 16:03:24 volumio mpd[952]: Dec 20 16:03 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 20 16:03:24 volumio mpd[952]: Dec 20 16:03 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 20 16:03:24 volumio volumio[762]: info: Loading plugin "airplay_emulation"... Dec 20 16:03:24 volumio systemd[1]: Started Music Player Daemon. Dec 20 16:03:24 volumio sudo[942]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:24 volumio volumio[762]: info: Starting Shairport Sync Dec 20 16:03:24 volumio volumio[762]: info: Loading plugin "last_100"... Dec 20 16:03:24 volumio volumio[762]: info: Loading plugin "webradio"... Dec 20 16:03:25 volumio volumio[762]: info: Loading plugin "i2s_dacs"... Dec 20 16:03:25 volumio volumio[762]: info: I2S DAC not set, start Auto-detection Dec 20 16:03:25 volumio volumio[762]: info: Loading plugin "volumiodiscovery"... Dec 20 16:03:25 volumio volumio[762]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 16:03:25 volumio volumio[762]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 16:03:25 volumio volumio[762]: *** WARNING *** For more information see Dec 20 16:03:25 volumio node[762]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 16:03:25 volumio volumio[762]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 16:03:25 volumio volumio[762]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 16:03:25 volumio volumio[762]: *** WARNING *** For more information see Dec 20 16:03:25 volumio node[762]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 16:03:25 volumio node[762]: *** WARNING *** For more information see Dec 20 16:03:25 volumio node[762]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 16:03:25 volumio node[762]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 16:03:25 volumio node[762]: *** WARNING *** For more information see Dec 20 16:03:25 volumio volumio[762]: info: Discovery: Started advertising with name: Volumio Dec 20 16:03:25 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 16:03:25 volumio volumio[762]: info: Loading plugin "outputs"... Dec 20 16:03:25 volumio volumio[762]: info: Loading plugin "albumart"... Dec 20 16:03:25 volumio volumio[762]: info: Plugin example_plugin is not enabled Dec 20 16:03:25 volumio volumio[762]: info: Loading plugin "inputs"... Dec 20 16:03:25 volumio volumio[762]: info: Loading plugin "updater_comm"... Dec 20 16:03:25 volumio volumio[762]: info: Plugin mpdemulation is not enabled Dec 20 16:03:25 volumio volumio[762]: info: Loading plugin "rest_api"... Dec 20 16:03:25 volumio volumio[762]: info: Loading plugin "websocket"... Dec 20 16:03:25 volumio volumio[762]: info: Loading i18n strings for locale en Dec 20 16:03:25 volumio volumio[762]: Updating browse sources language Dec 20 16:03:25 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 16:03:25 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 16:03:25 volumio volumio[762]: info: CoreCommandRouter::initPlayerControls Dec 20 16:03:26 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:26 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:26 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:26 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:26 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:26 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:26 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:26 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:26 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 16:03:26 volumio volumio[762]: Express server listening on port 3000 Dec 20 16:03:26 volumio volumio[762]: [Metrics] WebUI: 14s 11.21ms Dec 20 16:03:26 volumio volumio[762]: info: CoreStateMachine::resetVolumioState Dec 20 16:03:26 volumio volumio[762]: info: CoreStateMachine::getcurrentVolume Dec 20 16:03:26 volumio volumio[762]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 16:03:26 volumio volumio[762]: Forking 3 albumart workers Dec 20 16:03:26 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 16:03:26 volumio volumio[762]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 20 16:03:26 volumio volumio[762]: info: CoreStateMachine::getcurrentVolume Dec 20 16:03:26 volumio volumio[762]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 16:03:26 volumio volumio[762]: info: Cannot read play queue from file Dec 20 16:03:26 volumio systemd[1]: systemd-fsckd.service: Succeeded. Dec 20 16:03:26 volumio volumio[762]: info: MPD Permissions set Dec 20 16:03:27 volumio volumio[762]: info: MPD running with PID952 Dec 20 16:03:27 volumio volumio[762]: ,establishing connection Dec 20 16:03:27 volumio volumio[762]: info: VolumeController:: Volume=86 Mute =false Dec 20 16:03:27 volumio volumio[762]: info: CoreStateMachine::pushState Dec 20 16:03:27 volumio volumio[762]: info: CorePlayQueue::getTrack 0 Dec 20 16:03:27 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 16:03:27 volumio volumio[762]: info: CoreCommandRouter::volumioPushState Dec 20 16:03:27 volumio volumio[762]: info: CoreStateMachine::updateTrackBlock Dec 20 16:03:27 volumio volumio[762]: info: CorePlayQueue::getTrackBlock Dec 20 16:03:27 volumio volumio[762]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 16:03:27 volumio systemd[1]: Reloading. Dec 20 16:03:27 volumio volumio[762]: error: MPD error: The expression evaluated to a falsy value: Dec 20 16:03:27 volumio volumio[762]: assert.ok(self.idling) Dec 20 16:03:27 volumio volumio[762]: error: MPD error: The expression evaluated to a falsy value: Dec 20 16:03:27 volumio volumio[762]: assert.ok(self.idling) Dec 20 16:03:27 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:27 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:27 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:27 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:27 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:27 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:27 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:27 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:28 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 16:03:28 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 20 16:03:28 volumio volumio-remote-updater[470]: [2021-12-20 16:03:28] [connect] Successful connection Dec 20 16:03:28 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 20 16:03:28 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:28 volumio volumio[762]: info: VolumeController:: Volume=86 Mute =false Dec 20 16:03:28 volumio volumio[762]: info: CoreStateMachine::pushState Dec 20 16:03:28 volumio volumio[762]: info: CorePlayQueue::getTrack 0 Dec 20 16:03:28 volumio volumio[762]: info: CoreCommandRouter::volumioPushState Dec 20 16:03:28 volumio volumio[762]: info: CoreStateMachine::updateTrackBlock Dec 20 16:03:28 volumio volumio[762]: info: CorePlayQueue::getTrackBlock Dec 20 16:03:28 volumio volumio[762]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 16:03:28 volumio volumio[762]: info: Setting Device type: Raspberry PI Dec 20 16:03:28 volumio volumio[762]: error: updateQueue error: null Dec 20 16:03:28 volumio volumio[762]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 20 16:03:28 volumio volumio[762]: info: Completed loading Core Plugins Dec 20 16:03:28 volumio volumio[762]: info: Preparing to generate the ALSA configuration file Dec 20 16:03:28 volumio volumio[762]: Starting albumart workers Dec 20 16:03:28 volumio volumio-remote-updater[470]: [2021-12-20 16:03:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1640016208 101 Dec 20 16:03:28 volumio volumio[762]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Dec 20 16:03:28 volumio volumio[762]: info: VolumeController:: Volume=86 Mute =false Dec 20 16:03:28 volumio volumio[762]: info: CoreStateMachine::pushState Dec 20 16:03:28 volumio volumio[762]: info: CorePlayQueue::getTrack 0 Dec 20 16:03:28 volumio volumio[762]: info: CoreCommandRouter::volumioPushState Dec 20 16:03:28 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Dec 20 16:03:28 volumio volumio[762]: Starting albumart workers Dec 20 16:03:28 volumio volumio[762]: info: mDNS Browse raised the following error Error: getaddrinfo -3007 Dec 20 16:03:28 volumio volumio[762]: Starting albumart workers Dec 20 16:03:29 volumio volumio[762]: info: Asound.conf file written Dec 20 16:03:29 volumio sudo[1054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 20 16:03:29 volumio sudo[1054]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:29 volumio sudo[1054]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:29 volumio firststart.sh[764]: rescue-ssh.target is a disabled or a static unit, not starting it. Dec 20 16:03:29 volumio volumio[762]: No state is present for card b1 Dec 20 16:03:29 volumio volumio[762]: No state is present for card Headphones Dec 20 16:03:29 volumio volumio[762]: No state is present for card sndrpihifiberry Dec 20 16:03:29 volumio volumio[762]: Found hardware: "HifiberryDacp" "" "" "" "" Dec 20 16:03:29 volumio volumio[762]: Hardware is initialized using a generic method Dec 20 16:03:29 volumio volumio[762]: No state is present for card sndrpihifiberry Dec 20 16:03:29 volumio volumio[762]: info: Output device has changed, restarting MPD Dec 20 16:03:29 volumio volumio[762]: info: Output device has changed, restarting Shairport Sync Dec 20 16:03:29 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:29 volumio sudo[1076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 16:03:29 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 16:03:29 volumio sudo[1076]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:29 volumio sudo[1076]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:29 volumio sudo[1079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 16:03:29 volumio sudo[1079]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:29 volumio volumio[762]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 16:03:29 volumio volumio[762]: info: ___________ START PLUGINS ___________ Dec 20 16:03:29 volumio volumio[762]: info: Setting Hotspot Unique name on first start: Volumio-72F93 Dec 20 16:03:30 volumio systemd[1]: Stopping Music Player Daemon... Dec 20 16:03:30 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Dec 20 16:03:30 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 16:03:30 volumio volumio[762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 16:03:30 volumio systemd[1]: mpd.service: Succeeded. Dec 20 16:03:30 volumio systemd[1]: Stopped Music Player Daemon. Dec 20 16:03:30 volumio systemd[1]: Starting Music Player Daemon... Dec 20 16:03:30 volumio volumio[762]: info: [1640016210073] CoreMusicLibrary::Adding element Media Servers Dec 20 16:03:30 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 16:03:30 volumio volumio[762]: Cannot find translation for sourceMedia Servers Dec 20 16:03:30 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:30 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 16:03:30 volumio firststart.sh[764]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Dec 20 16:03:30 volumio firststart.sh[764]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Dec 20 16:03:30 volumio volumio[762]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 16:03:30 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:30 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 16:03:30 volumio volumio[762]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 16:03:30 volumio volumio[762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 16:03:30 volumio volumio[762]: info: [1640016210695] CoreMusicLibrary::Adding element Last_100 Dec 20 16:03:30 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 16:03:30 volumio volumio[762]: Cannot find translation for sourceMedia Servers Dec 20 16:03:30 volumio systemd[1]: Reloading. Dec 20 16:03:30 volumio volumio[762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 16:03:30 volumio volumio[762]: info: [1640016210705] CoreMusicLibrary::Adding element Webradio Dec 20 16:03:30 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 16:03:30 volumio volumio[762]: Cannot find translation for sourceMedia Servers Dec 20 16:03:30 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 16:03:30 volumio volumio[762]: info: Volumio Calling Home Dec 20 16:03:31 volumio volumio[762]: info: Completed starting Core Plugins Dec 20 16:03:31 volumio volumio[762]: info: ------------------------------------------- Dec 20 16:03:31 volumio volumio[762]: info: ----- MyVolumio plugins startup ---- Dec 20 16:03:31 volumio volumio[762]: info: ------------------------------------------- Dec 20 16:03:31 volumio volumio[762]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 20 16:03:31 volumio volumio[762]: info: VolumeController:: Volume=86 Mute =false Dec 20 16:03:31 volumio volumio[762]: info: CoreStateMachine::pushState Dec 20 16:03:31 volumio volumio[762]: info: CorePlayQueue::getTrack 0 Dec 20 16:03:31 volumio volumio[762]: info: CoreCommandRouter::volumioPushState Dec 20 16:03:31 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 20 16:03:31 volumio sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Dec 20 16:03:31 volumio sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:31 volumio volumio[762]: info: mDNS Browse raised the following error Error: getaddrinfo -3007 Dec 20 16:03:31 volumio sudo[1138]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:31 volumio sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 16:03:31 volumio volumio[762]: info: CorePlayQueue::getTrack 0 Dec 20 16:03:31 volumio sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:31 volumio sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 16:03:31 volumio sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:31 volumio sudo[1143]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:31 volumio sudo[1141]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:31 volumio volumio[762]: info: MPD Permissions set Dec 20 16:03:31 volumio volumio[762]: info: Permissions for /etc/hostapd/hostapd.conf Dec 20 16:03:31 volumio volumio[762]: info: Volumio called home Dec 20 16:03:31 volumio sudo[1147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 20 16:03:32 volumio sudo[1147]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:32 volumio volumio[762]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Dec 20 16:03:32 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:32 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:32 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:32 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:32 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:32 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:32 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:32 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:32 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 16:03:32 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:32 volumio systemd[1]: Starting Wireless Services... Dec 20 16:03:32 volumio volumio[762]: info: Starting Shairport Sync Dec 20 16:03:32 volumio volumio[762]: info: Starting Shairport Sync Dec 20 16:03:32 volumio sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 16:03:32 volumio sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:32 volumio volumio[762]: info: Starting Shairport Sync Dec 20 16:03:33 volumio sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 16:03:33 volumio sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 20 16:03:33 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 20 16:03:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 20 16:03:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 20 16:03:33 volumio sudo[1168]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:33 volumio shairport-sync[1176]: free(): double free detected in tcache 2 Dec 20 16:03:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 20 16:03:33 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=6/ABRT Dec 20 16:03:33 volumio systemd[1]: shairport-sync.service: Failed with result 'signal'. Dec 20 16:03:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 20 16:03:33 volumio sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 16:03:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 20 16:03:33 volumio sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:33 volumio sudo[1172]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:33 volumio sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 20 16:03:33 volumio sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 20 16:03:33 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 20 16:03:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 20 16:03:33 volumio mpd[1109]: Dec 20 16:03 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 16:03:33 volumio mpd[1109]: Dec 20 16:03 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 20 16:03:33 volumio mpd[1109]: Dec 20 16:03 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 20 16:03:33 volumio systemd[1]: Started Music Player Daemon. Dec 20 16:03:33 volumio sudo[1079]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:33 volumio firststart.sh[764]: Enabling SSH for first boot Dec 20 16:03:33 volumio volumio[762]: info: Shairport-Sync Started Dec 20 16:03:33 volumio volumio[762]: Error adding Membership: Error: addMembership EINVAL Dec 20 16:03:33 volumio volumio[762]: info: Shairport-Sync Started Dec 20 16:03:33 volumio systemd[1]: Starting OpenBSD Secure Shell server... Dec 20 16:03:33 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Dec 20 16:03:33 volumio volumio[762]: error: MPD error: The expression evaluated to a falsy value: Dec 20 16:03:33 volumio volumio[762]: assert.ok(self.idling) Dec 20 16:03:33 volumio sudo[1177]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 20 16:03:33 volumio sudo[1173]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:33 volumio volumio[762]: error: updateQueue error: null Dec 20 16:03:33 volumio volumio[762]: info: CoreCommandRouter::volumioGetState Dec 20 16:03:33 volumio volumio[762]: info: CorePlayQueue::getTrack 0 Dec 20 16:03:33 volumio volumio[762]: info: Shairport-Sync Started Dec 20 16:03:33 volumio volumio[762]: info: Upmpdcli Daemon Started Dec 20 16:03:33 volumio sshd[1191]: Server listening on 0.0.0.0 port 22. Dec 20 16:03:33 volumio sshd[1191]: Server listening on :: port 22. Dec 20 16:03:33 volumio systemd[1]: Started OpenBSD Secure Shell server. Dec 20 16:03:33 volumio firststart.sh[764]: Disabling firststart service Dec 20 16:03:33 volumio volumio[762]: error: updateQueue error: null Dec 20 16:03:33 volumio firststart.sh[764]: Removed /etc/systemd/system/multi-user.target.wants/firststart.service. Dec 20 16:03:33 volumio systemd[1]: Reloading. Dec 20 16:03:33 volumio wireless.js[1155]: WIRELESS DAEMON: start Dec 20 16:03:33 volumio wireless.js[1155]: WIRELESS: Loaded configuration Dec 20 16:03:33 volumio wireless.js[1155]: Cleaning previous... Dec 20 16:03:34 volumio volumio[1187]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 20 16:03:34 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 20 16:03:35 volumio sudo[1224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 16:03:35 volumio sudo[1224]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:35 volumio sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 16:03:35 volumio sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:35 volumio sudo[1224]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:35 volumio sudo[1226]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:35 volumio volumio[762]: verbose: New Socket.io Connection to 192.168.1.47 from 192.168.1.46 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Total Clients: 3 Dec 20 16:03:35 volumio firststart.sh[764]: Finalizing Dec 20 16:03:35 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Dec 20 16:03:36 volumio volumio[762]: info: CoreCommandRouter::volumioGetState Dec 20 16:03:36 volumio volumio[762]: info: CorePlayQueue::getTrack 0 Dec 20 16:03:36 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 16:03:36 volumio volumio[762]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 16:03:36 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 16:03:36 volumio volumio[762]: info: Listing playlists Dec 20 16:03:36 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 20 16:03:36 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 20 16:03:36 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 20 16:03:36 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 16:03:36 volumio systemd[1]: hostapd.service: Succeeded. Dec 20 16:03:36 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Dec 20 16:03:36 volumio sudo[1235]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 20 16:03:36 volumio sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:36 volumio avahi-daemon[479]: Withdrawing address record for 192.168.211.1 on wlan0. Dec 20 16:03:36 volumio avahi-daemon[479]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 20 16:03:36 volumio avahi-daemon[479]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 20 16:03:36 volumio sudo[1235]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:36 volumio sudo[1237]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 20 16:03:36 volumio sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:36 volumio volumio[762]: info: mDNS: A device disapperared from network Dec 20 16:03:36 volumio volumio[762]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Dec 20 16:03:36 volumio systemd[1]: firststart.service: Succeeded. Dec 20 16:03:36 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 20 16:03:36 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 20 16:03:36 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 20 16:03:36 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Dec 20 16:03:37 volumio sudo[1237]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:37 volumio wireless.js[1155]: Stopped aP Dec 20 16:03:37 volumio sudo[1248]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 20 16:03:37 volumio sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:37 volumio sudo[1248]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:37 volumio sudo[1250]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 20 16:03:37 volumio sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:37 volumio sudo[1250]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:37 volumio avahi-daemon[479]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 20 16:03:37 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 20 16:03:37 volumio avahi-daemon[479]: New relevant interface wlan0.IPv4 for mDNS. Dec 20 16:03:37 volumio avahi-daemon[479]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Dec 20 16:03:37 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Dec 20 16:03:37 volumio hostapd[1256]: Configuration file: /etc/hostapd/hostapd.conf Dec 20 16:03:37 volumio hostapd[1256]: wlan0: Could not connect to kernel driver Dec 20 16:03:37 volumio hostapd[1256]: Using interface wlan0 with hwaddr b8:27:eb:cc:13:2c and ssid "Volumio-72F93" Dec 20 16:03:37 volumio hostapd[1256]: wlan0: interface state UNINITIALIZED->ENABLED Dec 20 16:03:37 volumio hostapd[1256]: wlan0: AP-ENABLED Dec 20 16:03:37 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Dec 20 16:03:37 volumio systemd[1]: wireless.service: Succeeded. Dec 20 16:03:37 volumio systemd[1]: Started Wireless Services. Dec 20 16:03:37 volumio sudo[1147]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:38 volumio volumio[762]: info: mDNS Browse raised the following error Error: getaddrinfo -3007 Dec 20 16:03:38 volumio volumio[762]: error: updateQueue error: null Dec 20 16:03:38 volumio volumio[1187]: .............++++ Dec 20 16:03:38 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 16:03:39 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 16:03:40 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 20 16:03:40 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 20 16:03:42 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Dec 20 16:03:42 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Dec 20 16:03:42 volumio sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 16:03:42 volumio sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:42 volumio sudo[1261]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:47 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Dec 20 16:03:47 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Dec 20 16:03:47 volumio volumio[762]: info: Refreshing Cached IP Addresses Dec 20 16:03:47 volumio sudo[1264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 20 16:03:47 volumio sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:47 volumio sudo[1267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 16:03:47 volumio sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:47 volumio sudo[1267]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:47 volumio sudo[1264]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:47 volumio sudo[1269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 16:03:47 volumio sudo[1269]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:47 volumio sudo[1269]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:51 volumio volumio[762]: error: MyVolumio Plugin failed to start in a timely fashion Dec 20 16:03:51 volumio volumio[762]: info: BOOT COMPLETED Dec 20 16:03:51 volumio volumio[762]: [Metrics] CommandRouter: 37s 275.28ms Dec 20 16:03:51 volumio volumio[762]: info: CoreCommandRouter::volumiosetStartupVolume Dec 20 16:03:51 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:03:51 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 16:03:51 volumio volumio[762]: info: CoreCommandRouter::Close All Modals sent Dec 20 16:03:51 volumio volumio[762]: info: CoreCommandRouter::Close All Modals sent Dec 20 16:03:52 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 20 16:03:52 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 20 16:03:52 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 20 16:03:52 volumio volumio[762]: info: Version has changed, forcing UI Reload Dec 20 16:03:52 volumio volumio[762]: info: CoreCommandRouter::Reload Ui Dec 20 16:03:52 volumio sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 16:03:52 volumio sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:52 volumio sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 16:03:52 volumio sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:52 volumio sudo[1295]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:52 volumio sudo[1297]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:53 volumio volumio[762]: verbose: New Socket.io Connection to 192.168.1.47 from 192.168.1.46 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Total Clients: 3 Dec 20 16:03:53 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 20 16:03:53 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 20 16:03:53 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 20 16:03:53 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 16:03:53 volumio volumio[762]: info: CoreCommandRouter::volumioGetState Dec 20 16:03:53 volumio volumio[762]: info: CorePlayQueue::getTrack 0 Dec 20 16:03:53 volumio volumio[762]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 16:03:53 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 16:03:53 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 20 16:03:53 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 20 16:03:53 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 20 16:03:53 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 16:03:55 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 16:03:56 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 20 16:03:56 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 16:03:56 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 20 16:03:57 volumio sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 16:03:57 volumio sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:57 volumio sudo[1309]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:57 volumio sudo[1312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 16:03:57 volumio sudo[1312]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:57 volumio sudo[1312]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:57 volumio sudo[1314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 20 16:03:57 volumio sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:03:58 volumio sudo[1314]: pam_unix(sudo:session): session closed for user root Dec 20 16:03:58 volumio volumio[762]: info: Upmpdcli Daemon Started Dec 20 16:03:59 volumio volumio[1187]: ..........................................................................++++ Dec 20 16:03:59 volumio volumio[1187]: e is 65537 (0x010001) Dec 20 16:04:00 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 16:04:00 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 16:04:04 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 20 16:04:04 volumio volumio[762]: info: Loading i18n strings for locale en Dec 20 16:04:04 volumio volumio[762]: Updating browse sources language Dec 20 16:04:04 volumio volumio[762]: Cannot find translation for sourceMedia Servers Dec 20 16:04:04 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 16:04:04 volumio volumio[762]: Cannot find translation for sourceMedia Servers Dec 20 16:04:04 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 20 16:04:04 volumio volumio[762]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 20 16:04:04 volumio volumio[762]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 16:04:04 volumio volumio[762]: TypeError: Cannot read property 'name' of null Dec 20 16:04:04 volumio volumio[762]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1502:42) Dec 20 16:04:04 volumio volumio[762]: at Socket.emit (events.js:315:20) Dec 20 16:04:04 volumio volumio[762]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 20 16:04:04 volumio volumio[762]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 20 16:04:04 volumio volumio[762]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 16:04:04 volumio sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 20 16:04:04 volumio sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:04:05 volumio ntpd[620]: Soliciting pool server 202.29.58.130 Dec 20 16:04:05 volumio sudo[1331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2021-12-20 16:03 Dec 20 16:04:05 volumio sudo[1331]: 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="682e0b17433cee9f9da31457f545345105e06e63" VOLUMIO_FE_VERSION="fba46add594d630e1b506bc42f1beddaad53fb64" VOLUMIO_FE3_VERSION="c23fef6dba8e02db978bd39bd8d26872e60ada6e" VOLUMIO_BE_VERSION="f7885966dee9f148ddf4f5050103a7d6734e1a10" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 20 Dec 2021 04:57:50 PM CET" VOLUMIO_VERSION="3.179" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6d5d4c489f0b1e608d9cefb791a9102d"