-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Fri 2025-12-12 19:25:35 CET. -- Dec 12 19:24:02 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 12 19:24:02 volumio-primo volumio[1305]: info: CURURI: music-library/USB/Elements/Master Superior Audiophile (12 CD) Dec 12 19:24:02 volumio-primo volumio[1305]: info: Preload queue cleared Dec 12 19:24:17 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-DISCONNECTED bssid=08:b6:57:2a:d7:20 reason=34 Dec 12 19:24:17 volumio-primo dhcpcd[1151]: wlan0: carrier lost Dec 12 19:24:17 volumio-primo wpa_supplicant[963]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Dec 12 19:24:17 volumio-primo wpa_supplicant[963]: nl80211: Failed to set IPv4 unicast in multicast filter Dec 12 19:24:17 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Dec 12 19:24:17 volumio-primo kernel: cfg80211: World regulatory domain updated: Dec 12 19:24:17 volumio-primo kernel: cfg80211: DFS Master region: unset Dec 12 19:24:17 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Dec 12 19:24:17 volumio-primo kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:17 volumio-primo kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz, 92000 KHz AUTO), (N/A, 2000 mBm), (N/A) Dec 12 19:24:17 volumio-primo kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:17 volumio-primo kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A) Dec 12 19:24:17 volumio-primo kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s) Dec 12 19:24:17 volumio-primo kernel: cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s) Dec 12 19:24:17 volumio-primo kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:17 volumio-primo kernel: cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A) Dec 12 19:24:17 volumio-primo avahi-daemon[568]: Withdrawing address record for 192.168.178.48 on wlan0. Dec 12 19:24:17 volumio-primo avahi-daemon[568]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.48. Dec 12 19:24:17 volumio-primo avahi-daemon[568]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 12 19:24:17 volumio-primo volumio[1305]: info: Discovery: A device disappeared from network Dec 12 19:24:17 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Dec 12 19:24:17 volumio-primo kernel: cfg80211: Regulatory domain changed to country: DE Dec 12 19:24:17 volumio-primo kernel: cfg80211: DFS Master region: ETSI Dec 12 19:24:17 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Dec 12 19:24:17 volumio-primo kernel: cfg80211: (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:17 volumio-primo kernel: cfg80211: (5150000 KHz - 5250000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (N/A) Dec 12 19:24:17 volumio-primo kernel: cfg80211: (5250000 KHz - 5350000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (0 s) Dec 12 19:24:17 volumio-primo kernel: cfg80211: (5470000 KHz - 5725000 KHz @ 160000 KHz), (N/A, 2698 mBm), (0 s) Dec 12 19:24:17 volumio-primo kernel: cfg80211: (5725000 KHz - 5875000 KHz @ 80000 KHz), (N/A, 1397 mBm), (N/A) Dec 12 19:24:17 volumio-primo kernel: cfg80211: (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A) Dec 12 19:24:17 volumio-primo dhcpcd[1151]: wlan0: deleting route to 192.168.178.0/24 Dec 12 19:24:17 volumio-primo dhcpcd[1151]: wlan0: deleting default route via 192.168.178.1 Dec 12 19:24:17 volumio-primo ntpd[12326]: ntpd exiting on signal 15 (Terminated) Dec 12 19:24:17 volumio-primo ntpd[12326]: 192.168.178.1 local addr 192.168.178.102 -> Dec 12 19:24:17 volumio-primo systemd[1]: Stopping Network Time Service... Dec 12 19:24:17 volumio-primo systemd[1]: ntp.service: Succeeded. Dec 12 19:24:17 volumio-primo systemd[1]: Stopped Network Time Service. Dec 12 19:24:17 volumio-primo systemd[1]: Starting Network Time Service... Dec 12 19:24:17 volumio-primo ntpd[13918]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 12 19:24:17 volumio-primo ntpd[13918]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Dec 12 19:24:17 volumio-primo systemd[1]: Started Network Time Service. Dec 12 19:24:17 volumio-primo ntpd[13924]: proto: precision = 0.583 usec (-21) Dec 12 19:24:17 volumio-primo ntpd[13924]: Listen and drop on 0 v6wildcard [::]:123 Dec 12 19:24:17 volumio-primo ntpd[13924]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 12 19:24:17 volumio-primo ntpd[13924]: Listen normally on 2 lo 127.0.0.1:123 Dec 12 19:24:17 volumio-primo ntpd[13924]: Listen normally on 3 eth0 192.168.178.102:123 Dec 12 19:24:17 volumio-primo ntpd[13924]: Listening on routing socket on fd #20 for interface updates Dec 12 19:24:17 volumio-primo ntpd[13924]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 12 19:24:17 volumio-primo ntpd[13924]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 12 19:24:18 volumio-primo wpa_supplicant[963]: wlan0: Trying to associate with 08:b6:57:2a:d7:20 (SSID='FRITZ!Box 6670 QI' freq=2462 MHz) Dec 12 19:24:18 volumio-primo wpa_supplicant[963]: wlan0: Associated with 08:b6:57:2a:d7:20 Dec 12 19:24:18 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 12 19:24:18 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Dec 12 19:24:18 volumio-primo wpa_supplicant[963]: wlan0: WPA: Key negotiation completed with 08:b6:57:2a:d7:20 [PTK=CCMP GTK=CCMP] Dec 12 19:24:18 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-CONNECTED - Connection to 08:b6:57:2a:d7:20 completed [id=1 id_str=] Dec 12 19:24:18 volumio-primo dhcpcd[1151]: wlan0: carrier acquired Dec 12 19:24:18 volumio-primo dhcpcd[1151]: wlan0: IAID 05:5f:ea:cb Dec 12 19:24:18 volumio-primo kernel: cfg80211: Regulatory domain changed to country: DE Dec 12 19:24:18 volumio-primo kernel: cfg80211: DFS Master region: ETSI Dec 12 19:24:18 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Dec 12 19:24:18 volumio-primo kernel: cfg80211: (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:18 volumio-primo kernel: cfg80211: (5150000 KHz - 5250000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (N/A) Dec 12 19:24:18 volumio-primo kernel: cfg80211: (5250000 KHz - 5350000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (0 s) Dec 12 19:24:18 volumio-primo kernel: cfg80211: (5470000 KHz - 5725000 KHz @ 160000 KHz), (N/A, 2698 mBm), (0 s) Dec 12 19:24:18 volumio-primo kernel: cfg80211: (5725000 KHz - 5875000 KHz @ 80000 KHz), (N/A, 1397 mBm), (N/A) Dec 12 19:24:18 volumio-primo kernel: cfg80211: (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A) Dec 12 19:24:18 volumio-primo dhcpcd[1151]: wlan0: rebinding lease of 192.168.178.48 Dec 12 19:24:18 volumio-primo dhcpcd[1151]: wlan0: probing address 192.168.178.48/24 Dec 12 19:24:19 volumio-primo dhcpcd[1151]: wlan0: soliciting an IPv6 router Dec 12 19:24:21 volumio-primo volumio[1305]: info: Volumio Network Manager: Network status updated: 1 Dec 12 19:24:24 volumio-primo go-librespot[1945]: time="2025-12-12T19:24:24+01:00" level=trace msg="sent dealer ping" Dec 12 19:24:24 volumio-primo dhcpcd[1151]: wlan0: leased 192.168.178.48 for 864000 seconds Dec 12 19:24:24 volumio-primo dhcpcd[1151]: wlan0: adding route to 192.168.178.0/24 Dec 12 19:24:24 volumio-primo dhcpcd[1151]: wlan0: adding default route via 192.168.178.1 Dec 12 19:24:24 volumio-primo avahi-daemon[568]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.48. Dec 12 19:24:24 volumio-primo avahi-daemon[568]: New relevant interface wlan0.IPv4 for mDNS. Dec 12 19:24:24 volumio-primo avahi-daemon[568]: Registering new address record for 192.168.178.48 on wlan0.IPv4. Dec 12 19:24:24 volumio-primo go-librespot[1945]: time="2025-12-12T19:24:24+01:00" level=trace msg="received dealer pong" Dec 12 19:24:24 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-DISCONNECTED bssid=08:b6:57:2a:d7:20 reason=34 Dec 12 19:24:24 volumio-primo wpa_supplicant[963]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Dec 12 19:24:24 volumio-primo wpa_supplicant[963]: nl80211: Failed to set IPv4 unicast in multicast filter Dec 12 19:24:24 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Dec 12 19:24:24 volumio-primo ntpd[13924]: ntpd exiting on signal 15 (Terminated) Dec 12 19:24:24 volumio-primo ntpd[13924]: 192.168.178.1 local addr 192.168.178.102 -> Dec 12 19:24:24 volumio-primo systemd[1]: Stopping Network Time Service... Dec 12 19:24:24 volumio-primo kernel: cfg80211: World regulatory domain updated: Dec 12 19:24:24 volumio-primo kernel: cfg80211: DFS Master region: unset Dec 12 19:24:24 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Dec 12 19:24:24 volumio-primo kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:24 volumio-primo kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz, 92000 KHz AUTO), (N/A, 2000 mBm), (N/A) Dec 12 19:24:24 volumio-primo kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:24 volumio-primo kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A) Dec 12 19:24:24 volumio-primo kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s) Dec 12 19:24:24 volumio-primo kernel: cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s) Dec 12 19:24:24 volumio-primo kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:24 volumio-primo kernel: cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A) Dec 12 19:24:24 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Dec 12 19:24:24 volumio-primo kernel: cfg80211: Regulatory domain changed to country: DE Dec 12 19:24:24 volumio-primo kernel: cfg80211: DFS Master region: ETSI Dec 12 19:24:24 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Dec 12 19:24:24 volumio-primo kernel: cfg80211: (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:24 volumio-primo kernel: cfg80211: (5150000 KHz - 5250000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (N/A) Dec 12 19:24:24 volumio-primo kernel: cfg80211: (5250000 KHz - 5350000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (0 s) Dec 12 19:24:24 volumio-primo kernel: cfg80211: (5470000 KHz - 5725000 KHz @ 160000 KHz), (N/A, 2698 mBm), (0 s) Dec 12 19:24:24 volumio-primo kernel: cfg80211: (5725000 KHz - 5875000 KHz @ 80000 KHz), (N/A, 1397 mBm), (N/A) Dec 12 19:24:24 volumio-primo kernel: cfg80211: (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A) Dec 12 19:24:24 volumio-primo systemd[1]: ntp.service: Succeeded. Dec 12 19:24:24 volumio-primo systemd[1]: Stopped Network Time Service. Dec 12 19:24:24 volumio-primo systemd[1]: Starting Network Time Service... Dec 12 19:24:24 volumio-primo ntpd[13986]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 12 19:24:24 volumio-primo ntpd[13986]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Dec 12 19:24:24 volumio-primo systemd[1]: Started Network Time Service. Dec 12 19:24:24 volumio-primo dhcpcd[1151]: wlan0: carrier lost Dec 12 19:24:24 volumio-primo ntpd[13992]: proto: precision = 0.583 usec (-21) Dec 12 19:24:24 volumio-primo ntpd[13992]: Listen and drop on 0 v6wildcard [::]:123 Dec 12 19:24:24 volumio-primo ntpd[13992]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 12 19:24:24 volumio-primo ntpd[13992]: Listen normally on 2 lo 127.0.0.1:123 Dec 12 19:24:24 volumio-primo ntpd[13992]: Listen normally on 3 eth0 192.168.178.102:123 Dec 12 19:24:24 volumio-primo ntpd[13992]: Listening on routing socket on fd #20 for interface updates Dec 12 19:24:24 volumio-primo ntpd[13992]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 12 19:24:24 volumio-primo ntpd[13992]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 12 19:24:24 volumio-primo avahi-daemon[568]: Withdrawing address record for 192.168.178.48 on wlan0. Dec 12 19:24:24 volumio-primo avahi-daemon[568]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.48. Dec 12 19:24:24 volumio-primo avahi-daemon[568]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 12 19:24:24 volumio-primo volumio[1305]: info: Discovery: A device disappeared from network Dec 12 19:24:24 volumio-primo dhcpcd[1151]: wlan0: deleting route to 192.168.178.0/24 Dec 12 19:24:24 volumio-primo dhcpcd[1151]: wlan0: deleting default route via 192.168.178.1 Dec 12 19:24:24 volumio-primo ntpd[13992]: ntpd exiting on signal 15 (Terminated) Dec 12 19:24:24 volumio-primo ntpd[13992]: 192.168.178.1 local addr 192.168.178.102 -> Dec 12 19:24:24 volumio-primo systemd[1]: Stopping Network Time Service... Dec 12 19:24:24 volumio-primo systemd[1]: ntp.service: Succeeded. Dec 12 19:24:24 volumio-primo systemd[1]: Stopped Network Time Service. Dec 12 19:24:24 volumio-primo systemd[1]: Starting Network Time Service... Dec 12 19:24:24 volumio-primo ntpd[14045]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 12 19:24:24 volumio-primo ntpd[14045]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Dec 12 19:24:24 volumio-primo systemd[1]: Started Network Time Service. Dec 12 19:24:24 volumio-primo ntpd[14051]: proto: precision = 0.583 usec (-21) Dec 12 19:24:24 volumio-primo ntpd[14051]: Listen and drop on 0 v6wildcard [::]:123 Dec 12 19:24:24 volumio-primo ntpd[14051]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 12 19:24:24 volumio-primo ntpd[14051]: Listen normally on 2 lo 127.0.0.1:123 Dec 12 19:24:24 volumio-primo ntpd[14051]: Listen normally on 3 eth0 192.168.178.102:123 Dec 12 19:24:24 volumio-primo ntpd[14051]: Listening on routing socket on fd #20 for interface updates Dec 12 19:24:24 volumio-primo ntpd[14051]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 12 19:24:24 volumio-primo ntpd[14051]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 12 19:24:25 volumio-primo wpa_supplicant[963]: wlan0: Trying to associate with 08:b6:57:2a:d7:20 (SSID='FRITZ!Box 6670 QI' freq=2462 MHz) Dec 12 19:24:25 volumio-primo wpa_supplicant[963]: wlan0: Associated with 08:b6:57:2a:d7:20 Dec 12 19:24:25 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 12 19:24:25 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Dec 12 19:24:25 volumio-primo kernel: cfg80211: Regulatory domain changed to country: DE Dec 12 19:24:25 volumio-primo kernel: cfg80211: DFS Master region: ETSI Dec 12 19:24:25 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Dec 12 19:24:25 volumio-primo kernel: cfg80211: (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:25 volumio-primo kernel: cfg80211: (5150000 KHz - 5250000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (N/A) Dec 12 19:24:25 volumio-primo kernel: cfg80211: (5250000 KHz - 5350000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (0 s) Dec 12 19:24:25 volumio-primo kernel: cfg80211: (5470000 KHz - 5725000 KHz @ 160000 KHz), (N/A, 2698 mBm), (0 s) Dec 12 19:24:25 volumio-primo kernel: cfg80211: (5725000 KHz - 5875000 KHz @ 80000 KHz), (N/A, 1397 mBm), (N/A) Dec 12 19:24:25 volumio-primo kernel: cfg80211: (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A) Dec 12 19:24:25 volumio-primo wpa_supplicant[963]: wlan0: WPA: Key negotiation completed with 08:b6:57:2a:d7:20 [PTK=CCMP GTK=CCMP] Dec 12 19:24:25 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-CONNECTED - Connection to 08:b6:57:2a:d7:20 completed [id=1 id_str=] Dec 12 19:24:25 volumio-primo dhcpcd[1151]: wlan0: carrier acquired Dec 12 19:24:25 volumio-primo dhcpcd[1151]: wlan0: IAID 05:5f:ea:cb Dec 12 19:24:25 volumio-primo dhcpcd[1151]: wlan0: soliciting an IPv6 router Dec 12 19:24:26 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 12 19:24:26 volumio-primo volumio[1305]: info: CURURI: music-library/USB/Elements/Diana Krall - This Dream Of You Dec 12 19:24:26 volumio-primo volumio[1305]: info: Preload queue cleared Dec 12 19:24:26 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Diana Krall - This Dream Of You/01 But Beautiful.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Diana Krall - This Dream Of You/02 That's All _ Azure-Te.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Diana Krall - This Dream Of You/03 Autumn In New York.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Diana Krall - This Dream Of You/04 Almost Like Being In Love.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Diana Krall - This Dream Of You/05 More Than You Know.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Diana Krall - This Dream Of You/06 Just You, Just Me.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Diana Krall - This Dream Of You/07 There's No You.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Diana Krall - This Dream Of You/08 Don’t Smoke In Bed.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Diana Krall - This Dream Of You/09 This Dream Of You.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Diana Krall - This Dream Of You/10 I Wished On The Moon.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Diana Krall - This Dream Of You/11 How Deep Is The Ocean.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Diana Krall - This Dream Of You/12 Singing In The Rain.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Diana Krall - This Dream Of You/01 But Beautiful.flac in service mpd Dec 12 19:24:26 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Diana%20Krall/This%20Dream%20Of%20You/extralarge&path=%2Fmnt%2FUSB%2FElements%2FDiana%20Krall%20-%20This%20Dream%20Of%20You%2F01%20But%20Beautiful.flac&metadata=false Dec 12 19:24:26 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Diana Krall - This Dream Of You/01 But Beautiful.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Diana Krall - This Dream Of You/02 That's All _ Azure-Te.flac in service mpd Dec 12 19:24:26 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Diana%20Krall/This%20Dream%20Of%20You/extralarge&path=%2Fmnt%2FUSB%2FElements%2FDiana%20Krall%20-%20This%20Dream%20Of%20You%2F02%20That's%20All%20_%20Azure-Te.flac&metadata=false Dec 12 19:24:26 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Diana Krall - This Dream Of You/02 That's All _ Azure-Te.flac Dec 12 19:24:26 volumio-primo dhcpcd[1151]: wlan0: rebinding lease of 192.168.178.48 Dec 12 19:24:26 volumio-primo dhcpcd[1151]: wlan0: probing address 192.168.178.48/24 Dec 12 19:24:26 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Diana Krall - This Dream Of You/03 Autumn In New York.flac in service mpd Dec 12 19:24:26 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Diana%20Krall/This%20Dream%20Of%20You/extralarge&path=%2Fmnt%2FUSB%2FElements%2FDiana%20Krall%20-%20This%20Dream%20Of%20You%2F03%20Autumn%20In%20New%20York.flac&metadata=false Dec 12 19:24:26 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Diana Krall - This Dream Of You/03 Autumn In New York.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Diana Krall - This Dream Of You/04 Almost Like Being In Love.flac in service mpd Dec 12 19:24:26 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Diana%20Krall/This%20Dream%20Of%20You/extralarge&path=%2Fmnt%2FUSB%2FElements%2FDiana%20Krall%20-%20This%20Dream%20Of%20You%2F04%20Almost%20Like%20Being%20In%20Love.flac&metadata=false Dec 12 19:24:26 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Diana Krall - This Dream Of You/04 Almost Like Being In Love.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Diana Krall - This Dream Of You/05 More Than You Know.flac in service mpd Dec 12 19:24:26 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Diana%20Krall/This%20Dream%20Of%20You/extralarge&path=%2Fmnt%2FUSB%2FElements%2FDiana%20Krall%20-%20This%20Dream%20Of%20You%2F05%20More%20Than%20You%20Know.flac&metadata=false Dec 12 19:24:26 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Diana Krall - This Dream Of You/05 More Than You Know.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Diana Krall - This Dream Of You/06 Just You, Just Me.flac in service mpd Dec 12 19:24:26 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Diana%20Krall/This%20Dream%20Of%20You/extralarge&path=%2Fmnt%2FUSB%2FElements%2FDiana%20Krall%20-%20This%20Dream%20Of%20You%2F06%20Just%20You%2C%20Just%20Me.flac&metadata=false Dec 12 19:24:26 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Diana Krall - This Dream Of You/06 Just You, Just Me.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Diana Krall - This Dream Of You/07 There's No You.flac in service mpd Dec 12 19:24:26 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Diana%20Krall/This%20Dream%20Of%20You/extralarge&path=%2Fmnt%2FUSB%2FElements%2FDiana%20Krall%20-%20This%20Dream%20Of%20You%2F07%20There's%20No%20You.flac&metadata=false Dec 12 19:24:26 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Diana Krall - This Dream Of You/07 There's No You.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Diana Krall - This Dream Of You/08 Don’t Smoke In Bed.flac in service mpd Dec 12 19:24:26 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Diana%20Krall/This%20Dream%20Of%20You/extralarge&path=%2Fmnt%2FUSB%2FElements%2FDiana%20Krall%20-%20This%20Dream%20Of%20You%2F08%20Don%E2%80%99t%20Smoke%20In%20Bed.flac&metadata=false Dec 12 19:24:26 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Diana Krall - This Dream Of You/08 Don’t Smoke In Bed.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Diana Krall - This Dream Of You/09 This Dream Of You.flac in service mpd Dec 12 19:24:26 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Diana%20Krall/This%20Dream%20Of%20You/extralarge&path=%2Fmnt%2FUSB%2FElements%2FDiana%20Krall%20-%20This%20Dream%20Of%20You%2F09%20This%20Dream%20Of%20You.flac&metadata=false Dec 12 19:24:26 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Diana Krall - This Dream Of You/09 This Dream Of You.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Diana Krall - This Dream Of You/10 I Wished On The Moon.flac in service mpd Dec 12 19:24:26 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Diana%20Krall/This%20Dream%20Of%20You/extralarge&path=%2Fmnt%2FUSB%2FElements%2FDiana%20Krall%20-%20This%20Dream%20Of%20You%2F10%20I%20Wished%20On%20The%20Moon.flac&metadata=false Dec 12 19:24:26 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Diana Krall - This Dream Of You/10 I Wished On The Moon.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Diana Krall - This Dream Of You/11 How Deep Is The Ocean.flac in service mpd Dec 12 19:24:26 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Diana%20Krall/This%20Dream%20Of%20You/extralarge&path=%2Fmnt%2FUSB%2FElements%2FDiana%20Krall%20-%20This%20Dream%20Of%20You%2F11%20How%20Deep%20Is%20The%20Ocean.flac&metadata=false Dec 12 19:24:26 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Diana Krall - This Dream Of You/11 How Deep Is The Ocean.flac Dec 12 19:24:26 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Diana Krall - This Dream Of You/12 Singing In The Rain.flac in service mpd Dec 12 19:24:26 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Diana%20Krall/This%20Dream%20Of%20You/extralarge&path=%2Fmnt%2FUSB%2FElements%2FDiana%20Krall%20-%20This%20Dream%20Of%20You%2F12%20Singing%20In%20The%20Rain.flac&metadata=false Dec 12 19:24:26 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Diana Krall - This Dream Of You/12 Singing In The Rain.flac Dec 12 19:24:29 volumio-primo volumio[1305]: info: Discovery: Browse raised the following error Error: dns service error: unknown Dec 12 19:24:30 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-DISCONNECTED bssid=08:b6:57:2a:d7:20 reason=34 Dec 12 19:24:30 volumio-primo dhcpcd[1151]: wlan0: carrier lost Dec 12 19:24:30 volumio-primo wpa_supplicant[963]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Dec 12 19:24:30 volumio-primo wpa_supplicant[963]: nl80211: Failed to set IPv4 unicast in multicast filter Dec 12 19:24:30 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Dec 12 19:24:30 volumio-primo kernel: cfg80211: World regulatory domain updated: Dec 12 19:24:30 volumio-primo kernel: cfg80211: DFS Master region: unset Dec 12 19:24:30 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Dec 12 19:24:30 volumio-primo kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:30 volumio-primo kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz, 92000 KHz AUTO), (N/A, 2000 mBm), (N/A) Dec 12 19:24:30 volumio-primo kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:30 volumio-primo kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A) Dec 12 19:24:30 volumio-primo kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s) Dec 12 19:24:30 volumio-primo kernel: cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s) Dec 12 19:24:30 volumio-primo kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:30 volumio-primo kernel: cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A) Dec 12 19:24:30 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Dec 12 19:24:30 volumio-primo kernel: cfg80211: Regulatory domain changed to country: DE Dec 12 19:24:30 volumio-primo kernel: cfg80211: DFS Master region: ETSI Dec 12 19:24:30 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Dec 12 19:24:30 volumio-primo kernel: cfg80211: (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:30 volumio-primo kernel: cfg80211: (5150000 KHz - 5250000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (N/A) Dec 12 19:24:30 volumio-primo kernel: cfg80211: (5250000 KHz - 5350000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (0 s) Dec 12 19:24:30 volumio-primo kernel: cfg80211: (5470000 KHz - 5725000 KHz @ 160000 KHz), (N/A, 2698 mBm), (0 s) Dec 12 19:24:30 volumio-primo kernel: cfg80211: (5725000 KHz - 5875000 KHz @ 80000 KHz), (N/A, 1397 mBm), (N/A) Dec 12 19:24:30 volumio-primo kernel: cfg80211: (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A) Dec 12 19:24:30 volumio-primo ntpd[14051]: ntpd exiting on signal 15 (Terminated) Dec 12 19:24:30 volumio-primo ntpd[14051]: 192.168.178.1 local addr 192.168.178.102 -> Dec 12 19:24:30 volumio-primo systemd[1]: Stopping Network Time Service... Dec 12 19:24:30 volumio-primo systemd[1]: ntp.service: Succeeded. Dec 12 19:24:30 volumio-primo systemd[1]: Stopped Network Time Service. Dec 12 19:24:30 volumio-primo systemd[1]: Starting Network Time Service... Dec 12 19:24:30 volumio-primo ntpd[14102]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 12 19:24:30 volumio-primo ntpd[14102]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Dec 12 19:24:30 volumio-primo systemd[1]: Started Network Time Service. Dec 12 19:24:30 volumio-primo ntpd[14108]: proto: precision = 0.583 usec (-21) Dec 12 19:24:30 volumio-primo ntpd[14108]: Listen and drop on 0 v6wildcard [::]:123 Dec 12 19:24:30 volumio-primo ntpd[14108]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 12 19:24:30 volumio-primo ntpd[14108]: Listen normally on 2 lo 127.0.0.1:123 Dec 12 19:24:30 volumio-primo ntpd[14108]: Listen normally on 3 eth0 192.168.178.102:123 Dec 12 19:24:30 volumio-primo ntpd[14108]: Listening on routing socket on fd #20 for interface updates Dec 12 19:24:30 volumio-primo ntpd[14108]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 12 19:24:30 volumio-primo ntpd[14108]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 12 19:24:31 volumio-primo wpa_supplicant[963]: wlan0: Trying to associate with 08:b6:57:2a:d7:20 (SSID='FRITZ!Box 6670 QI' freq=2462 MHz) Dec 12 19:24:31 volumio-primo wpa_supplicant[963]: wlan0: Associated with 08:b6:57:2a:d7:20 Dec 12 19:24:31 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 12 19:24:31 volumio-primo wpa_supplicant[963]: wlan0: WPA: Key negotiation completed with 08:b6:57:2a:d7:20 [PTK=CCMP GTK=CCMP] Dec 12 19:24:31 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-CONNECTED - Connection to 08:b6:57:2a:d7:20 completed [id=1 id_str=] Dec 12 19:24:31 volumio-primo dhcpcd[1151]: wlan0: carrier acquired Dec 12 19:24:31 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Dec 12 19:24:31 volumio-primo kernel: cfg80211: Regulatory domain changed to country: DE Dec 12 19:24:31 volumio-primo kernel: cfg80211: DFS Master region: ETSI Dec 12 19:24:31 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Dec 12 19:24:31 volumio-primo kernel: cfg80211: (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:31 volumio-primo kernel: cfg80211: (5150000 KHz - 5250000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (N/A) Dec 12 19:24:31 volumio-primo kernel: cfg80211: (5250000 KHz - 5350000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (0 s) Dec 12 19:24:31 volumio-primo kernel: cfg80211: (5470000 KHz - 5725000 KHz @ 160000 KHz), (N/A, 2698 mBm), (0 s) Dec 12 19:24:31 volumio-primo kernel: cfg80211: (5725000 KHz - 5875000 KHz @ 80000 KHz), (N/A, 1397 mBm), (N/A) Dec 12 19:24:31 volumio-primo kernel: cfg80211: (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A) Dec 12 19:24:31 volumio-primo dhcpcd[1151]: wlan0: IAID 05:5f:ea:cb Dec 12 19:24:31 volumio-primo dhcpcd[1151]: wlan0: rebinding lease of 192.168.178.48 Dec 12 19:24:31 volumio-primo dhcpcd[1151]: wlan0: probing address 192.168.178.48/24 Dec 12 19:24:32 volumio-primo dhcpcd[1151]: wlan0: soliciting an IPv6 router Dec 12 19:24:36 volumio-primo dhcpcd[1151]: wlan0: leased 192.168.178.48 for 864000 seconds Dec 12 19:24:36 volumio-primo avahi-daemon[568]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.48. Dec 12 19:24:36 volumio-primo dhcpcd[1151]: wlan0: adding route to 192.168.178.0/24 Dec 12 19:24:36 volumio-primo avahi-daemon[568]: New relevant interface wlan0.IPv4 for mDNS. Dec 12 19:24:36 volumio-primo dhcpcd[1151]: wlan0: adding default route via 192.168.178.1 Dec 12 19:24:36 volumio-primo avahi-daemon[568]: Registering new address record for 192.168.178.48 on wlan0.IPv4. Dec 12 19:24:36 volumio-primo ntpd[14108]: ntpd exiting on signal 15 (Terminated) Dec 12 19:24:36 volumio-primo ntpd[14108]: 192.168.178.1 local addr 192.168.178.102 -> Dec 12 19:24:36 volumio-primo systemd[1]: Stopping Network Time Service... Dec 12 19:24:36 volumio-primo systemd[1]: ntp.service: Succeeded. Dec 12 19:24:36 volumio-primo systemd[1]: Stopped Network Time Service. Dec 12 19:24:36 volumio-primo systemd[1]: Starting Network Time Service... Dec 12 19:24:36 volumio-primo ntpd[14170]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 12 19:24:36 volumio-primo ntpd[14170]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Dec 12 19:24:36 volumio-primo systemd[1]: Started Network Time Service. Dec 12 19:24:36 volumio-primo ntpd[14176]: proto: precision = 0.583 usec (-21) Dec 12 19:24:36 volumio-primo ntpd[14176]: Listen and drop on 0 v6wildcard [::]:123 Dec 12 19:24:36 volumio-primo ntpd[14176]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 12 19:24:36 volumio-primo ntpd[14176]: Listen normally on 2 lo 127.0.0.1:123 Dec 12 19:24:36 volumio-primo ntpd[14176]: Listen normally on 3 eth0 192.168.178.102:123 Dec 12 19:24:36 volumio-primo ntpd[14176]: Listen normally on 4 wlan0 192.168.178.48:123 Dec 12 19:24:36 volumio-primo ntpd[14176]: Listening on routing socket on fd #21 for interface updates Dec 12 19:24:36 volumio-primo ntpd[14176]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 12 19:24:36 volumio-primo ntpd[14176]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 12 19:24:37 volumio-primo volumio[1305]: info: Discovery: adding bd94b6c7-9cd5-4dce-ae63-20809a124570 Dec 12 19:24:37 volumio-primo volumio[1305]: info: Discovery: Found device Volumio Primo Dec 12 19:24:37 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioGetState Dec 12 19:24:37 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:24:37 volumio-primo volumio[1305]: verbose: New Socket.io Connection to 192.168.178.48:3000 from 192.168.178.49 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 12 19:24:37 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 19:24:37 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 19:24:37 volumio-primo volumio[1305]: info: Discovery: Getting this device information Dec 12 19:24:37 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioGetState Dec 12 19:24:37 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:24:37 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 19:24:41 volumio-primo volumio[1305]: info: Volumio Network Manager: Network status updated: 3 Dec 12 19:24:44 volumio-primo volumiologrotate[557]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Dec 12 19:24:44 volumio-primo volumiologrotate[557]: ls: cannot access 'PRIMO': No such file or directory Dec 12 19:24:44 volumio-primo volumio[1305]: info: Preload queue cleared Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::ClearQueue Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::stop Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::stPlaybackTimer Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::updateTrackBlock Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrackBlock Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::serviceStop Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::serviceStop Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::stop Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::clearPlayQueue Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::saveQueue Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushQueue Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::addQueueItems Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::addQueueItems Dec 12 19:24:44 volumio-primo volumio[1305]: info: Preload queue cleared Dec 12 19:24:44 volumio-primo volumio[1305]: info: Adding Item to queue: music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res Dec 12 19:24:44 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res in service mpd Dec 12 19:24:44 volumio-primo volumio[1305]: info: Dec 12 19:24:44 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand stop took 9 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:24:44 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F01.%20%C3%93%20Deus%20de%20Amor.flac&metadata=false Dec 12 19:24:44 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac Dec 12 19:24:44 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F02.%20Do%20Santo%20S%C3%A1bado%20%C3%89s%20Senhor.flac&metadata=false Dec 12 19:24:44 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/02. Do Santo Sábado És Senhor.flac Dec 12 19:24:44 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F03.%20Bem%20de%20Manh%C3%A3.flac&metadata=false Dec 12 19:24:44 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/03. Bem de Manhã.flac Dec 12 19:24:44 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F04.%20Querido%20Jesus.flac&metadata=false Dec 12 19:24:44 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/04. Querido Jesus.flac Dec 12 19:24:44 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F05.%20Seu%20Maravilhoso%20Olhar.flac&metadata=false Dec 12 19:24:44 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/05. Seu Maravilhoso Olhar.flac Dec 12 19:24:44 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F06.%20Saudade.flac&metadata=false Dec 12 19:24:44 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/06. Saudade.flac Dec 12 19:24:44 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F07.%20Muito%20Al%C3%A9m%20do%20Sol.flac&metadata=false Dec 12 19:24:44 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/07. Muito Além do Sol.flac Dec 12 19:24:44 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F08.%20Abre%2C%20Senhor%2C%20Os%20Olhos%20Meus.flac&metadata=false Dec 12 19:24:44 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/08. Abre, Senhor, Os Olhos Meus.flac Dec 12 19:24:44 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F09.%20Que%20Te%20Darei%2C%20Meu%20Mestre_.flac&metadata=false Dec 12 19:24:44 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/09. Que Te Darei, Meu Mestre_.flac Dec 12 19:24:44 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F10.%20N%C3%A3o%20Ando%20S%C3%B3.flac&metadata=false Dec 12 19:24:44 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/10. Não Ando Só.flac Dec 12 19:24:44 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F11.%20Quero%20Estar%20Ao%20P%C3%A9%20da%20Cruz.flac&metadata=false Dec 12 19:24:44 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/11. Quero Estar Ao Pé da Cruz.flac Dec 12 19:24:44 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F12.%20Ser%20Igual%20a%20Cristo.flac&metadata=false Dec 12 19:24:44 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/12. Ser Igual a Cristo.flac Dec 12 19:24:44 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F13.%20D%C3%A1-Me%20Tua%20Paz.flac&metadata=false Dec 12 19:24:44 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/13. Dá-Me Tua Paz.flac Dec 12 19:24:44 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F14.%20Posl%C3%BAdio.flac&metadata=false Dec 12 19:24:44 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/14. Poslúdio.flac Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand status took 4 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushQueue Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::saveQueue Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::updateTrackBlock Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrackBlock Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPlay Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::play index 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::stop Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::play index undefined Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::startPlaybackTimer Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::clearAddPlayTracks USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 19:24:44 volumio-primo volumio[1305]: info: Dec 12 19:24:44 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:24:44 volumio-primo volumio[1305]: info: Dec 12 19:24:44 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand stop took 2 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand status took 1 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::parseTrackInfo Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand clear Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dance Lady Gipsy","artist":"Michael Schenker & Gary Barden","album":"Wine & Vocals","uri":"USB/Elements/VA - A Tasty Sound Collection-Wine & Vocals/02. Dance Lady Gipsy.flac","trackType":"flac"} Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: CURRENT POSITION 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService stop Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus stop Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: No code Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: ------------------------------ 19ms Dec 12 19:24:44 volumio-primo volumio[1305]: info: Dec 12 19:24:44 volumio-primo volumio[1305]: ---------------------------- MPD announces system playlist update Dec 12 19:24:44 volumio-primo volumio[1305]: info: Ignoring MPD Status Update Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand clear took 10 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand add "USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac" Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::parseTrackInfo Dec 12 19:24:44 volumio-primo volumio[1305]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 12 19:24:44 volumio-primo volumio[1305]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 12 19:24:44 volumio-primo volumio[1305]: info: ------------------------------ 12ms Dec 12 19:24:44 volumio-primo volumio[1305]: info: ------------------------------ 12ms Dec 12 19:24:44 volumio-primo volumio[1305]: info: Dec 12 19:24:44 volumio-primo volumio[1305]: ---------------------------- MPD announces system playlist update Dec 12 19:24:44 volumio-primo volumio[1305]: info: Ignoring MPD Status Update Dec 12 19:24:44 volumio-primo volumio[1305]: info: Dec 12 19:24:44 volumio-primo volumio[1305]: ---------------------------- MPD announces system playlist update Dec 12 19:24:44 volumio-primo volumio[1305]: info: Ignoring MPD Status Update Dec 12 19:24:44 volumio-primo volumio[1305]: error: updateQueue error: null Dec 12 19:24:44 volumio-primo volumio[1305]: info: Dec 12 19:24:44 volumio-primo volumio[1305]: ---------------------------- MPD announces system playlist update Dec 12 19:24:44 volumio-primo volumio[1305]: info: Ignoring MPD Status Update Dec 12 19:24:44 volumio-primo volumio[1305]: info: ------------------------------ 3ms Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand add "USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac" took 3 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: info: ------------------------------ 2ms Dec 12 19:24:44 volumio-primo volumio[1305]: info: ------------------------------ 1ms Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand play Dec 12 19:24:44 volumio-primo volumio[1305]: info: Dec 12 19:24:44 volumio-primo volumio[1305]: ---------------------------- MPD announces system playlist update Dec 12 19:24:44 volumio-primo volumio[1305]: info: Ignoring MPD Status Update Dec 12 19:24:44 volumio-primo volumio[1305]: info: Dec 12 19:24:44 volumio-primo volumio[1305]: ---------------------------- MPD announces system playlist update Dec 12 19:24:44 volumio-primo volumio[1305]: info: Ignoring MPD Status Update Dec 12 19:24:44 volumio-primo volumio[1305]: info: ------------------------------ 5ms Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand play took 4 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: info: ------------------------------ 4ms Dec 12 19:24:44 volumio-primo volumio[1305]: info: ------------------------------ 3ms Dec 12 19:24:44 volumio-primo volumio[1305]: info: Dec 12 19:24:44 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:24:44 volumio-primo volumio[1305]: info: Dec 12 19:24:44 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:24:44 volumio-primo volumio[1305]: info: Dec 12 19:24:44 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:24:44 volumio-primo volumio[1305]: info: Dec 12 19:24:44 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand status took 2 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand status took 2 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand status took 2 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:24:44 volumio-primo volumio[1305]: info: Dec 12 19:24:44 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:24:44 volumio-primo volumio[1305]: info: Dec 12 19:24:44 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand status took 11 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand status took 9 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand status took 9 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::parseTrackInfo Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::parseTrackInfo Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::parseTrackInfo Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":164,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1221 Kbps","isStreaming":false,"title":"Ó Deus de Amor","artist":"Ana Caram","album":"Não Ando Só","uri":"USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac","trackType":"flac"} Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: CURRENT POSITION 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService play Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus stop Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":164,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1221 Kbps","isStreaming":false,"title":"Ó Deus de Amor","artist":"Ana Caram","album":"Não Ando Só","uri":"USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac","trackType":"flac"} Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: CURRENT POSITION 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService play Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus play Dec 12 19:24:44 volumio-primo volumio[1305]: info: Received an update from plugin. extracting info from payload Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":164,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1221 Kbps","isStreaming":false,"title":"Ó Deus de Amor","artist":"Ana Caram","album":"Não Ando Só","uri":"USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac","trackType":"flac"} Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: CURRENT POSITION 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService play Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus play Dec 12 19:24:44 volumio-primo volumio[1305]: info: Received an update from plugin. extracting info from payload Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: ------------------------------ 26ms Dec 12 19:24:44 volumio-primo volumio[1305]: info: ------------------------------ 30ms Dec 12 19:24:44 volumio-primo volumio[1305]: info: ------------------------------ 29ms Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::parseTrackInfo Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: ControllerMpd::parseTrackInfo Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":164,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1221 Kbps","isStreaming":false,"title":"Ó Deus de Amor","artist":"Ana Caram","album":"Não Ando Só","uri":"USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac","trackType":"flac"} Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: CURRENT POSITION 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService play Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus play Dec 12 19:24:44 volumio-primo volumio[1305]: info: Received an update from plugin. extracting info from payload Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":164,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1221 Kbps","isStreaming":false,"title":"Ó Deus de Amor","artist":"Ana Caram","album":"Não Ando Só","uri":"USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac","trackType":"flac"} Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: CURRENT POSITION 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService play Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus play Dec 12 19:24:44 volumio-primo volumio[1305]: info: Received an update from plugin. extracting info from payload Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":164,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1221 Kbps","isStreaming":false,"title":"Ó Deus de Amor","artist":"Ana Caram","album":"Não Ando Só","uri":"USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac","trackType":"flac"} Dec 12 19:24:44 volumio-primo volumio[1305]: verbose: CURRENT POSITION 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService play Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus play Dec 12 19:24:44 volumio-primo volumio[1305]: info: Received an update from plugin. extracting info from payload Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:44 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:44 volumio-primo volumio[1305]: info: ------------------------------ 42ms Dec 12 19:24:44 volumio-primo volumio[1305]: info: ------------------------------ 40ms Dec 12 19:24:44 volumio-primo volumio[1305]: info: ------------------------------ 40ms Dec 12 19:24:46 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 12 19:24:46 volumio-primo volumio[1305]: info: CURURI: music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res Dec 12 19:24:46 volumio-primo volumio[1305]: info: Preload queue cleared Dec 12 19:24:46 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/02. Do Santo Sábado És Senhor.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/03. Bem de Manhã.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/04. Querido Jesus.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/05. Seu Maravilhoso Olhar.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/06. Saudade.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/07. Muito Além do Sol.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/08. Abre, Senhor, Os Olhos Meus.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/09. Que Te Darei, Meu Mestre_.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/10. Não Ando Só.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/11. Quero Estar Ao Pé da Cruz.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/12. Ser Igual a Cristo.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/13. Dá-Me Tua Paz.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Preloading song: music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/14. Poslúdio.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac in service mpd Dec 12 19:24:46 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F01.%20%C3%93%20Deus%20de%20Amor.flac&metadata=false Dec 12 19:24:46 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/02. Do Santo Sábado És Senhor.flac in service mpd Dec 12 19:24:46 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F02.%20Do%20Santo%20S%C3%A1bado%20%C3%89s%20Senhor.flac&metadata=false Dec 12 19:24:46 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/02. Do Santo Sábado És Senhor.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/03. Bem de Manhã.flac in service mpd Dec 12 19:24:46 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F03.%20Bem%20de%20Manh%C3%A3.flac&metadata=false Dec 12 19:24:46 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/03. Bem de Manhã.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/04. Querido Jesus.flac in service mpd Dec 12 19:24:46 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F04.%20Querido%20Jesus.flac&metadata=false Dec 12 19:24:46 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/04. Querido Jesus.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/05. Seu Maravilhoso Olhar.flac in service mpd Dec 12 19:24:46 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F05.%20Seu%20Maravilhoso%20Olhar.flac&metadata=false Dec 12 19:24:46 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/05. Seu Maravilhoso Olhar.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/06. Saudade.flac in service mpd Dec 12 19:24:46 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F06.%20Saudade.flac&metadata=false Dec 12 19:24:46 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/06. Saudade.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/07. Muito Além do Sol.flac in service mpd Dec 12 19:24:46 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F07.%20Muito%20Al%C3%A9m%20do%20Sol.flac&metadata=false Dec 12 19:24:46 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/07. Muito Além do Sol.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/08. Abre, Senhor, Os Olhos Meus.flac in service mpd Dec 12 19:24:46 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F08.%20Abre%2C%20Senhor%2C%20Os%20Olhos%20Meus.flac&metadata=false Dec 12 19:24:46 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/08. Abre, Senhor, Os Olhos Meus.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/09. Que Te Darei, Meu Mestre_.flac in service mpd Dec 12 19:24:46 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F09.%20Que%20Te%20Darei%2C%20Meu%20Mestre_.flac&metadata=false Dec 12 19:24:46 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/09. Que Te Darei, Meu Mestre_.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/10. Não Ando Só.flac in service mpd Dec 12 19:24:46 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F10.%20N%C3%A3o%20Ando%20S%C3%B3.flac&metadata=false Dec 12 19:24:46 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/10. Não Ando Só.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/11. Quero Estar Ao Pé da Cruz.flac in service mpd Dec 12 19:24:46 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F11.%20Quero%20Estar%20Ao%20P%C3%A9%20da%20Cruz.flac&metadata=false Dec 12 19:24:46 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/11. Quero Estar Ao Pé da Cruz.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/12. Ser Igual a Cristo.flac in service mpd Dec 12 19:24:46 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F12.%20Ser%20Igual%20a%20Cristo.flac&metadata=false Dec 12 19:24:46 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/12. Ser Igual a Cristo.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/13. Dá-Me Tua Paz.flac in service mpd Dec 12 19:24:46 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F13.%20D%C3%A1-Me%20Tua%20Paz.flac&metadata=false Dec 12 19:24:46 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/13. Dá-Me Tua Paz.flac Dec 12 19:24:46 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements/Ana Caram - Não Ando Só Hi-Res/14. Poslúdio.flac in service mpd Dec 12 19:24:46 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res%2F14.%20Posl%C3%BAdio.flac&metadata=false Dec 12 19:24:46 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/14. Poslúdio.flac Dec 12 19:24:48 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-DISCONNECTED bssid=08:b6:57:2a:d7:20 reason=34 Dec 12 19:24:48 volumio-primo dhcpcd[1151]: wlan0: carrier lost Dec 12 19:24:48 volumio-primo wpa_supplicant[963]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Dec 12 19:24:48 volumio-primo wpa_supplicant[963]: nl80211: Failed to set IPv4 unicast in multicast filter Dec 12 19:24:48 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Dec 12 19:24:48 volumio-primo kernel: cfg80211: World regulatory domain updated: Dec 12 19:24:48 volumio-primo kernel: cfg80211: DFS Master region: unset Dec 12 19:24:48 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Dec 12 19:24:48 volumio-primo kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:48 volumio-primo kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz, 92000 KHz AUTO), (N/A, 2000 mBm), (N/A) Dec 12 19:24:48 volumio-primo kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:48 volumio-primo kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A) Dec 12 19:24:48 volumio-primo kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s) Dec 12 19:24:48 volumio-primo kernel: cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s) Dec 12 19:24:48 volumio-primo kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:48 volumio-primo kernel: cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A) Dec 12 19:24:48 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Dec 12 19:24:48 volumio-primo kernel: cfg80211: Regulatory domain changed to country: DE Dec 12 19:24:48 volumio-primo kernel: cfg80211: DFS Master region: ETSI Dec 12 19:24:48 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Dec 12 19:24:48 volumio-primo kernel: cfg80211: (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:48 volumio-primo kernel: cfg80211: (5150000 KHz - 5250000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (N/A) Dec 12 19:24:48 volumio-primo kernel: cfg80211: (5250000 KHz - 5350000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (0 s) Dec 12 19:24:48 volumio-primo kernel: cfg80211: (5470000 KHz - 5725000 KHz @ 160000 KHz), (N/A, 2698 mBm), (0 s) Dec 12 19:24:48 volumio-primo kernel: cfg80211: (5725000 KHz - 5875000 KHz @ 80000 KHz), (N/A, 1397 mBm), (N/A) Dec 12 19:24:48 volumio-primo kernel: cfg80211: (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A) Dec 12 19:24:48 volumio-primo avahi-daemon[568]: Withdrawing address record for 192.168.178.48 on wlan0. Dec 12 19:24:48 volumio-primo avahi-daemon[568]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.48. Dec 12 19:24:48 volumio-primo avahi-daemon[568]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 12 19:24:48 volumio-primo volumio[1305]: info: Discovery: A device disappeared from network Dec 12 19:24:48 volumio-primo dhcpcd[1151]: wlan0: deleting route to 192.168.178.0/24 Dec 12 19:24:48 volumio-primo dhcpcd[1151]: wlan0: deleting default route via 192.168.178.1 Dec 12 19:24:48 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 19:24:48 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 19:24:48 volumio-primo volumio[1305]: info: Discovery: Getting this device information Dec 12 19:24:48 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioGetState Dec 12 19:24:48 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:48 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 19:24:48 volumio-primo volumio[1305]: verbose: New Socket.io Connection to 192.168.178.102:3000 from 192.168.178.49 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 12 19:24:48 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 12 19:24:48 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 12 19:24:48 volumio-primo ntpd[14176]: ntpd exiting on signal 15 (Terminated) Dec 12 19:24:48 volumio-primo ntpd[14176]: 192.168.178.1 local addr 192.168.178.102 -> Dec 12 19:24:48 volumio-primo systemd[1]: Stopping Network Time Service... Dec 12 19:24:48 volumio-primo systemd[1]: ntp.service: Succeeded. Dec 12 19:24:48 volumio-primo systemd[1]: Stopped Network Time Service. Dec 12 19:24:48 volumio-primo systemd[1]: Starting Network Time Service... Dec 12 19:24:49 volumio-primo ntpd[14285]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 12 19:24:49 volumio-primo ntpd[14285]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Dec 12 19:24:49 volumio-primo systemd[1]: Started Network Time Service. Dec 12 19:24:49 volumio-primo ntpd[14291]: proto: precision = 0.583 usec (-21) Dec 12 19:24:49 volumio-primo ntpd[14291]: Listen and drop on 0 v6wildcard [::]:123 Dec 12 19:24:49 volumio-primo ntpd[14291]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 12 19:24:49 volumio-primo ntpd[14291]: Listen normally on 2 lo 127.0.0.1:123 Dec 12 19:24:49 volumio-primo ntpd[14291]: Listen normally on 3 eth0 192.168.178.102:123 Dec 12 19:24:49 volumio-primo ntpd[14291]: Listening on routing socket on fd #20 for interface updates Dec 12 19:24:49 volumio-primo ntpd[14291]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 12 19:24:49 volumio-primo ntpd[14291]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 12 19:24:50 volumio-primo wpa_supplicant[963]: wlan0: Trying to associate with 08:b6:57:2a:d7:20 (SSID='FRITZ!Box 6670 QI' freq=2462 MHz) Dec 12 19:24:50 volumio-primo wpa_supplicant[963]: wlan0: Associated with 08:b6:57:2a:d7:20 Dec 12 19:24:50 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 12 19:24:50 volumio-primo wpa_supplicant[963]: wlan0: WPA: Key negotiation completed with 08:b6:57:2a:d7:20 [PTK=CCMP GTK=CCMP] Dec 12 19:24:50 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-CONNECTED - Connection to 08:b6:57:2a:d7:20 completed [id=1 id_str=] Dec 12 19:24:50 volumio-primo dhcpcd[1151]: wlan0: carrier acquired Dec 12 19:24:50 volumio-primo wpa_supplicant[963]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Dec 12 19:24:50 volumio-primo dhcpcd[1151]: wlan0: IAID 05:5f:ea:cb Dec 12 19:24:50 volumio-primo kernel: cfg80211: Regulatory domain changed to country: DE Dec 12 19:24:50 volumio-primo kernel: cfg80211: DFS Master region: ETSI Dec 12 19:24:50 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Dec 12 19:24:50 volumio-primo kernel: cfg80211: (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Dec 12 19:24:50 volumio-primo kernel: cfg80211: (5150000 KHz - 5250000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (N/A) Dec 12 19:24:50 volumio-primo kernel: cfg80211: (5250000 KHz - 5350000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (0 s) Dec 12 19:24:50 volumio-primo kernel: cfg80211: (5470000 KHz - 5725000 KHz @ 160000 KHz), (N/A, 2698 mBm), (0 s) Dec 12 19:24:50 volumio-primo kernel: cfg80211: (5725000 KHz - 5875000 KHz @ 80000 KHz), (N/A, 1397 mBm), (N/A) Dec 12 19:24:50 volumio-primo kernel: cfg80211: (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A) Dec 12 19:24:51 volumio-primo dhcpcd[1151]: wlan0: soliciting an IPv6 router Dec 12 19:24:51 volumio-primo dhcpcd[1151]: wlan0: rebinding lease of 192.168.178.48 Dec 12 19:24:51 volumio-primo dhcpcd[1151]: wlan0: probing address 192.168.178.48/24 Dec 12 19:24:51 volumio-primo volumio[1305]: info: Volumio Network Manager: Network status updated: 1 Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioSeek Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreStateMachine::seek Dec 12 19:24:53 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:53 volumio-primo volumio[1305]: info: TRACKBLOCK {"uri":"mnt/USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac","service":"mpd","name":"Ó Deus de Amor","artist":"Ana Caram","album":"Não Ando Só","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=128&web=Ana%20Caram/N%C3%A3o%20Ando%20S%C3%B3/extralarge&path=%2FUSB%2FElements%2FAna%20Caram%20-%20N%C3%A3o%20Ando%20S%C3%B3%20Hi-Res&metadata=false","duration":164,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreStateMachine::startPlaybackTimer Dec 12 19:24:53 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:53 volumio-primo volumio[1305]: info: ControllerMpd::seek Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:53 volumio-primo volumio[1305]: error: null Dec 12 19:24:53 volumio-primo volumio[1305]: info: Dec 12 19:24:53 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:24:53 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:24:53 volumio-primo volumio[1305]: info: Dec 12 19:24:53 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:24:53 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:24:53 volumio-primo volumio[1305]: info: Dec 12 19:24:53 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:24:53 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:24:53 volumio-primo volumio[1305]: info: sendMpdCommand status took 3 milliseconds Dec 12 19:24:53 volumio-primo volumio[1305]: info: sendMpdCommand status took 2 milliseconds Dec 12 19:24:53 volumio-primo volumio[1305]: info: sendMpdCommand status took 1 milliseconds Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:24:53 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 12 19:24:53 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 12 19:24:53 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: ControllerMpd::parseTrackInfo Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: ControllerMpd::parseTrackInfo Dec 12 19:24:53 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58000,"duration":164,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1328 Kbps","isStreaming":false,"title":"Ó Deus de Amor","artist":"Ana Caram","album":"Não Ando Só","uri":"USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac","trackType":"flac"} Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: CURRENT POSITION 0 Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService play Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus play Dec 12 19:24:53 volumio-primo volumio[1305]: info: Received an update from plugin. extracting info from payload Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58000,"duration":164,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1328 Kbps","isStreaming":false,"title":"Ó Deus de Amor","artist":"Ana Caram","album":"Não Ando Só","uri":"USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac","trackType":"flac"} Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: CURRENT POSITION 0 Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService play Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus play Dec 12 19:24:53 volumio-primo volumio[1305]: info: Received an update from plugin. extracting info from payload Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58000,"duration":164,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1328 Kbps","isStreaming":false,"title":"Ó Deus de Amor","artist":"Ana Caram","album":"Não Ando Só","uri":"USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac","trackType":"flac"} Dec 12 19:24:53 volumio-primo volumio[1305]: verbose: CURRENT POSITION 0 Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService play Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus play Dec 12 19:24:53 volumio-primo volumio[1305]: info: Received an update from plugin. extracting info from payload Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:53 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:24:53 volumio-primo volumio[1305]: info: ------------------------------ 21ms Dec 12 19:24:53 volumio-primo volumio[1305]: info: ------------------------------ 21ms Dec 12 19:24:53 volumio-primo volumio[1305]: info: ------------------------------ 20ms Dec 12 19:24:54 volumio-primo go-librespot[1945]: time="2025-12-12T19:24:54+01:00" level=trace msg="sent dealer ping" Dec 12 19:24:54 volumio-primo go-librespot[1945]: time="2025-12-12T19:24:54+01:00" level=trace msg="received dealer pong" Dec 12 19:24:55 volumio-primo dhcpcd[1151]: wlan0: leased 192.168.178.48 for 864000 seconds Dec 12 19:24:55 volumio-primo dhcpcd[1151]: wlan0: adding route to 192.168.178.0/24 Dec 12 19:24:55 volumio-primo avahi-daemon[568]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.48. Dec 12 19:24:55 volumio-primo avahi-daemon[568]: New relevant interface wlan0.IPv4 for mDNS. Dec 12 19:24:55 volumio-primo dhcpcd[1151]: wlan0: adding default route via 192.168.178.1 Dec 12 19:24:55 volumio-primo avahi-daemon[568]: Registering new address record for 192.168.178.48 on wlan0.IPv4. Dec 12 19:24:56 volumio-primo ntpd[14291]: ntpd exiting on signal 15 (Terminated) Dec 12 19:24:56 volumio-primo ntpd[14291]: 192.168.178.1 local addr 192.168.178.102 -> Dec 12 19:24:56 volumio-primo systemd[1]: Stopping Network Time Service... Dec 12 19:24:56 volumio-primo systemd[1]: ntp.service: Succeeded. Dec 12 19:24:56 volumio-primo systemd[1]: Stopped Network Time Service. Dec 12 19:24:56 volumio-primo systemd[1]: Starting Network Time Service... Dec 12 19:24:56 volumio-primo ntpd[14351]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 12 19:24:56 volumio-primo ntpd[14351]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Dec 12 19:24:56 volumio-primo systemd[1]: Started Network Time Service. Dec 12 19:24:56 volumio-primo ntpd[14357]: proto: precision = 0.583 usec (-21) Dec 12 19:24:56 volumio-primo ntpd[14357]: Listen and drop on 0 v6wildcard [::]:123 Dec 12 19:24:56 volumio-primo ntpd[14357]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 12 19:24:56 volumio-primo ntpd[14357]: Listen normally on 2 lo 127.0.0.1:123 Dec 12 19:24:56 volumio-primo ntpd[14357]: Listen normally on 3 eth0 192.168.178.102:123 Dec 12 19:24:56 volumio-primo ntpd[14357]: Listen normally on 4 wlan0 192.168.178.48:123 Dec 12 19:24:56 volumio-primo ntpd[14357]: Listening on routing socket on fd #21 for interface updates Dec 12 19:24:56 volumio-primo ntpd[14357]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 12 19:24:56 volumio-primo ntpd[14357]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 12 19:24:56 volumio-primo volumio[1305]: info: Discovery: adding bd94b6c7-9cd5-4dce-ae63-20809a124570 Dec 12 19:24:56 volumio-primo volumio[1305]: info: Discovery: Found device Volumio Primo Dec 12 19:24:56 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioGetState Dec 12 19:24:56 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:56 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 19:24:56 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 19:24:56 volumio-primo volumio[1305]: info: Discovery: Getting this device information Dec 12 19:24:56 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioGetState Dec 12 19:24:56 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:24:56 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 19:24:56 volumio-primo volumio[1305]: verbose: New Socket.io Connection to 192.168.178.48:3000 from 192.168.178.49 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 12 19:24:56 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 12 19:24:56 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioNext Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::next Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::stop Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::stPlaybackTimer Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::updateTrackBlock Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrackBlock Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::serviceStop Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 0 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::serviceStop Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::stop Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 19:25:00 volumio-primo volumio[1305]: info: Dec 12 19:25:00 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand stop took 3 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::play index undefined Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::startPlaybackTimer Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::clearAddPlayTracks USB/Elements/Ana Caram - Não Ando Só Hi-Res/02. Do Santo Sábado És Senhor.flac Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::updateTrackBlock Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrackBlock Dec 12 19:25:00 volumio-primo volumio[1305]: info: Dec 12 19:25:00 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:25:00 volumio-primo volumio[1305]: info: Dec 12 19:25:00 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand status took 3 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand stop took 3 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand status took 2 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand status took 1 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand clear Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:25:00 volumio-primo volumio[1305]: info: Dec 12 19:25:00 volumio-primo volumio[1305]: ---------------------------- MPD announces system playlist update Dec 12 19:25:00 volumio-primo volumio[1305]: info: Ignoring MPD Status Update Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand clear took 2 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::parseTrackInfo Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand add "USB/Elements/Ana Caram - Não Ando Só Hi-Res/02. Do Santo Sábado És Senhor.flac" Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::parseTrackInfo Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::parseTrackInfo Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ó Deus de Amor","artist":"Ana Caram","album":"Não Ando Só","uri":"USB/Elements/Ana Caram - Não Ando Só Hi-Res/01. Ó Deus de Amor.flac","trackType":"flac"} Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: CURRENT POSITION 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService stop Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus stop Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: No code Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:25:00 volumio-primo volumio[1305]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 12 19:25:00 volumio-primo volumio[1305]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 12 19:25:00 volumio-primo volumio[1305]: info: ------------------------------ 13ms Dec 12 19:25:00 volumio-primo volumio[1305]: info: ------------------------------ 11ms Dec 12 19:25:00 volumio-primo volumio[1305]: info: ------------------------------ 10ms Dec 12 19:25:00 volumio-primo volumio[1305]: info: Dec 12 19:25:00 volumio-primo volumio[1305]: ---------------------------- MPD announces system playlist update Dec 12 19:25:00 volumio-primo volumio[1305]: info: Ignoring MPD Status Update Dec 12 19:25:00 volumio-primo volumio[1305]: info: Dec 12 19:25:00 volumio-primo volumio[1305]: ---------------------------- MPD announces system playlist update Dec 12 19:25:00 volumio-primo volumio[1305]: info: Ignoring MPD Status Update Dec 12 19:25:00 volumio-primo volumio[1305]: error: updateQueue error: null Dec 12 19:25:00 volumio-primo volumio[1305]: info: Dec 12 19:25:00 volumio-primo volumio[1305]: ---------------------------- MPD announces system playlist update Dec 12 19:25:00 volumio-primo volumio[1305]: info: Ignoring MPD Status Update Dec 12 19:25:00 volumio-primo volumio[1305]: info: ------------------------------ 11ms Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand add "USB/Elements/Ana Caram - Não Ando Só Hi-Res/02. Do Santo Sábado És Senhor.flac" took 10 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: info: ------------------------------ 2ms Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand play Dec 12 19:25:00 volumio-primo volumio[1305]: info: Dec 12 19:25:00 volumio-primo volumio[1305]: ---------------------------- MPD announces system playlist update Dec 12 19:25:00 volumio-primo volumio[1305]: info: Ignoring MPD Status Update Dec 12 19:25:00 volumio-primo volumio[1305]: info: Dec 12 19:25:00 volumio-primo volumio[1305]: ---------------------------- MPD announces system playlist update Dec 12 19:25:00 volumio-primo volumio[1305]: info: Ignoring MPD Status Update Dec 12 19:25:00 volumio-primo volumio[1305]: info: ------------------------------ 7ms Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand play took 6 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: info: ------------------------------ 5ms Dec 12 19:25:00 volumio-primo volumio[1305]: info: Dec 12 19:25:00 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:25:00 volumio-primo volumio[1305]: info: Dec 12 19:25:00 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:25:00 volumio-primo volumio[1305]: info: Dec 12 19:25:00 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:25:00 volumio-primo volumio[1305]: info: Dec 12 19:25:00 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand status took 5 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand status took 4 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand status took 2 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::parseTrackInfo Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::parseTrackInfo Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Do Santo Sábado És Senhor","artist":"Ana Caram","album":"Não Ando Só","uri":"USB/Elements/Ana Caram - Não Ando Só Hi-Res/02. Do Santo Sábado És Senhor.flac","trackType":"flac"} Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: CURRENT POSITION 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService play Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus stop Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Do Santo Sábado És Senhor","artist":"Ana Caram","album":"Não Ando Só","uri":"USB/Elements/Ana Caram - Não Ando Só Hi-Res/02. Do Santo Sábado És Senhor.flac","trackType":"flac"} Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: CURRENT POSITION 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService play Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus play Dec 12 19:25:00 volumio-primo volumio[1305]: info: Received an update from plugin. extracting info from payload Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Do Santo Sábado És Senhor","artist":"Ana Caram","album":"Não Ando Só","uri":"USB/Elements/Ana Caram - Não Ando Só Hi-Res/02. Do Santo Sábado És Senhor.flac","trackType":"flac"} Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: CURRENT POSITION 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService play Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus play Dec 12 19:25:00 volumio-primo volumio[1305]: info: Received an update from plugin. extracting info from payload Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: ------------------------------ 15ms Dec 12 19:25:00 volumio-primo volumio[1305]: info: ------------------------------ 20ms Dec 12 19:25:00 volumio-primo volumio[1305]: info: ------------------------------ 20ms Dec 12 19:25:00 volumio-primo volumio[1305]: info: Dec 12 19:25:00 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:25:00 volumio-primo volumio[1305]: info: Dec 12 19:25:00 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::getState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand status Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand status took 5 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand status took 4 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::parseTrackInfo Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::parseState Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Do Santo Sábado És Senhor","artist":"Ana Caram","album":"Não Ando Só","uri":"USB/Elements/Ana Caram - Não Ando Só Hi-Res/02. Do Santo Sábado És Senhor.flac","trackType":"flac"} Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: CURRENT POSITION 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService play Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus play Dec 12 19:25:00 volumio-primo volumio[1305]: info: Received an update from plugin. extracting info from payload Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: ------------------------------ 31ms Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: ControllerMpd::parseTrackInfo Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1319 Kbps","isStreaming":false,"title":"Do Santo Sábado És Senhor","artist":"Ana Caram","album":"Não Ando Só","uri":"USB/Elements/Ana Caram - Não Ando Só Hi-Res/02. Do Santo Sábado És Senhor.flac","trackType":"flac"} Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: CURRENT POSITION 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService play Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus play Dec 12 19:25:00 volumio-primo volumio[1305]: info: Received an update from plugin. extracting info from payload Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: ControllerMpd::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::servicePushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1319 Kbps","isStreaming":false,"title":"Do Santo Sábado És Senhor","artist":"Ana Caram","album":"Não Ando Só","uri":"USB/Elements/Ana Caram - Não Ando Só Hi-Res/02. Do Santo Sábado És Senhor.flac","trackType":"flac"} Dec 12 19:25:00 volumio-primo volumio[1305]: verbose: CURRENT POSITION 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::syncState stateService play Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::syncState currentStatus play Dec 12 19:25:00 volumio-primo volumio[1305]: info: Received an update from plugin. extracting info from payload Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:00 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:25:00 volumio-primo volumio[1305]: info: ------------------------------ 32ms Dec 12 19:25:00 volumio-primo volumio[1305]: info: ------------------------------ 31ms Dec 12 19:25:01 volumio-primo volumio[1305]: info: Volumio Network Manager: Network status updated: 3 Dec 12 19:25:08 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 19:25:08 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 19:25:08 volumio-primo volumio[1305]: info: Discovery: Getting this device information Dec 12 19:25:08 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioGetState Dec 12 19:25:08 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:08 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 19:25:08 volumio-primo volumio[1305]: verbose: New Socket.io Connection to 192.168.178.102:3000 from 192.168.178.49 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 12 19:25:08 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 12 19:25:08 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 12 19:25:08 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 19:25:08 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 19:25:08 volumio-primo volumio[1305]: info: Discovery: Getting this device information Dec 12 19:25:08 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioGetState Dec 12 19:25:08 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:08 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 19:25:08 volumio-primo volumio[1305]: verbose: New Socket.io Connection to 192.168.178.48:3000 from 192.168.178.49 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 12 19:25:08 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 12 19:25:08 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 12 19:25:11 volumio-primo wpa_supplicant[963]: wlan0: WPA: Group rekeying completed with 08:b6:57:2a:d7:20 [GTK=CCMP] Dec 12 19:25:24 volumio-primo go-librespot[1945]: time="2025-12-12T19:25:24+01:00" level=trace msg="sent dealer ping" Dec 12 19:25:24 volumio-primo go-librespot[1945]: time="2025-12-12T19:25:24+01:00" level=trace msg="received dealer pong" Dec 12 19:25:24 volumio-primo go-librespot[1945]: time="2025-12-12T19:25:24+01:00" level=trace msg="received accesspoint ping" Dec 12 19:25:24 volumio-primo go-librespot[1945]: time="2025-12-12T19:25:24+01:00" level=trace msg="received accesspoint pong ack" Dec 12 19:25:24 volumio-primo volumio[1305]: info: Preload queue cleared Dec 12 19:25:24 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 12 19:25:24 volumio-primo volumio[1305]: info: CoreStateMachine::ClearQueue Dec 12 19:25:24 volumio-primo volumio[1305]: info: CoreStateMachine::stop Dec 12 19:25:24 volumio-primo volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 19:25:24 volumio-primo volumio[1305]: info: CoreStateMachine::stPlaybackTimer Dec 12 19:25:24 volumio-primo volumio[1305]: info: CoreStateMachine::updateTrackBlock Dec 12 19:25:24 volumio-primo volumio[1305]: info: CorePlayQueue::getTrackBlock Dec 12 19:25:24 volumio-primo volumio[1305]: info: CoreStateMachine::pushState Dec 12 19:25:24 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:24 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 19:25:24 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushState Dec 12 19:25:24 volumio-primo volumio[1305]: info: CoreStateMachine::serviceStop Dec 12 19:25:24 volumio-primo volumio[1305]: info: CorePlayQueue::getTrack 1 Dec 12 19:25:24 volumio-primo volumio[1305]: info: CoreCommandRouter::serviceStop Dec 12 19:25:24 volumio-primo volumio[1305]: info: ControllerMpd::stop Dec 12 19:25:24 volumio-primo volumio[1305]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 19:25:24 volumio-primo volumio[1305]: info: CorePlayQueue::clearPlayQueue Dec 12 19:25:24 volumio-primo volumio[1305]: info: CorePlayQueue::saveQueue Dec 12 19:25:24 volumio-primo volumio[1305]: info: CoreCommandRouter::volumioPushQueue Dec 12 19:25:24 volumio-primo volumio[1305]: info: CoreStateMachine::addQueueItems Dec 12 19:25:24 volumio-primo volumio[1305]: info: CorePlayQueue::addQueueItems Dec 12 19:25:24 volumio-primo volumio[1305]: info: Preload queue cleared Dec 12 19:25:24 volumio-primo volumio[1305]: info: Adding Item to queue: music-library/USB/Elements Dec 12 19:25:24 volumio-primo volumio[1305]: info: Exploding uri music-library/USB/Elements in service mpd Dec 12 19:25:28 volumio-primo ntfs-3g[1459]: ntfs_mst_post_read_fixup_warn: magic: 0x00000000 size: 4096 usa_ofs: 0 usa_count: 0: Invalid argument Dec 12 19:25:28 volumio-primo ntfs-3g[1459]: Corrupt index block signature: vcn 0 inode 58545 Dec 12 19:25:28 volumio-primo volumio[1305]: error: Failed to stat '/mnt/USB/Elements/Klassik/Royal London Cello Orchestra - The Glory Of Cremona Cellos (The Most Beautiful 12 Cellos) (2020) [WAV]/Artwork' Dec 12 19:25:28 volumio-primo ntfs-3g[1459]: ntfs_mst_post_read_fixup_warn: magic: 0x00000000 size: 4096 usa_ofs: 0 usa_count: 0: Invalid argument Dec 12 19:25:28 volumio-primo ntfs-3g[1459]: Corrupt index block signature: vcn 0 inode 58565 Dec 12 19:25:28 volumio-primo volumio[1305]: error: Failed to stat '/mnt/USB/Elements/Klassik/VA - The Magic Cello' Dec 12 19:25:28 volumio-primo ntfs-3g[1459]: ntfs_mst_post_read_fixup_warn: magic: 0x00000000 size: 4096 usa_ofs: 0 usa_count: 0: Invalid argument Dec 12 19:25:28 volumio-primo ntfs-3g[1459]: Corrupt index block signature: vcn 0 inode 983 Dec 12 19:25:28 volumio-primo volumio[1305]: error: Failed to stat '/mnt/USB/Elements/NBR Music/Various Artists - Rec Live! (Pure Audiophile Live Recording_ Velut Luna Private Concerts) (Live)' Dec 12 19:25:35 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 12 19:25:35 volumio-primo volumio[1305]: info: CURURI: music-library/USB/Elements Dec 12 19:25:35 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 12 19:25:35 volumio-primo volumio[1305]: info: CURURI: music-library/USB/Elements Dec 12 19:25:35 volumio-primo volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 12 19:25:35 volumio-primo volumio[1305]: info: CURURI: music-library/USB Dec 12 19:25:35 volumio-primo volumio[1305]: verbose: New Socket.io Connection to 192.168.178.48 from 192.168.178.49 UA: Mozilla/5.0 (Linux; Android 15; SM-X210 Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 12 19:25:35 volumio-primo volumio[1305]: info: Dec 12 19:25:35 volumio-primo volumio[1305]: ---------------------------- MPD announces state update: player Dec 12 19:25:35 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=//extralarge&path=%2Fmnt%2FUSB%2FElements%2F%24RECYCLE.BIN%2FS-1-5-21-939152041-2198050985-3100339122-1000%2F%24IFZG3VQ.flac&metadata=false Dec 12 19:25:35 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/$RECYCLE.BIN/S-1-5-21-939152041-2198050985-3100339122-1000/$IFZG3VQ.flac Dec 12 19:25:35 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=//extralarge&path=%2Fmnt%2FUSB%2FElements%2F%24RECYCLE.BIN%2FS-1-5-21-939152041-2198050985-3100339122-1000%2F%24ILR6KDF.flac&metadata=false Dec 12 19:25:35 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/$RECYCLE.BIN/S-1-5-21-939152041-2198050985-3100339122-1000/$ILR6KDF.flac Dec 12 19:25:35 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=//extralarge&path=%2Fmnt%2FUSB%2FElements%2F%24RECYCLE.BIN%2FS-1-5-21-939152041-2198050985-3100339122-1000%2F%24IRVIOCX.flac&metadata=false Dec 12 19:25:35 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/$RECYCLE.BIN/S-1-5-21-939152041-2198050985-3100339122-1000/$IRVIOCX.flac Dec 12 19:25:35 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=//extralarge&path=%2Fmnt%2FUSB%2FElements%2F%24RECYCLE.BIN%2FS-1-5-21-939152041-2198050985-3100339122-1000%2F%24IT17NWL.flac&metadata=false Dec 12 19:25:35 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/$RECYCLE.BIN/S-1-5-21-939152041-2198050985-3100339122-1000/$IT17NWL.flac Dec 12 19:25:35 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=//extralarge&path=%2Fmnt%2FUSB%2FElements%2F%24RECYCLE.BIN%2FS-1-5-21-939152041-2198050985-3100339122-1000%2F%24IWBTLEX.flac&metadata=false Dec 12 19:25:35 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/$RECYCLE.BIN/S-1-5-21-939152041-2198050985-3100339122-1000/$IWBTLEX.flac Dec 12 19:25:35 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=//extralarge&path=%2Fmnt%2FUSB%2FElements%2F%24RECYCLE.BIN%2FS-1-5-21-939152041-2198050985-3100339122-1000%2F%24IZ18XED.flac&metadata=false Dec 12 19:25:35 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/$RECYCLE.BIN/S-1-5-21-939152041-2198050985-3100339122-1000/$IZ18XED.flac Dec 12 19:25:35 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=NBR%20MUSIC/The%20Unread%20Letter%20(Acoustic%20version)/extralarge&path=%2Fmnt%2FUSB%2FElements%2F%24RECYCLE.BIN%2FS-1-5-21-939152041-2198050985-3100339122-1000%2F%24R4MYC9Y.zip&metadata=false Dec 12 19:25:35 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/$RECYCLE.BIN/S-1-5-21-939152041-2198050985-3100339122-1000/$R4MYC9Y.zip Dec 12 19:25:35 volumio-primo volumio[1305]: info: ALBUMART /albumart?cacheid=128&web=NBR%20MUSIC/The%20Unread%20Letter%20(Acoustic%20version)/extralarge&path=%2Fmnt%2FUSB%2FElements%2F%24RECYCLE.BIN%2FS-1-5-21-939152041-2198050985-3100339122-1000%2F%24R4MYC9Y.zip&metadata=false Dec 12 19:25:35 volumio-primo volumio[1305]: info: URI /mnt/USB/Elements/$RECYCLE.BIN/S-1-5-21-939152041-2198050985-3100339122-1000/$R4MYC9Y.zip Dec 12 19:25:35 volumio-primo volumio[1305]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 19:25:35 volumio-primo volumio[1305]: Error: Unable to resolve or reject the same promise twice Dec 12 19:25:35 volumio-primo volumio[1305]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 12 19:25:35 volumio-primo volumio[1305]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 12 19:25:35 volumio-primo volumio[1305]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 12 19:25:35 volumio-primo volumio[1305]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 12 19:25:35 volumio-primo volumio[1305]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 12 19:25:35 volumio-primo volumio[1305]: at Socket.emit (events.js:400:28) Dec 12 19:25:35 volumio-primo volumio[1305]: at addChunk (internal/streams/readable.js:293:12) Dec 12 19:25:35 volumio-primo volumio[1305]: at readableAddChunk (internal/streams/readable.js:263:11) Dec 12 19:25:35 volumio-primo volumio[1305]: at Socket.Readable.push (internal/streams/readable.js:206:10) Dec 12 19:25:35 volumio-primo volumio[1305]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Dec 12 19:25:35 volumio-primo volumio[1305]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 19:25:35 volumio-primo sudo[14433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-12 19:24 Dec 12 19:25:35 volumio-primo sudo[14433]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 07:17:52 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="3b20d491450ccf59e0e519a97572dc60"