-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Mon 2025-05-05 01:31:27 BST. -- May 05 01:30:00 volumio ntpd[809]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 05 01:30:00 volumio sudo[830]: pam_unix(sudo:session): session closed for user root May 05 01:30:00 volumio wireless.js[610]: Stopped aP May 05 01:30:00 volumio sudo[837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 05 01:30:00 volumio sudo[837]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:00 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 05 01:30:00 volumio sudo[837]: pam_unix(sudo:session): session closed for user root May 05 01:30:00 volumio sudo[839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 05 01:30:00 volumio systemd[1]: systemd-rfkill.service: Succeeded. May 05 01:30:00 volumio sudo[839]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:00 volumio sudo[839]: pam_unix(sudo:session): session closed for user root May 05 01:30:00 volumio sudo[846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 05 01:30:00 volumio sudo[846]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:00 volumio sudo[846]: pam_unix(sudo:session): session closed for user root May 05 01:30:00 volumio sudo[848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 05 01:30:00 volumio sudo[848]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:01 volumio ntpd[809]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 05 01:30:01 volumio sudo[848]: pam_unix(sudo:session): session closed for user root May 05 01:30:01 volumio wireless.js[610]: SETTING APPROPRIATE REG DOMAIN: GB May 05 01:30:01 volumio sudo[853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 05 01:30:01 volumio sudo[853]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:01 volumio sudo[853]: pam_unix(sudo:session): session closed for user root May 05 01:30:01 volumio sudo[855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set GB May 05 01:30:01 volumio sudo[855]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:01 volumio sudo[855]: pam_unix(sudo:session): session closed for user root May 05 01:30:01 volumio wireless.js[610]: SUCCESSFULLY SET NEW REGDOMAIN: GB May 05 01:30:01 volumio wireless.js[610]: Start wireless flow May 05 01:30:01 volumio wireless.js[610]: Stopped hotspot (if there).. May 05 01:30:01 volumio sudo[860]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 05 01:30:01 volumio sudo[860]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:01 volumio sudo[860]: pam_unix(sudo:session): session closed for user root May 05 01:30:01 volumio sudo[862]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 05 01:30:01 volumio sudo[862]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:01 volumio mpd[818]: May 05 01:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 05 01:30:01 volumio sudo[862]: pam_unix(sudo:session): session closed for user root May 05 01:30:01 volumio systemd[1]: Started Music Player Daemon. May 05 01:30:01 volumio mpd_monitor.sh[592]: MPD Monitor Service: MPD restarted due to no mpc output. May 05 01:30:02 volumio wireless.js[610]: DHCP IP May 05 01:30:02 volumio wireless.js[610]: Start ap May 05 01:30:02 volumio wpa_supplicant[867]: Successfully initialized wpa_supplicant May 05 01:30:02 volumio sudo[868]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 05 01:30:02 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 05 01:30:02 volumio sudo[868]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:02 volumio dhcpcd[869]: dev: loaded udev May 05 01:30:02 volumio ntpd[809]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 05 01:30:02 volumio dhcpcd-run-hooks[887]: wlan0: starting wpa_supplicant May 05 01:30:02 volumio volumio-remote-updater[602]: [2025-05-05 01:30:02] [info] asio async_connect error: system:111 (Connection refused) May 05 01:30:02 volumio volumio-remote-updater[602]: [2025-05-05 01:30:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 05 01:30:02 volumio volumio-remote-updater[602]: [2025-05-05 01:30:02] [error] handle_connect error: Underlying Transport Error May 05 01:30:02 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... May 05 01:30:02 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response May 05 01:30:02 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... May 05 01:30:02 volumio bthelper[709]: Changing power off succeeded May 05 01:30:02 volumio dhcpcd-run-hooks[898]: wlan0: failed to start wpa_supplicant May 05 01:30:02 volumio dhcpcd-run-hooks[899]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma May 05 01:30:02 volumio dhcpcd[869]: wlan0: connected to Access Point `' May 05 01:30:02 volumio dhcpcd[869]: no interfaces have a carrier May 05 01:30:02 volumio dhcpcd[869]: forked to background, child pid 909 May 05 01:30:02 volumio dhcpcd[909]: eth0: waiting for carrier May 05 01:30:02 volumio dhcpcd[909]: wlan0: waiting for carrier May 05 01:30:02 volumio sudo[868]: pam_unix(sudo:session): session closed for user root May 05 01:30:02 volumio bthelper[709]: [CHG] Controller B8:27:EB:6C:5D:36 Class: 0x0000041c May 05 01:30:02 volumio bthelper[709]: Changing power on succeeded May 05 01:30:02 volumio wpa_supplicant[891]: wlan0: Trying to associate with SSID 'G123' May 05 01:30:03 volumio wireless.js[610]: trying... May 05 01:30:03 volumio sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 01:30:03 volumio sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:03 volumio sudo[916]: pam_unix(sudo:session): session closed for user root May 05 01:30:03 volumio dhcpcd[909]: wlan0: carrier acquired May 05 01:30:03 volumio dhcpcd[909]: wlan0: connected to Access Point `G123' May 05 01:30:03 volumio wpa_supplicant[891]: wlan0: Associated with 9c:3d:cf:2d:90:e7 May 05 01:30:03 volumio wpa_supplicant[891]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:3d:cf:2d:90:e7 completed [id=0 id_str=] May 05 01:30:03 volumio wpa_supplicant[891]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 05 01:30:03 volumio wpa_supplicant[891]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=FR May 05 01:30:03 volumio dhcpcd[909]: DUID 00:01:00:01:2f:7d:86:a3:b8:27:eb:c6:f7:9c May 05 01:30:03 volumio dhcpcd[909]: wlan0: IAID eb:93:a2:c9 May 05 01:30:03 volumio dhcpcd[909]: wlan0: adding address fe80::80ca:82de:9e02:79bc May 05 01:30:03 volumio dhcpcd[909]: ipv6_addaddr1: Permission denied May 05 01:30:03 volumio dhcpcd[909]: wlan0: carrier lost May 05 01:30:03 volumio dhcpcd[909]: wlan0: carrier acquired May 05 01:30:03 volumio dhcpcd[909]: wlan0: IAID eb:93:a2:c9 May 05 01:30:03 volumio dhcpcd[909]: wlan0: rebinding lease of 192.168.1.191 May 05 01:30:03 volumio dhcpcd[909]: wlan0: soliciting an IPv6 router May 05 01:30:04 volumio wireless.js[610]: trying... May 05 01:30:04 volumio sudo[932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 01:30:04 volumio sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:04 volumio sudo[932]: pam_unix(sudo:session): session closed for user root May 05 01:30:05 volumio wireless.js[610]: trying... May 05 01:30:05 volumio sudo[935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 01:30:05 volumio sudo[935]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:05 volumio sudo[935]: pam_unix(sudo:session): session closed for user root May 05 01:30:06 volumio wireless.js[610]: trying... May 05 01:30:06 volumio sudo[938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 01:30:06 volumio sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:06 volumio sudo[938]: pam_unix(sudo:session): session closed for user root May 05 01:30:07 volumio wireless.js[610]: trying... May 05 01:30:07 volumio sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 01:30:07 volumio sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:07 volumio sudo[941]: pam_unix(sudo:session): session closed for user root May 05 01:30:07 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... May 05 01:30:07 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response May 05 01:30:07 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... May 05 01:30:08 volumio wireless.js[610]: trying... May 05 01:30:08 volumio sudo[949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 01:30:08 volumio sudo[949]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:08 volumio sudo[949]: pam_unix(sudo:session): session closed for user root May 05 01:30:08 volumio dhcpcd[909]: wlan0: probing address 192.168.1.191/24 May 05 01:30:09 volumio wireless.js[610]: trying... May 05 01:30:09 volumio sudo[952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 01:30:09 volumio sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:09 volumio sudo[952]: pam_unix(sudo:session): session closed for user root May 05 01:30:09 volumio volumio-remote-updater[602]: [2025-05-05 01:30:09] [info] asio async_connect error: system:111 (Connection refused) May 05 01:30:09 volumio volumio-remote-updater[602]: [2025-05-05 01:30:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 05 01:30:09 volumio volumio-remote-updater[602]: [2025-05-05 01:30:09] [error] handle_connect error: Underlying Transport Error May 05 01:30:10 volumio wireless.js[610]: trying... May 05 01:30:10 volumio sudo[955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 01:30:10 volumio sudo[955]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:10 volumio sudo[955]: pam_unix(sudo:session): session closed for user root May 05 01:30:11 volumio wireless.js[610]: trying... May 05 01:30:11 volumio sudo[958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 01:30:11 volumio sudo[958]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:11 volumio sudo[958]: pam_unix(sudo:session): session closed for user root May 05 01:30:12 volumio wireless.js[610]: trying... May 05 01:30:12 volumio sudo[961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 01:30:12 volumio sudo[961]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:12 volumio sudo[961]: pam_unix(sudo:session): session closed for user root May 05 01:30:12 volumio dhcpcd[909]: wlan0: leased 192.168.1.191 for 864000 seconds May 05 01:30:12 volumio avahi-daemon[599]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.191. May 05 01:30:12 volumio dhcpcd[909]: wlan0: adding route to 192.168.1.0/24 May 05 01:30:12 volumio avahi-daemon[599]: New relevant interface wlan0.IPv4 for mDNS. May 05 01:30:12 volumio dhcpcd[909]: wlan0: adding default route via 192.168.1.254 May 05 01:30:12 volumio avahi-daemon[599]: Registering new address record for 192.168.1.191 on wlan0.IPv4. May 05 01:30:12 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... May 05 01:30:12 volumio volumio-time-update[595]: volumio-time-update-util: Setting system time to: 2025-05-05 01:30:34 May 05 01:30:12 volumio sudo[989]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-05 01:30:34 May 05 01:30:12 volumio sudo[989]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:12 volumio dbus-daemon[609]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=990 comm="timedatectl set-time 2025-05-05 01:30:34 ") May 05 01:30:12 volumio systemd[1]: Starting Time & Date Service... May 05 01:30:12 volumio dbus-daemon[609]: [system] Successfully activated service 'org.freedesktop.timedate1' May 05 01:30:12 volumio systemd[1]: Started Time & Date Service. May 05 01:30:34 volumio systemd-timedated[991]: Changed local time to Mon May 5 01:30:34 2025 May 05 01:30:34 volumio sudo[989]: pam_unix(sudo:session): session closed for user root May 05 01:30:34 volumio volumio-time-update[595]: volumio-time-update-util: System time updated successfully. May 05 01:30:34 volumio systemd[1]: Started Volumio Time Update Utility. May 05 01:30:34 volumio wireless.js[610]: trying... May 05 01:30:34 volumio sudo[993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 05 01:30:34 volumio sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:34 volumio sudo[993]: pam_unix(sudo:session): session closed for user root May 05 01:30:34 volumio wireless.js[610]: Connected to: ----G123 May 05 01:30:34 volumio wireless.js[610]: ---- May 05 01:30:34 volumio sudo[996]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 01:30:34 volumio sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:34 volumio sudo[996]: pam_unix(sudo:session): session closed for user root May 05 01:30:34 volumio wireless.js[610]: ... joined AP, wlan0 IPv4 is 192.168.1.191, ipV6 is undefined May 05 01:30:34 volumio wireless.js[610]: It's done! AP May 05 01:30:34 volumio systemd[1]: Started Wireless Services. May 05 01:30:34 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 05 01:30:34 volumio systemd[1]: Started Volumio Backend Module. May 05 01:30:34 volumio systemd[1]: Started Volumio Cpu Tweaker. May 05 01:30:34 volumio volumio-cpu-tweak[1002]: Setting RT Priority for mpd May 05 01:30:34 volumio volumio-cpu-tweak[1002]: Setting MPD Affinity May 05 01:30:34 volumio volumio-cpu-tweak[1002]: pid 818's current affinity mask: f May 05 01:30:34 volumio volumio-cpu-tweak[1002]: pid 818's new affinity mask: 3 May 05 01:30:34 volumio volumio-cpu-tweak[1002]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 05 01:30:34 volumio volumio-cpu-tweak[1002]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 05 01:30:34 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. May 05 01:30:35 volumio ntpd[809]: Listen normally on 3 wlan0 192.168.1.191:123 May 05 01:30:35 volumio ntpd[809]: new interface(s) found: waking up resolver May 05 01:30:35 volumio nmbd[776]: [2025/05/05 01:30:35.614114, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 05 01:30:35 volumio nmbd[776]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 05 01:30:35 volumio systemd[1]: Started Samba NMB Daemon. May 05 01:30:35 volumio nmbd[776]: [2025/05/05 01:30:35.644338, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 05 01:30:35 volumio nmbd[776]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.191 for name WORKGROUP<1d>. May 05 01:30:35 volumio nmbd[776]: This response was from IP 192.168.1.116, reporting an IP address of 192.168.1.116. May 05 01:30:35 volumio systemd[1]: Starting Samba Winbind Daemon... May 05 01:30:36 volumio winbindd[1018]: [2025/05/05 01:30:36.016568, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 05 01:30:36 volumio winbindd[1018]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 05 01:30:36 volumio winbindd[1018]: [2025/05/05 01:30:36.038842, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 05 01:30:36 volumio winbindd[1018]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 05 01:30:36 volumio systemd[1]: Started Samba Winbind Daemon. May 05 01:30:36 volumio systemd[1]: Starting Samba SMB Daemon... May 05 01:30:36 volumio smbd[1023]: [2025/05/05 01:30:36.656857, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 05 01:30:36 volumio systemd[1]: Started Samba SMB Daemon. May 05 01:30:36 volumio smbd[1023]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 05 01:30:36 volumio systemd[1]: Reached target Multi-User System. May 05 01:30:36 volumio systemd[1]: Reached target Graphical Interface. May 05 01:30:36 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 05 01:30:36 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 05 01:30:36 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 05 01:30:36 volumio systemd[1]: Startup finished in 13.715s (kernel) + 23.943s (userspace) = 37.658s. May 05 01:30:37 volumio volumio[1001]: info: ------------------------------------------- May 05 01:30:37 volumio volumio[1001]: info: ----- Volumio3 ---- May 05 01:30:37 volumio volumio[1001]: info: ------------------------------------------- May 05 01:30:37 volumio volumio[1001]: info: ----- System startup ---- May 05 01:30:37 volumio volumio[1001]: info: ------------------------------------------- May 05 01:30:38 volumio volumio[1001]: info: MYVOLUMIO Environment detected May 05 01:30:38 volumio volumio[1001]: info: Plugin folders cleanup May 05 01:30:38 volumio volumio[1001]: info: Scanning into folder /volumio/app/plugins/ May 05 01:30:38 volumio volumio[1001]: info: Scanning category audio_interface May 05 01:30:38 volumio volumio[1001]: info: Scanning category miscellanea May 05 01:30:38 volumio volumio[1001]: info: Scanning category music_service May 05 01:30:38 volumio volumio[1001]: info: Scanning category plugins.json May 05 01:30:38 volumio volumio[1001]: info: Scanning category system_controller May 05 01:30:38 volumio volumio[1001]: info: Scanning category user_interface May 05 01:30:38 volumio volumio[1001]: info: Scanning into folder /data/plugins/ May 05 01:30:38 volumio volumio[1001]: info: Scanning category audio_interface May 05 01:30:38 volumio volumio[1001]: info: Scanning category music_service May 05 01:30:38 volumio volumio[1001]: info: Plugin folders cleanup completed May 05 01:30:38 volumio volumio[1001]: info: ------------------------------------------- May 05 01:30:38 volumio volumio[1001]: info: ----- Core plugins startup ---- May 05 01:30:38 volumio volumio[1001]: info: ------------------------------------------- May 05 01:30:38 volumio volumio[1001]: info: Loading plugins from folder /volumio/app/plugins/ May 05 01:30:38 volumio volumio[1001]: info: Adding plugin upnp to MyMusic Plugins May 05 01:30:38 volumio volumio[1001]: info: Adding plugin airplay_emulation to MyMusic Plugins May 05 01:30:38 volumio volumio[1001]: info: Adding plugin upnp_browser to MyMusic Plugins May 05 01:30:38 volumio volumio[1001]: info: Loading plugins from folder /data/plugins/ May 05 01:30:38 volumio volumio[1001]: info: Loading plugin "system"... May 05 01:30:38 volumio volumio[1001]: info: Loading plugin "appearance"... May 05 01:30:40 volumio volumio[1001]: info: Loading plugin "network"... May 05 01:30:40 volumio volumio[1001]: info: Refreshing Cached IP Addresses May 05 01:30:40 volumio sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 01:30:40 volumio sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:40 volumio sudo[1046]: pam_unix(sudo:session): session closed for user root May 05 01:30:40 volumio sudo[1048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 01:30:40 volumio sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:40 volumio volumio[1001]: info: Loading plugin "services"... May 05 01:30:40 volumio sudo[1048]: pam_unix(sudo:session): session closed for user root May 05 01:30:40 volumio volumio[1001]: info: Loading plugin "alsa_controller"... May 05 01:30:40 volumio sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 05 01:30:40 volumio sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:40 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 01:30:40 volumio volumio[1001]: info: Loading plugin "wizard"... May 05 01:30:40 volumio volumio[1001]: info: Loading plugin "networkfs"... May 05 01:30:40 volumio volumio[1001]: info: Starting Udev Watcher for removable devices May 05 01:30:40 volumio volumio[1001]: info: Ignoring mount for partition: boot May 05 01:30:40 volumio volumio[1001]: info: Ignoring mount for partition: volumio May 05 01:30:40 volumio volumio[1001]: info: Ignoring mount for partition: volumio_data May 05 01:30:40 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 01:30:40 volumio volumio[1001]: info: Loading plugin "volumio_command_line_client"... May 05 01:30:40 volumio volumio[1001]: info: Loading plugin "upnp"... May 05 01:30:40 volumio volumio[1001]: info: [1746405040564] Starting Upmpd Daemon May 05 01:30:40 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 01:30:40 volumio volumio[1001]: info: Loading plugin "my_music"... May 05 01:30:40 volumio volumio[1001]: info: Loading plugin "mpd"... May 05 01:30:40 volumio volumio-remote-updater[602]: [2025-05-05 01:30:40] [connect] Successful connection May 05 01:30:41 volumio volumio[1001]: info: Loading plugin "upnp_browser"... May 05 01:30:41 volumio sudo[1056]: pam_unix(sudo:session): session closed for user root May 05 01:30:41 volumio volumio[1001]: info: Loading plugin "alarm-clock"... May 05 01:30:41 volumio volumio[1001]: info: Loading plugin "airplay_emulation"... May 05 01:30:41 volumio volumio[1001]: info: Starting Shairport Sync May 05 01:30:41 volumio volumio[1001]: info: Loading plugin "last_100"... May 05 01:30:41 volumio volumio[1001]: info: Loading plugin "webradio"... May 05 01:30:41 volumio volumio[1001]: info: Loading plugin "i2s_dacs"... May 05 01:30:41 volumio volumio[1001]: info: Loading plugin "volumiodiscovery"... May 05 01:30:42 volumio volumio[1001]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 05 01:30:42 volumio volumio[1001]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 01:30:42 volumio volumio[1001]: *** WARNING *** For more information see May 05 01:30:42 volumio volumio[1001]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 05 01:30:42 volumio node[1001]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 05 01:30:42 volumio volumio[1001]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 01:30:42 volumio volumio[1001]: *** WARNING *** For more information see May 05 01:30:42 volumio node[1001]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 01:30:42 volumio node[1001]: *** WARNING *** For more information see May 05 01:30:42 volumio node[1001]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 05 01:30:42 volumio node[1001]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 01:30:42 volumio node[1001]: *** WARNING *** For more information see May 05 01:30:42 volumio volumio[1001]: info: Applying required configuration parameters for plugin volumiodiscovery May 05 01:30:42 volumio volumio[1001]: info: Discovery: Started advertising with name: Volumio May 05 01:30:42 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 01:30:42 volumio volumio[1001]: info: Loading plugin "outputs"... May 05 01:30:42 volumio volumio[1001]: info: Loading plugin "albumart"... May 05 01:30:42 volumio volumio[1001]: info: Plugin example_plugin is not enabled May 05 01:30:42 volumio volumio[1001]: info: Loading plugin "inputs"... May 05 01:30:42 volumio volumio[1001]: info: Loading plugin "updater_comm"... May 05 01:30:42 volumio volumio[1001]: info: Plugin mpdemulation is not enabled May 05 01:30:42 volumio volumio[1001]: info: Loading plugin "rest_api"... May 05 01:30:42 volumio volumio[1001]: info: Loading plugin "websocket"... May 05 01:30:42 volumio volumio[1001]: info: Starting Socket.io Server version 2.3.0 May 05 01:30:42 volumio volumio[1001]: info: Loading plugin "fusiondsp"... May 05 01:30:42 volumio volumio[1001]: Forking 3 albumart workers May 05 01:30:43 volumio volumio[1001]: info: Applying required configuration parameters for plugin fusiondsp May 05 01:30:43 volumio volumio[1001]: info: Plugin minidlna is not enabled May 05 01:30:43 volumio volumio[1001]: info: Loading i18n strings for locale en May 05 01:30:43 volumio volumio[1001]: Updating browse sources language May 05 01:30:43 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:30:43 volumio volumio[1001]: Starting albumart workers May 05 01:30:43 volumio volumio[1001]: Starting albumart workers May 05 01:30:43 volumio volumio[1001]: Starting albumart workers May 05 01:30:43 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 01:30:43 volumio volumio[1001]: info: CoreCommandRouter::initPlayerControls May 05 01:30:43 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:43 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:43 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:43 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:43 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:43 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:43 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:43 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:43 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 01:30:43 volumio volumio[1001]: Express server listening on port 3000 May 05 01:30:43 volumio volumio[1001]: [Metrics] WebUI: 7s 502.94ms May 05 01:30:43 volumio volumio[1001]: info: CoreStateMachine::resetVolumioState May 05 01:30:43 volumio volumio[1001]: info: CoreStateMachine::getcurrentVolume May 05 01:30:43 volumio volumio[1001]: info: CoreCommandRouter::volumioRetrievevolume May 05 01:30:43 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:30:43 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 05 01:30:43 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:30:44 volumio volumio[1001]: info: Volumio Network Manager: Network status updated: 2 May 05 01:30:44 volumio volumio-remote-updater[602]: [2025-05-05 01:30:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746405040 101 May 05 01:30:44 volumio volumio[1001]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 May 05 01:30:44 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:30:44 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState May 05 01:30:44 volumio volumio[1001]: info: CoreStateMachine::updateTrackBlock May 05 01:30:44 volumio volumio[1001]: info: CorePlayQueue::getTrackBlock May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::volumioRetrievevolume May 05 01:30:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:30:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 05 01:30:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:30:44 volumio volumio[1001]: info: Reloading queue from file May 05 01:30:44 volumio volumio[1001]: info: Setting Device type: Raspberry PI May 05 01:30:44 volumio volumio[1001]: info: CoreStateMachine::setRepeat null single undefined May 05 01:30:44 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:30:44 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState May 05 01:30:44 volumio volumio[1001]: info: CoreStateMachine::setRandom null May 05 01:30:44 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:30:44 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:30:44 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:30:44 volumio volumio[1001]: info: Completed loading Core Plugins May 05 01:30:44 volumio volumio[1001]: info: Preparing to generate the ALSA configuration file May 05 01:30:44 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:30:44 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState May 05 01:30:44 volumio volumio[1001]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 05 01:30:44 volumio volumio[1001]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 05 01:30:44 volumio volumio[1001]: info: Reading ALSA contributions from plugins. May 05 01:30:44 volumio volumio[1001]: info: Listing playlists May 05 01:30:44 volumio volumio[1001]: info: Listing playlists May 05 01:30:44 volumio volumio[1001]: info: Asound.conf file unchanged, so no further update is needed May 05 01:30:44 volumio volumio[1001]: info: Output device has changed, restarting MPD May 05 01:30:44 volumio volumio[1001]: info: Output device has changed, restarting Shairport Sync May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:44 volumio sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:30:44 volumio sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:44 volumio sudo[1149]: pam_unix(sudo:session): session closed for user root May 05 01:30:44 volumio sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 01:30:44 volumio sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:44 volumio systemd[1]: Stopping Music Player Daemon... May 05 01:30:44 volumio volumio[1001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 01:30:44 volumio volumio[1001]: info: ___________ START PLUGINS ___________ May 05 01:30:44 volumio volumio[1001]: info: ControllerMpd::onStart: Initializing MPD May 05 01:30:44 volumio volumio[1001]: info: Creating MPD Configuration file May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 01:30:44 volumio volumio[1001]: info: [1746405044693] CoreMusicLibrary::Adding element Media Servers May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:30:44 volumio sudo[1158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 01:30:44 volumio sudo[1158]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:44 volumio sudo[1158]: pam_unix(sudo:session): session closed for user root May 05 01:30:44 volumio sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 01:30:44 volumio sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:30:44 volumio systemd[1]: mpd.service: Succeeded. May 05 01:30:44 volumio systemd[1]: Stopped Music Player Daemon. May 05 01:30:44 volumio volumio[1001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:30:44 volumio volumio[1001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 01:30:44 volumio volumio[1001]: info: [1746405044847] CoreMusicLibrary::Adding element Last_100 May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:30:44 volumio systemd[1]: Starting Music Player Daemon... May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 01:30:44 volumio volumio[1001]: info: [1746405044852] CoreMusicLibrary::Adding element Webradio May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 01:30:44 volumio volumio[1001]: info: Initializing BBC Radios May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:30:44 volumio volumio[1001]: info: Loading i18n strings for locale en May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 05 01:30:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:44 volumio volumio[1001]: info: FusionDsp - mixtype--------------------- Software May 05 01:30:44 volumio volumio[1001]: info: Volumio Calling Home May 05 01:30:44 volumio sudo[1166]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 05 01:30:44 volumio sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:44 volumio sudo[1166]: pam_unix(sudo:session): session closed for user root May 05 01:30:45 volumio volumio[1001]: info: Preparing to generate the ALSA configuration file May 05 01:30:45 volumio volumio[1001]: info: Discovery: adding 24d461e7-3bea-44b2-a85a-d24edb3f99f9 May 05 01:30:45 volumio volumio[1001]: info: Discovery: Found device Volumio May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:30:45 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:30:45 volumio volumio[1001]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 05 01:30:45 volumio volumio[1001]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 05 01:30:45 volumio volumio[1001]: info: Reading ALSA contributions from plugins. May 05 01:30:45 volumio volumio[1001]: info: MPD Permissions set May 05 01:30:45 volumio volumio[1001]: info: MPD Permissions set May 05 01:30:45 volumio volumio[1001]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 05 01:30:45 volumio volumio[1001]: info: Volumio called home May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: Starting Shairport Sync May 05 01:30:45 volumio volumio[1001]: info: Starting Shairport Sync May 05 01:30:45 volumio volumio[1001]: info: Starting Shairport Sync May 05 01:30:45 volumio sudo[1194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 01:30:45 volumio sudo[1194]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:45 volumio sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 01:30:45 volumio sudo[1196]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:45 volumio sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 01:30:45 volumio sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 05 01:30:45 volumio systemd[1]: shairport-sync.service: Succeeded. May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:30:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 05 01:30:45 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:30:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 01:30:45 volumio sudo[1194]: pam_unix(sudo:session): session closed for user root May 05 01:30:45 volumio sudo[1196]: pam_unix(sudo:session): session closed for user root May 05 01:30:45 volumio volumio[1001]: info: Asound.conf file unchanged, so no further update is needed May 05 01:30:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 05 01:30:45 volumio volumio[1001]: info: Output device has changed, restarting MPD May 05 01:30:45 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 05 01:30:45 volumio systemd[1]: shairport-sync.service: Succeeded. May 05 01:30:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 05 01:30:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 01:30:45 volumio sudo[1199]: pam_unix(sudo:session): session closed for user root May 05 01:30:45 volumio sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 01:30:45 volumio sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:45 volumio sudo[1205]: pam_unix(sudo:session): session closed for user root May 05 01:30:45 volumio volumio[1001]: info: Output device has changed, restarting Shairport Sync May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:30:45 volumio sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 01:30:45 volumio sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:45 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 05 01:30:45 volumio systemd[1]: mpd.service: Succeeded. May 05 01:30:45 volumio systemd[1]: Stopped Music Player Daemon. May 05 01:30:45 volumio volumio[1001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 01:30:45 volumio volumio[1001]: info: Shairport-Sync Started May 05 01:30:45 volumio volumio[1001]: Error adding Membership: Error: addMembership EINVAL May 05 01:30:45 volumio volumio[1001]: info: Shairport-Sync Started May 05 01:30:45 volumio systemd[1]: Starting Music Player Daemon... May 05 01:30:45 volumio volumio[1001]: info: MPD Permissions set May 05 01:30:45 volumio volumio[1001]: info: Shairport-Sync Started May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:45 volumio volumio[1001]: info: Starting Shairport Sync May 05 01:30:45 volumio sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 01:30:45 volumio sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:45 volumio sudo[1215]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 05 01:30:45 volumio sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 05 01:30:45 volumio systemd[1]: shairport-sync.service: Succeeded. May 05 01:30:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 05 01:30:45 volumio sudo[1215]: pam_unix(sudo:session): session closed for user root May 05 01:30:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 01:30:46 volumio sudo[1222]: pam_unix(sudo:session): session closed for user root May 05 01:30:46 volumio volumio[1001]: info: Shairport-Sync Started May 05 01:30:46 volumio systemd[1]: systemd-fsckd.service: Succeeded. May 05 01:30:47 volumio volumio[1001]: info: camilladsp service started and running in background, instance 1 May 05 01:30:47 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:30:47 volumio volumio[1001]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA May 05 01:30:47 volumio volumio[1001]: info: FusionDsp loaded May 05 01:30:47 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 01:30:47 volumio sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 05 01:30:47 volumio sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:47 volumio volumio[1001]: info: FusionDsp - Reporting Fusion DSP Enabled May 05 01:30:47 volumio volumio[1001]: info: Adding Signal Path Element [object Object] May 05 01:30:47 volumio volumio[1001]: info: Adding fusiondspeq DSP Signal Path Element May 05 01:30:47 volumio volumio[1001]: info: FusionDsp - ---- installed callbackRead May 05 01:30:47 volumio volumio[1001]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 05 01:30:47 volumio volumio[1001]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 05 01:30:47 volumio volumio[1001]: error: FusionDsp - WebSocket error: [object Object] May 05 01:30:47 volumio systemd[1]: Started FusionDsp Daemon. May 05 01:30:47 volumio sudo[1239]: pam_unix(sudo:session): session closed for user root May 05 01:30:47 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:30:47 volumio mpd[1226]: May 05 01:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 05 01:30:47 volumio systemd[1]: Started Music Player Daemon. May 05 01:30:47 volumio sudo[1160]: pam_unix(sudo:session): session closed for user root May 05 01:30:47 volumio sudo[1209]: pam_unix(sudo:session): session closed for user root May 05 01:30:47 volumio sudo[1151]: pam_unix(sudo:session): session closed for user root May 05 01:30:47 volumio volumio[1001]: info: Completed starting Core Plugins May 05 01:30:47 volumio volumio[1001]: info: ------------------------------------------- May 05 01:30:47 volumio volumio[1001]: info: ----- MyVolumio plugins startup ---- May 05 01:30:47 volumio volumio[1001]: info: ------------------------------------------- May 05 01:30:47 volumio volumio[1001]: info: [MyVolumio PluginManager] Fetching plans data.... May 05 01:30:47 volumio volumio[1001]: info: MPD running with PID1226 May 05 01:30:47 volumio volumio[1001]: ,establishing connection May 05 01:30:47 volumio volumio[1001]: error: MPD error: The expression evaluated to a falsy value: May 05 01:30:47 volumio volumio[1001]: assert.ok(self.idling) May 05 01:30:47 volumio volumio[1001]: error: The expression evaluated to a falsy value: May 05 01:30:47 volumio volumio[1001]: assert.ok(self.idling) May 05 01:30:47 volumio volumio[1001]: error: MPD error: The expression evaluated to a falsy value: May 05 01:30:47 volumio volumio[1001]: assert.ok(self.idling) May 05 01:30:47 volumio volumio[1001]: error: The expression evaluated to a falsy value: May 05 01:30:47 volumio volumio[1001]: assert.ok(self.idling) May 05 01:30:47 volumio volumio[1001]: error: MPD error: The expression evaluated to a falsy value: May 05 01:30:47 volumio volumio[1001]: assert.ok(self.idling) May 05 01:30:47 volumio volumio[1001]: error: The expression evaluated to a falsy value: May 05 01:30:47 volumio volumio[1001]: assert.ok(self.idling) May 05 01:30:47 volumio volumio[1001]: error: updateQueue error: null May 05 01:30:48 volumio sh[528]: timed out May 05 01:30:48 volumio dhcpcd[576]: timed out May 05 01:30:48 volumio sh[528]: dhcpcd exited May 05 01:30:48 volumio dhcpcd[576]: dhcpcd exited May 05 01:30:48 volumio sh[528]: ifup: failed to bring up eth0 May 05 01:30:48 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 05 01:30:48 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 05 01:30:49 volumio systemd[1]: systemd-hostnamed.service: Succeeded. May 05 01:30:50 volumio sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 01:30:50 volumio sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:50 volumio sudo[1271]: pam_unix(sudo:session): session closed for user root May 05 01:30:50 volumio sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 01:30:50 volumio sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:50 volumio sudo[1273]: pam_unix(sudo:session): session closed for user root May 05 01:30:50 volumio sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 05 01:30:50 volumio sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:50 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 05 01:30:50 volumio sudo[1277]: pam_unix(sudo:session): session closed for user root May 05 01:30:50 volumio volumio[1001]: info: Upmpdcli Daemon Started May 05 01:30:50 volumio volumio[1279]: Generating RSA private key, 4096 bit long modulus (2 primes) May 05 01:30:51 volumio volumio[1279]: ..++++ May 05 01:30:52 volumio volumio[1001]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 05 01:30:54 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:30:54 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 05 01:30:55 volumio volumio[1001]: info: Adding plugin bluetooth to MyMusic Plugins May 05 01:30:55 volumio volumio[1001]: info: Adding plugin multiroom to MyMusic Plugins May 05 01:30:55 volumio volumio[1001]: info: Adding plugin metavolumio to MyMusic Plugins May 05 01:30:55 volumio volumio[1001]: info: Adding plugin cd_controller to MyMusic Plugins May 05 01:30:55 volumio volumio[1001]: info: Adding plugin smart_inputs to MyMusic Plugins May 05 01:30:55 volumio volumio[1001]: info: Adding plugin tidalconnect to MyMusic Plugins May 05 01:30:55 volumio volumio[1001]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 05 01:30:56 volumio volumio[1279]: .......................................++++ May 05 01:30:56 volumio volumio[1279]: e is 65537 (0x010001) May 05 01:30:56 volumio volumio[1279]: writing RSA key May 05 01:30:57 volumio volumio[1001]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 05 01:30:57 volumio volumio[1001]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 05 01:30:57 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:30:57 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:30:57 volumio volumio[1001]: info: Starting MyVolumio Remote Streaming Endpoints May 05 01:30:57 volumio volumio[1001]: info: MyVolumio login type: Token May 05 01:30:57 volumio volumio[1001]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 05 01:30:57 volumio volumio[1001]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 05 01:30:58 volumio volumio[1001]: info: Starting Streaming Service Transparent Proxy May 05 01:30:58 volumio volumio[1001]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 05 01:30:58 volumio volumio[1001]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 05 01:30:58 volumio volumio[1001]: info: Streaming services startup May 05 01:30:58 volumio volumio[1001]: info: Starting Streaming Daemon May 05 01:30:58 volumio sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 05 01:30:58 volumio sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:30:58 volumio volumio[1001]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 05 01:30:58 volumio sudo[1318]: pam_unix(sudo:session): session closed for user root May 05 01:30:59 volumio volumio[1001]: error: Cannot start Volumio Streaming Daemon May 05 01:30:59 volumio volumio[1001]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 05 01:30:59 volumio volumio[1001]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 05 01:30:59 volumio volumio[1001]: error: MyVolumio Custom Token format not valid, refreshing it May 05 01:31:00 volumio volumio[1001]: STREAMING PROXY: Starting server on port 3245 May 05 01:31:00 volumio volumio[1001]: Node JS runtime: 14 May 05 01:31:00 volumio volumio[1001]: info: MyVolumio login type: Token May 05 01:31:00 volumio volumio[1001]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 05 01:31:01 volumio volumio[1001]: info: MyVolumio token set successfully May 05 01:31:01 volumio volumio[1001]: info: MYVOLUMIO: Adding device May 05 01:31:01 volumio volumio[1001]: info: MYVOLUMIO: Evaluating Server May 05 01:31:01 volumio volumio[1001]: info: MyVolumio status changed May 05 01:31:01 volumio volumio[1001]: info: Streaming services startup May 05 01:31:01 volumio volumio[1001]: info: Starting Streaming Daemon May 05 01:31:01 volumio volumio[1001]: info: Removing browser output: myVolumio user plan is not superstar May 05 01:31:01 volumio volumio[1001]: info: Removing audio output: May 05 01:31:01 volumio volumio[1001]: info: Stoppping Tunnel 1 May 05 01:31:02 volumio sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 05 01:31:02 volumio sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:31:02 volumio sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 05 01:31:02 volumio sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:31:02 volumio sudo[1357]: pam_unix(sudo:session): session closed for user root May 05 01:31:02 volumio volumio[1001]: error: Cannot start Volumio Streaming Daemon May 05 01:31:02 volumio volumio[1001]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 05 01:31:02 volumio volumio[1001]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 05 01:31:02 volumio sudo[1359]: pam_unix(sudo:session): session closed for user root May 05 01:31:02 volumio volumio[1001]: info: Remote SSH Stopped May 05 01:31:02 volumio volumio[1001]: info: Setting Geolocation for MyVolumio to eu3 May 05 01:31:02 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:31:02 volumio volumio[1001]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 05 01:31:03 volumio volumio[1001]: info: Updating MyVolumio device info May 05 01:31:03 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:31:03 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:31:03 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:31:03 volumio volumio[1001]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 05 01:31:04 volumio systemd[1]: systemd-timedated.service: Succeeded. May 05 01:31:04 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:31:04 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:04 volumio volumio[1001]: info: Listing playlists May 05 01:31:04 volumio volumio[1001]: info: Listing playlists May 05 01:31:06 volumio sudo[1368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 01:31:06 volumio sudo[1368]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:31:06 volumio sudo[1368]: pam_unix(sudo:session): session closed for user root May 05 01:31:06 volumio sudo[1370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 01:31:06 volumio sudo[1370]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:31:06 volumio sudo[1370]: pam_unix(sudo:session): session closed for user root May 05 01:31:06 volumio volumio[1001]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 4 May 05 01:31:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 05 01:31:06 volumio volumio[1001]: info: CoreCommandRouter::volumioGetVisibleSources May 05 01:31:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:31:06 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:31:06 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 05 01:31:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 01:31:06 volumio volumio[1001]: info: Received Get System Info May 05 01:31:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 01:31:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 01:31:06 volumio volumio[1001]: info: Discovery: Getting this device information May 05 01:31:06 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:31:06 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 01:31:06 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:31:06 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:06 volumio volumio[1001]: info: Listing playlists May 05 01:31:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 05 01:31:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 05 01:31:06 volumio volumio[1001]: info: MYVOLUMIO: Adding device May 05 01:31:06 volumio volumio[1001]: info: MYVOLUMIO: Evaluating Server May 05 01:31:06 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 05 01:31:06 volumio volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 01:31:06 volumio volumio[1001]: info: FusionDsp - Bass-FusionDsp May 05 01:31:07 volumio volumio[1001]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 05 01:31:07 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:31:07 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:07 volumio volumio[1001]: info: Setting Geolocation for MyVolumio to eu3 May 05 01:31:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:31:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:31:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:31:08 volumio volumio[1001]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 05 01:31:08 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 05 01:31:08 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 05 01:31:08 volumio volumio[1001]: info: Received Get System Info May 05 01:31:08 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 01:31:08 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 01:31:08 volumio volumio[1001]: info: Discovery: Getting this device information May 05 01:31:08 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:31:08 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:08 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 01:31:08 volumio volumio[1001]: info: Updating MyVolumio device info May 05 01:31:08 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:31:09 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 05 01:31:09 volumio volumio[1001]: info: Received Get System Info May 05 01:31:09 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 01:31:09 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 01:31:09 volumio volumio[1001]: info: Discovery: Getting this device information May 05 01:31:09 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:31:09 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:09 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 01:31:09 volumio volumio[1001]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 05 01:31:11 volumio volumio[1001]: info: Clearing queue after UPNP request May 05 01:31:12 volumio volumio[1001]: info: CoreStateMachine::ClearQueue May 05 01:31:12 volumio volumio[1001]: info: CoreStateMachine::stop May 05 01:31:12 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 01:31:12 volumio volumio[1001]: info: CorePlayQueue::clearPlayQueue May 05 01:31:12 volumio volumio[1001]: info: CorePlayQueue::saveQueue May 05 01:31:12 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState May 05 01:31:12 volumio volumio[1001]: info: CoreCommandRouter::volumioPushQueue May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: error: updateQueue error: null May 05 01:31:12 volumio volumio[1001]: error: updateQueue error: null May 05 01:31:12 volumio volumio[1001]: error: updateQueue error: null May 05 01:31:12 volumio volumio[1001]: error: updateQueue error: null May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 14ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 11ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 10ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 7ms May 05 01:31:12 volumio volumio[1001]: info: Starting UPNP Playback May 05 01:31:12 volumio volumio[1001]: info: Preparing playback through UPNP May 05 01:31:12 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:31:12 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:12 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd May 05 01:31:12 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 05 01:31:12 volumio volumio[1001]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 05 01:31:12 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 9ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 8ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 7ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 5ms May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 8ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 6ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 5ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 3ms May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 8ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 6ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 5ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 4ms May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 8ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 6ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 4ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 4ms May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 15ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 13ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 12ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 3ms May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:12 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 7ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 6ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 5ms May 05 01:31:12 volumio volumio[1001]: info: ------------------------------ 3ms May 05 01:31:12 volumio volumio[1279]: 10 May 05 01:31:12 volumio volumio[1279]: % Total % Received % Xferd Average Speed Time Time Time Current May 05 01:31:12 volumio volumio[1279]: Dload Upload Total Spent Left Speed May 05 01:31:12 volumio volumio[1001]: info: May 05 01:31:12 volumio volumio[1001]: ---------------------------- Client requests Volume 10 May 05 01:31:12 volumio volumio[1001]: info: VolumeController::SetAlsaVolume10 May 05 01:31:12 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:12 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:31:12 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState May 05 01:31:12 volumio volumio[1279]: [158B blob data] May 05 01:31:12 volumio volumio[1279]: {"time":1746405072835,"response":"volume Success"}10 May 05 01:31:12 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:31:12 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 05 01:31:12 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:14 volumio volumio[1001]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 May 05 01:31:14 volumio volumio[1001]: info: FusionDsp - ---- read samplerate from file: 48000 May 05 01:31:14 volumio volumio[1001]: info: camilladsp stopping service pid 1235... May 05 01:31:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:31:14 volumio volumio[1001]: info: camilladsp service terminated, instance 1 May 05 01:31:14 volumio volumio[1001]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 05 01:31:14 volumio volumio[1001]: info: camilladsp service started and running in background, instance 1 May 05 01:31:14 volumio volumio[1001]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 May 05 01:31:14 volumio volumio[1001]: info: FusionDsp - ---- read samplerate from file: 48000 May 05 01:31:14 volumio volumio[1001]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:14 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand status took 81 milliseconds May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand status took 79 milliseconds May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand status took 68 milliseconds May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand status took 65 milliseconds May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand status took 19 milliseconds May 05 01:31:14 volumio volumio[1001]: info: ------------------------------ 25ms May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand status took 18 milliseconds May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 17 milliseconds May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 15 milliseconds May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 14 milliseconds May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 15 milliseconds May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand status took 11 milliseconds May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand status took 10 milliseconds May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand status took 8 milliseconds May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:14 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:14 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"cc00910325ee470b882cb5e9a5919eef.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/BwBqcqa5/media_player.volumio_upnp_av/cc00910325ee470b882cb5e9a5919eef.mp3","trackType":"mp3"} May 05 01:31:14 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:14 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:14 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"cc00910325ee470b882cb5e9a5919eef.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/BwBqcqa5/media_player.volumio_upnp_av/cc00910325ee470b882cb5e9a5919eef.mp3","trackType":"mp3"} May 05 01:31:14 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:14 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:14 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:14 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:14 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"cc00910325ee470b882cb5e9a5919eef.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/BwBqcqa5/media_player.volumio_upnp_av/cc00910325ee470b882cb5e9a5919eef.mp3","trackType":"mp3"} May 05 01:31:14 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:14 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:14 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:14 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:14 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"cc00910325ee470b882cb5e9a5919eef.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/BwBqcqa5/media_player.volumio_upnp_av/cc00910325ee470b882cb5e9a5919eef.mp3","trackType":"mp3"} May 05 01:31:14 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:14 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:14 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:14 volumio volumio[1001]: info: ------------------------------ 141ms May 05 01:31:14 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:14 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:14 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:14 volumio volumio[1001]: info: ------------------------------ 138ms May 05 01:31:14 volumio volumio[1001]: info: ------------------------------ 135ms May 05 01:31:14 volumio volumio[1001]: info: ------------------------------ 124ms May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:14 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:14 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:14 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:14 volumio volumio[1001]: error: FusionDsp - WebSocket error: [object Object] May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 82 milliseconds May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 82 milliseconds May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 78 milliseconds May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 77 milliseconds May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 77 milliseconds May 05 01:31:14 volumio volumio[1001]: info: ------------------------------ 51ms May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand status took 39 milliseconds May 05 01:31:14 volumio volumio[1001]: info: ------------------------------ 39ms May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand status took 38 milliseconds May 05 01:31:14 volumio volumio[1001]: info: ------------------------------ 37ms May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand status took 35 milliseconds May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:14 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:14 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"cc00910325ee470b882cb5e9a5919eef.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/BwBqcqa5/media_player.volumio_upnp_av/cc00910325ee470b882cb5e9a5919eef.mp3","trackType":"mp3"} May 05 01:31:14 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:14 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:14 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:14 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:14 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"cc00910325ee470b882cb5e9a5919eef.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/BwBqcqa5/media_player.volumio_upnp_av/cc00910325ee470b882cb5e9a5919eef.mp3","trackType":"mp3"} May 05 01:31:14 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:14 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:14 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:14 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:14 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"cc00910325ee470b882cb5e9a5919eef.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/BwBqcqa5/media_player.volumio_upnp_av/cc00910325ee470b882cb5e9a5919eef.mp3","trackType":"mp3"} May 05 01:31:14 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:14 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:14 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:14 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:14 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"cc00910325ee470b882cb5e9a5919eef.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/BwBqcqa5/media_player.volumio_upnp_av/cc00910325ee470b882cb5e9a5919eef.mp3","trackType":"mp3"} May 05 01:31:14 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:14 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:14 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:14 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:14 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"cc00910325ee470b882cb5e9a5919eef.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/BwBqcqa5/media_player.volumio_upnp_av/cc00910325ee470b882cb5e9a5919eef.mp3","trackType":"mp3"} May 05 01:31:14 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:14 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:14 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:14 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:14 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:14 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:14 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:14 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:14 volumio volumio[1001]: info: ------------------------------ 152ms May 05 01:31:14 volumio volumio[1001]: info: ------------------------------ 150ms May 05 01:31:14 volumio volumio[1001]: info: ------------------------------ 137ms May 05 01:31:14 volumio volumio[1001]: info: ------------------------------ 135ms May 05 01:31:14 volumio volumio[1001]: info: ------------------------------ 134ms May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 33 milliseconds May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 33 milliseconds May 05 01:31:14 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 32 milliseconds May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:14 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:14 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:14 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"cc00910325ee470b882cb5e9a5919eef.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/BwBqcqa5/media_player.volumio_upnp_av/cc00910325ee470b882cb5e9a5919eef.mp3","trackType":"mp3"} May 05 01:31:14 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:14 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:14 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:14 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:14 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"cc00910325ee470b882cb5e9a5919eef.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/BwBqcqa5/media_player.volumio_upnp_av/cc00910325ee470b882cb5e9a5919eef.mp3","trackType":"mp3"} May 05 01:31:14 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:14 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:14 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:14 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:14 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:14 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"cc00910325ee470b882cb5e9a5919eef.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/BwBqcqa5/media_player.volumio_upnp_av/cc00910325ee470b882cb5e9a5919eef.mp3","trackType":"mp3"} May 05 01:31:14 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:14 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:31:14 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:14 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:14 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:14 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:14 volumio volumio[1001]: info: ------------------------------ 110ms May 05 01:31:14 volumio volumio[1001]: info: ------------------------------ 98ms May 05 01:31:14 volumio volumio[1001]: info: ------------------------------ 95ms May 05 01:31:14 volumio volumio[1279]: 18 May 05 01:31:14 volumio volumio[1279]: % Total % Received % Xferd Average Speed Time Time Time Current May 05 01:31:14 volumio volumio[1279]: Dload Upload Total Spent Left Speed May 05 01:31:14 volumio volumio[1001]: info: May 05 01:31:14 volumio volumio[1001]: ---------------------------- Client requests Volume 18 May 05 01:31:14 volumio volumio[1001]: info: VolumeController::SetAlsaVolume18 May 05 01:31:14 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:14 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:14 volumio volumio[1279]: [158B blob data] May 05 01:31:14 volumio volumio[1279]: {"time":1746405074639,"response":"volume Success"}18 May 05 01:31:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:31:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 05 01:31:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:31:14 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 05 01:31:15 volumio volumio-remote-updater[602]: No test mode May 05 01:31:15 volumio volumio-remote-updater[602]: No alpha test mode May 05 01:31:15 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 05 01:31:15 volumio volumio[1001]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 05 01:31:15 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 05 01:31:15 volumio volumio[1279]: 8 May 05 01:31:15 volumio volumio[1279]: % Total % Received % Xferd Average Speed Time Time Time Current May 05 01:31:15 volumio volumio[1279]: Dload Upload Total Spent Left Speed May 05 01:31:15 volumio volumio[1001]: info: May 05 01:31:15 volumio volumio[1001]: ---------------------------- Client requests Volume 8 May 05 01:31:15 volumio volumio[1001]: info: VolumeController::SetAlsaVolume8 May 05 01:31:15 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:15 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:15 volumio volumio[1279]: [158B blob data] May 05 01:31:15 volumio volumio[1279]: {"time":1746405075787,"response":"volume Success"}8 May 05 01:31:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:31:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 05 01:31:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:31:17 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 05 01:31:17 volumio volumio[1279]: 2 May 05 01:31:17 volumio volumio[1279]: % Total % Received % Xferd Average Speed Time Time Time Current May 05 01:31:17 volumio volumio[1279]: Dload Upload Total Spent Left Speed May 05 01:31:17 volumio volumio[1001]: info: May 05 01:31:17 volumio volumio[1001]: ---------------------------- Client requests Volume 2 May 05 01:31:17 volumio volumio[1001]: info: VolumeController::SetAlsaVolume2 May 05 01:31:17 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:17 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:17 volumio volumio[1279]: [158B blob data] May 05 01:31:17 volumio volumio[1279]: {"time":1746405077251,"response":"volume Success"}2 May 05 01:31:17 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:31:17 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 05 01:31:17 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:31:17 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:31:17 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:31:17 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 05 01:31:17 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 05 01:31:17 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 05 01:31:17 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 05 01:31:17 volumio volumio[1001]: info: CoreCommandRouter::volumioGetBrowseSources May 05 01:31:17 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 01:31:18 volumio volumio[1001]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 05 01:31:18 volumio volumio[1001]: info: Completed starting MyVolumio Plugin May 05 01:31:18 volumio volumio[1001]: [Metrics] CommandRouter: 41s 624.80ms May 05 01:31:18 volumio volumio[1001]: info: CoreCommandRouter::volumiosetStartupVolume May 05 01:31:18 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:31:18 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:31:18 volumio volumio[1001]: info: CoreCommandRouter::Close All Modals sent May 05 01:31:18 volumio volumio[1001]: info: CoreCommandRouter::Close All Modals sent May 05 01:31:19 volumio volumio[1001]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 05 01:31:19 volumio volumio[1001]: info: FusionDsp - ---- read samplerate from file: 44100 May 05 01:31:19 volumio volumio[1001]: info: camilladsp stopping service pid 1424... May 05 01:31:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:31:19 volumio volumio[1001]: info: camilladsp service terminated, instance 1 May 05 01:31:19 volumio volumio[1001]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 05 01:31:19 volumio volumio[1001]: info: camilladsp service started and running in background, instance 1 May 05 01:31:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:31:19 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 05 01:31:19 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 05 01:31:19 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 05 01:31:23 volumio volumio[1001]: info: May 05 01:31:23 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:23 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:23 volumio volumio[1001]: info: May 05 01:31:23 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:23 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:23 volumio volumio[1001]: info: May 05 01:31:23 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:23 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:23 volumio volumio[1001]: info: May 05 01:31:23 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:23 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:23 volumio volumio[1001]: info: sendMpdCommand status took 8 milliseconds May 05 01:31:23 volumio volumio[1001]: info: sendMpdCommand status took 5 milliseconds May 05 01:31:23 volumio volumio[1001]: info: sendMpdCommand status took 4 milliseconds May 05 01:31:23 volumio volumio[1001]: info: sendMpdCommand status took 3 milliseconds May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:23 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 5 milliseconds May 05 01:31:23 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 4 milliseconds May 05 01:31:23 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 4 milliseconds May 05 01:31:23 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 3 milliseconds May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:23 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:23 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:23 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:23 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:23 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:23 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:23 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:23 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:23 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:23 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:23 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:23 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:23 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:23 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:23 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:23 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:23 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:23 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:23 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:23 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:23 volumio volumio[1001]: info: ------------------------------ 38ms May 05 01:31:23 volumio volumio[1001]: info: ------------------------------ 35ms May 05 01:31:23 volumio volumio[1001]: info: ------------------------------ 33ms May 05 01:31:23 volumio volumio[1001]: info: ------------------------------ 32ms May 05 01:31:23 volumio volumio[1001]: info: Clearing queue after UPNP request May 05 01:31:23 volumio volumio[1001]: info: CoreStateMachine::ClearQueue May 05 01:31:23 volumio volumio[1001]: info: CoreStateMachine::stop May 05 01:31:23 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 01:31:23 volumio volumio[1001]: info: CoreStateMachine::stPlaybackTimer May 05 01:31:23 volumio volumio[1001]: info: CoreStateMachine::updateTrackBlock May 05 01:31:23 volumio volumio[1001]: info: CorePlayQueue::getTrackBlock May 05 01:31:23 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:23 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:23 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:31:23 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState May 05 01:31:23 volumio volumio[1001]: info: CoreStateMachine::serviceStop May 05 01:31:23 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:23 volumio volumio[1001]: info: ControllerMpd::stop May 05 01:31:23 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop May 05 01:31:23 volumio volumio[1001]: info: CorePlayQueue::clearPlayQueue May 05 01:31:23 volumio volumio[1001]: info: CorePlayQueue::saveQueue May 05 01:31:23 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState May 05 01:31:23 volumio volumio[1001]: info: CoreCommandRouter::volumioPushQueue May 05 01:31:23 volumio volumio[1001]: info: May 05 01:31:23 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:23 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:23 volumio volumio[1001]: info: sendMpdCommand stop took 28 milliseconds May 05 01:31:23 volumio volumio[1001]: info: May 05 01:31:23 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:23 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:23 volumio volumio[1001]: info: May 05 01:31:23 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:23 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:23 volumio volumio[1001]: info: May 05 01:31:23 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:23 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:23 volumio volumio[1001]: error: updateQueue error: null May 05 01:31:23 volumio volumio[1001]: error: updateQueue error: null May 05 01:31:23 volumio volumio[1001]: error: updateQueue error: null May 05 01:31:23 volumio volumio[1001]: error: updateQueue error: null May 05 01:31:23 volumio volumio[1001]: info: ------------------------------ 8ms May 05 01:31:23 volumio volumio[1001]: info: ------------------------------ 7ms May 05 01:31:23 volumio volumio[1001]: info: ------------------------------ 5ms May 05 01:31:23 volumio volumio[1001]: info: ------------------------------ 4ms May 05 01:31:23 volumio volumio[1001]: info: Starting UPNP Playback May 05 01:31:23 volumio volumio[1001]: info: Preparing playback through UPNP May 05 01:31:23 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:31:23 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:23 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd May 05 01:31:24 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:31:24 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:24 volumio volumio[1001]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined May 05 01:31:24 volumio volumio[1001]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) May 05 01:31:24 volumio volumio[1001]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) May 05 01:31:24 volumio volumio[1001]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) May 05 01:31:24 volumio volumio[1001]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) May 05 01:31:24 volumio volumio[1001]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) May 05 01:31:24 volumio volumio[1001]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) May 05 01:31:24 volumio volumio[1001]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) May 05 01:31:24 volumio volumio[1001]: at /volumio/node_modules/express/lib/router/index.js:281:22 May 05 01:31:24 volumio volumio[1001]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) May 05 01:31:24 volumio volumio[1001]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) May 05 01:31:24 volumio volumio[1001]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) May 05 01:31:24 volumio volumio[1001]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) May 05 01:31:24 volumio volumio[1001]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) May 05 01:31:24 volumio volumio[1001]: at /volumio/node_modules/express/lib/router/index.js:284:7 May 05 01:31:24 volumio volumio[1001]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) May 05 01:31:24 volumio volumio[1001]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 9ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 9ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 7ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 6ms May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 8ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 6ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 5ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 4ms May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 7ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 6ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 4ms May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 7ms May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 7ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 6ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 4ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 3ms May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 8ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 5ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 5ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 3ms May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: May 05 01:31:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:24 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 7ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 5ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 4ms May 05 01:31:24 volumio volumio[1001]: info: ------------------------------ 3ms May 05 01:31:24 volumio volumio[1001]: info: Listing playlists May 05 01:31:25 volumio volumio[1279]: 14 May 05 01:31:25 volumio volumio[1279]: % Total % Received % Xferd Average Speed Time Time Time Current May 05 01:31:25 volumio volumio[1279]: Dload Upload Total Spent Left Speed May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- Client requests Volume 14 May 05 01:31:25 volumio volumio[1001]: info: VolumeController::SetAlsaVolume14 May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:25 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:25 volumio volumio[1279]: [158B blob data] May 05 01:31:25 volumio volumio[1279]: {"time":1746405085276,"response":"volume Success"}14 May 05 01:31:25 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:31:25 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 05 01:31:25 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:25 volumio volumio[1001]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 May 05 01:31:25 volumio volumio[1001]: info: FusionDsp - ---- read samplerate from file: 48000 May 05 01:31:25 volumio volumio[1001]: info: camilladsp stopping service pid 1501... May 05 01:31:25 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:31:25 volumio volumio[1001]: info: camilladsp service terminated, instance 1 May 05 01:31:25 volumio volumio[1001]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 05 01:31:25 volumio volumio[1001]: info: camilladsp service started and running in background, instance 1 May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand status took 43 milliseconds May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand status took 42 milliseconds May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand status took 40 milliseconds May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand status took 39 milliseconds May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:25 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand status took 28 milliseconds May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 27 milliseconds May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 26 milliseconds May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 25 milliseconds May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 24 milliseconds May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand status took 21 milliseconds May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand status took 17 milliseconds May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand status took 15 milliseconds May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:25 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:25 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":"None","album":"None","uri":"http://192.168.1.33:8097/flow/XZ6C5X3R/media_player.volumio_upnp_av/35fd2fab8f2b438b87e6dc3da01834ab.mp3","trackType":"mp3"} May 05 01:31:25 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:25 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:25 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":"None","album":"None","uri":"http://192.168.1.33:8097/flow/XZ6C5X3R/media_player.volumio_upnp_av/35fd2fab8f2b438b87e6dc3da01834ab.mp3","trackType":"mp3"} May 05 01:31:25 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:25 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:25 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:25 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":"None","album":"None","uri":"http://192.168.1.33:8097/flow/XZ6C5X3R/media_player.volumio_upnp_av/35fd2fab8f2b438b87e6dc3da01834ab.mp3","trackType":"mp3"} May 05 01:31:25 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:25 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:25 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:25 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":"None","album":"None","uri":"http://192.168.1.33:8097/flow/XZ6C5X3R/media_player.volumio_upnp_av/35fd2fab8f2b438b87e6dc3da01834ab.mp3","trackType":"mp3"} May 05 01:31:25 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:25 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState May 05 01:31:25 volumio volumio[1001]: info: ------------------------------ 126ms May 05 01:31:25 volumio volumio[1001]: info: ------------------------------ 167ms May 05 01:31:25 volumio volumio[1001]: info: ------------------------------ 166ms May 05 01:31:25 volumio volumio[1001]: info: ------------------------------ 165ms May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:25 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:25 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- MPD announces system playlist update May 05 01:31:25 volumio volumio[1001]: info: Ignoring MPD Status Update May 05 01:31:25 volumio volumio[1001]: info: May 05 01:31:25 volumio volumio[1001]: ---------------------------- MPD announces state update: player May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::getState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status May 05 01:31:25 volumio volumio[1001]: info: ------------------------------ 133ms May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand status took 128 milliseconds May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 128 milliseconds May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 126 milliseconds May 05 01:31:25 volumio volumio[1001]: info: ------------------------------ 38ms May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand status took 36 milliseconds May 05 01:31:25 volumio volumio[1001]: info: ------------------------------ 35ms May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand status took 33 milliseconds May 05 01:31:25 volumio volumio[1001]: info: ------------------------------ 32ms May 05 01:31:25 volumio volumio[1001]: info: sendMpdCommand status took 30 milliseconds May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::parseState May 05 01:31:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:25 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:25 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"35fd2fab8f2b438b87e6dc3da01834ab.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/XZ6C5X3R/media_player.volumio_upnp_av/35fd2fab8f2b438b87e6dc3da01834ab.mp3","trackType":"mp3"} May 05 01:31:25 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:25 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:25 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:25 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:25 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"35fd2fab8f2b438b87e6dc3da01834ab.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/XZ6C5X3R/media_player.volumio_upnp_av/35fd2fab8f2b438b87e6dc3da01834ab.mp3","trackType":"mp3"} May 05 01:31:25 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:25 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:25 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:25 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:25 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"35fd2fab8f2b438b87e6dc3da01834ab.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/XZ6C5X3R/media_player.volumio_upnp_av/35fd2fab8f2b438b87e6dc3da01834ab.mp3","trackType":"mp3"} May 05 01:31:25 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:25 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:25 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:25 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:25 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:25 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"35fd2fab8f2b438b87e6dc3da01834ab.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/XZ6C5X3R/media_player.volumio_upnp_av/35fd2fab8f2b438b87e6dc3da01834ab.mp3","trackType":"mp3"} May 05 01:31:25 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:25 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:25 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:25 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:25 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:25 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:26 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:26 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:26 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:26 volumio volumio[1001]: info: ------------------------------ 229ms May 05 01:31:26 volumio volumio[1001]: info: ------------------------------ 218ms May 05 01:31:26 volumio volumio[1001]: info: ------------------------------ 216ms May 05 01:31:26 volumio volumio[1001]: info: ------------------------------ 213ms May 05 01:31:26 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 05 01:31:26 volumio volumio[1001]: info: BOOT COMPLETED May 05 01:31:26 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 159 milliseconds May 05 01:31:26 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 155 milliseconds May 05 01:31:26 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 153 milliseconds May 05 01:31:26 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 153 milliseconds May 05 01:31:26 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:26 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:26 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo May 05 01:31:26 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:26 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:26 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:26 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"35fd2fab8f2b438b87e6dc3da01834ab.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/XZ6C5X3R/media_player.volumio_upnp_av/35fd2fab8f2b438b87e6dc3da01834ab.mp3","trackType":"mp3"} May 05 01:31:26 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:26 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:26 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:26 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:26 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:26 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:26 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:26 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:26 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:26 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"35fd2fab8f2b438b87e6dc3da01834ab.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/XZ6C5X3R/media_player.volumio_upnp_av/35fd2fab8f2b438b87e6dc3da01834ab.mp3","trackType":"mp3"} May 05 01:31:26 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:26 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:26 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:26 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:26 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:26 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:26 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:26 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:26 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:26 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"35fd2fab8f2b438b87e6dc3da01834ab.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/XZ6C5X3R/media_player.volumio_upnp_av/35fd2fab8f2b438b87e6dc3da01834ab.mp3","trackType":"mp3"} May 05 01:31:26 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:26 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:26 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:26 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:26 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:26 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:26 volumio volumio[1001]: info: ControllerMpd::pushState May 05 01:31:26 volumio volumio[1001]: info: CoreCommandRouter::servicePushState May 05 01:31:26 volumio volumio[1001]: verbose: In UPNP mode May 05 01:31:26 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"35fd2fab8f2b438b87e6dc3da01834ab.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.1.33:8097/flow/XZ6C5X3R/media_player.volumio_upnp_av/35fd2fab8f2b438b87e6dc3da01834ab.mp3","trackType":"mp3"} May 05 01:31:26 volumio volumio[1001]: verbose: CURRENT POSITION 0 May 05 01:31:26 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play May 05 01:31:26 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play May 05 01:31:26 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload May 05 01:31:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 05 01:31:26 volumio volumio[1001]: info: CoreStateMachine::pushState May 05 01:31:26 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:26 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:26 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:26 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:26 volumio volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 05 01:31:26 volumio volumio[1001]: info: ------------------------------ 337ms May 05 01:31:26 volumio volumio[1001]: info: ------------------------------ 238ms May 05 01:31:26 volumio volumio[1001]: info: ------------------------------ 235ms May 05 01:31:26 volumio volumio[1001]: info: ------------------------------ 231ms May 05 01:31:26 volumio volumio[1001]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 05 01:31:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 05 01:31:26 volumio volumio[1001]: info: Received Get System Version May 05 01:31:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 01:31:26 volumio volumio[1001]: info: Received Get System Info May 05 01:31:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 01:31:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 01:31:26 volumio volumio[1001]: info: Discovery: Getting this device information May 05 01:31:26 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState May 05 01:31:26 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 May 05 01:31:26 volumio volumio[1001]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 01:31:26 volumio volumio[1001]: TypeError: Cannot read property 'name' of undefined May 05 01:31:26 volumio volumio[1001]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) May 05 01:31:26 volumio volumio[1001]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) May 05 01:31:26 volumio volumio[1001]: at ControllerVolumioDiscovery.getThisDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:464:38) May 05 01:31:26 volumio volumio[1001]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 05 01:31:26 volumio volumio[1001]: at ControllerSystem.getSystemInfo (/volumio/app/plugins/system_controller/system/index.js:617:45) May 05 01:31:26 volumio volumio[1001]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 05 01:31:26 volumio volumio[1001]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1057:49) May 05 01:31:26 volumio volumio[1001]: at Socket.emit (events.js:315:20) May 05 01:31:26 volumio volumio[1001]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 05 01:31:26 volumio volumio[1001]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 05 01:31:26 volumio volumio[1001]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 01:31:27 volumio ntpd[809]: Soliciting pool server 162.159.200.1 May 05 01:31:27 volumio ntpd[809]: Soliciting pool server 217.154.60.177 May 05 01:31:27 volumio sudo[1569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-05 01:30 May 05 01:31:27 volumio sudo[1569]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:31:27 volumio ntpd[809]: Soliciting pool server 131.111.8.63 PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"