-- Logs begin at Sat 2025-11-15 07:00:41 UTC, end at Sat 2026-03-28 03:52:26 UTC. -- Mar 28 03:51:49 ifi-ifi systemd[1]: Starting Internet Detect for iFi Streamer... Mar 28 03:51:49 ifi-ifi systemd[1]: Starting Daily apt download activities... Mar 28 03:51:49 ifi-ifi wireless.js[498]: trying... Mar 28 03:51:49 ifi-ifi systemd[1]: ifi-system-internet.service: Succeeded. Mar 28 03:51:49 ifi-ifi systemd[1]: Started Internet Detect for iFi Streamer. Mar 28 03:51:49 ifi-ifi sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 28 03:51:49 ifi-ifi sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:51:49 ifi-ifi sudo[1188]: pam_unix(sudo:session): session closed for user root Mar 28 03:51:50 ifi-ifi systemd[1]: apt-daily.service: Succeeded. Mar 28 03:51:50 ifi-ifi systemd[1]: Started Daily apt download activities. Mar 28 03:51:50 ifi-ifi systemd[1]: Starting Daily apt upgrade and clean activities... Mar 28 03:51:50 ifi-ifi wireless.js[498]: trying... Mar 28 03:51:50 ifi-ifi sudo[1250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 28 03:51:50 ifi-ifi sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:51:50 ifi-ifi sudo[1250]: pam_unix(sudo:session): session closed for user root Mar 28 03:51:51 ifi-ifi systemd[1]: apt-daily-upgrade.service: Succeeded. Mar 28 03:51:51 ifi-ifi systemd[1]: Started Daily apt upgrade and clean activities. Mar 28 03:51:51 ifi-ifi wireless.js[498]: trying... Mar 28 03:51:51 ifi-ifi sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 28 03:51:51 ifi-ifi sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:51:51 ifi-ifi sudo[1320]: pam_unix(sudo:session): session closed for user root Mar 28 03:51:52 ifi-ifi wireless.js[498]: trying... Mar 28 03:51:52 ifi-ifi sudo[1324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 28 03:51:52 ifi-ifi sudo[1324]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:51:52 ifi-ifi sudo[1324]: pam_unix(sudo:session): session closed for user root Mar 28 03:51:53 ifi-ifi wpa_supplicant[899]: wlan0: Authentication with ac:8b:a9:dd:e8:72 timed out. Mar 28 03:51:53 ifi-ifi kernel: CFG80211-ERROR) wl_cfg80211_disconnect : Reason 3 Mar 28 03:51:53 ifi-ifi kernel: dhd_dbg_stop_pkt_monitor, 1963 Mar 28 03:51:53 ifi-ifi kernel: ANDROID-ERROR) wl_cfg80211_check_in4way: WPA failed at 13 Mar 28 03:51:53 ifi-ifi kernel: wl_iw_event: Link Down with ac:8b:a9:dd:e8:72, reason=2 Mar 28 03:51:53 ifi-ifi kernel: CFG80211-ERROR) wl_is_linkdown : Link down Reason : WLC_E_LINK Mar 28 03:51:53 ifi-ifi kernel: link down if wlan0 may call cfg80211_disconnected. event : 16, reason=2 from ac:8b:a9:dd:e8:72 Mar 28 03:51:53 ifi-ifi kernel: dhd_dbg_stop_pkt_monitor, 1963 Mar 28 03:51:53 ifi-ifi kernel: CFG80211-ERROR) wl_cfg80211_tdls_config : No change in tdls state. Do nothing. tdls_enable:1 Mar 28 03:51:53 ifi-ifi wireless.js[498]: trying... Mar 28 03:51:53 ifi-ifi wpa_supplicant[899]: wlan0: CTRL-EVENT-DISCONNECTED bssid=ac:8b:a9:dd:e8:72 reason=3 locally_generated=1 Mar 28 03:51:53 ifi-ifi wpa_supplicant[899]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Mar 28 03:51:53 ifi-ifi wpa_supplicant[899]: nl80211: Failed to set IPv4 unicast in multicast filter Mar 28 03:51:53 ifi-ifi wpa_supplicant[899]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Mar 28 03:51:53 ifi-ifi wpa_supplicant[899]: nl80211: Failed to set IPv4 unicast in multicast filter Mar 28 03:51:53 ifi-ifi wpa_supplicant[899]: nl80211: Was expecting local disconnect but got another disconnect event first Mar 28 03:51:53 ifi-ifi wpa_supplicant[899]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Mar 28 03:51:53 ifi-ifi wpa_supplicant[899]: nl80211: Failed to set IPv4 unicast in multicast filter Mar 28 03:51:53 ifi-ifi sudo[1334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 28 03:51:53 ifi-ifi sudo[1334]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:51:53 ifi-ifi sudo[1334]: pam_unix(sudo:session): session closed for user root Mar 28 03:51:53 ifi-ifi kernel: cfg80211: World regulatory domain updated: Mar 28 03:51:53 ifi-ifi kernel: cfg80211: DFS Master region: unset Mar 28 03:51:53 ifi-ifi kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Mar 28 03:51:53 ifi-ifi kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Mar 28 03:51:53 ifi-ifi kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz, 92000 KHz AUTO), (N/A, 2000 mBm), (N/A) Mar 28 03:51:53 ifi-ifi kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A) Mar 28 03:51:53 ifi-ifi kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A) Mar 28 03:51:53 ifi-ifi kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s) Mar 28 03:51:53 ifi-ifi kernel: cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s) Mar 28 03:51:53 ifi-ifi kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A) Mar 28 03:51:53 ifi-ifi kernel: cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A) Mar 28 03:51:53 ifi-ifi wpa_supplicant[899]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Mar 28 03:51:53 ifi-ifi kernel: cfg80211: Regulatory domain changed to country: CA Mar 28 03:51:53 ifi-ifi kernel: cfg80211: DFS Master region: FCC Mar 28 03:51:53 ifi-ifi kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Mar 28 03:51:53 ifi-ifi kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 3000 mBm), (N/A) Mar 28 03:51:53 ifi-ifi kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 1700 mBm), (N/A) Mar 28 03:51:53 ifi-ifi kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2400 mBm), (0 s) Mar 28 03:51:53 ifi-ifi kernel: cfg80211: (5490000 KHz - 5600000 KHz @ 80000 KHz), (N/A, 2400 mBm), (0 s) Mar 28 03:51:53 ifi-ifi kernel: cfg80211: (5650000 KHz - 5730000 KHz @ 80000 KHz), (N/A, 2400 mBm), (0 s) Mar 28 03:51:53 ifi-ifi kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 3000 mBm), (N/A) Mar 28 03:51:53 ifi-ifi wpa_supplicant[899]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CA Mar 28 03:51:54 ifi-ifi wireless.js[498]: trying... Mar 28 03:51:54 ifi-ifi sudo[1344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 28 03:51:54 ifi-ifi sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:51:54 ifi-ifi sudo[1344]: pam_unix(sudo:session): session closed for user root Mar 28 03:51:54 ifi-ifi systemd[1]: Starting Internet Detect for iFi Streamer... Mar 28 03:51:54 ifi-ifi systemd[1]: ifi-system-internet.service: Succeeded. Mar 28 03:51:54 ifi-ifi systemd[1]: Started Internet Detect for iFi Streamer. Mar 28 03:51:55 ifi-ifi wireless.js[498]: trying... Mar 28 03:51:55 ifi-ifi sudo[1375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 28 03:51:55 ifi-ifi sudo[1375]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:51:55 ifi-ifi sudo[1375]: pam_unix(sudo:session): session closed for user root Mar 28 03:51:56 ifi-ifi wireless.js[498]: trying... Mar 28 03:51:56 ifi-ifi sudo[1379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 28 03:51:56 ifi-ifi sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:51:56 ifi-ifi sudo[1379]: pam_unix(sudo:session): session closed for user root Mar 28 03:51:56 ifi-ifi wpa_supplicant[899]: wlan0: Trying to associate with ac:8b:a9:dd:e8:71 (SSID='Iwonka i Jurek_IoT' freq=2462 MHz) Mar 28 03:51:56 ifi-ifi kernel: Connecting with ac:8b:a9:dd:e8:71 ssid "Iwonka i Jurek_IoT", len (18) channel=11 Mar 28 03:51:56 ifi-ifi kernel: dhd_dbg_start_pkt_monitor, 1724 Mar 28 03:51:56 ifi-ifi kernel: wl_iw_event: Link UP with ac:8b:a9:dd:e8:71 Mar 28 03:51:56 ifi-ifi kernel: wl_bss_connect_done succeeded with ac:8b:a9:dd:e8:71 Mar 28 03:51:56 ifi-ifi wpa_supplicant[899]: wlan0: Associated with ac:8b:a9:dd:e8:71 Mar 28 03:51:56 ifi-ifi wpa_supplicant[899]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 28 03:51:57 ifi-ifi wpa_supplicant[899]: wlan0: WPA: Key negotiation completed with ac:8b:a9:dd:e8:71 [PTK=CCMP GTK=CCMP] Mar 28 03:51:57 ifi-ifi wpa_supplicant[899]: wlan0: CTRL-EVENT-CONNECTED - Connection to ac:8b:a9:dd:e8:71 completed [id=1 id_str=] Mar 28 03:51:57 ifi-ifi dhcpcd[1039]: wlan0: carrier acquired Mar 28 03:51:57 ifi-ifi kernel: cfg80211: Regulatory domain changed to country: CA Mar 28 03:51:57 ifi-ifi kernel: cfg80211: DFS Master region: FCC Mar 28 03:51:57 ifi-ifi kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Mar 28 03:51:57 ifi-ifi kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 3000 mBm), (N/A) Mar 28 03:51:57 ifi-ifi kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 1700 mBm), (N/A) Mar 28 03:51:57 ifi-ifi kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2400 mBm), (0 s) Mar 28 03:51:57 ifi-ifi kernel: cfg80211: (5490000 KHz - 5600000 KHz @ 80000 KHz), (N/A, 2400 mBm), (0 s) Mar 28 03:51:57 ifi-ifi kernel: cfg80211: (5650000 KHz - 5730000 KHz @ 80000 KHz), (N/A, 2400 mBm), (0 s) Mar 28 03:51:57 ifi-ifi kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 3000 mBm), (N/A) Mar 28 03:51:57 ifi-ifi dhcpcd[1039]: wlan0: IAID f6:5c:b6:7c Mar 28 03:51:57 ifi-ifi wpa_supplicant[899]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CA Mar 28 03:51:57 ifi-ifi kernel: wl_bss_connect_done succeeded with ac:8b:a9:dd:e8:71 vndr_oui: 8C-FD-F0 Mar 28 03:51:57 ifi-ifi dhcpcd[1039]: wlan0: soliciting an IPv6 router Mar 28 03:51:57 ifi-ifi wireless.js[498]: trying... Mar 28 03:51:57 ifi-ifi sudo[1391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 28 03:51:57 ifi-ifi sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:51:57 ifi-ifi sudo[1391]: pam_unix(sudo:session): session closed for user root Mar 28 03:51:58 ifi-ifi dhcpcd[1039]: wlan0: soliciting a DHCP lease Mar 28 03:51:58 ifi-ifi dhcpcd[1039]: wlan0: offered 192.168.2.246 from 192.168.2.2 Mar 28 03:51:58 ifi-ifi dhcpcd[1039]: wlan0: probing address 192.168.2.246/24 Mar 28 03:51:58 ifi-ifi wireless.js[498]: Overtime, starting plan B Mar 28 03:51:58 ifi-ifi systemd[1]: Started Wireless Services. Mar 28 03:51:58 ifi-ifi systemd[1]: Started Volumio Backend Module. Mar 28 03:51:58 ifi-ifi systemd[1]: Started Volumio Cpu Tweaker. Mar 28 03:51:58 ifi-ifi systemd[1]: Started iFi Streamer hotspot button irq daemon. Mar 28 03:51:58 ifi-ifi systemd[1]: Reached target Multi-User System. Mar 28 03:51:58 ifi-ifi systemd[1]: Reached target Graphical Interface. Mar 28 03:51:58 ifi-ifi systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 28 03:51:58 ifi-ifi volumio-cpu-tweak[1398]: Setting RT Priority for mpd Mar 28 03:51:58 ifi-ifi volumio-cpu-tweak[1398]: pid 35's current scheduling policy: SCHED_OTHER Mar 28 03:51:58 ifi-ifi volumio-cpu-tweak[1398]: pid 35's current scheduling priority: 0 Mar 28 03:51:58 ifi-ifi volumio-cpu-tweak[1398]: Setting MPD Affinity Mar 28 03:51:58 ifi-ifi volumio-cpu-tweak[1398]: pid 3's current affinity mask: 1 Mar 28 03:51:58 ifi-ifi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 28 03:51:58 ifi-ifi systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 28 03:51:58 ifi-ifi systemd[1]: Startup finished in 3.646s (kernel) + 32.693s (userspace) = 36.339s. Mar 28 03:51:58 ifi-ifi volumio-cpu-tweak[1398]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Mar 28 03:51:58 ifi-ifi volumio-cpu-tweak[1398]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 28 03:51:58 ifi-ifi systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 28 03:52:01 ifi-ifi volumio[1397]: info: ------------------------------------------- Mar 28 03:52:01 ifi-ifi volumio[1397]: info: ----- Volumio3 ---- Mar 28 03:52:01 ifi-ifi volumio[1397]: info: ------------------------------------------- Mar 28 03:52:01 ifi-ifi volumio[1397]: info: ----- System startup ---- Mar 28 03:52:01 ifi-ifi volumio[1397]: info: ------------------------------------------- Mar 28 03:52:01 ifi-ifi volumio[1397]: info: ConvSwitch: Client Start Mar 28 03:52:01 ifi-ifi volumio[1397]: info: MetaClient: Client Start Mar 28 03:52:01 ifi-ifi node[506]: A new connection has been established. Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Plugin folders cleanup Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Scanning into folder /volumio/app/plugins/ Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Scanning category audio_interface Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Scanning category miscellanea Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Scanning category music_service Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Scanning category plugins.json Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Scanning category system_controller Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Scanning category user_interface Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Scanning into folder /data/plugins/ Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Scanning category music_service Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Plugin folders cleanup completed Mar 28 03:52:02 ifi-ifi volumio[1397]: info: ------------------------------------------- Mar 28 03:52:02 ifi-ifi volumio[1397]: info: ----- Core plugins startup ---- Mar 28 03:52:02 ifi-ifi volumio[1397]: info: ------------------------------------------- Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Loading plugins from folder /volumio/app/plugins/ Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Adding plugin networkaudiod to MyMusic Plugins Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Adding plugin qobuz_connect to MyMusic Plugins Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Adding plugin spotify_connect to MyMusic Plugins Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Adding plugin tidal_connect to MyMusic Plugins Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Adding plugin upnp to MyMusic Plugins Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Adding plugin airplay1_switch to MyMusic Plugins Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Adding plugin airplay2_switch to MyMusic Plugins Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Adding plugin roon_ready to MyMusic Plugins Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Loading plugins from folder /data/plugins/ Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Loading plugin "system"... Mar 28 03:52:02 ifi-ifi volumio[1397]: info: Loading plugin "appearance"... Mar 28 03:52:03 ifi-ifi dhcpcd[1039]: wlan0: leased 192.168.2.246 for 86400 seconds Mar 28 03:52:03 ifi-ifi dhcpcd[1039]: wlan0: adding route to 192.168.2.0/24 Mar 28 03:52:03 ifi-ifi dhcpcd[1039]: wlan0: adding default route via 192.168.2.2 Mar 28 03:52:03 ifi-ifi systemd[1]: Starting Internet Detect for iFi Streamer... Mar 28 03:52:03 ifi-ifi systemd[1]: ifi-system-internet.service: Succeeded. Mar 28 03:52:03 ifi-ifi systemd[1]: Started Internet Detect for iFi Streamer. Mar 28 03:52:03 ifi-ifi ntpd[1037]: ntpd exiting on signal 15 (Terminated) Mar 28 03:52:03 ifi-ifi ntpd[1037]: 216.232.132.95 local addr 192.168.2.252 -> Mar 28 03:52:03 ifi-ifi systemd[1]: Stopping Network Time Service... Mar 28 03:52:03 ifi-ifi ntpd[1037]: 216.232.132.21 local addr 192.168.2.252 -> Mar 28 03:52:03 ifi-ifi ntpd[1037]: 216.232.132.19 local addr 192.168.2.252 -> Mar 28 03:52:03 ifi-ifi ntpd[1037]: 174.138.194.79 local addr 192.168.2.252 -> Mar 28 03:52:03 ifi-ifi ntpd[1037]: 216.197.156.83 local addr 192.168.2.252 -> Mar 28 03:52:03 ifi-ifi ntpd[1037]: 206.108.0.132 local addr 192.168.2.252 -> Mar 28 03:52:03 ifi-ifi ntpd[1037]: 167.160.187.12 local addr 192.168.2.252 -> Mar 28 03:52:03 ifi-ifi ntpd[1037]: 147.189.136.126 local addr 192.168.2.252 -> Mar 28 03:52:03 ifi-ifi ntpd[1037]: 162.159.200.123 local addr 192.168.2.252 -> Mar 28 03:52:03 ifi-ifi ntpd[1037]: 23.133.168.244 local addr 192.168.2.252 -> Mar 28 03:52:03 ifi-ifi ntpd[1037]: 142.4.192.253 local addr 192.168.2.252 -> Mar 28 03:52:03 ifi-ifi ntpd[1037]: 170.39.49.50 local addr 192.168.2.252 -> Mar 28 03:52:03 ifi-ifi ntpd[1037]: 23.133.168.246 local addr 192.168.2.252 -> Mar 28 03:52:03 ifi-ifi ntpd[1037]: 158.69.247.84 local addr 192.168.2.252 -> Mar 28 03:52:03 ifi-ifi ntpd[1037]: 54.39.23.64 local addr 192.168.2.252 -> Mar 28 03:52:03 ifi-ifi systemd[1]: ntp.service: Succeeded. Mar 28 03:52:03 ifi-ifi systemd[1]: Stopped Network Time Service. Mar 28 03:52:03 ifi-ifi systemd[1]: Starting Network Time Service... Mar 28 03:52:03 ifi-ifi ntpd[1530]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 28 03:52:03 ifi-ifi ntpd[1530]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 28 03:52:03 ifi-ifi systemd[1]: Started Network Time Service. Mar 28 03:52:03 ifi-ifi ntpd[1536]: proto: precision = 0.875 usec (-20) Mar 28 03:52:03 ifi-ifi ntpd[1536]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 28 03:52:03 ifi-ifi ntpd[1536]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 28 03:52:03 ifi-ifi ntpd[1536]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1187 days ago Mar 28 03:52:03 ifi-ifi ntpd[1536]: Listen and drop on 0 v6wildcard [::]:123 Mar 28 03:52:03 ifi-ifi ntpd[1536]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 28 03:52:03 ifi-ifi ntpd[1536]: Listen normally on 2 lo 127.0.0.1:123 Mar 28 03:52:03 ifi-ifi ntpd[1536]: Listen normally on 3 eth0 192.168.2.252:123 Mar 28 03:52:03 ifi-ifi ntpd[1536]: Listen normally on 4 wlan0 192.168.2.246:123 Mar 28 03:52:03 ifi-ifi ntpd[1536]: Listening on routing socket on fd #21 for interface updates Mar 28 03:52:03 ifi-ifi ntpd[1536]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Mar 28 03:52:03 ifi-ifi ntpd[1536]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Mar 28 03:52:04 ifi-ifi ntpd[1536]: Soliciting pool server 216.232.132.95 Mar 28 03:52:04 ifi-ifi volumio[1397]: info: Loading plugin "network"... Mar 28 03:52:04 ifi-ifi volumio[1397]: info: Refreshing Cached IP Addresses Mar 28 03:52:04 ifi-ifi sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 03:52:04 ifi-ifi sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:04 ifi-ifi sudo[1541]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:05 ifi-ifi sudo[1543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 03:52:05 ifi-ifi sudo[1543]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:05 ifi-ifi sudo[1543]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Loading plugin "services"... Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Loading plugin "alsa_controller"... Mar 28 03:52:05 ifi-ifi sudo[1552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 28 03:52:05 ifi-ifi sudo[1552]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:05 ifi-ifi kernel: wl_escan_set_scan: ioctl_ver=2 Mar 28 03:52:05 ifi-ifi kernel: wl_escan_set_scan: LEGACY_SCAN Mar 28 03:52:05 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:05 ifi-ifi volumio[1397]: warn: The ALSA output card is not set, defaulting to card [object Object] Mar 28 03:52:05 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 03:52:05 ifi-ifi volumio[1397]: error: Could not retrieve plugin system_controller i2s_dacs Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Found match in Cards Database: setting mixer for card SPDIF Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Ignoring Mixers Options Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Device 0,0 does not have any Mixer Control Available Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Restoring Previous Volume level: 100 false false Mar 28 03:52:05 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 28 03:52:05 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 28 03:52:05 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:05 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 28 03:52:05 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Loading plugin "wizard"... Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Loading plugin "networkfs"... Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Starting Udev Watcher for removable devices Mar 28 03:52:05 ifi-ifi volumio[1397]: error: Cannot associate FS Label, not mounting Mar 28 03:52:05 ifi-ifi volumio[1397]: error: Cannot associate FS Label, not mounting Mar 28 03:52:05 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Ignoring mount for partition: BOOT Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Ignoring mount for partition: archivefs Mar 28 03:52:05 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Ignoring mount for partition: rootfs Mar 28 03:52:05 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Loading plugin "volumio_command_line_client"... Mar 28 03:52:05 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Loading plugin "networkaudiod"... Mar 28 03:52:05 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:05 ifi-ifi volumio[1397]: info: [1774669925467] Starting NAA Service Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Loading plugin "tidal_connect"... Mar 28 03:52:05 ifi-ifi volumio[1397]: info: [1774669925491] Starting Tidal Connect Service Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Loading plugin "upnp"... Mar 28 03:52:05 ifi-ifi sudo[1590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart networkaudiod.service Mar 28 03:52:05 ifi-ifi sudo[1590]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:05 ifi-ifi volumio[1397]: info: [1774669925504] Starting Upmpd Daemon Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Starting Upmpd Daemon Mar 28 03:52:05 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 28 03:52:05 ifi-ifi volumio[1397]: error: ATTENTION!!!: Plugin upnp does not return adequate promise from onVolumioStart: please update! Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Loading plugin "my_music"... Mar 28 03:52:05 ifi-ifi volumio[1397]: info: Loading plugin "mpd"... Mar 28 03:52:05 ifi-ifi systemd[1]: Stopping Network Audio Adapter daemon... Mar 28 03:52:05 ifi-ifi systemd[1]: networkaudiod.service: Succeeded. Mar 28 03:52:05 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:05 ifi-ifi systemd[1]: Stopped Network Audio Adapter daemon. Mar 28 03:52:05 ifi-ifi systemd[1]: Started Network Audio Adapter daemon. Mar 28 03:52:05 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:05 ifi-ifi sudo[1590]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:05 ifi-ifi networkaudiod[1592]: [/usr/sbin/networkaudiod] (1592): networkaudiod Copyright (C) 2011-2020 Jussi Laako / Signalyst. All rights reserved. Mar 28 03:52:05 ifi-ifi networkaudiod[1592]: [/usr/sbin/networkaudiod] (1592): create alsa engine Mar 28 03:52:05 ifi-ifi networkaudiod[1592]: [/usr/sbin/networkaudiod] (1592): listen discovery on :: Mar 28 03:52:05 ifi-ifi networkaudiod[1592]: [/usr/sbin/networkaudiod] (1592): clSocket::SetOption(): setsockopt(..., 41,20, ...): No such device Mar 28 03:52:05 ifi-ifi networkaudiod[1592]: [/usr/sbin/networkaudiod] (1592): Socket failure, falling back to IPv4-only! Mar 28 03:52:05 ifi-ifi ntpd[1536]: Soliciting pool server 216.232.132.21 Mar 28 03:52:05 ifi-ifi ntpd[1536]: Soliciting pool server 216.232.132.19 Mar 28 03:52:05 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:05 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:05 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:05 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:06 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:06 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:06 ifi-ifi volumio[1397]: info: Loading plugin "upnp_browser"... Mar 28 03:52:06 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:06 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:06 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:06 ifi-ifi networkaudiod[1592]: [/usr/sbin/networkaudiod] (1592): listen discovery on 0.0.0.0 Mar 28 03:52:06 ifi-ifi ntpd[1536]: Soliciting pool server 170.39.49.50 Mar 28 03:52:06 ifi-ifi ntpd[1536]: Soliciting pool server 216.197.156.83 Mar 28 03:52:06 ifi-ifi ntpd[1536]: Soliciting pool server 206.108.0.132 Mar 28 03:52:06 ifi-ifi volumio[1397]: info: Loading plugin "qobuz_connect"... Mar 28 03:52:06 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:06 ifi-ifi volumio[1397]: info: [1774669926706] Starting Qobuz Connect Service Mar 28 03:52:06 ifi-ifi volumio[1397]: info: Loading plugin "alarm-clock"... Mar 28 03:52:06 ifi-ifi volumio[1397]: info: Loading plugin "last_100"... Mar 28 03:52:06 ifi-ifi volumio[1397]: info: Loading plugin "roon_ready"... Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Starting RoonReady Sync Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Loading plugin "webradio"... Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Loading plugin "i2s_dacs"... Mar 28 03:52:07 ifi-ifi volumio[1397]: info: I2S DAC not set, start Auto-detection Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Loading plugin "volumiodiscovery"... Mar 28 03:52:07 ifi-ifi volumio[1397]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 28 03:52:07 ifi-ifi volumio[1397]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 28 03:52:07 ifi-ifi volumio[1397]: *** WARNING *** For more information see Mar 28 03:52:07 ifi-ifi volumio[1397]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 28 03:52:07 ifi-ifi volumio[1397]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 28 03:52:07 ifi-ifi volumio[1397]: *** WARNING *** For more information see Mar 28 03:52:07 ifi-ifi node[1397]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 28 03:52:07 ifi-ifi node[1397]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 28 03:52:07 ifi-ifi node[1397]: *** WARNING *** For more information see Mar 28 03:52:07 ifi-ifi node[1397]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 28 03:52:07 ifi-ifi node[1397]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 28 03:52:07 ifi-ifi node[1397]: *** WARNING *** For more information see Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 28 03:52:07 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Loading plugin "spotify_connect"... Mar 28 03:52:07 ifi-ifi volumio[1397]: info: [1774669927289] Starting Spotify Connect Service Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Plugin airplay1_switch is not enabled Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Loading plugin "airplay2_switch"... Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Loading plugin "airplay_emulation"... Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Starting Shairport Sync Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Loading plugin "outputs"... Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Loading plugin "albumart"... Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Plugin example_plugin is not enabled Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Loading plugin "inputs"... Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Loading plugin "updater_comm"... Mar 28 03:52:07 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:07 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:07 ifi-ifi ntpd[1536]: Soliciting pool server 142.167.7.212 Mar 28 03:52:07 ifi-ifi ntpd[1536]: Soliciting pool server 174.138.194.79 Mar 28 03:52:07 ifi-ifi ntpd[1536]: Soliciting pool server 23.133.168.244 Mar 28 03:52:07 ifi-ifi ntpd[1536]: Soliciting pool server 158.69.247.84 Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Plugin mpdemulation is not enabled Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Loading plugin "rest_api"... Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Loading plugin "websocket"... Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Starting Socket.io Server version 2.3.0 Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Plugin ifi-cdplayer is not enabled Mar 28 03:52:07 ifi-ifi volumio[1397]: info: Loading i18n strings for locale en Mar 28 03:52:07 ifi-ifi volumio[1397]: Updating browse sources language Mar 28 03:52:07 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 03:52:07 ifi-ifi volumio[1397]: Forking 3 albumart workers Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreCommandRouter::initPlayerControls Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Mar 28 03:52:08 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 03:52:08 ifi-ifi volumio[1397]: Express server listening on port 3000 Mar 28 03:52:08 ifi-ifi volumio[1397]: [Metrics] WebUI: 8s 76.36ms Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreStateMachine::resetVolumioState Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreStateMachine::getcurrentVolume Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioRetrievevolume Mar 28 03:52:08 ifi-ifi volumio[1397]: Meta server listening Mar 28 03:52:08 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CorePlayQueue::getTrack 0 Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:08 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 03:52:08 ifi-ifi volumio[1397]: info: Server listening on port 6599 Mar 28 03:52:08 ifi-ifi ntpd[1536]: Soliciting pool server 162.159.200.123 Mar 28 03:52:08 ifi-ifi ntpd[1536]: Soliciting pool server 162.159.200.1 Mar 28 03:52:08 ifi-ifi ntpd[1536]: Soliciting pool server 167.160.187.12 Mar 28 03:52:08 ifi-ifi sudo[1644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Mar 28 03:52:08 ifi-ifi volumio[1397]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 28 03:52:08 ifi-ifi volumio[1397]: info: Completed loading Core Plugins Mar 28 03:52:08 ifi-ifi sudo[1644]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:08 ifi-ifi sudo[1646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Mar 28 03:52:08 ifi-ifi volumio[1397]: info: Preparing to generate the ALSA configuration file Mar 28 03:52:08 ifi-ifi sudo[1646]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:08 ifi-ifi volumio[1397]: info: Cannot read play queue from file Mar 28 03:52:08 ifi-ifi systemd[1]: Starting Internet Detect for iFi Streamer... Mar 28 03:52:08 ifi-ifi volumio[1397]: info: ConvSwitch: Connected to server Mar 28 03:52:08 ifi-ifi volumio[1397]: info: MetaClient: Connected to server Mar 28 03:52:08 ifi-ifi node[494]: ConvSwitch: Add client volumio Mar 28 03:52:08 ifi-ifi volumio[1397]: info: Volumio Network Manager: Network status updated: 3 Mar 28 03:52:08 ifi-ifi volumio[1397]: info: NAA Service Started Mar 28 03:52:08 ifi-ifi volumio[1397]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 28 03:52:08 ifi-ifi systemd[1]: Started iFi Streamer Qobuz Connect Service. Mar 28 03:52:08 ifi-ifi sudo[1646]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:09 ifi-ifi systemd[1]: Started iFi Streamer Tidal Connect Service. Mar 28 03:52:09 ifi-ifi volumio[1397]: info: ConvSwitch: Get message: success Mar 28 03:52:09 ifi-ifi sudo[1644]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:09 ifi-ifi volumio[1397]: info: MetaClient: Get message: success Mar 28 03:52:09 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:09 ifi-ifi systemd[1]: Started iFi Streamer Tidal Metadata Service. Mar 28 03:52:09 ifi-ifi volumio[1397]: info: Tidal Connect Service Started Mar 28 03:52:09 ifi-ifi volumio[1397]: info: Qobuz Connect Service Started Mar 28 03:52:09 ifi-ifi node[506]: A new connection has been established. Mar 28 03:52:09 ifi-ifi systemd[1]: ifi-system-internet.service: Succeeded. Mar 28 03:52:09 ifi-ifi systemd[1]: Started Internet Detect for iFi Streamer. Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreStateMachine::getcurrentVolume Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioRetrievevolume Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Mar 28 03:52:09 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 8 Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CorePlayQueue::getTrack 0 Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreStateMachine::setRepeat null single undefined Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CorePlayQueue::getTrack 0 Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreStateMachine::setRandom null Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CorePlayQueue::getTrack 0 Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:09 ifi-ifi volumio[1397]: info: Asound.conf file unchanged, so no further update is needed Mar 28 03:52:09 ifi-ifi volumio[1397]: info: Tidal connect: Output device has changed Mar 28 03:52:09 ifi-ifi volumio[1397]: info: Output device has changed, restarting MPD Mar 28 03:52:09 ifi-ifi volumio[1397]: info: Qobuz connect: Output device has changed Mar 28 03:52:09 ifi-ifi volumio[1397]: info: Roon Ready: Output device has changed Mar 28 03:52:09 ifi-ifi volumio[1397]: info: Spotify: Output device has changed Mar 28 03:52:09 ifi-ifi volumio[1397]: info: ___________ START PLUGINS ___________ Mar 28 03:52:09 ifi-ifi volumio[1397]: info: ControllerMpd::onStart: Initializing MPD Mar 28 03:52:09 ifi-ifi volumio[1397]: info: Creating MPD Configuration file Mar 28 03:52:09 ifi-ifi sudo[1708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 03:52:09 ifi-ifi sudo[1708]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:09 ifi-ifi sudo[1708]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.585 [1713.1713] DEBUG SampleApp: Starting sample application Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.585 [1713.1713] DEBUG SampleApp: Device Name: iFi Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.585 [1713.1713] DEBUG SampleApp: Manufacturer: Sample manufacturer Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.586 [1713.1713] DEBUG SampleApp: Model: iFi Streamer Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.586 [1713.1713] DEBUG SampleApp: Serial Number: ifi:36:cf:8c:9b:ce:dd Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.586 [1713.1713] DEBUG SampleApp: Audio output device: volumio Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.586 [1713.1713] DEBUG SampleApp: Volume control device: default Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.586 [1713.1713] DEBUG SampleApp: Volume control element: Master Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.586 [1713.1713] DEBUG SampleApp: Local config server port: 8000 Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.592 [1713.1713] ERROR SampleApp: Unable to find ALSA mixer element 'Master' on index 0 Mar 28 03:52:09 ifi-ifi volumio[1397]: info: [1774669929595] CoreMusicLibrary::Adding element Media Servers Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.601 [1713.1713] DEBUG SampleApp: Maximum supported audio quality: HIRES_L3 Mar 28 03:52:09 ifi-ifi play[1655]: [Info] MetaClient: MetaClient SDK -- v1.02 Mar 28 03:52:09 ifi-ifi node[506]: A new connection has been established. Mar 28 03:52:09 ifi-ifi sudo[1717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 03:52:09 ifi-ifi sudo[1717]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:09 ifi-ifi play[1655]: [Info] MetaClient: MetaClientInit Mar 28 03:52:09 ifi-ifi play[1655]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Mar 28 03:52:09 ifi-ifi node[494]: ConvSwitch: Add client qobuz-connect Mar 28 03:52:09 ifi-ifi sudo[1719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Mar 28 03:52:09 ifi-ifi sudo[1719]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:09 ifi-ifi ntpd[1536]: Soliciting pool server 142.4.192.253 Mar 28 03:52:09 ifi-ifi ntpd[1536]: Soliciting pool server 23.133.168.245 Mar 28 03:52:09 ifi-ifi sudo[1717]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:09 ifi-ifi play[1655]: [Info] ConvSwitch: ConvSwitchInit Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.736 [1713.1713] DEBUG ActiveStateManager: [0xaae73348]: Creating new Active State Manager Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.737 [1713.1713] DEBUG NetworkActivityTracker: [0xaae733b8]: Creating new Network Activity Tracker Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.743 [1713.1713] DEBUG EndpointManager: [0xaae74330]: Creating Endpoint Manager Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.743 [1713.1713] DEBUG PlaybackSessionManager: [0xaae74720]: Creating Playback Session Manager Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.743 [1713.1713] DEBUG BackoffTimer: [0xaae74898]: Creating Backoff Timer Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.743 [1713.1713] DEBUG MediaEngine: [0xaae749b8]: Creating new Media Engine Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.743 [1713.1713] DEBUG AudioRenderer: [0xaae74bd8]: Creating Audio Renderer Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] TRACE MediaEngineCommandSequencer: [0xaae74d30]: Creating Media Engine Command Sequencer Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] DEBUG PlaybackControlsManager: [0xaae74d90]: Creating new Playback Controls Manager Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] DEBUG VolumeManager: [0xaae74e20]: Creating new Volume Manager Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] DEBUG ProtocolHandler: [0xaae750f8]: Creating Protocol Handler Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] DEBUG MessageReceiver: [0xaae75198]: Creating Message Receiver Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] DEBUG RendererActivationController: [0xaae75310]: Creating new Renderer Activation Controller Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] TRACE RendererActivationCommandSequencer: [0xaae753b8]: Creating Renderer Activation Command Sequencer Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] DEBUG ReportingManager: [0xaae754c8]: Creating new Reporting Manager Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] DEBUG StreamingEventReporter: [0xaae75510]: Creating Streaming Event Reporter Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] DEBUG BackoffTimer: [0xaae755f0]: Creating Backoff Timer Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] DEBUG TokenExpiryController: [0xaae75700]: Creating Token Expiry Controller Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] DEBUG UserInactivityManager: [0xaae75750]: Creating new User Inactivity Manager Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] INFO VolumeManager: [0xaae74e20]: Setting new playback volume: 100 Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] INFO VolumeManager: [0xaae74e20]: Setting new mute state: 1 Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] DEBUG SampleApp: Qobuz Connect has been initialized Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] TRACE SampleApp: Running event loop Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] INFO QobuzConnect: [0xaae757f0]: Client initialized! Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.744 [1713.1713] INFO SampleApp: Starting Avahi advertising, name: iFi, service name: _qobuz-connect._tcp Mar 28 03:52:09 ifi-ifi volumio[1397]: info: [1774669929746] CoreMusicLibrary::Adding element Last_100 Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 03:52:09 ifi-ifi volumio[1397]: info: Starting RoonReady Sync Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.774 [1713.1713] DEBUG SampleApp: Avahi client state changed: RUNNING Mar 28 03:52:09 ifi-ifi kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03b316000 drv_status 0x0 e_type 69 e_states 0 Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.782 [1713.1713] DEBUG SampleApp: Avahi entry group state changed: UNCOMMITED Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.794 [1713.1713] INFO LocalConfigManager: [0xaae74700]: Starting Local Configuration server Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.794 [1713.1713] INFO SampleApp: Starting Local configuration server Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.796 [1713.1713] INFO SampleApp: Playback mute state changed: 1 Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.796 [1713.1713] ERROR SampleApp: Unable to set mute state, no ALSA volume element Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.796 [1713.1713] DEBUG SampleApp: Avahi entry group state changed: REGISTERING Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.796 [1713.1713] INFO SampleApp: Playback volume changed: 100 Mar 28 03:52:09 ifi-ifi play[1655]: 20260328 03:52:09.796 [1713.1713] ERROR SampleApp: Unable to set volume, no ALSA volume element Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 28 03:52:09 ifi-ifi volumio[1397]: info: [1774669929800] CoreMusicLibrary::Adding element Webradio Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 03:52:09 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 03:52:09 ifi-ifi sudo[1552]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:09 ifi-ifi volumio[1397]: info: Initializing BBC Radios Mar 28 03:52:09 ifi-ifi sudo[1719]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:09 ifi-ifi sudo[1727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Mar 28 03:52:09 ifi-ifi volumio[1397]: Starting albumart workers Mar 28 03:52:09 ifi-ifi sudo[1727]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:09 ifi-ifi systemd[1]: Started iFi Streamer Roon Ready Service. Mar 28 03:52:09 ifi-ifi sudo[1727]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:10 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 03:52:10 ifi-ifi volumio[1397]: Starting albumart workers Mar 28 03:52:10 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 03:52:10 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:10 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 03:52:10 ifi-ifi play[1736]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Mar 28 03:52:10 ifi-ifi sudo[1743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -snf /usr/local/bin/airplay2 /usr/local/bin/shairport-sync Mar 28 03:52:10 ifi-ifi sudo[1743]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:10 ifi-ifi volumio[1397]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 03:52:10 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:10 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 03:52:10 ifi-ifi sudo[1743]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:10 ifi-ifi volumio[1397]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 03:52:10 ifi-ifi volumio[1397]: info: Volumio Calling Home Mar 28 03:52:10 ifi-ifi volumio[1397]: Starting albumart workers Mar 28 03:52:10 ifi-ifi ntpd[1536]: Soliciting pool server 54.39.23.64 Mar 28 03:52:10 ifi-ifi ntpd[1536]: Soliciting pool server 2607:f2c0:f00e:6f00::123 Mar 28 03:52:10 ifi-ifi node[494]: ConvSwitch: Add client roon-ready Mar 28 03:52:10 ifi-ifi play[1655]: 20260328 03:52:10.793 [1713.1713] DEBUG SampleApp: Avahi entry group state changed: ESTABLISHED Mar 28 03:52:10 ifi-ifi volumio[1397]: info: VolumeController::SetAlsaVolume100 Mar 28 03:52:10 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Mar 28 03:52:10 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:10 ifi-ifi volumio[1397]: info: CorePlayQueue::getTrack 0 Mar 28 03:52:10 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:10 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:11 ifi-ifi volumio[1397]: info: Starting RoonReady Sync Mar 28 03:52:11 ifi-ifi sudo[1785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Mar 28 03:52:11 ifi-ifi sudo[1785]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:11 ifi-ifi sudo[1787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Mar 28 03:52:11 ifi-ifi volumio[1397]: info: Discovery: adding b7a7124e-140c-4391-971c-f87eb8bd01b7 Mar 28 03:52:11 ifi-ifi volumio[1397]: info: Discovery: Found device iFi Mar 28 03:52:11 ifi-ifi sudo[1787]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:11 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioGetState Mar 28 03:52:11 ifi-ifi volumio[1397]: info: CorePlayQueue::getTrack 0 Mar 28 03:52:11 ifi-ifi volumio[1397]: error: Could not retrieve plugin audio_interface multiroom Mar 28 03:52:11 ifi-ifi sudo[1795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Mar 28 03:52:11 ifi-ifi sudo[1795]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:11 ifi-ifi volumio[1397]: info: ConvSwitch: Get message: request:pause Mar 28 03:52:11 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioStop Mar 28 03:52:11 ifi-ifi volumio[1397]: info: CoreStateMachine::stop Mar 28 03:52:11 ifi-ifi volumio[1397]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 03:52:11 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioStop Mar 28 03:52:11 ifi-ifi volumio[1397]: info: CoreStateMachine::stop Mar 28 03:52:11 ifi-ifi volumio[1397]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 03:52:11 ifi-ifi volumio[1397]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 03:52:11 ifi-ifi systemd[1]: Started iFi Streamer Spotify Connect Service. Mar 28 03:52:11 ifi-ifi sudo[1795]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:11 ifi-ifi node[494]: ConvSwitch: Drop client roon-ready Mar 28 03:52:11 ifi-ifi systemd[1]: Stopping iFi Streamer Roon Ready Service... Mar 28 03:52:11 ifi-ifi systemd[1]: Stopping iFi Streamer Qobuz Connect Service... Mar 28 03:52:11 ifi-ifi node[506]: Closing connection with the client qobuz-connect Mar 28 03:52:11 ifi-ifi node[494]: ConvSwitch: Drop client qobuz-connect Mar 28 03:52:11 ifi-ifi systemd[1]: ifi-streamer-qobuz-connect.service: Main process exited, code=killed, status=15/TERM Mar 28 03:52:11 ifi-ifi systemd[1]: ifi-streamer-qobuz-connect.service: Succeeded. Mar 28 03:52:11 ifi-ifi systemd[1]: Stopped iFi Streamer Qobuz Connect Service. Mar 28 03:52:11 ifi-ifi volumio[1397]: info: Airplay2 Started Mar 28 03:52:11 ifi-ifi volumio[1397]: error: Could not retrieve plugin music_service airplay1_switch Mar 28 03:52:11 ifi-ifi systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=killed, status=15/TERM Mar 28 03:52:11 ifi-ifi systemd[1]: ifi-streamer-roon-ready.service: Succeeded. Mar 28 03:52:11 ifi-ifi systemd[1]: Stopped iFi Streamer Roon Ready Service. Mar 28 03:52:11 ifi-ifi volumio[1397]: info: Disabling plugin airplay1_switch Mar 28 03:52:11 ifi-ifi volumio[1397]: info: Done. Mar 28 03:52:11 ifi-ifi volumio[1397]: info: RoonReady Started Mar 28 03:52:11 ifi-ifi volumio[1397]: info: MPD Permissions set Mar 28 03:52:11 ifi-ifi volumio[1397]: info: MPD Permissions set Mar 28 03:52:11 ifi-ifi sudo[1853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Mar 28 03:52:11 ifi-ifi sudo[1853]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:11 ifi-ifi sudo[1785]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:11 ifi-ifi sudo[1860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Mar 28 03:52:11 ifi-ifi sudo[1860]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:11 ifi-ifi sudo[1865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Mar 28 03:52:11 ifi-ifi sudo[1865]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:11 ifi-ifi play[1820]: output: volumio mixer: hw:0 Mar 28 03:52:11 ifi-ifi node[494]: ConvSwitch: Add client spotify Mar 28 03:52:11 ifi-ifi volumio[1397]: info: Spotify Connect Service Started Mar 28 03:52:11 ifi-ifi volumio[1397]: info: Volumio called home Mar 28 03:52:11 ifi-ifi node[506]: A new connection has been established. Mar 28 03:52:11 ifi-ifi volumio[1397]: info: Mar 28 03:52:11 ifi-ifi volumio[1397]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Mar 28 03:52:11 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Mar 28 03:52:11 ifi-ifi volumio[1397]: info: Roon Ready playback start Mar 28 03:52:11 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioStop Mar 28 03:52:11 ifi-ifi volumio[1397]: info: CoreStateMachine::stop Mar 28 03:52:11 ifi-ifi volumio[1397]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.895 I api SpRegisterDebugCallbacks [returned value: 0] Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.895 I api SpInit(0xfffb99b0, API v66) Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.895 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.896 I esdk Successfully registered socket callbacks: cre, set_opt, cl, bind, list, con, acc, rd, wr, rd_from, wr_to, err, readable, writable, local_addresses, address, pump Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.896 I esdk Successfully registered dns callback: dns_lookup_callback Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.896 I download Using 128 kB for cache buffer Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.896 I download Using 621 kB for compressed audio buffer Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.896 I esdk Successfully registered error callback Mar 28 03:52:11 ifi-ifi systemd[1]: Listening on mpd.socket. Mar 28 03:52:11 ifi-ifi sudo[1865]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:11 ifi-ifi volumio[1397]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 03:52:11 ifi-ifi systemd[1]: Starting Music Player Daemon... Mar 28 03:52:11 ifi-ifi volumio[1397]: info: Starting Roon Ready metadata receiver Mar 28 03:52:11 ifi-ifi volumio[1397]: Error adding Membership: Error: addMembership EINVAL Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.920 I esdk Creating IPv4 socket (domain 2) Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.920 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.920 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.921 I esdk Creating IPv4 socket (domain 2) Mar 28 03:52:11 ifi-ifi systemd[1]: Started iFi Streamer Roon Ready Service. Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.923 I api SpInit [returned value: 0] Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.923 I api SpRegisterConnectionCallbacks(0xfffb9998, (nil)) Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.923 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.923 I api SpRegisterConnectionCallbacks [returned value: 0] Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.923 I api SpRegisterPlaybackCallbacks(0xfffb9980, (nil)) Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.923 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.923 I api SpRegisterPlaybackCallbacks [returned value: 0] Mar 28 03:52:11 ifi-ifi sudo[1787]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:11 ifi-ifi play[1820]: 2026-03-28 03:52:11.930 I app Initialized! Mar 28 03:52:12 ifi-ifi sudo[1853]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:12 ifi-ifi play[1885]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Mar 28 03:52:12 ifi-ifi sudo[1884]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 28 03:52:12 ifi-ifi sudo[1884]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:12 ifi-ifi sudo[1884]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 28 03:52:12 ifi-ifi sudo[1884]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:12 ifi-ifi ifi_metadata[1666]: [Info] MetaClient: MetaClient SDK -- v1.02 Mar 28 03:52:12 ifi-ifi ifi_metadata[1666]: [Info] MetaClient: MetaClientInit Mar 28 03:52:12 ifi-ifi ifi_metadata[1666]: Could not connect: Reason::CONNECTION_ERROR Mar 28 03:52:12 ifi-ifi node[494]: ConvSwitch: Add client roon-ready Mar 28 03:52:12 ifi-ifi node[494]: ConvSwitch: Add client tidal Mar 28 03:52:12 ifi-ifi tidal_connect_application[1841]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Mar 28 03:52:12 ifi-ifi tidal_connect_application[1841]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 28 03:52:12 ifi-ifi tidal_connect_application[1841]: *** WARNING *** For more information see Mar 28 03:52:12 ifi-ifi ifi_metadata[1666]: Connected Mar 28 03:52:13 ifi-ifi volumio[1397]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:13 ifi-ifi sudo[1939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Mar 28 03:52:13 ifi-ifi sudo[1939]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 28 03:52:13 ifi-ifi systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Mar 28 03:52:13 ifi-ifi node[506]: Closing connection with the client tidal-connect Mar 28 03:52:13 ifi-ifi systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Mar 28 03:52:13 ifi-ifi systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Mar 28 03:52:13 ifi-ifi systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Mar 28 03:52:13 ifi-ifi systemd[1]: Stopping iFi Streamer Tidal Connect Service... Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:13 ifi-ifi systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Mar 28 03:52:13 ifi-ifi node[494]: ConvSwitch: Drop client tidal Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:13 ifi-ifi systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:13 ifi-ifi systemd[1]: Stopped iFi Streamer Tidal Connect Service. Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:13 ifi-ifi systemd[1]: Started iFi Streamer Tidal Connect Service. Mar 28 03:52:13 ifi-ifi sudo[1939]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:13 ifi-ifi systemd[1]: Started iFi Streamer Tidal Metadata Service. Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 28 03:52:13 ifi-ifi node[506]: A new connection has been established. Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:13 ifi-ifi volumio[1397]: info: ConvSwitch: Get message: request:pause Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioStop Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreStateMachine::stop Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreStateMachine::serviceStop Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::serviceStop Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioStop Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreStateMachine::stop Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 03:52:13 ifi-ifi volumio[1397]: info: Roon Ready Stop Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioStop Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreStateMachine::stop Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 03:52:13 ifi-ifi volumio[1397]: info: Shairport-Sync Stopped Mar 28 03:52:13 ifi-ifi volumio[1397]: info: RoonReady Started Mar 28 03:52:13 ifi-ifi sudo[1971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Mar 28 03:52:13 ifi-ifi sudo[1971]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:13 ifi-ifi volumio[1397]: info: Tidal Connect Service Started Mar 28 03:52:13 ifi-ifi systemd[1]: mpd.socket: Succeeded. Mar 28 03:52:13 ifi-ifi systemd[1]: Closed mpd.socket. Mar 28 03:52:13 ifi-ifi systemd[1]: Stopping mpd.socket. Mar 28 03:52:13 ifi-ifi systemd[1]: mpd.socket: Socket service mpd.service already active, refusing. Mar 28 03:52:13 ifi-ifi systemd[1]: Failed to listen on mpd.socket. Mar 28 03:52:13 ifi-ifi volumio[1397]: info: Mar 28 03:52:13 ifi-ifi volumio[1397]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Mar 28 03:52:13 ifi-ifi volumio[1397]: info: Roon Ready playback start Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioStop Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreStateMachine::stop Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 03:52:13 ifi-ifi volumio[1397]: info: Roon Ready metadata receiver is on Mar 28 03:52:13 ifi-ifi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 28 03:52:13 ifi-ifi systemd[1]: mpd.service: Succeeded. Mar 28 03:52:13 ifi-ifi systemd[1]: Stopped Music Player Daemon. Mar 28 03:52:13 ifi-ifi systemd[1]: Listening on mpd.socket. Mar 28 03:52:13 ifi-ifi systemd[1]: Starting Music Player Daemon... Mar 28 03:52:13 ifi-ifi volumio[1397]: info: Starting Shairport Sync Mar 28 03:52:13 ifi-ifi volumio[1397]: info: Starting Shairport Sync Mar 28 03:52:13 ifi-ifi sudo[1975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 03:52:13 ifi-ifi sudo[1975]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:13 ifi-ifi sudo[1977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 03:52:13 ifi-ifi sudo[1977]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:13 ifi-ifi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 28 03:52:13 ifi-ifi sudo[1975]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:13 ifi-ifi volumio[1397]: info: ++++--++++ getState Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioGetState Mar 28 03:52:13 ifi-ifi volumio[1397]: info: CorePlayQueue::getTrack 0 Mar 28 03:52:13 ifi-ifi sudo[1973]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 28 03:52:13 ifi-ifi sudo[1973]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:13 ifi-ifi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 28 03:52:13 ifi-ifi sudo[1973]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 28 03:52:13 ifi-ifi sudo[1973]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:13 ifi-ifi systemd[1]: Starting Internet Detect for iFi Streamer... Mar 28 03:52:13 ifi-ifi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 28 03:52:13 ifi-ifi systemd[1]: shairport-sync.service: Succeeded. Mar 28 03:52:13 ifi-ifi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 28 03:52:13 ifi-ifi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 28 03:52:13 ifi-ifi sudo[1977]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:13 ifi-ifi node[506]: A new connection has been established. Mar 28 03:52:13 ifi-ifi volumio[1397]: info: Shairport-Sync Started Mar 28 03:52:13 ifi-ifi volumio[1397]: Error adding Membership: Error: addMembership EINVAL Mar 28 03:52:14 ifi-ifi volumio[1397]: info: Shairport-Sync Started Mar 28 03:52:14 ifi-ifi systemd[1]: ifi-system-internet.service: Succeeded. Mar 28 03:52:14 ifi-ifi systemd[1]: Started Internet Detect for iFi Streamer. Mar 28 03:52:14 ifi-ifi sudo[2011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Mar 28 03:52:14 ifi-ifi sudo[2011]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:14 ifi-ifi sudo[2013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Mar 28 03:52:14 ifi-ifi sudo[2013]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:14 ifi-ifi systemd[1]: Started iFi Streamer Qobuz Connect Service. Mar 28 03:52:14 ifi-ifi play[1820]: 2026-03-28 03:52:14.291 E esdk select error: 4 -> Interrupted system call Mar 28 03:52:14 ifi-ifi play[1820]: 2026-03-28 03:52:14.292 I app Exiting with error = 0 Mar 28 03:52:14 ifi-ifi play[1820]: 2026-03-28 03:52:14.292 I api SpFree() Mar 28 03:52:14 ifi-ifi play[1820]: 2026-03-28 03:52:14.293 I api SpFree [returned value: 0] Mar 28 03:52:14 ifi-ifi play[1820]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Mar 28 03:52:14 ifi-ifi play[1820]: [Info] ConvSwitch: ConvSwitchInit Mar 28 03:52:14 ifi-ifi play[1820]: [Info] MetaClient: MetaClient SDK -- v1.02 Mar 28 03:52:14 ifi-ifi play[1820]: [Info] MetaClient: MetaClientInit Mar 28 03:52:14 ifi-ifi play[1820]: [Info] ConvSwitch: ConvSwitchExit Mar 28 03:52:14 ifi-ifi play[1820]: [Info] MetaClient: MetaClientExit Mar 28 03:52:14 ifi-ifi node[494]: ConvSwitch: Drop client spotify Mar 28 03:52:14 ifi-ifi node[506]: Closing connection with the client spotify Mar 28 03:52:14 ifi-ifi systemd[1]: Stopping iFi Streamer Spotify Connect Service... Mar 28 03:52:14 ifi-ifi systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Mar 28 03:52:14 ifi-ifi systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Mar 28 03:52:14 ifi-ifi systemd[1]: Stopped iFi Streamer Spotify Connect Service. Mar 28 03:52:14 ifi-ifi sudo[2013]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:14 ifi-ifi volumio[1397]: info: Qobuz Connect Service Started Mar 28 03:52:14 ifi-ifi systemd[1]: Started iFi Streamer Spotify Connect Service. Mar 28 03:52:14 ifi-ifi sudo[2011]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:14 ifi-ifi volumio[1397]: info: Spotify Connect Service Started Mar 28 03:52:14 ifi-ifi play[2020]: output: volumio mixer: hw:0 Mar 28 03:52:14 ifi-ifi node[494]: ConvSwitch: Add client spotify Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.557 [2050.2050] DEBUG SampleApp: Starting sample application Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.557 [2050.2050] DEBUG SampleApp: Device Name: iFi Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.557 [2050.2050] DEBUG SampleApp: Manufacturer: Sample manufacturer Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.557 [2050.2050] DEBUG SampleApp: Model: iFi Streamer Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.557 [2050.2050] DEBUG SampleApp: Serial Number: ifi:36:cf:8c:9b:ce:dd Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.557 [2050.2050] DEBUG SampleApp: Audio output device: volumio Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.557 [2050.2050] DEBUG SampleApp: Volume control device: default Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.557 [2050.2050] DEBUG SampleApp: Volume control element: Master Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.557 [2050.2050] DEBUG SampleApp: Local config server port: 8000 Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.563 [2050.2050] ERROR SampleApp: Unable to find ALSA mixer element 'Master' on index 0 Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.563 [2050.2050] DEBUG SampleApp: Maximum supported audio quality: HIRES_L3 Mar 28 03:52:14 ifi-ifi play[2016]: [Info] MetaClient: MetaClient SDK -- v1.02 Mar 28 03:52:14 ifi-ifi node[506]: A new connection has been established. Mar 28 03:52:14 ifi-ifi node[506]: A new connection has been established. Mar 28 03:52:14 ifi-ifi play[2016]: [Info] MetaClient: MetaClientInit Mar 28 03:52:14 ifi-ifi play[2016]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Mar 28 03:52:14 ifi-ifi node[494]: ConvSwitch: Add client qobuz-connect Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.649 I api SpRegisterDebugCallbacks [returned value: 0] Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.649 I api SpInit(0xffd26740, API v66) Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.649 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.650 I esdk Successfully registered socket callbacks: cre, set_opt, cl, bind, list, con, acc, rd, wr, rd_from, wr_to, err, readable, writable, local_addresses, address, pump Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.650 I esdk Successfully registered dns callback: dns_lookup_callback Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.650 I download Using 128 kB for cache buffer Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.650 I download Using 621 kB for compressed audio buffer Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.650 I esdk Successfully registered error callback Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.662 I esdk Creating IPv4 socket (domain 2) Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.663 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.663 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.663 I esdk Creating IPv4 socket (domain 2) Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.663 I api SpInit [returned value: 0] Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.663 I api SpRegisterConnectionCallbacks(0xffd26728, (nil)) Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.663 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.663 I api SpRegisterConnectionCallbacks [returned value: 0] Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.663 I api SpRegisterPlaybackCallbacks(0xffd26710, (nil)) Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.663 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.663 I api SpRegisterPlaybackCallbacks [returned value: 0] Mar 28 03:52:14 ifi-ifi play[2020]: 2026-03-28 03:52:14.671 I app Initialized! Mar 28 03:52:14 ifi-ifi play[2016]: [Info] ConvSwitch: ConvSwitchInit Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.672 [2050.2050] DEBUG ActiveStateManager: [0xab25b348]: Creating new Active State Manager Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.672 [2050.2050] DEBUG NetworkActivityTracker: [0xab25b3b8]: Creating new Network Activity Tracker Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.673 [2050.2050] DEBUG EndpointManager: [0xab25c330]: Creating Endpoint Manager Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.673 [2050.2050] DEBUG PlaybackSessionManager: [0xab25c720]: Creating Playback Session Manager Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.673 [2050.2050] DEBUG BackoffTimer: [0xab25c898]: Creating Backoff Timer Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.673 [2050.2050] DEBUG MediaEngine: [0xab25c9b8]: Creating new Media Engine Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.673 [2050.2050] DEBUG AudioRenderer: [0xab25cbd8]: Creating Audio Renderer Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.673 [2050.2050] TRACE MediaEngineCommandSequencer: [0xab25cd30]: Creating Media Engine Command Sequencer Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.673 [2050.2050] DEBUG PlaybackControlsManager: [0xab25cd90]: Creating new Playback Controls Manager Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.673 [2050.2050] DEBUG VolumeManager: [0xab25ce20]: Creating new Volume Manager Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.673 [2050.2050] DEBUG ProtocolHandler: [0xab25d0f8]: Creating Protocol Handler Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.673 [2050.2050] DEBUG MessageReceiver: [0xab25d198]: Creating Message Receiver Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.673 [2050.2050] DEBUG RendererActivationController: [0xab25d310]: Creating new Renderer Activation Controller Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.673 [2050.2050] TRACE RendererActivationCommandSequencer: [0xab25d3b8]: Creating Renderer Activation Command Sequencer Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.673 [2050.2050] DEBUG ReportingManager: [0xab25d4c8]: Creating new Reporting Manager Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.673 [2050.2050] DEBUG StreamingEventReporter: [0xab25d510]: Creating Streaming Event Reporter Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.673 [2050.2050] DEBUG BackoffTimer: [0xab25d5f0]: Creating Backoff Timer Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.674 [2050.2050] DEBUG TokenExpiryController: [0xab25d700]: Creating Token Expiry Controller Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.674 [2050.2050] DEBUG UserInactivityManager: [0xab25d750]: Creating new User Inactivity Manager Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.674 [2050.2050] INFO VolumeManager: [0xab25ce20]: Setting new playback volume: 100 Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.674 [2050.2050] INFO VolumeManager: [0xab25ce20]: Setting new mute state: 1 Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.674 [2050.2050] DEBUG SampleApp: Qobuz Connect has been initialized Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.674 [2050.2050] TRACE SampleApp: Running event loop Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.674 [2050.2050] INFO QobuzConnect: [0xab25d7f0]: Client initialized! Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.674 [2050.2050] INFO SampleApp: Starting Avahi advertising, name: iFi, service name: _qobuz-connect._tcp Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.687 [2050.2050] DEBUG SampleApp: Avahi client state changed: RUNNING Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.691 [2050.2050] DEBUG SampleApp: Avahi entry group state changed: UNCOMMITED Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.699 [2050.2050] INFO LocalConfigManager: [0xab25c700]: Starting Local Configuration server Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.699 [2050.2050] INFO SampleApp: Starting Local configuration server Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.700 [2050.2050] INFO SampleApp: Playback mute state changed: 1 Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.700 [2050.2050] ERROR SampleApp: Unable to set mute state, no ALSA volume element Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.701 [2050.2050] DEBUG SampleApp: Avahi entry group state changed: REGISTERING Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.708 [2050.2050] INFO SampleApp: Playback volume changed: 100 Mar 28 03:52:14 ifi-ifi play[2016]: 20260328 03:52:14.708 [2050.2050] ERROR SampleApp: Unable to set volume, no ALSA volume element Mar 28 03:52:15 ifi-ifi sudo[2088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 03:52:15 ifi-ifi sudo[2088]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:15 ifi-ifi sudo[2088]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:15 ifi-ifi sudo[2089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 03:52:15 ifi-ifi sudo[2089]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:15 ifi-ifi sudo[2092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart upmpdcli.service Mar 28 03:52:15 ifi-ifi sudo[2092]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 03:52:15 ifi-ifi sudo[2089]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:15 ifi-ifi play[2016]: 20260328 03:52:15.698 [2050.2050] DEBUG SampleApp: Avahi entry group state changed: ESTABLISHED Mar 28 03:52:15 ifi-ifi systemd[1]: Starting UPnP Renderer front-end to MPD... Mar 28 03:52:15 ifi-ifi node[494]: ConvSwitch: Add client tidal Mar 28 03:52:15 ifi-ifi tidal_connect_application[2081]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Mar 28 03:52:15 ifi-ifi tidal_connect_application[2081]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 28 03:52:15 ifi-ifi tidal_connect_application[2081]: *** WARNING *** For more information see Mar 28 03:52:16 ifi-ifi ifi_metadata[1951]: [Info] MetaClient: MetaClient SDK -- v1.02 Mar 28 03:52:16 ifi-ifi ifi_metadata[1951]: [Info] MetaClient: MetaClientInit Mar 28 03:52:16 ifi-ifi ifi_metadata[1951]: Connected Mar 28 03:52:17 ifi-ifi mpd[1984]: Mar 28 03:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 28 03:52:17 ifi-ifi systemd[1]: Started Music Player Daemon. Mar 28 03:52:17 ifi-ifi sudo[1860]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:17 ifi-ifi sudo[1971]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:17 ifi-ifi volumio[1397]: info: Completed starting Core Plugins Mar 28 03:52:17 ifi-ifi volumio[1397]: [Metrics] CommandRouter: 15s 603.57ms Mar 28 03:52:17 ifi-ifi volumio[1397]: info: CoreCommandRouter::Close All Modals sent Mar 28 03:52:17 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumiosetStartupVolume Mar 28 03:52:17 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:17 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:17 ifi-ifi volumio[1397]: info: warning: volume is underfined, set volume to 100 Mar 28 03:52:17 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 03:52:17 ifi-ifi volumio[1397]: info: VolumeController::SetAlsaVolume100 Mar 28 03:52:17 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 03:52:17 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Mar 28 03:52:17 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:17 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:17 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:17 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:17 ifi-ifi kernel: wm8804 0-003b: wm8804_hw_params rate: 0xac44 format: 0x2 Mar 28 03:52:17 ifi-ifi volumio[1397]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.socket mpd.service Mar 28 03:52:17 ifi-ifi volumio[1397]: Job for mpd.socket failed. Mar 28 03:52:17 ifi-ifi volumio[1397]: See "systemctl status mpd.socket" and "journalctl -xe" for details. Mar 28 03:52:17 ifi-ifi volumio[1397]: info: MPD running with PID1984 Mar 28 03:52:17 ifi-ifi volumio[1397]: ,establishing connection Mar 28 03:52:17 ifi-ifi volumio[1397]: error: MPD error: The expression evaluated to a falsy value: Mar 28 03:52:17 ifi-ifi volumio[1397]: assert.ok(self.idling) Mar 28 03:52:17 ifi-ifi volumio[1397]: error: The expression evaluated to a falsy value: Mar 28 03:52:17 ifi-ifi volumio[1397]: assert.ok(self.idling) Mar 28 03:52:17 ifi-ifi volumio[1397]: error: MPD error: The expression evaluated to a falsy value: Mar 28 03:52:17 ifi-ifi volumio[1397]: assert.ok(self.idling) Mar 28 03:52:17 ifi-ifi volumio[1397]: error: The expression evaluated to a falsy value: Mar 28 03:52:17 ifi-ifi volumio[1397]: assert.ok(self.idling) Mar 28 03:52:17 ifi-ifi volumio[1397]: error: updateQueue error: null Mar 28 03:52:18 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 28 03:52:18 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 03:52:18 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 28 03:52:19 ifi-ifi volumio[1397]: info: Updating volume from AirPlay: 0; 100% Mar 28 03:52:19 ifi-ifi volumio[1397]: info: VolumeController::SetAlsaVolume100 Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:19 ifi-ifi shairport-sync[1985]: warning: The output device "volumio" is busy and can't be used by Shairport Sync at present. Mar 28 03:52:19 ifi-ifi volumio[1397]: info: Airplay started streaming, receiving metadatas Mar 28 03:52:19 ifi-ifi volumio[1397]: info: ConvSwitch: Sent Play Mar 28 03:52:19 ifi-ifi volumio[1397]: info: Airplay started streaming, receiving metadatas Mar 28 03:52:19 ifi-ifi volumio[1397]: info: ConvSwitch: Sent Play Mar 28 03:52:19 ifi-ifi volumio[1397]: info: ConvSwitch: Get message: successsuccess Mar 28 03:52:19 ifi-ifi shairport-sync[1985]: %% TToottaall %% RReecceeiivveedd %% XXffeerrdd AAvveerraaggee S pSepeede d T iTmiem e T iTmiem e T i mTei m eC u rCruernrte Mar 28 03:52:19 ifi-ifi shairport-sync[1985]: n t Mar 28 03:52:19 ifi-ifi volumio[1397]: info: Mar 28 03:52:19 ifi-ifi volumio[1397]: ---------------------------- Client requests AirplayActive Mar 28 03:52:19 ifi-ifi shairport-sync[1985]: D l o a d DUlpolaoda d U p lTooatda l T oStpaeln t S p eLnetf t SLpeefetd Mar 28 03:52:19 ifi-ifi shairport-sync[1985]: Speed Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Mar 28 03:52:19 ifi-ifi shairport-sync[1985]: [237B blob data] Mar 28 03:52:19 ifi-ifi volumio[1397]: info: Mar 28 03:52:19 ifi-ifi volumio[1397]: ---------------------------- Client requests Start Airplay PlaybackRoutine Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Mar 28 03:52:19 ifi-ifi volumio[1397]: info: Airplay playback start Mar 28 03:52:19 ifi-ifi volumio[1397]: info: ConvSwitch: Sent Play Mar 28 03:52:19 ifi-ifi shairport-sync[1985]: [136B blob data] Mar 28 03:52:19 ifi-ifi volumio[1397]: info: ConvSwitch: Get message: success Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::servicePushState Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::servicePushState Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::servicePushState Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:19 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:20 ifi-ifi volumio[1397]: info: CoreCommandRouter::servicePushState Mar 28 03:52:20 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:20 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:20 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:20 ifi-ifi volumio[1397]: info: CoreCommandRouter::servicePushState Mar 28 03:52:20 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:20 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:20 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:20 ifi-ifi volumio[1397]: info: CoreCommandRouter::servicePushState Mar 28 03:52:20 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:20 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:20 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:20 ifi-ifi systemd[1]: Started UPnP Renderer front-end to MPD. Mar 28 03:52:20 ifi-ifi sudo[2092]: pam_unix(sudo:session): session closed for user root Mar 28 03:52:20 ifi-ifi volumio[1397]: info: Upmpdcli Daemon Started Mar 28 03:52:20 ifi-ifi systemd[1]: Starting Internet Detect for iFi Streamer... Mar 28 03:52:20 ifi-ifi volumio[1397]: New connection from ::ffff:127.0.0.1:43658 Mar 28 03:52:20 ifi-ifi volumio[1397]: Connected to MPD server Mar 28 03:52:20 ifi-ifi volumio[1397]: New connection from ::ffff:127.0.0.1:43662 Mar 28 03:52:20 ifi-ifi volumio[1397]: Connected to MPD server Mar 28 03:52:20 ifi-ifi systemd[1]: ifi-system-internet.service: Succeeded. Mar 28 03:52:20 ifi-ifi systemd[1]: Started Internet Detect for iFi Streamer. Mar 28 03:52:20 ifi-ifi volumio[2152]: writing RSA key Mar 28 03:52:21 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Mar 28 03:52:21 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:21 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:21 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:21 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:21 ifi-ifi volumio[1397]: info: MetaClient: Sent Meta airplay Mar 28 03:52:21 ifi-ifi volumio[1397]: info: MetaClient: Get message: success Mar 28 03:52:22 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Mar 28 03:52:22 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:22 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:22 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:22 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:22 ifi-ifi volumio[1397]: info: MetaClient: Sent Meta airplay Mar 28 03:52:22 ifi-ifi volumio[1397]: info: MetaClient: Get message: success Mar 28 03:52:22 ifi-ifi volumio[1397]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 28 03:52:22 ifi-ifi shairport-sync[1985]: warning: The output device "volumio" is no longer busy and will be used by Shairport Sync. Mar 28 03:52:22 ifi-ifi kernel: wm8804 0-003b: wm8804_hw_params rate: 0xac44 format: 0x2 Mar 28 03:52:23 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Mar 28 03:52:23 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:23 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:23 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:23 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:23 ifi-ifi volumio[1397]: info: MetaClient: Sent Meta airplay Mar 28 03:52:23 ifi-ifi volumio[1397]: info: MetaClient: Get message: success Mar 28 03:52:24 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Mar 28 03:52:24 ifi-ifi volumio[1397]: info: CoreStateMachine::pushState Mar 28 03:52:24 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:24 ifi-ifi volumio[1397]: info: CoreCommandRouter::volumioPushState Mar 28 03:52:24 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 03:52:24 ifi-ifi volumio[1397]: info: MetaClient: Sent Meta airplay Mar 28 03:52:24 ifi-ifi volumio[1397]: info: MetaClient: Get message: success Mar 28 03:52:24 ifi-ifi volumio[1397]: info: BOOT COMPLETED Mar 28 03:52:25 ifi-ifi volumio[1397]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Mar 28 03:52:25 ifi-ifi volumio[1397]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 03:52:25 ifi-ifi volumio[1397]: Error: Type object is not supported Mar 28 03:52:25 ifi-ifi volumio[1397]: at Config.assertSupportedType (/volumio/node_modules/v-conf/index.js:299:15) Mar 28 03:52:25 ifi-ifi volumio[1397]: at Config.addConfigValue (/volumio/node_modules/v-conf/index.js:275:10) Mar 28 03:52:25 ifi-ifi volumio[1397]: at ControllerAlsa.saveCurrentVolume (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2792:19) Mar 28 03:52:25 ifi-ifi volumio[1397]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1220:32) Mar 28 03:52:25 ifi-ifi volumio[1397]: at CoreCommandRouter.volumioupdatevolume (/volumio/app/index.js:191:8) Mar 28 03:52:25 ifi-ifi volumio[1397]: at Timeout._onTimeout (/volumio/app/index.js:387:12) Mar 28 03:52:25 ifi-ifi volumio[1397]: at listOnTimeout (internal/timers.js:557:17) Mar 28 03:52:25 ifi-ifi volumio[1397]: at processTimers (internal/timers.js:500:7) Mar 28 03:52:25 ifi-ifi volumio[1397]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 03:52:26 ifi-ifi sudo[2285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-28 03:51 Mar 28 03:52:26 ifi-ifi sudo[2285]: 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="45aad196ab181636cec04ab79f52572f8ba3c549" VOLUMIO_FE_VERSION="8a7c1663e5becc06293ef92fd46324fc2cb9ce6e" VOLUMIO_FE3_VERSION="c5cad6acce062070373bc18affd0d2a1a9956a16" VOLUMIO_BE_VERSION="0fad662d4c47d7729fc677cd069fd5e675bebf9c" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="ifi" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Nov 15 15:05:02 CST 2025" VOLUMIO_VERSION="3.00.6" VOLUMIO_HARDWARE="zen-stream" VOLUMIO_DEVICENAME="ZEN Stream" VOLUMIO_HASH="3350ed41a04d50b70f39bebb8e49f746"