-- Logs begin at Thu 2024-04-04 22:31:51 UTC, end at Thu 2024-04-04 22:33:51 UTC. -- Apr 04 22:32:00 volumio volumio-remote-updater[548]: Error: No active session Apr 04 22:32:00 volumio volumio-remote-updater[548]: [2024-04-04 22:32:00] [info] asio async_connect error: system:111 (Connection refused) Apr 04 22:32:00 volumio volumio-remote-updater[548]: [2024-04-04 22:32:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 04 22:32:00 volumio volumio-remote-updater[548]: [2024-04-04 22:32:00] [error] handle_connect error: Underlying Transport Error Apr 04 22:32:00 volumio volumio[547]: Could not open config: /tmp/upmpdcli.conf Apr 04 22:32:00 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 04 22:32:00 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 04 22:32:00 volumio systemd[1]: systemd-rfkill.service: Succeeded. Apr 04 22:32:00 volumio ntpd[701]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 04 22:32:01 volumio sudo[544]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:01 volumio systemd[1]: nanosoundcd_progressweb.service: Main process exited, code=exited, status=1/FAILURE Apr 04 22:32:01 volumio systemd[1]: nanosoundcd_progressweb.service: Failed with result 'exit-code'. Apr 04 22:32:01 volumio nmbd[679]: [2024/04/04 22:32:01.269805, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 04 22:32:01 volumio nmbd[679]: started asyncdns process 722 Apr 04 22:32:01 volumio nmbd[679]: [2024/04/04 22:32:01.271553, 0] ../lib/util/become_daemon.c:149(daemon_status) Apr 04 22:32:01 volumio nmbd[679]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Apr 04 22:32:01 volumio nmbd[679]: [2024/04/04 22:32:01.271686, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Apr 04 22:32:01 volumio nmbd[679]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Apr 04 22:32:01 volumio sudo[557]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:01 volumio systemd[1]: nanosoundcd_web.service: Main process exited, code=exited, status=1/FAILURE Apr 04 22:32:01 volumio systemd[1]: nanosoundcd_web.service: Failed with result 'exit-code'. Apr 04 22:32:01 volumio sh[445]: eth0: carrier acquired Apr 04 22:32:01 volumio dhcpcd[496]: eth0: carrier acquired Apr 04 22:32:01 volumio sh[445]: DUID 00:01:00:01:29:d8:61:9e:dc:a6:32:22:db:74 Apr 04 22:32:01 volumio sh[445]: eth0: IAID 32:75:1b:7f Apr 04 22:32:01 volumio dhcpcd[496]: DUID 00:01:00:01:29:d8:61:9e:dc:a6:32:22:db:74 Apr 04 22:32:01 volumio dhcpcd[496]: eth0: IAID 32:75:1b:7f Apr 04 22:32:01 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Apr 04 22:32:01 volumio dhcpcd[496]: eth0: adding address fe80::447d:a1a5:c470:d23f Apr 04 22:32:01 volumio sh[445]: eth0: adding address fe80::447d:a1a5:c470:d23f Apr 04 22:32:01 volumio sh[445]: ipv6_addaddr1: Permission denied Apr 04 22:32:01 volumio dhcpcd[496]: ipv6_addaddr1: Permission denied Apr 04 22:32:01 volumio ifplugd(eth0)[694]: Link beat detected. Apr 04 22:32:01 volumio ifplugd(eth0)[694]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 04 22:32:01 volumio ifplugd(eth0)[694]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Apr 04 22:32:01 volumio ntpd[701]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 04 22:32:02 volumio wireless.js[540]: WIRELESS DAEMON: start Apr 04 22:32:02 volumio wireless.js[540]: WIRELESS: Loaded configuration Apr 04 22:32:02 volumio wireless.js[540]: Cleaning previous... Apr 04 22:32:02 volumio sudo[733]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 04 22:32:02 volumio sudo[733]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:02 volumio sudo[733]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:02 volumio sudo[735]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 04 22:32:02 volumio sudo[735]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:02 volumio sh[445]: eth0: soliciting a DHCP lease Apr 04 22:32:02 volumio dhcpcd[496]: eth0: soliciting a DHCP lease Apr 04 22:32:02 volumio sh[445]: eth0: soliciting an IPv6 router Apr 04 22:32:02 volumio dhcpcd[496]: eth0: soliciting an IPv6 router Apr 04 22:32:02 volumio mpd[697]: Apr 04 22:32 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 04 22:32:02 volumio mpd[697]: Apr 04 22:32 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 04 22:32:02 volumio mpd[697]: Apr 04 22:32 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 04 22:32:02 volumio sudo[735]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:02 volumio systemd[1]: Started Music Player Daemon. Apr 04 22:32:02 volumio wireless.js[540]: Stopped aP Apr 04 22:32:02 volumio wireless.js[540]: Start wireless flow Apr 04 22:32:02 volumio wireless.js[540]: Stopped hotspot (if there).. Apr 04 22:32:02 volumio sudo[744]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 04 22:32:02 volumio sudo[744]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:02 volumio sudo[744]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:02 volumio sudo[746]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 04 22:32:02 volumio sudo[746]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:02 volumio sudo[746]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:02 volumio wireless.js[540]: DHCP IP Apr 04 22:32:02 volumio wireless.js[540]: Start ap Apr 04 22:32:02 volumio wpa_supplicant[748]: Successfully initialized wpa_supplicant Apr 04 22:32:02 volumio sudo[749]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Apr 04 22:32:02 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 04 22:32:02 volumio sudo[749]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:02 volumio dhcpcd[750]: dev: loaded udev Apr 04 22:32:02 volumio ntpd[701]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 04 22:32:02 volumio dhcpcd-run-hooks[759]: wlan0: starting wpa_supplicant Apr 04 22:32:03 volumio dhcpcd-run-hooks[764]: wlan0: failed to start wpa_supplicant Apr 04 22:32:03 volumio dhcpcd-run-hooks[765]: 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 Apr 04 22:32:03 volumio dhcpcd[750]: wlan0: connected to Access Point `' Apr 04 22:32:03 volumio dhcpcd[750]: DUID 00:01:00:01:29:d8:61:9e:dc:a6:32:22:db:74 Apr 04 22:32:03 volumio dhcpcd[750]: eth0: IAID 32:75:1b:7f Apr 04 22:32:03 volumio dhcpcd[750]: eth0: adding address fe80::447d:a1a5:c470:d23f Apr 04 22:32:03 volumio dhcpcd[750]: ipv6_addaddr1: Permission denied Apr 04 22:32:03 volumio dhcpcd[750]: wlan0: waiting for carrier Apr 04 22:32:03 volumio dhcpcd[750]: eth0: soliciting an IPv6 router Apr 04 22:32:03 volumio dhcpcd[750]: eth0: soliciting a DHCP lease Apr 04 22:32:03 volumio sh[445]: eth0: offered 192.168.2.141 from 192.168.2.1 Apr 04 22:32:03 volumio dhcpcd[496]: eth0: offered 192.168.2.141 from 192.168.2.1 Apr 04 22:32:03 volumio sh[445]: eth0: probing address 192.168.2.141/24 Apr 04 22:32:03 volumio dhcpcd[496]: eth0: probing address 192.168.2.141/24 Apr 04 22:32:03 volumio wireless.js[540]: trying... Apr 04 22:32:03 volumio sudo[776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 22:32:03 volumio sudo[776]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:03 volumio sudo[776]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:03 volumio ntpd[701]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 04 22:32:04 volumio kernel: Bluetooth: Core ver 2.22 Apr 04 22:32:04 volumio kernel: NET: Registered protocol family 31 Apr 04 22:32:04 volumio kernel: Bluetooth: HCI device and connection manager initialized Apr 04 22:32:04 volumio kernel: Bluetooth: HCI socket layer initialized Apr 04 22:32:04 volumio kernel: Bluetooth: L2CAP socket layer initialized Apr 04 22:32:04 volumio kernel: Bluetooth: SCO socket layer initialized Apr 04 22:32:04 volumio kernel: Bluetooth: HCI UART driver ver 2.3 Apr 04 22:32:04 volumio kernel: Bluetooth: HCI UART protocol H4 registered Apr 04 22:32:04 volumio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Apr 04 22:32:04 volumio kernel: Bluetooth: HCI UART protocol Broadcom registered Apr 04 22:32:04 volumio btuart[523]: bcm43xx_init Apr 04 22:32:04 volumio btuart[523]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Apr 04 22:32:04 volumio btuart[523]: Set Controller UART speed to 3000000 bit/s Apr 04 22:32:04 volumio btuart[523]: Device setup complete Apr 04 22:32:04 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Apr 04 22:32:04 volumio systemd[1]: Started Configure Bluetooth Modems connected by UART. Apr 04 22:32:04 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Apr 04 22:32:04 volumio systemd[1]: Created slice system-bthelper.slice. Apr 04 22:32:04 volumio systemd[1]: Starting Raspberry Pi bluetooth helper... Apr 04 22:32:04 volumio bthelper[787]: Raspberry Pi BDADDR already set Apr 04 22:32:04 volumio systemd[1]: Started Raspberry Pi bluetooth helper. Apr 04 22:32:04 volumio systemd[1]: Starting Bluetooth service... Apr 04 22:32:04 volumio bluetoothd[797]: Bluetooth daemon 5.50 Apr 04 22:32:04 volumio bluetoothd[797]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Apr 04 22:32:04 volumio systemd[1]: Started Bluetooth service. Apr 04 22:32:04 volumio systemd[1]: Reached target Bluetooth. Apr 04 22:32:04 volumio bluetoothd[797]: Starting SDP server Apr 04 22:32:04 volumio bluetoothd[797]: Excluding (cli) sap Apr 04 22:32:04 volumio bluetoothd[797]: Bluetooth management interface 1.18 initialized Apr 04 22:32:04 volumio dbus-daemon[519]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=797 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Apr 04 22:32:04 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 04 22:32:04 volumio kernel: Bluetooth: BNEP filters: protocol multicast Apr 04 22:32:04 volumio kernel: Bluetooth: BNEP socket layer initialized Apr 04 22:32:04 volumio systemd[1]: Starting Hostname Service... Apr 04 22:32:04 volumio dbus-daemon[519]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 04 22:32:04 volumio systemd[1]: Started Hostname Service. Apr 04 22:32:04 volumio bluetoothd[797]: Failed to set privacy: Rejected (0x0b) Apr 04 22:32:04 volumio wireless.js[540]: trying... Apr 04 22:32:04 volumio sudo[801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 22:32:04 volumio sudo[801]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:04 volumio sudo[801]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:05 volumio volumio-remote-updater[548]: [2024-04-04 22:32:05] [info] asio async_connect error: system:111 (Connection refused) Apr 04 22:32:05 volumio volumio-remote-updater[548]: [2024-04-04 22:32:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 04 22:32:05 volumio volumio-remote-updater[548]: [2024-04-04 22:32:05] [error] handle_connect error: Underlying Transport Error Apr 04 22:32:05 volumio wpa_supplicant[763]: wlan0: Trying to associate with SSID 'EasyBox-2D2404' Apr 04 22:32:05 volumio wireless.js[540]: trying... Apr 04 22:32:05 volumio sudo[804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 22:32:05 volumio sudo[804]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:05 volumio sudo[804]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:06 volumio wireless.js[540]: trying... Apr 04 22:32:06 volumio sudo[807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 22:32:06 volumio sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:06 volumio sudo[807]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:07 volumio wireless.js[540]: trying... Apr 04 22:32:07 volumio sudo[810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 22:32:07 volumio sudo[810]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:07 volumio sudo[810]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: carrier acquired Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: connected to Access Point `EasyBox-2D2404' Apr 04 22:32:08 volumio wpa_supplicant[763]: wlan0: Associated with 3c:98:72:d6:d4:ae Apr 04 22:32:08 volumio wpa_supplicant[763]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:98:72:d6:d4:ae completed [id=0 id_str=] Apr 04 22:32:08 volumio wpa_supplicant[763]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 04 22:32:08 volumio wpa_supplicant[763]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: IAID 32:75:1b:80 Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: adding address fe80::5e1a:dc86:ba4e:bdd1 Apr 04 22:32:08 volumio dhcpcd[750]: ipv6_addaddr1: Permission denied Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: carrier lost Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: carrier acquired Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: IAID 32:75:1b:80 Apr 04 22:32:08 volumio dhcpcd[750]: eth0: probing for an IPv4LL address Apr 04 22:32:08 volumio sh[445]: eth0: leased 192.168.2.141 for 31536000 seconds Apr 04 22:32:08 volumio sh[445]: eth0: adding route to 192.168.2.0/24 Apr 04 22:32:08 volumio sh[445]: eth0: adding default route via 192.168.2.1 Apr 04 22:32:08 volumio dhcpcd[496]: eth0: leased 192.168.2.141 for 31536000 seconds Apr 04 22:32:08 volumio avahi-daemon[518]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.141. Apr 04 22:32:08 volumio avahi-daemon[518]: New relevant interface eth0.IPv4 for mDNS. Apr 04 22:32:08 volumio dhcpcd[496]: eth0: adding route to 192.168.2.0/24 Apr 04 22:32:08 volumio avahi-daemon[518]: Registering new address record for 192.168.2.141 on eth0.IPv4. Apr 04 22:32:08 volumio dhcpcd[496]: eth0: adding default route via 192.168.2.1 Apr 04 22:32:08 volumio sh[445]: forked to background, child pid 845 Apr 04 22:32:08 volumio dhcpcd[496]: forked to background, child pid 845 Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: rebinding lease of 192.168.2.127 Apr 04 22:32:08 volumio wireless.js[540]: trying... Apr 04 22:32:08 volumio sudo[847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 22:32:08 volumio sudo[847]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:08 volumio sudo[847]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: soliciting an IPv6 router Apr 04 22:32:08 volumio ifplugd(eth0)[694]: client: ifup: interface eth0 already configured Apr 04 22:32:08 volumio sh[445]: eth0=eth0 Apr 04 22:32:09 volumio ifplugd(eth0)[694]: Program executed successfully. Apr 04 22:32:09 volumio systemd[1]: systemd-rfkill.service: Succeeded. Apr 04 22:32:09 volumio bthelper[787]: Changing power off succeeded Apr 04 22:32:09 volumio dhcpcd[750]: wlan0: probing address 192.168.2.127/24 Apr 04 22:32:09 volumio dhcpcd[750]: eth0: offered 192.168.2.141 from 192.168.2.1 Apr 04 22:32:09 volumio bthelper[787]: [CHG] Controller DC:A6:32:75:1B:81 Class: 0x0000041c Apr 04 22:32:09 volumio bthelper[787]: Changing power on succeeded Apr 04 22:32:09 volumio dhcpcd[750]: eth0: leased 192.168.2.141 for 31536000 seconds Apr 04 22:32:09 volumio dhcpcd[750]: eth0: adding route to 192.168.2.0/24 Apr 04 22:32:09 volumio dhcpcd[750]: eth0: adding default route via 192.168.2.1 Apr 04 22:32:09 volumio wireless.js[540]: trying... Apr 04 22:32:09 volumio dhcpcd[750]: forked to background, child pid 904 Apr 04 22:32:09 volumio sudo[903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 22:32:09 volumio sudo[903]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:09 volumio sudo[903]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:09 volumio sudo[749]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:10 volumio wireless.js[540]: trying... Apr 04 22:32:10 volumio sudo[907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 22:32:10 volumio sudo[907]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:10 volumio sudo[907]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:10 volumio ntpd[701]: Listen normally on 3 eth0 192.168.2.141:123 Apr 04 22:32:10 volumio ntpd[701]: new interface(s) found: waking up resolver Apr 04 22:32:11 volumio systemd[1]: Started Samba NMB Daemon. Apr 04 22:32:11 volumio nmbd[679]: [2024/04/04 22:32:11.280588, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 04 22:32:11 volumio nmbd[679]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 04 22:32:11 volumio systemd[1]: Starting Samba Winbind Daemon... Apr 04 22:32:11 volumio nmbd[679]: [2024/04/04 22:32:11.296782, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 04 22:32:11 volumio nmbd[679]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.141 for name WORKGROUP<1d>. Apr 04 22:32:11 volumio nmbd[679]: This response was from IP 192.168.2.100, reporting an IP address of 192.168.2.100. Apr 04 22:32:11 volumio winbindd[909]: [2024/04/04 22:32:11.466730, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 04 22:32:11 volumio winbindd[909]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 04 22:32:11 volumio winbindd[909]: [2024/04/04 22:32:11.484268, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 04 22:32:11 volumio winbindd[909]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 04 22:32:11 volumio systemd[1]: Started Samba Winbind Daemon. Apr 04 22:32:11 volumio systemd[1]: Starting Samba SMB Daemon... Apr 04 22:32:11 volumio winbindd[909]: [2024/04/04 22:32:11.795844, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Apr 04 22:32:11 volumio winbindd[909]: res_names->count = 3, expected 4 Apr 04 22:32:11 volumio wireless.js[540]: trying... Apr 04 22:32:11 volumio smbd[914]: [2024/04/04 22:32:11.833766, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 04 22:32:11 volumio systemd[1]: Started Samba SMB Daemon. Apr 04 22:32:11 volumio smbd[914]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 04 22:32:11 volumio sudo[921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 22:32:11 volumio sudo[921]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:11 volumio sudo[921]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:12 volumio volumio-remote-updater[548]: [2024-04-04 22:32:12] [info] asio async_connect error: system:111 (Connection refused) Apr 04 22:32:12 volumio volumio-remote-updater[548]: [2024-04-04 22:32:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 04 22:32:12 volumio volumio-remote-updater[548]: [2024-04-04 22:32:12] [error] handle_connect error: Underlying Transport Error Apr 04 22:32:12 volumio wireless.js[540]: trying... Apr 04 22:32:12 volumio sudo[926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 22:32:12 volumio sudo[926]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:12 volumio sudo[926]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:13 volumio kernel: cam-dummy-reg: disabling Apr 04 22:32:13 volumio kernel: cam1-reg: disabling Apr 04 22:32:13 volumio wireless.js[540]: trying... Apr 04 22:32:13 volumio sudo[929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 22:32:13 volumio sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:13 volumio sudo[929]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:14 volumio dhcpcd[904]: eth0: using IPv4LL address 169.254.197.80 Apr 04 22:32:14 volumio dhcpcd[904]: eth0: adding route to 169.254.0.0/16 Apr 04 22:32:14 volumio avahi-daemon[518]: Registering new address record for 169.254.197.80 on eth0.IPv4. Apr 04 22:32:14 volumio dhcpcd[904]: wlan0: leased 192.168.2.127 for 31536000 seconds Apr 04 22:32:14 volumio avahi-daemon[518]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.127. Apr 04 22:32:14 volumio avahi-daemon[518]: New relevant interface wlan0.IPv4 for mDNS. Apr 04 22:32:14 volumio dhcpcd[904]: wlan0: adding route to 192.168.2.0/24 Apr 04 22:32:14 volumio avahi-daemon[518]: Registering new address record for 192.168.2.127 on wlan0.IPv4. Apr 04 22:32:14 volumio dhcpcd[904]: wlan0: adding default route via 192.168.2.1 Apr 04 22:32:14 volumio wireless.js[540]: trying... Apr 04 22:32:14 volumio sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 04 22:32:14 volumio sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:14 volumio sudo[972]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:14 volumio wireless.js[540]: Connected to: ----EasyBox-2D2404 Apr 04 22:32:14 volumio wireless.js[540]: ---- Apr 04 22:32:14 volumio sudo[975]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 04 22:32:14 volumio sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:14 volumio sudo[975]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:14 volumio wireless.js[540]: ... joined AP, wlan0 IPv4 is 192.168.2.127, ipV6 is undefined Apr 04 22:32:14 volumio wireless.js[540]: It's done! AP Apr 04 22:32:14 volumio systemd[1]: Started Wireless Services. Apr 04 22:32:14 volumio systemd[1]: Started Volumio Backend Module. Apr 04 22:32:14 volumio systemd[1]: Started Volumio Streaming Daemon. Apr 04 22:32:14 volumio systemd[1]: Reached target Multi-User System. Apr 04 22:32:14 volumio systemd[1]: Reached target Graphical Interface. Apr 04 22:32:14 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 04 22:32:14 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 04 22:32:14 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 04 22:32:14 volumio systemd[1]: Startup finished in 9.356s (kernel) + 25.541s (userspace) = 34.897s. Apr 04 22:32:15 volumio volumio-streaming-daemon[980]: ############################ Apr 04 22:32:15 volumio volumio-streaming-daemon[980]: # Volumio Streaming Daemon # Apr 04 22:32:15 volumio volumio-streaming-daemon[980]: # Running on port 7777 # Apr 04 22:32:15 volumio volumio-streaming-daemon[980]: ############################ Apr 04 22:32:15 volumio volumio-streaming-daemon[980]: Environment: production Apr 04 22:32:15 volumio ntpd[701]: Listen normally on 4 eth0 169.254.197.80:123 Apr 04 22:32:15 volumio ntpd[701]: Listen normally on 5 wlan0 192.168.2.127:123 Apr 04 22:32:15 volumio ntpd[701]: new interface(s) found: waking up resolver Apr 04 22:32:16 volumio volumio[979]: info: ------------------------------------------- Apr 04 22:32:16 volumio volumio[979]: info: ----- Volumio3 ---- Apr 04 22:32:16 volumio volumio[979]: info: ------------------------------------------- Apr 04 22:32:16 volumio volumio[979]: info: ----- System startup ---- Apr 04 22:32:16 volumio volumio[979]: info: ------------------------------------------- Apr 04 22:32:17 volumio volumio[979]: info: MYVOLUMIO Environment detected Apr 04 22:32:17 volumio volumio[979]: info: Plugin folders cleanup Apr 04 22:32:17 volumio volumio[979]: info: Scanning into folder /volumio/app/plugins/ Apr 04 22:32:17 volumio volumio[979]: info: Scanning category audio_interface Apr 04 22:32:17 volumio volumio[979]: info: Scanning category miscellanea Apr 04 22:32:17 volumio volumio[979]: info: Scanning category music_service Apr 04 22:32:17 volumio volumio[979]: info: Scanning category plugins.json Apr 04 22:32:17 volumio volumio[979]: info: Scanning category system_controller Apr 04 22:32:17 volumio volumio[979]: info: Scanning category user_interface Apr 04 22:32:17 volumio volumio[979]: info: Scanning into folder /data/plugins/ Apr 04 22:32:17 volumio volumio[979]: info: Scanning category music_service Apr 04 22:32:17 volumio volumio[979]: info: Plugin folders cleanup completed Apr 04 22:32:17 volumio volumio[979]: info: ------------------------------------------- Apr 04 22:32:17 volumio volumio[979]: info: ----- Core plugins startup ---- Apr 04 22:32:17 volumio volumio[979]: info: ------------------------------------------- Apr 04 22:32:17 volumio volumio[979]: info: Loading plugins from folder /volumio/app/plugins/ Apr 04 22:32:17 volumio volumio[979]: info: Adding plugin upnp to MyMusic Plugins Apr 04 22:32:17 volumio volumio[979]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 04 22:32:17 volumio volumio[979]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 04 22:32:17 volumio volumio[979]: info: Loading plugins from folder /data/plugins/ Apr 04 22:32:17 volumio volumio[979]: info: Loading plugin "system"... Apr 04 22:32:17 volumio volumio[979]: info: Loading plugin "appearance"... Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "network"... Apr 04 22:32:18 volumio volumio[979]: info: Refreshing Cached IP Addresses Apr 04 22:32:18 volumio sudo[1004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 04 22:32:18 volumio sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:18 volumio sudo[1004]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:18 volumio sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 04 22:32:18 volumio sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "services"... Apr 04 22:32:18 volumio sudo[1006]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "alsa_controller"... Apr 04 22:32:18 volumio sudo[1009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 04 22:32:18 volumio sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "wizard"... Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "volumio_command_line_client"... Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "upnp"... Apr 04 22:32:18 volumio volumio[979]: info: [1712269938608] Starting Upmpd Daemon Apr 04 22:32:18 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "my_music"... Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "mpd"... Apr 04 22:32:18 volumio volumio[979]: info: Creating MPD Configuration file Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "upnp_browser"... Apr 04 22:32:18 volumio sudo[1017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 04 22:32:18 volumio sudo[1017]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:18 volumio sudo[1017]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:18 volumio sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 04 22:32:18 volumio sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:18 volumio systemd[1]: Stopping Music Player Daemon... Apr 04 22:32:19 volumio systemd[1]: mpd.service: Succeeded. Apr 04 22:32:19 volumio systemd[1]: Stopped Music Player Daemon. Apr 04 22:32:19 volumio systemd[1]: Starting Music Player Daemon... Apr 04 22:32:19 volumio volumio[979]: info: Loading plugin "networkfs"... Apr 04 22:32:19 volumio volumio[979]: info: Starting Udev Watcher for removable devices Apr 04 22:32:19 volumio mpd[1024]: Apr 04 22:32 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 04 22:32:19 volumio mpd[1024]: Apr 04 22:32 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 04 22:32:19 volumio mpd[1024]: Apr 04 22:32 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 04 22:32:19 volumio systemd[1]: Started Music Player Daemon. Apr 04 22:32:19 volumio sudo[1019]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:19 volumio volumio[979]: info: Ignoring mount for partition: boot Apr 04 22:32:19 volumio volumio[979]: info: Ignoring mount for partition: volumio Apr 04 22:32:19 volumio volumio[979]: info: Ignoring mount for partition: volumio_data Apr 04 22:32:19 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 04 22:32:19 volumio volumio[979]: info: Loading plugin "alarm-clock"... Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "airplay_emulation"... Apr 04 22:32:20 volumio volumio[979]: info: Starting Shairport Sync Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "last_100"... Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "webradio"... Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "i2s_dacs"... Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "volumiodiscovery"... Apr 04 22:32:20 volumio volumio[979]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 04 22:32:20 volumio node[979]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 04 22:32:20 volumio volumio[979]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 04 22:32:20 volumio node[979]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 04 22:32:20 volumio volumio[979]: *** WARNING *** For more information see Apr 04 22:32:20 volumio node[979]: *** WARNING *** For more information see Apr 04 22:32:20 volumio volumio[979]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 04 22:32:20 volumio node[979]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 04 22:32:20 volumio volumio[979]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 04 22:32:20 volumio node[979]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 04 22:32:20 volumio volumio[979]: *** WARNING *** For more information see Apr 04 22:32:20 volumio node[979]: *** WARNING *** For more information see Apr 04 22:32:20 volumio volumio[979]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 04 22:32:20 volumio volumio[979]: info: Discovery: Started advertising with name: Volumio Apr 04 22:32:20 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "outputs"... Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "albumart"... Apr 04 22:32:20 volumio volumio[979]: info: Plugin example_plugin is not enabled Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "inputs"... Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "updater_comm"... Apr 04 22:32:20 volumio volumio[979]: info: Plugin mpdemulation is not enabled Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "rest_api"... Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "websocket"... Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "nanosound_cd"... Apr 04 22:32:20 volumio volumio[979]: Forking 3 albumart workers Apr 04 22:32:21 volumio volumio[979]: Starting albumart workers Apr 04 22:32:21 volumio volumio[979]: Starting albumart workers Apr 04 22:32:21 volumio volumio[979]: Starting albumart workers Apr 04 22:32:21 volumio volumio[979]: info: Applying required configuration parameters for plugin nanosound_cd Apr 04 22:32:21 volumio volumio[979]: info: Loading i18n strings for locale de Apr 04 22:32:21 volumio volumio[979]: Updating browse sources language Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::initPlayerControls Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 22:32:21 volumio volumio[979]: Express server listening on port 3000 Apr 04 22:32:21 volumio volumio[979]: [Metrics] WebUI: 5s 403.97ms Apr 04 22:32:21 volumio volumio[979]: info: CoreStateMachine::resetVolumioState Apr 04 22:32:21 volumio volumio[979]: info: CoreStateMachine::getcurrentVolume Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::volumioRetrievevolume Apr 04 22:32:21 volumio volumio[979]: info: MPD Permissions set Apr 04 22:32:21 volumio volumio[979]: verbose: New Socket.io Connection to 192.168.2.141 from 192.168.2.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 1 Apr 04 22:32:21 volumio volumio[979]: verbose: New Socket.io Connection to 192.168.2.141 from 192.168.2.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 2 Apr 04 22:32:21 volumio volumio[979]: info: MPD running with PID1024 Apr 04 22:32:21 volumio volumio[979]: ,establishing connection Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: Reloading queue from file Apr 04 22:32:21 volumio volumio[979]: error: updateQueue error: null Apr 04 22:32:21 volumio volumio[979]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 04 22:32:21 volumio volumio[979]: info: VolumeController:: Volume=30 Mute =false Apr 04 22:32:21 volumio volumio[979]: info: CoreStateMachine::pushState Apr 04 22:32:21 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::volumioPushState Apr 04 22:32:21 volumio volumio[979]: info: CoreStateMachine::updateTrackBlock Apr 04 22:32:21 volumio volumio[979]: info: CorePlayQueue::getTrackBlock Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::volumioRetrievevolume Apr 04 22:32:21 volumio volumio[979]: info: CoreStateMachine::setRepeat null single undefined Apr 04 22:32:21 volumio volumio[979]: info: CoreStateMachine::pushState Apr 04 22:32:21 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::volumioPushState Apr 04 22:32:21 volumio volumio[979]: info: CoreStateMachine::setRandom null Apr 04 22:32:21 volumio volumio[979]: info: CoreStateMachine::pushState Apr 04 22:32:21 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::volumioPushState Apr 04 22:32:21 volumio volumio[979]: error: updateQueue error: null Apr 04 22:32:21 volumio volumio[979]: info: Setting Device type: Raspberry PI Apr 04 22:32:21 volumio volumio[979]: info: Completed loading Core Plugins Apr 04 22:32:21 volumio volumio[979]: info: Preparing to generate the ALSA configuration file Apr 04 22:32:21 volumio volumio[979]: info: Discovery: adding b46a5f31-d384-4637-bc1f-d3f244fd3af9 Apr 04 22:32:21 volumio volumio[979]: info: mDNS: Found device Volumio Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 04 22:32:21 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:21 volumio volumio[979]: info: Discovery: this is already registered, b46a5f31-d384-4637-bc1f-d3f244fd3af9 Apr 04 22:32:21 volumio volumio[979]: info: mDNS: Found device Volumio Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 04 22:32:21 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:21 volumio volumio[979]: info: Asound.conf file unchanged, so no further update is needed Apr 04 22:32:21 volumio volumio[979]: info: Output device has changed, restarting MPD Apr 04 22:32:21 volumio sudo[1102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 04 22:32:21 volumio sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:21 volumio volumio[979]: info: Output device has changed, restarting Shairport Sync Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 22:32:21 volumio sudo[1102]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:21 volumio sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 04 22:32:21 volumio sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:21 volumio systemd[1]: Stopping Music Player Daemon... Apr 04 22:32:21 volumio volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 04 22:32:21 volumio volumio[979]: info: ___________ START PLUGINS ___________ Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 04 22:32:21 volumio volumio[979]: info: [1712269941955] CoreMusicLibrary::Adding element Media Servers Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 22:32:21 volumio volumio[979]: Cannot find translation for sourceMedia Servers Apr 04 22:32:21 volumio systemd[1]: mpd.service: Succeeded. Apr 04 22:32:21 volumio systemd[1]: Stopped Music Player Daemon. Apr 04 22:32:21 volumio systemd[1]: Starting Music Player Daemon... Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 22:32:22 volumio volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 22:32:22 volumio volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 04 22:32:22 volumio volumio[979]: info: [1712269942052] CoreMusicLibrary::Adding element Last_100 Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 22:32:22 volumio volumio[979]: Cannot find translation for sourceMedia Servers Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 04 22:32:22 volumio volumio[979]: info: [1712269942054] CoreMusicLibrary::Adding element Webradio Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 22:32:22 volumio volumio[979]: Cannot find translation for sourceMedia Servers Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 04 22:32:22 volumio volumio[979]: info: [1712269942062] CoreMusicLibrary::Adding element NanoSound CD Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 22:32:22 volumio volumio[979]: Cannot find translation for sourceMedia Servers Apr 04 22:32:22 volumio volumio[979]: Cannot find translation for sourceNanoSound CD Apr 04 22:32:22 volumio sudo[1009]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:22 volumio volumio[979]: info: Volumio Calling Home Apr 04 22:32:22 volumio sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start nanosoundcd_progressweb Apr 04 22:32:22 volumio sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:22 volumio systemd[1]: Started NanoSound CD Progress Web Service. Apr 04 22:32:22 volumio sudo[1119]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:22 volumio sudo[1125]: root : TTY=unknown ; PWD=/home/volumio/nanomesher_nanosoundcd ; USER=root ; COMMAND=/usr/bin/python -c import ripprogress_web; ripprogress_web.main() Apr 04 22:32:22 volumio sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:22 volumio volumio[979]: info: MPD Permissions set Apr 04 22:32:22 volumio volumio[979]: info: VolumeController:: Volume=30 Mute =false Apr 04 22:32:22 volumio volumio[979]: info: CoreStateMachine::pushState Apr 04 22:32:22 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::volumioPushState Apr 04 22:32:22 volumio sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start nanosoundcd_web Apr 04 22:32:22 volumio sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:22 volumio volumio[979]: info: Volumio called home Apr 04 22:32:22 volumio volumio[979]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:22 volumio systemd[1]: Started NanoSound CD Web Service. Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 22:32:22 volumio sudo[1129]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:22 volumio sudo[1136]: root : TTY=unknown ; PWD=/home/volumio/nanomesher_nanosoundcd ; USER=root ; COMMAND=/usr/bin/python -c import nanosoundcd_web; nanosoundcd_web.main() Apr 04 22:32:22 volumio sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:22 volumio volumio[979]: info: NanoSound CD daemon started Apr 04 22:32:22 volumio volumio[979]: info: Completed starting Core Plugins Apr 04 22:32:22 volumio volumio[979]: info: ------------------------------------------- Apr 04 22:32:22 volumio volumio[979]: info: ----- MyVolumio plugins startup ---- Apr 04 22:32:22 volumio volumio[979]: info: ------------------------------------------- Apr 04 22:32:22 volumio volumio[979]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 04 22:32:22 volumio volumio[979]: info: Starting Shairport Sync Apr 04 22:32:22 volumio volumio[979]: info: Starting Shairport Sync Apr 04 22:32:22 volumio sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 04 22:32:22 volumio sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:22 volumio volumio[979]: info: Starting Shairport Sync Apr 04 22:32:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 04 22:32:22 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 04 22:32:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 04 22:32:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 04 22:32:22 volumio sudo[1140]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:22 volumio sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 04 22:32:22 volumio sudo[1145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 04 22:32:22 volumio sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:22 volumio sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:22 volumio volumio-remote-updater[548]: [2024-04-04 22:32:22] [connect] Successful connection Apr 04 22:32:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 04 22:32:22 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 04 22:32:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 04 22:32:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 04 22:32:22 volumio sudo[1145]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 04 22:32:22 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 04 22:32:22 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 04 22:32:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 04 22:32:22 volumio volumio[979]: info: Shairport-Sync Started Apr 04 22:32:22 volumio volumio[979]: Error adding Membership: Error: addMembership EINVAL Apr 04 22:32:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 04 22:32:22 volumio sudo[1142]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:22 volumio volumio[979]: info: Shairport-Sync Started Apr 04 22:32:22 volumio volumio-remote-updater[548]: [2024-04-04 22:32:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712269942 101 Apr 04 22:32:22 volumio volumio[979]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4 Apr 04 22:32:22 volumio volumio[979]: info: Shairport-Sync Started Apr 04 22:32:22 volumio sudo[1125]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 04 22:32:22 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:22 volumio systemd[1]: nanosoundcd_progressweb.service: Main process exited, code=exited, status=1/FAILURE Apr 04 22:32:22 volumio systemd[1]: nanosoundcd_progressweb.service: Failed with result 'exit-code'. Apr 04 22:32:22 volumio mpd[1120]: Apr 04 22:32 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 04 22:32:22 volumio mpd[1120]: Apr 04 22:32 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 04 22:32:22 volumio mpd[1120]: Apr 04 22:32 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 04 22:32:22 volumio systemd[1]: Started Music Player Daemon. Apr 04 22:32:22 volumio sudo[1105]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:22 volumio volumio[979]: error: updateQueue error: null Apr 04 22:32:22 volumio sudo[1136]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:22 volumio systemd[1]: nanosoundcd_web.service: Main process exited, code=exited, status=1/FAILURE Apr 04 22:32:22 volumio systemd[1]: nanosoundcd_web.service: Failed with result 'exit-code'. Apr 04 22:32:23 volumio volumio[979]: verbose: New Socket.io Connection to volumio.local from 192.168.2.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 5 Apr 04 22:32:23 volumio volumio[979]: verbose: New Socket.io Connection to volumio.local from 192.168.2.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 6 Apr 04 22:32:24 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 04 22:32:24 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:24 volumio volumio[979]: info: CoreCommandRouter::volumioGetQueue Apr 04 22:32:24 volumio volumio[979]: info: CoreStateMachine::getQueue Apr 04 22:32:24 volumio volumio[979]: info: CorePlayQueue::getQueue Apr 04 22:32:24 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Apr 04 22:32:25 volumio volumio[979]: verbose: New Socket.io Connection to 192.168.2.141 from 192.168.2.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 7 Apr 04 22:32:25 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 04 22:32:25 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 04 22:32:25 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:25 volumio volumio[979]: info: CoreCommandRouter::volumioGetVisibleSources Apr 04 22:32:25 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 22:32:25 volumio volumio[979]: info: CoreCommandRouter::volumioGetQueue Apr 04 22:32:25 volumio volumio[979]: info: CoreStateMachine::getQueue Apr 04 22:32:25 volumio volumio[979]: info: CorePlayQueue::getQueue Apr 04 22:32:25 volumio volumio[979]: info: Listing playlists Apr 04 22:32:25 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 04 22:32:28 volumio sudo[1159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 04 22:32:28 volumio sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:28 volumio sudo[1159]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:28 volumio sudo[1161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 04 22:32:28 volumio sudo[1161]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:28 volumio sudo[1161]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:28 volumio sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 04 22:32:28 volumio sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:28 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 04 22:32:28 volumio sudo[1165]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:28 volumio volumio[979]: info: Upmpdcli Daemon Started Apr 04 22:32:28 volumio volumio[1167]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 04 22:32:30 volumio volumio[979]: info: Adding plugin bluetooth to MyMusic Plugins Apr 04 22:32:30 volumio volumio[979]: info: Adding plugin multiroom to MyMusic Plugins Apr 04 22:32:30 volumio volumio[979]: info: Adding plugin metavolumio to MyMusic Plugins Apr 04 22:32:30 volumio volumio[979]: info: Adding plugin cd_controller to MyMusic Plugins Apr 04 22:32:30 volumio volumio[979]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 04 22:32:30 volumio volumio[979]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 04 22:32:31 volumio volumio[979]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 04 22:32:31 volumio volumio[979]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 04 22:32:31 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 22:32:31 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 22:32:31 volumio volumio[979]: info: Starting MyVolumio Remote Streaming Endpoints Apr 04 22:32:31 volumio volumio[979]: info: MyVolumio not started Apr 04 22:32:31 volumio volumio[979]: info: Initializing device activation check Apr 04 22:32:31 volumio volumio[979]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 04 22:32:31 volumio volumio[979]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 04 22:32:31 volumio volumio[979]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 04 22:32:31 volumio volumio[979]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 04 22:32:31 volumio volumio[979]: info: Streaming services startup Apr 04 22:32:31 volumio volumio[979]: info: Starting Streaming Daemon Apr 04 22:32:31 volumio sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 04 22:32:31 volumio sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 22:32:31 volumio systemd[1]: Stopping Volumio Streaming Daemon... Apr 04 22:32:31 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Apr 04 22:32:31 volumio systemd[1]: volumio-streaming-daemon.service: Succeeded. Apr 04 22:32:31 volumio systemd[1]: Stopped Volumio Streaming Daemon. Apr 04 22:32:31 volumio systemd[1]: Started Volumio Streaming Daemon. Apr 04 22:32:31 volumio sudo[1184]: pam_unix(sudo:session): session closed for user root Apr 04 22:32:31 volumio volumio-streaming-daemon[1189]: ############################ Apr 04 22:32:31 volumio volumio-streaming-daemon[1189]: # Volumio Streaming Daemon # Apr 04 22:32:31 volumio volumio-streaming-daemon[1189]: # Running on port 7777 # Apr 04 22:32:31 volumio volumio-streaming-daemon[1189]: ############################ Apr 04 22:32:31 volumio volumio-streaming-daemon[1189]: Environment: production Apr 04 22:32:32 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 04 22:32:34 volumio volumio-streaming-daemon[1189]: Environment: production Apr 04 22:32:34 volumio volumio[979]: info: Fetching Streaming Services browse cache Apr 04 22:32:34 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 04 22:32:35 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 04 22:32:35 volumio volumio[979]: info: TuneIn handleBrowseUri: tunein/local Apr 04 22:32:37 volumio volumio[979]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 04 22:32:37 volumio volumio[979]: info: CoreStateMachine::ClearQueue Apr 04 22:32:37 volumio volumio[979]: info: CoreStateMachine::stop Apr 04 22:32:37 volumio volumio[979]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 22:32:37 volumio volumio[979]: verbose: UNSET VOLATILE Apr 04 22:32:37 volumio volumio[979]: info: CorePlayQueue::clearPlayQueue Apr 04 22:32:37 volumio volumio[979]: info: CorePlayQueue::saveQueue Apr 04 22:32:37 volumio volumio[979]: info: CoreCommandRouter::volumioPushState Apr 04 22:32:37 volumio volumio[979]: info: CoreCommandRouter::volumioPushQueue Apr 04 22:32:37 volumio volumio[979]: info: CoreStateMachine::addQueueItems Apr 04 22:32:37 volumio volumio[979]: info: CorePlayQueue::addQueueItems Apr 04 22:32:37 volumio volumio[979]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s25260 Apr 04 22:32:37 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 04 22:32:38 volumio volumio[979]: info: CorePlayQueue::saveQueue Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::volumioPushQueue Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::updateTrackBlock Apr 04 22:32:38 volumio volumio[979]: info: CorePlayQueue::getTrackBlock Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::volumioPlay Apr 04 22:32:38 volumio volumio[979]: verbose: UNSET VOLATILE Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::play index 0 Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::stop Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 22:32:38 volumio volumio[979]: verbose: UNSET VOLATILE Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::play index undefined Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 22:32:38 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::startPlaybackTimer Apr 04 22:32:38 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:38 volumio volumio[979]: info: [1712269958009] ControllerWebradio::clearAddPlayTrack Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand stop Apr 04 22:32:38 volumio volumio[979]: info: sendMpdCommand stop took 3 milliseconds Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand clear Apr 04 22:32:38 volumio volumio[979]: info: Apr 04 22:32:38 volumio volumio[979]: ---------------------------- MPD announces system playlist update Apr 04 22:32:38 volumio volumio[979]: info: Ignoring MPD Status Update Apr 04 22:32:38 volumio volumio[979]: info: sendMpdCommand clear took 2 milliseconds Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand load "https://wdr-1live-live.icecastssl.wdr.de/wdr/1live/live/mp3/128/stream.mp3?aggregator=tunein" Apr 04 22:32:38 volumio volumio[979]: error: updateQueue error: null Apr 04 22:32:38 volumio volumio[979]: info: ------------------------------ 5ms Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand add "https://wdr-1live-live.icecastssl.wdr.de/wdr/1live/live/mp3/128/stream.mp3?aggregator=tunein" Apr 04 22:32:38 volumio volumio[979]: info: sendMpdCommand add "https://wdr-1live-live.icecastssl.wdr.de/wdr/1live/live/mp3/128/stream.mp3?aggregator=tunein" took 0 milliseconds Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand play Apr 04 22:32:38 volumio volumio[979]: info: Apr 04 22:32:38 volumio volumio[979]: ---------------------------- MPD announces system playlist update Apr 04 22:32:38 volumio volumio[979]: info: Ignoring MPD Status Update Apr 04 22:32:38 volumio volumio[979]: info: sendMpdCommand play took 4 milliseconds Apr 04 22:32:38 volumio volumio[979]: info: ------------------------------ 3ms Apr 04 22:32:38 volumio volumio[979]: info: Apr 04 22:32:38 volumio volumio[979]: ---------------------------- MPD announces state update: player Apr 04 22:32:38 volumio volumio[979]: info: ControllerMpd::getState Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand status Apr 04 22:32:38 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 04 22:32:38 volumio volumio[979]: info: sendMpdCommand status took 20 milliseconds Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::parseState Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 22:32:38 volumio volumio[979]: info: Apr 04 22:32:38 volumio volumio[979]: ---------------------------- MPD announces state update: player Apr 04 22:32:38 volumio volumio[979]: info: ControllerMpd::getState Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand status Apr 04 22:32:38 volumio volumio[979]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 04 22:32:38 volumio volumio[979]: info: sendMpdCommand status took 1 milliseconds Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::parseTrackInfo Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::parseState Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 22:32:38 volumio volumio[979]: info: ControllerMpd::pushState Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::servicePushState Apr 04 22:32:38 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:38 volumio volumio[979]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://wdr-1live-live.icecastssl.wdr.de/wdr/1live/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Apr 04 22:32:38 volumio volumio[979]: verbose: CURRENT POSITION 0 Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::syncState stateService play Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::syncState currentStatus stop Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::pushState Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::volumioPushState Apr 04 22:32:38 volumio volumio[979]: info: ------------------------------ 37ms Apr 04 22:32:38 volumio volumio[979]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::parseTrackInfo Apr 04 22:32:38 volumio volumio[979]: info: ControllerMpd::pushState Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::servicePushState Apr 04 22:32:38 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:38 volumio volumio[979]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://wdr-1live-live.icecastssl.wdr.de/wdr/1live/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Apr 04 22:32:38 volumio volumio[979]: verbose: CURRENT POSITION 0 Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::syncState stateService play Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::syncState currentStatus play Apr 04 22:32:38 volumio volumio[979]: info: Received an update from plugin. extracting info from payload Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::pushState Apr 04 22:32:38 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::volumioPushState Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::pushState Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::volumioPushState Apr 04 22:32:38 volumio volumio[979]: info: ------------------------------ 28ms Apr 04 22:32:39 volumio volumio[979]: info: Apr 04 22:32:39 volumio volumio[979]: ---------------------------- MPD announces system playlist update Apr 04 22:32:39 volumio volumio[979]: info: Ignoring MPD Status Update Apr 04 22:32:39 volumio volumio[979]: info: Apr 04 22:32:39 volumio volumio[979]: ---------------------------- MPD announces state update: player Apr 04 22:32:39 volumio volumio[979]: info: ControllerMpd::getState Apr 04 22:32:39 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand status Apr 04 22:32:39 volumio volumio[979]: info: Apr 04 22:32:39 volumio volumio[979]: ---------------------------- MPD announces system playlist update Apr 04 22:32:39 volumio volumio[979]: info: Ignoring MPD Status Update Apr 04 22:32:39 volumio volumio[979]: info: Apr 04 22:32:39 volumio volumio[979]: ---------------------------- MPD announces state update: player Apr 04 22:32:39 volumio volumio[979]: info: ------------------------------ 4ms Apr 04 22:32:39 volumio volumio[979]: info: sendMpdCommand status took 3 milliseconds Apr 04 22:32:39 volumio volumio[979]: info: ControllerMpd::getState Apr 04 22:32:39 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand status Apr 04 22:32:39 volumio volumio[979]: verbose: ControllerMpd::parseState Apr 04 22:32:39 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 22:32:39 volumio volumio[979]: info: ------------------------------ 4ms Apr 04 22:32:39 volumio volumio[979]: info: sendMpdCommand status took 3 milliseconds Apr 04 22:32:39 volumio volumio[979]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 04 22:32:39 volumio volumio[979]: verbose: ControllerMpd::parseState Apr 04 22:32:39 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 22:32:39 volumio volumio[979]: verbose: ControllerMpd::parseTrackInfo Apr 04 22:32:39 volumio volumio[979]: info: ControllerMpd::pushState Apr 04 22:32:39 volumio volumio[979]: info: CoreCommandRouter::servicePushState Apr 04 22:32:39 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:39 volumio volumio[979]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Die junge Nacht der ARD","artist":"1Live, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-1live-live.icecastssl.wdr.de/wdr/1live/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Apr 04 22:32:39 volumio volumio[979]: verbose: CURRENT POSITION 0 Apr 04 22:32:39 volumio volumio[979]: info: CoreStateMachine::syncState stateService play Apr 04 22:32:39 volumio volumio[979]: info: CoreStateMachine::syncState currentStatus play Apr 04 22:32:39 volumio volumio[979]: info: Received an update from plugin. extracting info from payload Apr 04 22:32:39 volumio volumio[979]: info: CoreStateMachine::pushState Apr 04 22:32:39 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 22:32:39 volumio volumio[979]: info: CoreCommandRouter::volumioPushState Apr 04 22:32:39 volumio volumio[979]: info: CoreStateMachine::pushState Apr 04 22:32:39 volumio volumio[979]: info: CoreCommandRouter::volumioPushState Apr 04 22:32:39 volumio volumio[979]: info: ------------------------------ 19ms Apr 04 22:32:39 volumio volumio[979]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 04 22:32:39 volumio volumio[979]: verbose: ControllerMpd::parseTrackInfo Apr 04 22:32:39 volumio volumio[979]: info: ControllerMpd::pushState Apr 04 22:32:39 volumio volumio[979]: info: CoreCommandRouter::servicePushState Apr 04 22:32:39 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:39 volumio volumio[979]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Die junge Nacht der ARD","artist":"1Live, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-1live-live.icecastssl.wdr.de/wdr/1live/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Apr 04 22:32:39 volumio volumio[979]: verbose: CURRENT POSITION 0 Apr 04 22:32:39 volumio volumio[979]: info: CoreStateMachine::syncState stateService play Apr 04 22:32:39 volumio volumio[979]: info: CoreStateMachine::syncState currentStatus play Apr 04 22:32:39 volumio volumio[979]: info: Received an update from plugin. extracting info from payload Apr 04 22:32:39 volumio volumio[979]: info: CoreStateMachine::pushState Apr 04 22:32:39 volumio volumio[979]: info: CoreCommandRouter::volumioPushState Apr 04 22:32:39 volumio volumio[979]: info: CoreStateMachine::pushState Apr 04 22:32:39 volumio volumio[979]: info: CoreCommandRouter::volumioPushState Apr 04 22:32:39 volumio volumio[979]: info: ------------------------------ 29ms Apr 04 22:32:41 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: nanosound_cd , handleBrowseUri Apr 04 22:32:45 volumio volumio[1167]: ......................................................................................................................................................................................++++ Apr 04 22:32:51 volumio volumio[979]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 04 22:32:51 volumio volumio[979]: info: Completed starting MyVolumio Plugin Apr 04 22:32:51 volumio volumio[979]: info: BOOT COMPLETED Apr 04 22:32:51 volumio volumio[979]: [Metrics] CommandRouter: 35s 258.49ms Apr 04 22:32:51 volumio volumio[979]: info: CoreCommandRouter::volumiosetStartupVolume Apr 04 22:32:51 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 22:32:51 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 22:32:51 volumio volumio[979]: info: CoreCommandRouter::Close All Modals sent Apr 04 22:32:51 volumio volumio[979]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Apr 04 22:32:51 volumio volumio[979]: aplay: main:828: audio open error: Device or resource busy Apr 04 22:32:52 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 22:32:52 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 22:32:52 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 04 22:32:52 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 04 22:32:52 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 04 22:32:55 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 22:32:56 volumio volumio[979]: info: CoreCommandRouter::Close All Modals sent Apr 04 22:32:59 volumio volumio[979]: info: Apr 04 22:32:59 volumio volumio[979]: ---------------------------- MPD announces system playlist update Apr 04 22:32:59 volumio volumio[979]: info: Ignoring MPD Status Update Apr 04 22:32:59 volumio volumio[979]: info: Apr 04 22:32:59 volumio volumio[979]: ---------------------------- MPD announces state update: player Apr 04 22:32:59 volumio volumio[979]: info: ControllerMpd::getState Apr 04 22:32:59 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand status Apr 04 22:32:59 volumio volumio[979]: info: ------------------------------ 3ms Apr 04 22:32:59 volumio volumio[979]: info: sendMpdCommand status took 2 milliseconds Apr 04 22:32:59 volumio volumio[979]: verbose: ControllerMpd::parseState Apr 04 22:32:59 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 22:32:59 volumio volumio[979]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 04 22:32:59 volumio volumio[979]: verbose: ControllerMpd::parseTrackInfo Apr 04 22:32:59 volumio volumio[979]: info: ControllerMpd::pushState Apr 04 22:32:59 volumio volumio[979]: info: CoreCommandRouter::servicePushState Apr 04 22:32:59 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 04 22:32:59 volumio volumio[979]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21112,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Teddy Swims - Lose Control","artist":"1Live, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-1live-live.icecastssl.wdr.de/wdr/1live/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Apr 04 22:32:59 volumio volumio[979]: verbose: CURRENT POSITION 0 Apr 04 22:32:59 volumio volumio[979]: info: CoreStateMachine::syncState stateService play Apr 04 22:32:59 volumio volumio[979]: info: CoreStateMachine::syncState currentStatus play Apr 04 22:32:59 volumio volumio[979]: info: Received an update from plugin. extracting info from payload Apr 04 22:32:59 volumio volumio[979]: info: CoreStateMachine::pushState Apr 04 22:32:59 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 22:32:59 volumio volumio[979]: info: CoreCommandRouter::volumioPushState Apr 04 22:32:59 volumio volumio[979]: info: CoreStateMachine::pushState Apr 04 22:32:59 volumio volumio[979]: info: CoreCommandRouter::volumioPushState Apr 04 22:32:59 volumio volumio[979]: info: ------------------------------ 18ms Apr 04 22:32:59 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 04 22:33:05 volumio ntpd[701]: Soliciting pool server 195.201.20.16 Apr 04 22:33:05 volumio ntpd[701]: Soliciting pool server 178.63.166.157 Apr 04 22:33:06 volumio ntpd[701]: Soliciting pool server 168.119.238.107 Apr 04 22:33:06 volumio ntpd[701]: Soliciting pool server 176.9.44.212 Apr 04 22:33:07 volumio ntpd[701]: Soliciting pool server 167.235.246.170 Apr 04 22:33:07 volumio ntpd[701]: Soliciting pool server 162.159.200.123 Apr 04 22:33:08 volumio ntpd[701]: Soliciting pool server 89.58.51.72 Apr 04 22:33:08 volumio ntpd[701]: Soliciting pool server 88.218.226.91 Apr 04 22:33:08 volumio ntpd[701]: Soliciting pool server 193.158.22.13 Apr 04 22:33:09 volumio ntpd[701]: Soliciting pool server 162.159.200.1 Apr 04 22:33:09 volumio volumio[1167]: ....................................................................................................................................................................................................................................................................................++++ Apr 04 22:33:09 volumio volumio[1167]: e is 65537 (0x010001) Apr 04 22:33:10 volumio volumio[1167]: writing RSA key Apr 04 22:33:10 volumio ntpd[701]: Soliciting pool server 157.90.24.29 Apr 04 22:33:10 volumio ntpd[701]: Soliciting pool server 193.203.3.171 Apr 04 22:33:11 volumio ntpd[701]: Soliciting pool server 144.91.116.85 Apr 04 22:33:11 volumio ntpd[701]: Soliciting pool server 88.99.76.254 Apr 04 22:33:12 volumio ntpd[701]: Soliciting pool server 144.76.66.156 Apr 04 22:33:12 volumio ntpd[701]: Soliciting pool server 2a0e:b107:27d0:1::6 Apr 04 22:33:13 volumio ntpd[701]: Soliciting pool server 89.238.82.145 Apr 04 22:33:50 volumio volumio[979]: info: CALLMETHOD: music_service nanosound_cd saveReg [object Object] Apr 04 22:33:50 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: nanosound_cd , saveReg Apr 04 22:33:50 volumio volumio[979]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 04 22:33:50 volumio volumio[979]: TypeError: Cannot read property 'response' of undefined Apr 04 22:33:50 volumio volumio[979]: at Request._callback (/data/plugins/music_service/nanosound_cd/index.js:40:10) Apr 04 22:33:50 volumio volumio[979]: at self.callback (/data/plugins/music_service/nanosound_cd/node_modules/request/request.js:185:22) Apr 04 22:33:50 volumio volumio[979]: at Request.emit (events.js:315:20) Apr 04 22:33:50 volumio volumio[979]: at Request.onRequestError (/data/plugins/music_service/nanosound_cd/node_modules/request/request.js:877:8) Apr 04 22:33:50 volumio volumio[979]: at ClientRequest.emit (events.js:315:20) Apr 04 22:33:50 volumio volumio[979]: at Socket.socketErrorListener (_http_client.js:469:9) Apr 04 22:33:50 volumio volumio[979]: at Socket.emit (events.js:315:20) Apr 04 22:33:50 volumio volumio[979]: at emitErrorNT (internal/streams/destroy.js:106:8) Apr 04 22:33:50 volumio volumio[979]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Apr 04 22:33:50 volumio volumio[979]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 04 22:33:50 volumio volumio[979]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 04 22:33:51 volumio sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-04 22:32 Apr 04 22:33:51 volumio sudo[1326]: 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="1c5fa5a8788b087ca429be9d10851b25c5cc4f5b" VOLUMIO_FE_VERSION="e5ce74e254a237ac98580c4437c4ed223c7d5cd9" VOLUMIO_FE3_VERSION="a86adf3c7ed2d6c50d9bca768f58ab2f56832dc1" VOLUMIO_BE_VERSION="c8882d89d3d4e0a1571e0dc9701741128f5a1656" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 31 Mar 2022 02:51:41 PM CEST" VOLUMIO_VERSION="3.251" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e83cc5fcd68eb3ee4105ab0b8b3779ea"