-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2023-08-27 08:34:04 CEST. -- Aug 27 08:33:00 volumio nmbd[630]: [2023/08/27 08:33:00.467955, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Aug 27 08:33:00 volumio nmbd[630]: started asyncdns process 663 Aug 27 08:33:00 volumio nmbd[630]: [2023/08/27 08:33:00.474788, 0] ../lib/util/become_daemon.c:149(daemon_status) Aug 27 08:33:00 volumio nmbd[630]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Aug 27 08:33:00 volumio nmbd[630]: [2023/08/27 08:33:00.475073, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Aug 27 08:33:00 volumio nmbd[630]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Aug 27 08:33:00 volumio ntpd[654]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 27 08:33:01 volumio wireless.js[496]: Cleaning previous... Aug 27 08:33:01 volumio ntpd[654]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 27 08:33:01 volumio sudo[672]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 27 08:33:01 volumio sudo[672]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:01 volumio sudo[672]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:01 volumio sudo[674]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 27 08:33:01 volumio sudo[674]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:02 volumio sudo[674]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:02 volumio mpd[650]: Aug 27 08:33 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 08:33:02 volumio wireless.js[496]: Stopped aP Aug 27 08:33:02 volumio sudo[681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 27 08:33:02 volumio sudo[681]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:02 volumio sudo[681]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:02 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 27 08:33:02 volumio sudo[683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Aug 27 08:33:02 volumio sudo[683]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:02 volumio sudo[683]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:02 volumio sudo[690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 27 08:33:02 volumio sudo[690]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:02 volumio mpd[650]: Aug 27 08:33 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 27 08:33:02 volumio mpd[650]: Aug 27 08:33 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 27 08:33:02 volumio sudo[690]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:02 volumio sudo[692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Aug 27 08:33:02 volumio sudo[692]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:02 volumio systemd[1]: Started Music Player Daemon. Aug 27 08:33:02 volumio ntpd[654]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 27 08:33:03 volumio kernel: Bluetooth: Core ver 2.22 Aug 27 08:33:03 volumio kernel: NET: Registered protocol family 31 Aug 27 08:33:03 volumio kernel: Bluetooth: HCI device and connection manager initialized Aug 27 08:33:03 volumio kernel: Bluetooth: HCI socket layer initialized Aug 27 08:33:03 volumio kernel: Bluetooth: L2CAP socket layer initialized Aug 27 08:33:03 volumio kernel: Bluetooth: SCO socket layer initialized Aug 27 08:33:03 volumio kernel: Bluetooth: HCI UART driver ver 2.3 Aug 27 08:33:03 volumio kernel: Bluetooth: HCI UART protocol H4 registered Aug 27 08:33:03 volumio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Aug 27 08:33:03 volumio kernel: Bluetooth: HCI UART protocol Broadcom registered Aug 27 08:33:03 volumio btuart[495]: bcm43xx_init Aug 27 08:33:03 volumio btuart[495]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Aug 27 08:33:03 volumio btuart[495]: Set BDADDR UART: b8:27:eb:31:20:8e Aug 27 08:33:03 volumio btuart[495]: Set Controller UART speed to 3000000 bit/s Aug 27 08:33:03 volumio btuart[495]: Device setup complete Aug 27 08:33:03 volumio systemd[1]: Started Configure Bluetooth Modems connected by UART. Aug 27 08:33:03 volumio systemd[1]: Created slice system-bthelper.slice. Aug 27 08:33:03 volumio systemd[1]: Starting Raspberry Pi bluetooth helper... Aug 27 08:33:03 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Aug 27 08:33:03 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Aug 27 08:33:03 volumio bthelper[706]: Raspberry Pi BDADDR already set Aug 27 08:33:03 volumio systemd[1]: Started Raspberry Pi bluetooth helper. Aug 27 08:33:03 volumio systemd[1]: Starting Bluetooth service... Aug 27 08:33:03 volumio bluetoothd[717]: Bluetooth daemon 5.50 Aug 27 08:33:03 volumio bluetoothd[717]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Aug 27 08:33:03 volumio systemd[1]: Started Bluetooth service. Aug 27 08:33:03 volumio systemd[1]: Reached target Bluetooth. Aug 27 08:33:03 volumio bluetoothd[717]: Starting SDP server Aug 27 08:33:03 volumio bluetoothd[717]: Excluding (cli) sap Aug 27 08:33:03 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 27 08:33:03 volumio kernel: Bluetooth: BNEP filters: protocol multicast Aug 27 08:33:03 volumio kernel: Bluetooth: BNEP socket layer initialized Aug 27 08:33:03 volumio bluetoothd[717]: Bluetooth management interface 1.18 initialized Aug 27 08:33:03 volumio dbus-daemon[493]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=717 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Aug 27 08:33:03 volumio systemd[1]: Starting Hostname Service... Aug 27 08:33:03 volumio bluetoothd[717]: Failed to set privacy: Rejected (0x0b) Aug 27 08:33:03 volumio dbus-daemon[493]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 27 08:33:03 volumio systemd[1]: Started Hostname Service. Aug 27 08:33:04 volumio volumio-remote-updater[503]: [2023-08-27 08:33:04] [info] asio async_connect error: system:111 (Connection refused) Aug 27 08:33:04 volumio volumio-remote-updater[503]: [2023-08-27 08:33:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 27 08:33:04 volumio volumio-remote-updater[503]: [2023-08-27 08:33:04] [error] handle_connect error: Underlying Transport Error Aug 27 08:33:05 volumio sudo[692]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:05 volumio wireless.js[496]: Start wireless flow Aug 27 08:33:05 volumio wireless.js[496]: Stopped hotspot (if there).. Aug 27 08:33:05 volumio sudo[721]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 27 08:33:05 volumio sudo[721]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:05 volumio sudo[721]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:05 volumio sudo[723]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 27 08:33:05 volumio sudo[723]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:05 volumio sudo[723]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:05 volumio wireless.js[496]: DHCP IP Aug 27 08:33:05 volumio wireless.js[496]: Start ap Aug 27 08:33:05 volumio wpa_supplicant[725]: Successfully initialized wpa_supplicant Aug 27 08:33:05 volumio sudo[726]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Aug 27 08:33:05 volumio sudo[726]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:05 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 27 08:33:05 volumio dhcpcd[727]: dev: loaded udev Aug 27 08:33:05 volumio dhcpcd-run-hooks[745]: wlan0: starting wpa_supplicant Aug 27 08:33:06 volumio dhcpcd-run-hooks[750]: wlan0: failed to start wpa_supplicant Aug 27 08:33:06 volumio dhcpcd-run-hooks[751]: 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 Aug 27 08:33:06 volumio dhcpcd[727]: wlan0: connected to Access Point `' Aug 27 08:33:06 volumio dhcpcd[727]: no interfaces have a carrier Aug 27 08:33:06 volumio dhcpcd[727]: forked to background, child pid 761 Aug 27 08:33:06 volumio dhcpcd[761]: eth0: waiting for carrier Aug 27 08:33:06 volumio dhcpcd[761]: wlan0: waiting for carrier Aug 27 08:33:06 volumio sudo[726]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:06 volumio wireless.js[496]: trying... Aug 27 08:33:06 volumio sudo[763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 27 08:33:06 volumio sudo[763]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:06 volumio sudo[763]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:07 volumio wireless.js[496]: trying... Aug 27 08:33:07 volumio sudo[766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 27 08:33:07 volumio sudo[766]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:07 volumio sudo[766]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:08 volumio wpa_supplicant[749]: wlan0: Trying to associate with SSID 'Cisco16729' Aug 27 08:33:08 volumio systemd[1]: systemd-rfkill.service: Succeeded. Aug 27 08:33:08 volumio bthelper[706]: Changing power off succeeded Aug 27 08:33:08 volumio wireless.js[496]: trying... Aug 27 08:33:08 volumio sudo[770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 27 08:33:08 volumio sudo[770]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:08 volumio sudo[770]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:08 volumio bthelper[706]: [CHG] Controller B8:27:EB:31:20:8E Class: 0x0000041c Aug 27 08:33:08 volumio bthelper[706]: Changing power on succeeded Aug 27 08:33:09 volumio wireless.js[496]: trying... Aug 27 08:33:09 volumio sudo[779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 27 08:33:09 volumio sudo[779]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:09 volumio sudo[779]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:09 volumio kernel: cam1-reg: disabling Aug 27 08:33:09 volumio kernel: cam-dummy-reg: disabling Aug 27 08:33:10 volumio wireless.js[496]: trying... Aug 27 08:33:10 volumio sudo[782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 27 08:33:10 volumio sudo[782]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:10 volumio sudo[782]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:11 volumio dhcpcd[761]: wlan0: carrier acquired Aug 27 08:33:11 volumio dhcpcd[761]: wlan0: connected to Access Point `Cisco16729' Aug 27 08:33:11 volumio wpa_supplicant[749]: wlan0: Associated with 2c:fd:a1:5f:f9:74 Aug 27 08:33:11 volumio wpa_supplicant[749]: wlan0: CTRL-EVENT-CONNECTED - Connection to 2c:fd:a1:5f:f9:74 completed [id=0 id_str=] Aug 27 08:33:11 volumio wpa_supplicant[749]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 27 08:33:11 volumio dhcpcd[761]: DUID 00:01:00:01:29:4a:1d:8b:b8:27:eb:9b:8a:24 Aug 27 08:33:11 volumio dhcpcd[761]: wlan0: IAID eb:ce:df:71 Aug 27 08:33:11 volumio dhcpcd[761]: wlan0: adding address fe80::1bd6:e470:8b5b:5454 Aug 27 08:33:11 volumio dhcpcd[761]: ipv6_addaddr1: Permission denied Aug 27 08:33:11 volumio dhcpcd[761]: wlan0: carrier lost Aug 27 08:33:11 volumio dhcpcd[761]: wlan0: carrier acquired Aug 27 08:33:11 volumio dhcpcd[761]: wlan0: IAID eb:ce:df:71 Aug 27 08:33:11 volumio dhcpcd[761]: wlan0: rebinding lease of 192.168.1.136 Aug 27 08:33:11 volumio dhcpcd[761]: wlan0: probing address 192.168.1.136/24 Aug 27 08:33:11 volumio volumio-remote-updater[503]: [2023-08-27 08:33:11] [info] asio async_connect error: system:111 (Connection refused) Aug 27 08:33:11 volumio volumio-remote-updater[503]: [2023-08-27 08:33:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 27 08:33:11 volumio volumio-remote-updater[503]: [2023-08-27 08:33:11] [error] handle_connect error: Underlying Transport Error Aug 27 08:33:11 volumio dhcpcd[761]: wlan0: soliciting an IPv6 router Aug 27 08:33:11 volumio wireless.js[496]: trying... Aug 27 08:33:11 volumio sudo[797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 27 08:33:11 volumio sudo[797]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:11 volumio sudo[797]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:12 volumio wireless.js[496]: trying... Aug 27 08:33:12 volumio sudo[800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 27 08:33:12 volumio sudo[800]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:12 volumio sudo[800]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:13 volumio wireless.js[496]: trying... Aug 27 08:33:13 volumio sudo[803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 27 08:33:13 volumio sudo[803]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:13 volumio sudo[803]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:14 volumio wireless.js[496]: trying... Aug 27 08:33:14 volumio sudo[806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 27 08:33:14 volumio sudo[806]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:14 volumio sudo[806]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:15 volumio wireless.js[496]: trying... Aug 27 08:33:15 volumio sudo[809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 27 08:33:15 volumio sudo[809]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:15 volumio sudo[809]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:16 volumio dhcpcd[761]: wlan0: leased 192.168.1.136 for 86400 seconds Aug 27 08:33:16 volumio avahi-daemon[491]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.136. Aug 27 08:33:16 volumio dhcpcd[761]: wlan0: adding route to 192.168.1.0/24 Aug 27 08:33:16 volumio dhcpcd[761]: wlan0: adding default route via 192.168.1.1 Aug 27 08:33:16 volumio avahi-daemon[491]: New relevant interface wlan0.IPv4 for mDNS. Aug 27 08:33:16 volumio avahi-daemon[491]: Registering new address record for 192.168.1.136 on wlan0.IPv4. Aug 27 08:33:16 volumio wireless.js[496]: trying... Aug 27 08:33:16 volumio sudo[832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 27 08:33:16 volumio sudo[832]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:16 volumio sudo[832]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:16 volumio wireless.js[496]: Connected to: ----Cisco16729 Aug 27 08:33:16 volumio wireless.js[496]: ---- Aug 27 08:33:16 volumio sudo[835]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 08:33:16 volumio sudo[835]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:16 volumio sudo[835]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:16 volumio wireless.js[496]: ... joined AP, wlan0 IPv4 is 192.168.1.136, ipV6 is undefined Aug 27 08:33:16 volumio wireless.js[496]: It's done! AP Aug 27 08:33:16 volumio systemd[1]: Started Wireless Services. Aug 27 08:33:16 volumio systemd[1]: Started Volumio Backend Module. Aug 27 08:33:16 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 27 08:33:17 volumio ntpd[654]: Listen normally on 3 wlan0 192.168.1.136:123 Aug 27 08:33:17 volumio ntpd[654]: new interface(s) found: waking up resolver Aug 27 08:33:19 volumio volumio[840]: info: ------------------------------------------- Aug 27 08:33:19 volumio volumio[840]: info: ----- Volumio3 ---- Aug 27 08:33:19 volumio volumio[840]: info: ------------------------------------------- Aug 27 08:33:19 volumio volumio[840]: info: ----- System startup ---- Aug 27 08:33:19 volumio volumio[840]: info: ------------------------------------------- Aug 27 08:33:20 volumio volumio[840]: info: MYVOLUMIO Environment detected Aug 27 08:33:20 volumio systemd[1]: Started Samba NMB Daemon. Aug 27 08:33:20 volumio nmbd[630]: [2023/08/27 08:33:20.785251, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 27 08:33:20 volumio nmbd[630]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Aug 27 08:33:20 volumio systemd[1]: Starting Samba Winbind Daemon... Aug 27 08:33:20 volumio volumio[840]: info: Plugin folders cleanup Aug 27 08:33:20 volumio volumio[840]: info: Scanning into folder /volumio/app/plugins/ Aug 27 08:33:20 volumio volumio[840]: info: Scanning category audio_interface Aug 27 08:33:20 volumio volumio[840]: info: Scanning category miscellanea Aug 27 08:33:20 volumio volumio[840]: info: Scanning category music_service Aug 27 08:33:20 volumio volumio[840]: info: Scanning category plugins.json Aug 27 08:33:20 volumio volumio[840]: info: Scanning category system_controller Aug 27 08:33:20 volumio volumio[840]: info: Scanning category user_interface Aug 27 08:33:20 volumio volumio[840]: info: Scanning into folder /data/plugins/ Aug 27 08:33:20 volumio volumio[840]: info: Scanning category music_service Aug 27 08:33:20 volumio volumio[840]: info: Plugin folders cleanup completed Aug 27 08:33:20 volumio volumio[840]: info: ------------------------------------------- Aug 27 08:33:20 volumio volumio[840]: info: ----- Core plugins startup ---- Aug 27 08:33:20 volumio volumio[840]: info: ------------------------------------------- Aug 27 08:33:20 volumio volumio[840]: info: Loading plugins from folder /volumio/app/plugins/ Aug 27 08:33:20 volumio volumio[840]: info: Adding plugin upnp to MyMusic Plugins Aug 27 08:33:20 volumio volumio[840]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 27 08:33:20 volumio volumio[840]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 27 08:33:20 volumio volumio[840]: info: Loading plugins from folder /data/plugins/ Aug 27 08:33:20 volumio volumio[840]: info: Loading plugin "system"... Aug 27 08:33:20 volumio volumio[840]: info: Loading plugin "appearance"... Aug 27 08:33:21 volumio winbindd[857]: [2023/08/27 08:33:21.127470, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Aug 27 08:33:21 volumio winbindd[857]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 27 08:33:21 volumio winbindd[857]: [2023/08/27 08:33:21.160723, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 27 08:33:21 volumio systemd[1]: Started Samba Winbind Daemon. Aug 27 08:33:21 volumio winbindd[857]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 27 08:33:21 volumio systemd[1]: Starting Samba SMB Daemon... Aug 27 08:33:21 volumio volumio-remote-updater[503]: [2023-08-27 08:33:21] [connect] Successful connection Aug 27 08:33:21 volumio winbindd[857]: [2023/08/27 08:33:21.908112, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Aug 27 08:33:21 volumio winbindd[857]: res_names->count = 3, expected 4 Aug 27 08:33:21 volumio systemd[1]: Started Samba SMB Daemon. Aug 27 08:33:21 volumio systemd[1]: Reached target Multi-User System. Aug 27 08:33:21 volumio smbd[862]: [2023/08/27 08:33:21.943185, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 27 08:33:21 volumio systemd[1]: Reached target Graphical Interface. Aug 27 08:33:21 volumio smbd[862]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 27 08:33:21 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 27 08:33:21 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 27 08:33:21 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 27 08:33:22 volumio systemd[1]: Startup finished in 11.576s (kernel) + 34.907s (userspace) = 46.484s. Aug 27 08:33:22 volumio volumio[840]: info: Loading plugin "network"... Aug 27 08:33:22 volumio volumio[840]: info: Refreshing Cached IP Addresses Aug 27 08:33:22 volumio sudo[872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 08:33:22 volumio sudo[872]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:22 volumio sudo[872]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:22 volumio volumio[840]: info: Loading plugin "services"... Aug 27 08:33:22 volumio sudo[874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 08:33:22 volumio sudo[874]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:22 volumio volumio[840]: info: Loading plugin "alsa_controller"... Aug 27 08:33:22 volumio sudo[874]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:22 volumio sudo[877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 27 08:33:22 volumio sudo[877]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:22 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 08:33:22 volumio volumio[840]: info: Loading plugin "wizard"... Aug 27 08:33:22 volumio volumio[840]: info: Loading plugin "volumio_command_line_client"... Aug 27 08:33:22 volumio volumio[840]: info: Loading plugin "upnp"... Aug 27 08:33:22 volumio volumio[840]: info: [1693118002839] Starting Upmpd Daemon Aug 27 08:33:22 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 08:33:22 volumio volumio[840]: info: Loading plugin "my_music"... Aug 27 08:33:22 volumio volumio[840]: info: Loading plugin "mpd"... Aug 27 08:33:23 volumio volumio[840]: info: Creating MPD Configuration file Aug 27 08:33:23 volumio volumio[840]: info: Loading plugin "upnp_browser"... Aug 27 08:33:23 volumio sudo[883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 08:33:23 volumio sudo[883]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:23 volumio sudo[883]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:23 volumio sudo[885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 08:33:23 volumio sudo[885]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:23 volumio systemd[1]: Stopping Music Player Daemon... Aug 27 08:33:23 volumio systemd[1]: mpd.service: Succeeded. Aug 27 08:33:23 volumio systemd[1]: Stopped Music Player Daemon. Aug 27 08:33:23 volumio systemd[1]: Starting Music Player Daemon... Aug 27 08:33:24 volumio volumio[840]: info: Loading plugin "networkfs"... Aug 27 08:33:24 volumio volumio[840]: info: Starting Udev Watcher for removable devices Aug 27 08:33:24 volumio systemd[1]: systemd-fsckd.service: Succeeded. Aug 27 08:33:24 volumio volumio[840]: info: Ignoring mount for partition: boot Aug 27 08:33:24 volumio volumio[840]: info: Ignoring mount for partition: volumio Aug 27 08:33:24 volumio volumio[840]: info: Ignoring mount for partition: volumio_data Aug 27 08:33:24 volumio volumio[840]: info: Mounting Device 30E6-F47E Aug 27 08:33:24 volumio sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/30E6-F47E -o noatime Aug 27 08:33:24 volumio sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:24 volumio mpd[890]: Aug 27 08:33 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 08:33:24 volumio mount.exfat[898]: volume was not unmounted cleanly Aug 27 08:33:24 volumio kernel: fuse: init (API version 7.32) Aug 27 08:33:24 volumio sudo[896]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:24 volumio volumio[840]: WARN: volume was not unmounted cleanly. Aug 27 08:33:24 volumio systemd[1]: Mounting FUSE Control File System... Aug 27 08:33:24 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 08:33:25 volumio volumio[840]: info: Loading plugin "alarm-clock"... Aug 27 08:33:25 volumio systemd[1]: Mounted FUSE Control File System. Aug 27 08:33:25 volumio mpd[890]: Aug 27 08:33 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 27 08:33:25 volumio mpd[890]: Aug 27 08:33 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 27 08:33:25 volumio systemd[1]: Started Music Player Daemon. Aug 27 08:33:25 volumio sudo[885]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:25 volumio volumio[840]: info: Loading plugin "airplay_emulation"... Aug 27 08:33:25 volumio volumio[840]: info: Starting Shairport Sync Aug 27 08:33:25 volumio volumio[840]: info: Loading plugin "last_100"... Aug 27 08:33:25 volumio volumio[840]: info: Loading plugin "webradio"... Aug 27 08:33:25 volumio volumio[840]: info: Loading plugin "i2s_dacs"... Aug 27 08:33:25 volumio volumio[840]: info: I2S DAC not set, start Auto-detection Aug 27 08:33:25 volumio volumio[840]: info: Loading plugin "volumiodiscovery"... Aug 27 08:33:25 volumio volumio[840]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 08:33:25 volumio volumio[840]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 08:33:25 volumio volumio[840]: *** WARNING *** For more information see Aug 27 08:33:25 volumio volumio[840]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 08:33:25 volumio node[840]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 08:33:25 volumio volumio[840]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 08:33:25 volumio volumio[840]: *** WARNING *** For more information see Aug 27 08:33:25 volumio node[840]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 08:33:25 volumio node[840]: *** WARNING *** For more information see Aug 27 08:33:25 volumio node[840]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 08:33:25 volumio node[840]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 08:33:25 volumio node[840]: *** WARNING *** For more information see Aug 27 08:33:25 volumio volumio[840]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 27 08:33:25 volumio volumio[840]: info: Discovery: Started advertising with name: Volumio Aug 27 08:33:25 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 08:33:25 volumio volumio[840]: info: Loading plugin "ytcr"... Aug 27 08:33:25 volumio sh[397]: timed out Aug 27 08:33:25 volumio dhcpcd[443]: timed out Aug 27 08:33:25 volumio sh[397]: dhcpcd exited Aug 27 08:33:25 volumio dhcpcd[443]: dhcpcd exited Aug 27 08:33:25 volumio sh[397]: ifup: failed to bring up eth0 Aug 27 08:33:25 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Aug 27 08:33:25 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Aug 27 08:33:26 volumio sudo[877]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:28 volumio volumio[840]: info: Loading plugin "outputs"... Aug 27 08:33:28 volumio volumio[840]: info: Loading plugin "albumart"... Aug 27 08:33:28 volumio volumio[840]: info: Plugin example_plugin is not enabled Aug 27 08:33:28 volumio volumio[840]: info: Loading plugin "inputs"... Aug 27 08:33:28 volumio volumio[840]: info: Loading plugin "updater_comm"... Aug 27 08:33:28 volumio volumio[840]: info: Plugin mpdemulation is not enabled Aug 27 08:33:28 volumio volumio[840]: info: Loading plugin "rest_api"... Aug 27 08:33:28 volumio volumio[840]: info: Loading plugin "websocket"... Aug 27 08:33:28 volumio volumio[840]: info: Loading i18n strings for locale en Aug 27 08:33:28 volumio volumio[840]: Updating browse sources language Aug 27 08:33:28 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 08:33:28 volumio volumio[840]: Forking 3 albumart workers Aug 27 08:33:28 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 08:33:28 volumio volumio[840]: info: CoreCommandRouter::initPlayerControls Aug 27 08:33:28 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:28 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:28 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:28 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:28 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:28 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:28 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:28 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:28 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 08:33:28 volumio volumio[840]: Express server listening on port 3000 Aug 27 08:33:28 volumio volumio[840]: [Metrics] WebUI: 10s 66.60ms Aug 27 08:33:28 volumio volumio[840]: info: CoreStateMachine::resetVolumioState Aug 27 08:33:28 volumio volumio[840]: info: CoreStateMachine::getcurrentVolume Aug 27 08:33:28 volumio volumio[840]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 08:33:28 volumio volumio[840]: info: CoreStateMachine::pushState Aug 27 08:33:28 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:33:28 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 08:33:28 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 27 08:33:28 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 08:33:28 volumio volumio[840]: info: MPD Permissions set Aug 27 08:33:29 volumio volumio[840]: info: MPD running with PID890 Aug 27 08:33:29 volumio volumio[840]: ,establishing connection Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:29 volumio volumio[840]: info: Reloading queue from file Aug 27 08:33:29 volumio volumio[840]: error: updateQueue error: null Aug 27 08:33:29 volumio volumio[840]: info: CoreStateMachine::setRepeat false single undefined Aug 27 08:33:29 volumio volumio[840]: info: CoreStateMachine::pushState Aug 27 08:33:29 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 27 08:33:29 volumio volumio[840]: info: CoreStateMachine::setRandom null Aug 27 08:33:29 volumio volumio[840]: info: CoreStateMachine::pushState Aug 27 08:33:29 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 27 08:33:29 volumio volumio[840]: info: Setting Device type: Raspberry PI Aug 27 08:33:29 volumio volumio[840]: info: Completed loading Core Plugins Aug 27 08:33:29 volumio volumio[840]: info: Preparing to generate the ALSA configuration file Aug 27 08:33:29 volumio volumio[840]: error: updateQueue error: null Aug 27 08:33:29 volumio volumio[840]: info: Asound.conf file unchanged, so no further update is needed Aug 27 08:33:29 volumio volumio[840]: info: Output device has changed, restarting MPD Aug 27 08:33:29 volumio volumio[840]: info: Output device has changed, restarting Shairport Sync Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 08:33:29 volumio sudo[954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 08:33:29 volumio sudo[954]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:29 volumio sudo[954]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:29 volumio sudo[955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 08:33:29 volumio sudo[955]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:29 volumio volumio[840]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 08:33:29 volumio volumio[840]: info: ___________ START PLUGINS ___________ Aug 27 08:33:29 volumio systemd[1]: Stopping Music Player Daemon... Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 08:33:29 volumio volumio[840]: info: [1693118009418] CoreMusicLibrary::Adding element Media Servers Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 08:33:29 volumio volumio[840]: Starting albumart workers Aug 27 08:33:29 volumio volumio[840]: Starting albumart workers Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 08:33:29 volumio systemd[1]: mpd.service: Succeeded. Aug 27 08:33:29 volumio systemd[1]: Stopped Music Player Daemon. Aug 27 08:33:29 volumio systemd[1]: Starting Music Player Daemon... Aug 27 08:33:29 volumio volumio[840]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 08:33:29 volumio volumio[840]: Starting albumart workers Aug 27 08:33:29 volumio volumio[840]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 08:33:29 volumio volumio[840]: info: [1693118009618] CoreMusicLibrary::Adding element Last_100 Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 08:33:29 volumio volumio[840]: info: [1693118009621] CoreMusicLibrary::Adding element Webradio Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 08:33:29 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 08:33:29 volumio volumio[840]: info: Volumio Calling Home Aug 27 08:33:30 volumio volumio[840]: info: Discovery: adding a26b4b89-1593-439b-8159-eee32069ab2e Aug 27 08:33:30 volumio volumio[840]: info: Discovery: Found device Volumio Aug 27 08:33:30 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 27 08:33:30 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:33:30 volumio volumio[840]: info: MPD Permissions set Aug 27 08:33:30 volumio volumio[840]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Aug 27 08:33:30 volumio volumio[840]: info: Volumio called home Aug 27 08:33:30 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:30 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:30 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:30 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:30 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:30 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:30 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:30 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:30 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 08:33:30 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:30 volumio volumio[840]: info: Starting Shairport Sync Aug 27 08:33:30 volumio volumio[840]: info: Starting Shairport Sync Aug 27 08:33:30 volumio volumio[840]: info: Starting Shairport Sync Aug 27 08:33:30 volumio sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 08:33:30 volumio sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:30 volumio sudo[1004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 08:33:30 volumio sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 27 08:33:30 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 27 08:33:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 27 08:33:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 27 08:33:30 volumio sudo[1008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 08:33:30 volumio sudo[1008]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:30 volumio sudo[1002]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 27 08:33:30 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 27 08:33:30 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 27 08:33:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 27 08:33:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 27 08:33:30 volumio sudo[1004]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:30 volumio volumio[840]: info: Shairport-Sync Started Aug 27 08:33:30 volumio volumio[840]: Error adding Membership: Error: addMembership EINVAL Aug 27 08:33:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 27 08:33:30 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 27 08:33:30 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 27 08:33:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 27 08:33:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 27 08:33:30 volumio volumio[840]: info: Shairport-Sync Started Aug 27 08:33:30 volumio sudo[1008]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:30 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 27 08:33:30 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:33:30 volumio volumio[840]: info: Shairport-Sync Started Aug 27 08:33:31 volumio mpd[970]: Aug 27 08:33 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 08:33:31 volumio mpd[970]: Aug 27 08:33 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 27 08:33:31 volumio mpd[970]: Aug 27 08:33 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 27 08:33:31 volumio systemd[1]: Started Music Player Daemon. Aug 27 08:33:31 volumio sudo[955]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:31 volumio volumio[840]: error: updateQueue error: null Aug 27 08:33:31 volumio volumio[840]: [yt-cast-receiver] Listening on port 8098 Aug 27 08:33:31 volumio volumio[840]: info: Completed starting Core Plugins Aug 27 08:33:31 volumio volumio[840]: info: ------------------------------------------- Aug 27 08:33:31 volumio volumio[840]: info: ----- MyVolumio plugins startup ---- Aug 27 08:33:31 volumio volumio[840]: info: ------------------------------------------- Aug 27 08:33:31 volumio volumio[840]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 27 08:33:32 volumio sudo[1018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 08:33:32 volumio sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:32 volumio sudo[1018]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:32 volumio sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 08:33:32 volumio sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:32 volumio sudo[1020]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:32 volumio sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 27 08:33:32 volumio sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:32 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 27 08:33:32 volumio sudo[1024]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:32 volumio volumio[840]: info: Upmpdcli Daemon Started Aug 27 08:33:33 volumio volumio[1026]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 27 08:33:34 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Aug 27 08:33:36 volumio volumio-remote-updater[503]: [2023-08-27 08:33:36] [connect] Successful connection Aug 27 08:33:36 volumio volumio-remote-updater[503]: [2023-08-27 08:33:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1693118016 101 Aug 27 08:33:36 volumio volumio[840]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Aug 27 08:33:37 volumio volumio[840]: verbose: New Socket.io Connection to 192.168.1.136 from 192.168.1.138 UA: okhttp/4.9.2 Total Clients: 3 Aug 27 08:33:37 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 27 08:33:37 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:33:37 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 08:33:37 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 08:33:49 volumio sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 08:33:49 volumio sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:49 volumio sudo[1045]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:49 volumio sudo[1048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 08:33:49 volumio sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 08:33:49 volumio sudo[1048]: pam_unix(sudo:session): session closed for user root Aug 27 08:33:49 volumio volumio[840]: verbose: New Socket.io Connection to 192.168.1.136 from 192.168.1.138 UA: okhttp/4.9.2 Total Clients: 4 Aug 27 08:33:49 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 27 08:33:49 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 08:33:49 volumio volumio[840]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 08:33:49 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 08:33:49 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 27 08:33:49 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:33:49 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 27 08:33:49 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 08:33:49 volumio volumio[840]: info: Received Get System Info Aug 27 08:33:49 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 08:33:49 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 08:33:49 volumio volumio[840]: info: Discovery: Getting this device information Aug 27 08:33:49 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 27 08:33:49 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:33:49 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 08:33:49 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 27 08:33:49 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:33:49 volumio volumio[840]: info: Listing playlists Aug 27 08:33:49 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 27 08:33:49 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 08:33:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 08:33:51 volumio volumio[840]: info: Received Get System Info Aug 27 08:33:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 08:33:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 08:33:51 volumio volumio[840]: info: Discovery: Getting this device information Aug 27 08:33:51 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 27 08:33:51 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:33:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 08:33:51 volumio volumio[840]: error: MyVolumio Plugin failed to start in a timely fashion Aug 27 08:33:51 volumio volumio[840]: info: BOOT COMPLETED Aug 27 08:33:51 volumio volumio[840]: [Metrics] CommandRouter: 31s 852.08ms Aug 27 08:33:51 volumio volumio[840]: info: CoreCommandRouter::volumiosetStartupVolume Aug 27 08:33:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 08:33:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 08:33:51 volumio volumio[840]: info: CoreCommandRouter::Close All Modals sent Aug 27 08:33:51 volumio volumio[840]: info: CoreCommandRouter::Close All Modals sent Aug 27 08:33:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 27 08:33:52 volumio volumio[840]: info: CoreCommandRouter::volumioGetQueue Aug 27 08:33:52 volumio volumio[840]: info: CoreStateMachine::getQueue Aug 27 08:33:52 volumio volumio[840]: info: CorePlayQueue::getQueue Aug 27 08:33:52 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 08:33:52 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 08:33:52 volumio volumio[840]: info: Discovery: Getting this device information Aug 27 08:33:52 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 27 08:33:52 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:33:52 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 08:33:52 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 27 08:33:52 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 08:33:52 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 27 08:33:52 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 08:33:52 volumio volumio[840]: info: Received Get System Info Aug 27 08:33:52 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 08:33:52 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 08:33:52 volumio volumio[840]: info: Discovery: Getting this device information Aug 27 08:33:52 volumio volumio[840]: info: CoreCommandRouter::volumioGetState Aug 27 08:33:52 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:33:52 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 08:33:53 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 27 08:33:59 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 08:33:59 volumio volumio[840]: info: CURURI: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077 Aug 27 08:33:59 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 08:33:59 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 08:33:59 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 08:33:59 volumio volumio[840]: info: Preload queue cleared Aug 27 08:33:59 volumio volumio[1026]: .......................................................................................................................................................................................................................................++++ Aug 27 08:34:01 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 08:34:01 volumio volumio[840]: info: CURURI: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1 Aug 27 08:34:01 volumio volumio[840]: info: Preload queue cleared Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/01 - Marcin Przybylowicz - V.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/02 - Paul Leonard-Morgan - Extraction Action.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/03 - P.T. Adamczyk - The Rebel Path.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/04 - Paul Leonard-Morgan - The Streets Are Long-Ass Gutters.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/05 - P.T. Adamczyk - Outsider No More.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/06 - Marcin Przybylowicz - Cloudy Day.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/07 - Marcin Przybylowicz - Wushu Dolls.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/08 - P.T. Adamczyk - Scavenger Hunt.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/09 - P.T. Adamczyk - Мусорщики.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/10 - Marcin Przybylowicz - Close Probing.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/11 - Paul Leonard-Morgan - There_s Gonna Be A Parade!.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/12 - Paul Leonard-Morgan - Trouble Finds Trouble.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/13 - P.T. Adamczyk - You Shall Never Have To Forgive Me Again.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/14 - P.T. Adamczyk - Code Red Initiated.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/15 - P.T. Adamczyk - The Heist.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/16 - Marcin Przybylowicz - Streetfighters.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/17 - Marcin Przybylowicz - Patri(di)ots.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/18 - Paul Leonard-Morgan - Mining Minds.flac Aug 27 08:34:01 volumio volumio[840]: info: Preloading song: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/19 - P.T. Adamczyk - Rite Of Passage.flac Aug 27 08:34:01 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/01 - Marcin Przybylowicz - V.flac in service mpd Aug 27 08:34:01 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=Marcin%20Przybylowicz/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F01%20-%20Marcin%20Przybylowicz%20-%20V.flac&metadata=false Aug 27 08:34:01 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/01 - Marcin Przybylowicz - V.flac Aug 27 08:34:01 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/02 - Paul Leonard-Morgan - Extraction Action.flac in service mpd Aug 27 08:34:01 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=Paul%20Leonard-Morgan/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F02%20-%20Paul%20Leonard-Morgan%20-%20Extraction%20Action.flac&metadata=false Aug 27 08:34:01 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/02 - Paul Leonard-Morgan - Extraction Action.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/03 - P.T. Adamczyk - The Rebel Path.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=P.T.%20Adamczyk/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F03%20-%20P.T.%20Adamczyk%20-%20The%20Rebel%20Path.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/03 - P.T. Adamczyk - The Rebel Path.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/04 - Paul Leonard-Morgan - The Streets Are Long-Ass Gutters.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=Paul%20Leonard-Morgan/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F04%20-%20Paul%20Leonard-Morgan%20-%20The%20Streets%20Are%20Long-Ass%20Gutters.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/04 - Paul Leonard-Morgan - The Streets Are Long-Ass Gutters.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/05 - P.T. Adamczyk - Outsider No More.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=P.T.%20Adamczyk/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F05%20-%20P.T.%20Adamczyk%20-%20Outsider%20No%20More.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/05 - P.T. Adamczyk - Outsider No More.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/06 - Marcin Przybylowicz - Cloudy Day.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=Marcin%20Przybylowicz/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F06%20-%20Marcin%20Przybylowicz%20-%20Cloudy%20Day.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/06 - Marcin Przybylowicz - Cloudy Day.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/07 - Marcin Przybylowicz - Wushu Dolls.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=Marcin%20Przybylowicz/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F07%20-%20Marcin%20Przybylowicz%20-%20Wushu%20Dolls.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/07 - Marcin Przybylowicz - Wushu Dolls.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/08 - P.T. Adamczyk - Scavenger Hunt.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=P.T.%20Adamczyk/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F08%20-%20P.T.%20Adamczyk%20-%20Scavenger%20Hunt.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/08 - P.T. Adamczyk - Scavenger Hunt.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/09 - P.T. Adamczyk - Мусорщики.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=P.T.%20Adamczyk/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F09%20-%20P.T.%20Adamczyk%20-%20%D0%9C%D1%83%D1%81%D0%BE%D1%80%D1%89%D0%B8%D0%BA%D0%B8.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/09 - P.T. Adamczyk - Мусорщики.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/10 - Marcin Przybylowicz - Close Probing.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=Marcin%20Przybylowicz/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F10%20-%20Marcin%20Przybylowicz%20-%20Close%20Probing.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/10 - Marcin Przybylowicz - Close Probing.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/11 - Paul Leonard-Morgan - There_s Gonna Be A Parade!.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=Paul%20Leonard-Morgan/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F11%20-%20Paul%20Leonard-Morgan%20-%20There_s%20Gonna%20Be%20A%20Parade!.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/11 - Paul Leonard-Morgan - There_s Gonna Be A Parade!.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/12 - Paul Leonard-Morgan - Trouble Finds Trouble.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=Paul%20Leonard-Morgan/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F12%20-%20Paul%20Leonard-Morgan%20-%20Trouble%20Finds%20Trouble.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/12 - Paul Leonard-Morgan - Trouble Finds Trouble.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/13 - P.T. Adamczyk - You Shall Never Have To Forgive Me Again.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=P.T.%20Adamczyk/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F13%20-%20P.T.%20Adamczyk%20-%20You%20Shall%20Never%20Have%20To%20Forgive%20Me%20Again.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/13 - P.T. Adamczyk - You Shall Never Have To Forgive Me Again.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/14 - P.T. Adamczyk - Code Red Initiated.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=P.T.%20Adamczyk/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F14%20-%20P.T.%20Adamczyk%20-%20Code%20Red%20Initiated.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/14 - P.T. Adamczyk - Code Red Initiated.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/15 - P.T. Adamczyk - The Heist.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=P.T.%20Adamczyk/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F15%20-%20P.T.%20Adamczyk%20-%20The%20Heist.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/15 - P.T. Adamczyk - The Heist.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/16 - Marcin Przybylowicz - Streetfighters.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=Marcin%20Przybylowicz/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F16%20-%20Marcin%20Przybylowicz%20-%20Streetfighters.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/16 - Marcin Przybylowicz - Streetfighters.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/17 - Marcin Przybylowicz - Patri(di)ots.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=Marcin%20Przybylowicz/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F17%20-%20Marcin%20Przybylowicz%20-%20Patri(di)ots.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/17 - Marcin Przybylowicz - Patri(di)ots.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/18 - Paul Leonard-Morgan - Mining Minds.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=Paul%20Leonard-Morgan/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F18%20-%20Paul%20Leonard-Morgan%20-%20Mining%20Minds.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/18 - Paul Leonard-Morgan - Mining Minds.flac Aug 27 08:34:02 volumio volumio[840]: info: Exploding uri music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/19 - P.T. Adamczyk - Rite Of Passage.flac in service mpd Aug 27 08:34:02 volumio volumio[840]: info: ALBUMART /albumart?cacheid=748&web=P.T.%20Adamczyk/Cyberpunk%202077/extralarge&path=%2Fmnt%2FUSB%2F30E6-F47E%2FSoundtracks%2FCyberpunk%202077%2Fcd1%2F19%20-%20P.T.%20Adamczyk%20-%20Rite%20Of%20Passage.flac&metadata=false Aug 27 08:34:02 volumio volumio[840]: info: URI /mnt/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/19 - P.T. Adamczyk - Rite Of Passage.flac Aug 27 08:34:02 volumio volumio[840]: info: Preload queue cleared Aug 27 08:34:02 volumio volumio[840]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::ClearQueue Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::stop Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 08:34:02 volumio volumio[840]: info: CorePlayQueue::clearPlayQueue Aug 27 08:34:02 volumio volumio[840]: info: CorePlayQueue::saveQueue Aug 27 08:34:02 volumio volumio[840]: info: CoreCommandRouter::volumioPushQueue Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::addQueueItems Aug 27 08:34:02 volumio volumio[840]: info: CorePlayQueue::addQueueItems Aug 27 08:34:02 volumio volumio[840]: info: Preload queue cleared Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/01 - Marcin Przybylowicz - V.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/01 - Marcin Przybylowicz - V.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/02 - Paul Leonard-Morgan - Extraction Action.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/02 - Paul Leonard-Morgan - Extraction Action.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/03 - P.T. Adamczyk - The Rebel Path.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/03 - P.T. Adamczyk - The Rebel Path.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/04 - Paul Leonard-Morgan - The Streets Are Long-Ass Gutters.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/04 - Paul Leonard-Morgan - The Streets Are Long-Ass Gutters.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/05 - P.T. Adamczyk - Outsider No More.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/05 - P.T. Adamczyk - Outsider No More.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/06 - Marcin Przybylowicz - Cloudy Day.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/06 - Marcin Przybylowicz - Cloudy Day.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/07 - Marcin Przybylowicz - Wushu Dolls.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/07 - Marcin Przybylowicz - Wushu Dolls.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/08 - P.T. Adamczyk - Scavenger Hunt.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/08 - P.T. Adamczyk - Scavenger Hunt.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/09 - P.T. Adamczyk - Мусорщики.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/09 - P.T. Adamczyk - Мусорщики.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/10 - Marcin Przybylowicz - Close Probing.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/10 - Marcin Przybylowicz - Close Probing.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/11 - Paul Leonard-Morgan - There_s Gonna Be A Parade!.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/11 - Paul Leonard-Morgan - There_s Gonna Be A Parade!.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/12 - Paul Leonard-Morgan - Trouble Finds Trouble.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/12 - Paul Leonard-Morgan - Trouble Finds Trouble.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/13 - P.T. Adamczyk - You Shall Never Have To Forgive Me Again.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/13 - P.T. Adamczyk - You Shall Never Have To Forgive Me Again.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/14 - P.T. Adamczyk - Code Red Initiated.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/14 - P.T. Adamczyk - Code Red Initiated.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/15 - P.T. Adamczyk - The Heist.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/15 - P.T. Adamczyk - The Heist.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/16 - Marcin Przybylowicz - Streetfighters.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/16 - Marcin Przybylowicz - Streetfighters.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/17 - Marcin Przybylowicz - Patri(di)ots.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/17 - Marcin Przybylowicz - Patri(di)ots.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/18 - Paul Leonard-Morgan - Mining Minds.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/18 - Paul Leonard-Morgan - Mining Minds.flac Aug 27 08:34:02 volumio volumio[840]: info: Adding Item to queue: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/19 - P.T. Adamczyk - Rite Of Passage.flac Aug 27 08:34:02 volumio volumio[840]: info: Using cached record of: music-library/USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/19 - P.T. Adamczyk - Rite Of Passage.flac Aug 27 08:34:02 volumio volumio[840]: info: CoreCommandRouter::volumioPushQueue Aug 27 08:34:02 volumio volumio[840]: info: CorePlayQueue::saveQueue Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::updateTrackBlock Aug 27 08:34:02 volumio volumio[840]: info: CorePlayQueue::getTrackBlock Aug 27 08:34:02 volumio volumio[840]: info: CoreCommandRouter::volumioPlay Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::play index 0 Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::stop Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::play index undefined Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 08:34:02 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::startPlaybackTimer Aug 27 08:34:02 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:34:02 volumio volumio[840]: verbose: ControllerMpd::clearAddPlayTracks USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/01 - Marcin Przybylowicz - V.flac Aug 27 08:34:02 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 08:34:02 volumio volumio[840]: info: sendMpdCommand stop took 5 milliseconds Aug 27 08:34:02 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand clear Aug 27 08:34:02 volumio volumio[840]: info: Aug 27 08:34:02 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 27 08:34:02 volumio volumio[840]: info: Ignoring MPD Status Update Aug 27 08:34:02 volumio volumio[840]: info: sendMpdCommand clear took 5 milliseconds Aug 27 08:34:02 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand add "USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/01 - Marcin Przybylowicz - V.flac" Aug 27 08:34:02 volumio volumio[840]: error: updateQueue error: null Aug 27 08:34:02 volumio volumio[840]: info: Aug 27 08:34:02 volumio volumio[840]: ---------------------------- MPD announces system playlist update Aug 27 08:34:02 volumio volumio[840]: info: Ignoring MPD Status Update Aug 27 08:34:02 volumio volumio[840]: info: ------------------------------ 13ms Aug 27 08:34:02 volumio volumio[840]: info: sendMpdCommand add "USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/01 - Marcin Przybylowicz - V.flac" took 11 milliseconds Aug 27 08:34:02 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand play Aug 27 08:34:02 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Aug 27 08:34:02 volumio volumio[840]: info: Aug 27 08:34:02 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 27 08:34:02 volumio volumio[840]: info: ------------------------------ 15ms Aug 27 08:34:02 volumio volumio[840]: info: sendMpdCommand play took 12 milliseconds Aug 27 08:34:02 volumio volumio[840]: info: ControllerMpd::getState Aug 27 08:34:02 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 27 08:34:02 volumio volumio[840]: info: Aug 27 08:34:02 volumio volumio[840]: ---------------------------- MPD announces state update: player Aug 27 08:34:02 volumio volumio[840]: info: ControllerMpd::getState Aug 27 08:34:02 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand status Aug 27 08:34:02 volumio volumio[840]: info: sendMpdCommand status took 7 milliseconds Aug 27 08:34:02 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 27 08:34:02 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 08:34:02 volumio volumio[840]: info: sendMpdCommand status took 10 milliseconds Aug 27 08:34:02 volumio volumio[840]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 27 08:34:02 volumio volumio[840]: verbose: ControllerMpd::parseState Aug 27 08:34:02 volumio volumio[840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 08:34:02 volumio volumio[840]: verbose: ControllerMpd::parseTrackInfo Aug 27 08:34:02 volumio volumio[840]: info: ControllerMpd::pushState Aug 27 08:34:02 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 27 08:34:02 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:34:02 volumio volumio[840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":154,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"V","artist":"Marcin Przybylowicz","album":"Cyberpunk 2077","uri":"USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/01 - Marcin Przybylowicz - V.flac","trackType":"flac"} Aug 27 08:34:02 volumio volumio[840]: verbose: CURRENT POSITION 0 Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::syncState stateService play Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus stop Aug 27 08:34:02 volumio volumio[840]: info: ------------------------------ 31ms Aug 27 08:34:02 volumio volumio[840]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 27 08:34:02 volumio volumio[840]: verbose: ControllerMpd::parseTrackInfo Aug 27 08:34:02 volumio volumio[840]: info: ControllerMpd::pushState Aug 27 08:34:02 volumio volumio[840]: info: CoreCommandRouter::servicePushState Aug 27 08:34:02 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:34:02 volumio volumio[840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":154,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"V","artist":"Marcin Przybylowicz","album":"Cyberpunk 2077","uri":"USB/30E6-F47E/Soundtracks/Cyberpunk 2077/cd1/01 - Marcin Przybylowicz - V.flac","trackType":"flac"} Aug 27 08:34:02 volumio volumio[840]: verbose: CURRENT POSITION 0 Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::syncState stateService play Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::syncState currentStatus play Aug 27 08:34:02 volumio volumio[840]: info: Received an update from plugin. extracting info from payload Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::pushState Aug 27 08:34:02 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:34:02 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 08:34:02 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 27 08:34:02 volumio volumio[840]: info: CoreStateMachine::pushState Aug 27 08:34:02 volumio volumio[840]: info: CorePlayQueue::getTrack 0 Aug 27 08:34:02 volumio volumio[840]: info: CoreCommandRouter::volumioPushState Aug 27 08:34:03 volumio volumio[840]: info: ------------------------------ 63ms Aug 27 08:34:03 volumio volumio[840]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 08:34:03 volumio volumio[840]: Error: certificate is not yet valid Aug 27 08:34:03 volumio volumio[840]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Aug 27 08:34:03 volumio volumio[840]: at TLSSocket.emit (events.js:315:20) Aug 27 08:34:03 volumio volumio[840]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Aug 27 08:34:03 volumio volumio[840]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Aug 27 08:34:03 volumio volumio[840]: code: 'CERT_NOT_YET_VALID' Aug 27 08:34:03 volumio volumio[840]: } Aug 27 08:34:03 volumio volumio[840]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 08:34:04 volumio sudo[1073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-08-27 08:33 Aug 27 08:34:04 volumio sudo[1073]: 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"