-- Logs begin at Sat 2025-01-11 13:27:39 PST, end at Sat 2025-01-11 13:29:54 PST. -- Jan 11 13:28:00 volumio1 sudo[959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:00 volumio1 sudo[959]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:00 volumio1 sudo[959]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:00 volumio1 kernel: wlan0: send auth to 60:b7:6e:27:8e:05 (try 2/3) Jan 11 13:28:00 volumio1 kernel: wlan0: send auth to 60:b7:6e:27:8e:05 (try 3/3) Jan 11 13:28:00 volumio1 kernel: wlan0: authentication with 60:b7:6e:27:8e:05 timed out Jan 11 13:28:00 volumio1 wpa_supplicant[902]: wlan0: SME: Trying to authenticate with 60:b7:6e:27:9b:4c (SSID='thesacmcleods' freq=2462 MHz) Jan 11 13:28:00 volumio1 kernel: wlan0: authenticate with 60:b7:6e:27:9b:4c Jan 11 13:28:00 volumio1 kernel: wlan0: 80 MHz not supported, disabling VHT Jan 11 13:28:00 volumio1 kernel: wlan0: send auth to 60:b7:6e:27:9b:4c (try 1/3) Jan 11 13:28:00 volumio1 kernel: wlan0: send auth to 60:b7:6e:27:9b:4c (try 2/3) Jan 11 13:28:00 volumio1 wpa_supplicant[902]: wlan0: Trying to associate with 60:b7:6e:27:9b:4c (SSID='thesacmcleods' freq=2462 MHz) Jan 11 13:28:00 volumio1 kernel: wlan0: authenticated Jan 11 13:28:00 volumio1 kernel: wlan0: associate with 60:b7:6e:27:9b:4c (try 1/3) Jan 11 13:28:00 volumio1 kernel: wlan0: associate with 60:b7:6e:27:9b:4c (try 2/3) Jan 11 13:28:00 volumio1 kernel: wlan0: RX AssocResp from 60:b7:6e:27:9b:4c (capab=0x1431 status=0 aid=3) Jan 11 13:28:00 volumio1 wpa_supplicant[902]: wlan0: Associated with 60:b7:6e:27:9b:4c Jan 11 13:28:00 volumio1 wpa_supplicant[902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 11 13:28:00 volumio1 wpa_supplicant[902]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jan 11 13:28:00 volumio1 kernel: wlan0: associated Jan 11 13:28:00 volumio1 wireless.js[561]: trying... Jan 11 13:28:01 volumio1 sudo[969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:01 volumio1 sudo[969]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:01 volumio1 sudo[969]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:01 volumio1 kernel: cryptd: max_cpu_qlen set to 1000 Jan 11 13:28:01 volumio1 wpa_supplicant[902]: wlan0: WPA: Key negotiation completed with 60:b7:6e:27:9b:4c [PTK=CCMP GTK=CCMP] Jan 11 13:28:01 volumio1 wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to 60:b7:6e:27:9b:4c completed [id=0 id_str=] Jan 11 13:28:01 volumio1 dhcpcd[947]: wlan0: carrier acquired Jan 11 13:28:01 volumio1 dhcpcd[947]: wlan0: connected to Access Point `thesacmcleods' Jan 11 13:28:01 volumio1 kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 60:b7:6e:27:9b:4c Jan 11 13:28:01 volumio1 dhcpcd[947]: wlan0: IAID 02:9b:e8:6d Jan 11 13:28:01 volumio1 dhcpcd[947]: wlan0: adding address fe80::294e:b669:7647:d06f Jan 11 13:28:01 volumio1 dhcpcd[947]: ipv6_addaddr1: Permission denied Jan 11 13:28:01 volumio1 dhcpcd[947]: wlan0: rebinding lease of 192.168.86.49 Jan 11 13:28:01 volumio1 dhcpcd[947]: wlan0: soliciting an IPv6 router Jan 11 13:28:01 volumio1 wireless.js[561]: trying... Jan 11 13:28:02 volumio1 sudo[997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:02 volumio1 sudo[997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:02 volumio1 sudo[997]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:02 volumio1 volumio-remote-updater[565]: [2025-01-11 13:28:02] [info] asio async_connect error: system:111 (Connection refused) Jan 11 13:28:02 volumio1 volumio-remote-updater[565]: [2025-01-11 13:28:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 11 13:28:02 volumio1 volumio-remote-updater[565]: [2025-01-11 13:28:02] [error] handle_connect error: Underlying Transport Error Jan 11 13:28:02 volumio1 wireless.js[561]: trying... Jan 11 13:28:03 volumio1 sudo[1003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:03 volumio1 sudo[1003]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:03 volumio1 sudo[1003]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:03 volumio1 wireless.js[561]: trying... Jan 11 13:28:04 volumio1 sudo[1009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:04 volumio1 sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:04 volumio1 sudo[1009]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:04 volumio1 wireless.js[561]: trying... Jan 11 13:28:05 volumio1 sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:05 volumio1 sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:05 volumio1 sudo[1015]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:05 volumio1 wireless.js[561]: trying... Jan 11 13:28:06 volumio1 sudo[1021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:06 volumio1 sudo[1021]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:06 volumio1 sudo[1021]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:06 volumio1 dhcpcd[947]: wlan0: probing for an IPv4LL address Jan 11 13:28:06 volumio1 dhcpcd[947]: wlan0: DHCP lease expired Jan 11 13:28:06 volumio1 dhcpcd[947]: wlan0: soliciting a DHCP lease Jan 11 13:28:06 volumio1 wireless.js[561]: trying... Jan 11 13:28:07 volumio1 sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:07 volumio1 sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:07 volumio1 sudo[1044]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:07 volumio1 wireless.js[561]: trying... Jan 11 13:28:08 volumio1 sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:08 volumio1 sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:08 volumio1 sudo[1050]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:08 volumio1 wireless.js[561]: trying... Jan 11 13:28:09 volumio1 sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:09 volumio1 sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:09 volumio1 sudo[1056]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:09 volumio1 wireless.js[561]: trying... Jan 11 13:28:10 volumio1 sudo[1062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:10 volumio1 sudo[1062]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:10 volumio1 sudo[1062]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:10 volumio1 kernel: wlan0: deauthenticated from 60:b7:6e:27:9b:4c (Reason: 7=CLASS3_FRAME_FROM_NONASSOC_STA) Jan 11 13:28:10 volumio1 dhcpcd[947]: wlan0: carrier lost Jan 11 13:28:10 volumio1 wpa_supplicant[902]: wlan0: CTRL-EVENT-DISCONNECTED bssid=60:b7:6e:27:9b:4c reason=7 Jan 11 13:28:10 volumio1 wpa_supplicant[902]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jan 11 13:28:10 volumio1 wpa_supplicant[902]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Jan 11 13:28:10 volumio1 wpa_supplicant[902]: wlan0: SME: Trying to authenticate with 60:b7:6e:27:8e:05 (SSID='thesacmcleods' freq=2462 MHz) Jan 11 13:28:10 volumio1 kernel: wlan0: authenticate with 60:b7:6e:27:8e:05 Jan 11 13:28:10 volumio1 kernel: wlan0: 80 MHz not supported, disabling VHT Jan 11 13:28:10 volumio1 kernel: wlan0: send auth to 60:b7:6e:27:8e:05 (try 1/3) Jan 11 13:28:10 volumio1 kernel: wlan0: send auth to 60:b7:6e:27:8e:05 (try 2/3) Jan 11 13:28:10 volumio1 wpa_supplicant[902]: wlan0: Trying to associate with 60:b7:6e:27:8e:05 (SSID='thesacmcleods' freq=2462 MHz) Jan 11 13:28:10 volumio1 kernel: wlan0: authenticated Jan 11 13:28:10 volumio1 kernel: wlan0: associate with 60:b7:6e:27:8e:05 (try 1/3) Jan 11 13:28:10 volumio1 kernel: wlan0: associate with 60:b7:6e:27:8e:05 (try 2/3) Jan 11 13:28:10 volumio1 kernel: wlan0: RX AssocResp from 60:b7:6e:27:8e:05 (capab=0x1031 status=0 aid=5) Jan 11 13:28:10 volumio1 wpa_supplicant[902]: wlan0: Associated with 60:b7:6e:27:8e:05 Jan 11 13:28:10 volumio1 wpa_supplicant[902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 11 13:28:10 volumio1 wpa_supplicant[902]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jan 11 13:28:10 volumio1 kernel: wlan0: associated Jan 11 13:28:10 volumio1 kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 60:b7:6e:27:8e:05 Jan 11 13:28:10 volumio1 wpa_supplicant[902]: wlan0: WPA: Key negotiation completed with 60:b7:6e:27:8e:05 [PTK=CCMP GTK=CCMP] Jan 11 13:28:10 volumio1 wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to 60:b7:6e:27:8e:05 completed [id=0 id_str=] Jan 11 13:28:10 volumio1 dhcpcd[947]: wlan0: carrier acquired Jan 11 13:28:10 volumio1 dhcpcd[947]: wlan0: IAID 02:9b:e8:6d Jan 11 13:28:10 volumio1 wireless.js[561]: trying... Jan 11 13:28:11 volumio1 sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:11 volumio1 sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:11 volumio1 sudo[1105]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:11 volumio1 dhcpcd[947]: wlan0: soliciting an IPv6 router Jan 11 13:28:11 volumio1 dhcpcd[947]: wlan0: soliciting a DHCP lease Jan 11 13:28:11 volumio1 dhcpcd[947]: wlan0: offered 192.168.86.49 from 192.168.86.1 Jan 11 13:28:11 volumio1 wireless.js[561]: trying... Jan 11 13:28:12 volumio1 dhcpcd[947]: wlan0: probing address 192.168.86.49/24 Jan 11 13:28:12 volumio1 sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:12 volumio1 sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:12 volumio1 sudo[1111]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:12 volumio1 volumio-remote-updater[565]: [2025-01-11 13:28:12] [info] asio async_connect error: system:111 (Connection refused) Jan 11 13:28:12 volumio1 volumio-remote-updater[565]: [2025-01-11 13:28:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 11 13:28:12 volumio1 volumio-remote-updater[565]: [2025-01-11 13:28:12] [error] handle_connect error: Underlying Transport Error Jan 11 13:28:12 volumio1 wireless.js[561]: trying... Jan 11 13:28:13 volumio1 sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:13 volumio1 sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:13 volumio1 sudo[1117]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:13 volumio1 wireless.js[561]: trying... Jan 11 13:28:14 volumio1 sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:14 volumio1 sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:14 volumio1 sudo[1123]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:14 volumio1 systemd[1]: systemd-fsckd.service: Succeeded. Jan 11 13:28:14 volumio1 wireless.js[561]: trying... Jan 11 13:28:15 volumio1 sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:15 volumio1 sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:15 volumio1 sudo[1126]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:15 volumio1 wireless.js[561]: trying... Jan 11 13:28:16 volumio1 sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:16 volumio1 sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:16 volumio1 sudo[1132]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:17 volumio1 wireless.js[561]: trying... Jan 11 13:28:17 volumio1 sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 13:28:17 volumio1 sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:17 volumio1 sudo[1138]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:17 volumio1 dhcpcd[947]: wlan0: leased 192.168.86.49 for 86400 seconds Jan 11 13:28:17 volumio1 avahi-daemon[575]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.86.49. Jan 11 13:28:17 volumio1 dhcpcd[947]: wlan0: adding route to 192.168.86.0/24 Jan 11 13:28:17 volumio1 avahi-daemon[575]: New relevant interface wlan0.IPv4 for mDNS. Jan 11 13:28:17 volumio1 avahi-daemon[575]: Registering new address record for 192.168.86.49 on wlan0.IPv4. Jan 11 13:28:17 volumio1 dhcpcd[947]: wlan0: adding default route via 192.168.86.1 Jan 11 13:28:18 volumio1 wireless.js[561]: Overtime, starting plan B Jan 11 13:28:18 volumio1 iwconfig[1170]: Error for wireless request "Set Power Management" (8B2C) : Jan 11 13:28:18 volumio1 iwconfig[1170]: SET failed on device wlan0 ; Operation not supported. Jan 11 13:28:18 volumio1 systemd[1]: Started Wireless Services. Jan 11 13:28:18 volumio1 systemd[1]: Started Volumio Backend Module. Jan 11 13:28:18 volumio1 systemd[1]: Started Volumio Cpu Tweaker. Jan 11 13:28:18 volumio1 systemd[1]: Reached target Multi-User System. Jan 11 13:28:18 volumio1 systemd[1]: Reached target Graphical Interface. Jan 11 13:28:18 volumio1 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 11 13:28:18 volumio1 ntpd[721]: Listen normally on 4 wlan0 192.168.86.49:123 Jan 11 13:28:18 volumio1 ntpd[721]: new interface(s) found: waking up resolver Jan 11 13:28:18 volumio1 volumio-cpu-tweak[1172]: Setting RT Priority for mpd Jan 11 13:28:18 volumio1 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 11 13:28:18 volumio1 volumio-cpu-tweak[1172]: pid 35's current scheduling policy: SCHED_OTHER Jan 11 13:28:18 volumio1 volumio-cpu-tweak[1172]: pid 35's current scheduling priority: 0 Jan 11 13:28:18 volumio1 systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 11 13:28:18 volumio1 volumio-cpu-tweak[1172]: Setting MPD Affinity Jan 11 13:28:18 volumio1 systemd[1]: Startup finished in 18.490s (kernel) + 42.466s (userspace) = 1min 956ms. Jan 11 13:28:18 volumio1 volumio-cpu-tweak[1172]: pid 3's current affinity mask: f Jan 11 13:28:18 volumio1 volumio-cpu-tweak[1172]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jan 11 13:28:18 volumio1 volumio-cpu-tweak[1172]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 11 13:28:18 volumio1 systemd[1]: volumio_cpu_tweak.service: Succeeded. Jan 11 13:28:22 volumio1 volumio-remote-updater[565]: [2025-01-11 13:28:22] [connect] Successful connection Jan 11 13:28:23 volumio1 volumio[1171]: info: ------------------------------------------- Jan 11 13:28:23 volumio1 volumio[1171]: info: ----- Volumio3 ---- Jan 11 13:28:23 volumio1 volumio[1171]: info: ------------------------------------------- Jan 11 13:28:23 volumio1 volumio[1171]: info: ----- System startup ---- Jan 11 13:28:23 volumio1 volumio[1171]: info: ------------------------------------------- Jan 11 13:28:25 volumio1 volumio[1171]: info: MYVOLUMIO Environment detected Jan 11 13:28:26 volumio1 volumio[1171]: info: Plugin folders cleanup Jan 11 13:28:26 volumio1 volumio[1171]: info: Scanning into folder /volumio/app/plugins/ Jan 11 13:28:26 volumio1 volumio[1171]: info: Scanning category audio_interface Jan 11 13:28:26 volumio1 volumio[1171]: info: Scanning category miscellanea Jan 11 13:28:26 volumio1 volumio[1171]: info: Scanning category music_service Jan 11 13:28:26 volumio1 volumio[1171]: info: Scanning category plugins.json Jan 11 13:28:26 volumio1 volumio[1171]: info: Scanning category system_controller Jan 11 13:28:26 volumio1 volumio[1171]: info: Scanning category user_interface Jan 11 13:28:26 volumio1 volumio[1171]: info: Scanning into folder /data/plugins/ Jan 11 13:28:26 volumio1 volumio[1171]: info: Scanning category system_hardware Jan 11 13:28:26 volumio1 volumio[1171]: info: Plugin folders cleanup completed Jan 11 13:28:26 volumio1 volumio[1171]: info: ------------------------------------------- Jan 11 13:28:26 volumio1 volumio[1171]: info: ----- Core plugins startup ---- Jan 11 13:28:26 volumio1 volumio[1171]: info: ------------------------------------------- Jan 11 13:28:26 volumio1 volumio[1171]: info: Loading plugins from folder /volumio/app/plugins/ Jan 11 13:28:26 volumio1 volumio[1171]: info: Adding plugin upnp to MyMusic Plugins Jan 11 13:28:26 volumio1 volumio[1171]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 11 13:28:26 volumio1 volumio[1171]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 11 13:28:26 volumio1 volumio[1171]: info: Loading plugins from folder /data/plugins/ Jan 11 13:28:26 volumio1 volumio[1171]: info: Loading plugin "system"... Jan 11 13:28:26 volumio1 volumio[1171]: info: Loading plugin "appearance"... Jan 11 13:28:29 volumio1 volumio[1171]: info: Loading plugin "network"... Jan 11 13:28:29 volumio1 volumio[1171]: info: Refreshing Cached IP Addresses Jan 11 13:28:29 volumio1 sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 13:28:29 volumio1 sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:29 volumio1 volumio[1171]: info: Loading plugin "services"... Jan 11 13:28:29 volumio1 sudo[1233]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:29 volumio1 sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 13:28:29 volumio1 sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:29 volumio1 sudo[1235]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:29 volumio1 volumio[1171]: info: Loading plugin "alsa_controller"... Jan 11 13:28:29 volumio1 sudo[1237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 11 13:28:29 volumio1 sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:29 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 13:28:29 volumio1 volumio[1171]: info: Loading plugin "wizard"... Jan 11 13:28:29 volumio1 volumio[1171]: info: Loading plugin "networkfs"... Jan 11 13:28:29 volumio1 volumio[1171]: info: Starting Udev Watcher for removable devices Jan 11 13:28:29 volumio1 sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=volumio,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.86.45/volumio /mnt/NAS/volumio Jan 11 13:28:29 volumio1 sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:29 volumio1 sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=volumio,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.86.46/volumio /mnt/NAS/volumio Jan 11 13:28:29 volumio1 sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:29 volumio1 volumio[1171]: info: Ignoring mount for partition: boot Jan 11 13:28:29 volumio1 volumio[1171]: info: Ignoring mount for partition: volumio Jan 11 13:28:29 volumio1 volumio[1171]: info: Ignoring mount for partition: volumio_data Jan 11 13:28:29 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 13:28:29 volumio1 volumio[1171]: info: Loading plugin "volumio_command_line_client"... Jan 11 13:28:29 volumio1 volumio[1171]: info: Loading plugin "upnp"... Jan 11 13:28:30 volumio1 volumio[1171]: info: [1736630910008] Starting Upmpd Daemon Jan 11 13:28:30 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 13:28:30 volumio1 volumio[1171]: info: Loading plugin "my_music"... Jan 11 13:28:30 volumio1 volumio[1171]: info: Loading plugin "mpd"... Jan 11 13:28:30 volumio1 kernel: Key type cifs.spnego registered Jan 11 13:28:30 volumio1 kernel: Key type cifs.idmap registered Jan 11 13:28:30 volumio1 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jan 11 13:28:30 volumio1 kernel: CIFS: Attempting to mount \\192.168.86.45\volumio Jan 11 13:28:30 volumio1 kernel: CIFS: Attempting to mount \\192.168.86.46\volumio Jan 11 13:28:30 volumio1 sudo[1249]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:30 volumio1 sudo[1251]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:31 volumio1 volumio[1171]: info: Loading plugin "upnp_browser"... Jan 11 13:28:31 volumio1 volumio[1171]: info: Loading plugin "alarm-clock"... Jan 11 13:28:32 volumio1 volumio[1171]: info: Loading plugin "airplay_emulation"... Jan 11 13:28:32 volumio1 volumio[1171]: info: Starting Shairport Sync Jan 11 13:28:32 volumio1 volumio[1171]: info: Loading plugin "last_100"... Jan 11 13:28:32 volumio1 volumio[1171]: info: Loading plugin "webradio"... Jan 11 13:28:32 volumio1 sudo[1237]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:32 volumio1 volumio[1171]: info: Loading plugin "i2s_dacs"... Jan 11 13:28:32 volumio1 volumio[1171]: info: Loading plugin "volumiodiscovery"... Jan 11 13:28:33 volumio1 volumio[1171]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 11 13:28:33 volumio1 volumio[1171]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 13:28:33 volumio1 volumio[1171]: *** WARNING *** For more information see Jan 11 13:28:33 volumio1 node[1171]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 11 13:28:33 volumio1 volumio[1171]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 11 13:28:33 volumio1 volumio[1171]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 13:28:33 volumio1 volumio[1171]: *** WARNING *** For more information see Jan 11 13:28:33 volumio1 node[1171]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 13:28:33 volumio1 node[1171]: *** WARNING *** For more information see Jan 11 13:28:33 volumio1 node[1171]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 11 13:28:33 volumio1 node[1171]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 13:28:33 volumio1 node[1171]: *** WARNING *** For more information see Jan 11 13:28:33 volumio1 volumio[1171]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 11 13:28:33 volumio1 volumio[1171]: info: Discovery: Started advertising with name: Volumio1 Jan 11 13:28:33 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 13:28:33 volumio1 volumio[1171]: info: Loading plugin "outputs"... Jan 11 13:28:33 volumio1 volumio[1171]: info: Loading plugin "albumart"... Jan 11 13:28:33 volumio1 volumio[1171]: info: Plugin example_plugin is not enabled Jan 11 13:28:33 volumio1 volumio[1171]: info: Loading plugin "inputs"... Jan 11 13:28:33 volumio1 volumio[1171]: info: Loading plugin "updater_comm"... Jan 11 13:28:33 volumio1 volumio[1171]: info: Plugin mpdemulation is not enabled Jan 11 13:28:33 volumio1 volumio[1171]: info: Loading plugin "rest_api"... Jan 11 13:28:33 volumio1 volumio[1171]: info: Loading plugin "websocket"... Jan 11 13:28:33 volumio1 volumio[1171]: info: Starting Socket.io Server version 2.3.0 Jan 11 13:28:33 volumio1 volumio[1171]: info: Loading plugin "ir_controller"... Jan 11 13:28:34 volumio1 volumio[1171]: Forking 3 albumart workers Jan 11 13:28:34 volumio1 volumio[1171]: info: Applying required configuration parameters for plugin ir_controller Jan 11 13:28:34 volumio1 volumio[1171]: info: Loading plugin "mpd_oled"... Jan 11 13:28:35 volumio1 volumio[1171]: info: Applying required configuration parameters for plugin mpd_oled Jan 11 13:28:35 volumio1 volumio[1171]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Jan 11 13:28:35 volumio1 volumio[1171]: info: Loading i18n strings for locale en Jan 11 13:28:35 volumio1 volumio[1171]: Updating browse sources language Jan 11 13:28:35 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 13:28:36 volumio1 volumio[1171]: Starting albumart workers Jan 11 13:28:36 volumio1 volumio[1171]: Starting albumart workers Jan 11 13:28:36 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:28:36 volumio1 volumio[1171]: info: CoreCommandRouter::initPlayerControls Jan 11 13:28:36 volumio1 volumio[1171]: Starting albumart workers Jan 11 13:28:36 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:36 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:36 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:36 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:36 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:36 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:36 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:36 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:36 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:28:36 volumio1 volumio[1171]: Express server listening on port 3000 Jan 11 13:28:36 volumio1 volumio[1171]: [Metrics] WebUI: 14s 864.01ms Jan 11 13:28:36 volumio1 volumio[1171]: info: CoreStateMachine::resetVolumioState Jan 11 13:28:36 volumio1 volumio[1171]: info: CoreStateMachine::getcurrentVolume Jan 11 13:28:36 volumio1 volumio[1171]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 13:28:37 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 1 Jan 11 13:28:37 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 2 Jan 11 13:28:37 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 3 Jan 11 13:28:37 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 4 Jan 11 13:28:37 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 5 Jan 11 13:28:37 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 6 Jan 11 13:28:37 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 7 Jan 11 13:28:37 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 8 Jan 11 13:28:37 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 9 Jan 11 13:28:37 volumio1 volumio-remote-updater[565]: [2025-01-11 13:28:37] [connect] Successful connection Jan 11 13:28:37 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 10 Jan 11 13:28:37 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 11 Jan 11 13:28:37 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 12 Jan 11 13:28:37 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 13 Jan 11 13:28:37 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 14 Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 15 Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 16 Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 17 Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49:3000 from 192.168.86.47 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 19 Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 20 Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 21 Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 22 Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 23 Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 24 Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 25 Jan 11 13:28:38 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:28:38 volumio1 volumio[1171]: info: Reloading queue from file Jan 11 13:28:38 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:38 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 26 Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 27 Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 28 Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 29 Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 30 Jan 11 13:28:38 volumio1 volumio-remote-updater[565]: [2025-01-11 13:28:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736630917 101 Jan 11 13:28:38 volumio1 volumio[1171]: 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: 31 Jan 11 13:28:38 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:38 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:38 volumio1 volumio[1171]: info: VolumeController:: Volume=67 Mute =false Jan 11 13:28:38 volumio1 volumio[1171]: info: CoreStateMachine::pushState Jan 11 13:28:38 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:38 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 13:28:38 volumio1 volumio[1171]: info: CoreCommandRouter::volumioPushState Jan 11 13:28:38 volumio1 volumio[1171]: info: CoreStateMachine::updateTrackBlock Jan 11 13:28:38 volumio1 volumio[1171]: info: CorePlayQueue::getTrackBlock Jan 11 13:28:38 volumio1 volumio[1171]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 13:28:38 volumio1 volumio[1171]: info: CoreStateMachine::setRepeat null single undefined Jan 11 13:28:38 volumio1 volumio[1171]: info: CoreStateMachine::pushState Jan 11 13:28:38 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:38 volumio1 volumio[1171]: info: CoreCommandRouter::volumioPushState Jan 11 13:28:38 volumio1 volumio[1171]: info: CoreStateMachine::setRandom null Jan 11 13:28:38 volumio1 volumio[1171]: info: CoreStateMachine::pushState Jan 11 13:28:38 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:38 volumio1 volumio[1171]: info: CoreCommandRouter::volumioPushState Jan 11 13:28:38 volumio1 volumio[1171]: info: Setting Device type: Raspberry PI Jan 11 13:28:38 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 32 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 33 Jan 11 13:28:39 volumio1 volumio[1171]: info: Received Get System Info Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 13:28:39 volumio1 volumio[1171]: info: Discovery: Getting this device information Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:39 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:39 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:39 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:39 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 11 13:28:39 volumio1 volumio[1171]: info: Received Get System Info Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 13:28:39 volumio1 volumio[1171]: info: Discovery: Getting this device information Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:39 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:39 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:39 volumio1 volumio[1171]: info: Listing playlists Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetQueue Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreStateMachine::getQueue Jan 11 13:28:39 volumio1 volumio[1171]: info: CorePlayQueue::getQueue Jan 11 13:28:39 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 34 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 35 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 36 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 37 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 38 Jan 11 13:28:39 volumio1 volumio[1171]: info: Completed loading Core Plugins Jan 11 13:28:39 volumio1 volumio[1171]: info: Preparing to generate the ALSA configuration file Jan 11 13:28:39 volumio1 volumio[1171]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jan 11 13:28:39 volumio1 volumio[1171]: info: Reading ALSA contributions from plugins. Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 39 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 40 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 41 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 42 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 43 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 44 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 45 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 46 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 47 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 48 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 49 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 50 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 51 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 52 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 53 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 54 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 55 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 56 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 57 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 58 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 59 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 60 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 61 Jan 11 13:28:39 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 62 Jan 11 13:28:40 volumio1 volumio[1171]: info: Discovery: adding 9989748c-6bf0-42e7-991a-53e40bdb6510 Jan 11 13:28:40 volumio1 volumio[1171]: info: Discovery: Found device Volumio2 Jan 11 13:28:40 volumio1 volumio[1171]: info: Discovery: Connecting to remote: 192.168.86.230 Jan 11 13:28:40 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 63 Jan 11 13:28:40 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 64 Jan 11 13:28:40 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 65 Jan 11 13:28:40 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 66 Jan 11 13:28:40 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 67 Jan 11 13:28:40 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 68 Jan 11 13:28:40 volumio1 volumio[1171]: info: VolumeController:: Volume=67 Mute =false Jan 11 13:28:40 volumio1 volumio[1171]: info: CoreStateMachine::pushState Jan 11 13:28:40 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:40 volumio1 volumio[1171]: info: CoreCommandRouter::volumioPushState Jan 11 13:28:40 volumio1 sudo[1387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 13:28:40 volumio1 sudo[1387]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:40 volumio1 sudo[1387]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:40 volumio1 sudo[1389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 13:28:40 volumio1 sudo[1389]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:40 volumio1 sudo[1389]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:40 volumio1 volumio[1171]: info: Discovery: this is already registered, 9989748c-6bf0-42e7-991a-53e40bdb6510 Jan 11 13:28:40 volumio1 volumio[1171]: info: Discovery: Found device Volumio2 Jan 11 13:28:40 volumio1 volumio[1171]: info: Discovery: Connecting to remote: 192.168.86.230 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 69 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 70 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 71 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 72 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 73 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 74 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 75 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 76 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 77 Jan 11 13:28:41 volumio1 volumio[1171]: info: Discovery: adding 1de27509-ac78-4d5e-9b65-e1341d99e8bc Jan 11 13:28:41 volumio1 volumio[1171]: info: Discovery: Found device Volumio1 Jan 11 13:28:41 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:41 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 78 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 79 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 80 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 81 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 82 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 83 Jan 11 13:28:41 volumio1 volumio[1171]: info: Discovery: this is already registered, 1de27509-ac78-4d5e-9b65-e1341d99e8bc Jan 11 13:28:41 volumio1 volumio[1171]: info: Discovery: Found device Volumio1 Jan 11 13:28:41 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:41 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 84 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 85 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 86 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 87 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 88 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 89 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 90 Jan 11 13:28:41 volumio1 volumio[1171]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 91 Jan 11 13:28:41 volumio1 volumio[1171]: info: Asound.conf file unchanged, so no further update is needed Jan 11 13:28:41 volumio1 volumio[1171]: info: Output device has changed, restarting MPD Jan 11 13:28:41 volumio1 volumio[1171]: info: Output device has changed, restarting Shairport Sync Jan 11 13:28:41 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:41 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:28:41 volumio1 sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 13:28:41 volumio1 sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:41 volumio1 sudo[1396]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:41 volumio1 sudo[1398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 13:28:41 volumio1 sudo[1398]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:41 volumio1 volumio[1171]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 13:28:41 volumio1 volumio[1171]: info: ___________ START PLUGINS ___________ Jan 11 13:28:41 volumio1 volumio[1171]: info: ControllerMpd::onStart: Initializing MPD Jan 11 13:28:41 volumio1 volumio[1171]: info: Creating MPD Configuration file Jan 11 13:28:41 volumio1 systemd[1]: Listening on mpd.socket. Jan 11 13:28:41 volumio1 systemd[1]: Starting Music Player Daemon... Jan 11 13:28:41 volumio1 sudo[1409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 13:28:41 volumio1 sudo[1409]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:41 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:28:41 volumio1 sudo[1409]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:41 volumio1 volumio[1171]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 13:28:41 volumio1 volumio[1171]: info: [1736630921888] CoreMusicLibrary::Adding element Media Servers Jan 11 13:28:41 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 13:28:41 volumio1 sudo[1412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 13:28:41 volumio1 sudo[1412]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:41 volumio1 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 11 13:28:41 volumio1 systemd[1]: mpd.service: Succeeded. Jan 11 13:28:41 volumio1 systemd[1]: Stopped Music Player Daemon. Jan 11 13:28:41 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:42 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:28:42 volumio1 systemd[1]: Starting Music Player Daemon... Jan 11 13:28:42 volumio1 volumio[1171]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 13:28:42 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:42 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:28:42 volumio1 volumio[1171]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 13:28:42 volumio1 volumio[1171]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 13:28:42 volumio1 volumio[1171]: info: [1736630922209] CoreMusicLibrary::Adding element Last_100 Jan 11 13:28:42 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 13:28:42 volumio1 volumio[1171]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 13:28:42 volumio1 volumio[1171]: info: [1736630922222] CoreMusicLibrary::Adding element Webradio Jan 11 13:28:42 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 13:28:42 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 13:28:42 volumio1 volumio[1171]: info: Initializing BBC Radios Jan 11 13:28:42 volumio1 sudo[1415]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 13:28:42 volumio1 sudo[1415]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:42 volumio1 sudo[1415]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 11 13:28:42 volumio1 sudo[1415]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:42 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 13:28:42 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:28:42 volumio1 volumio[1171]: info: Loading i18n strings for locale en Jan 11 13:28:42 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 13:28:42 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 11 13:28:42 volumio1 volumio[1171]: info: Volumio Calling Home Jan 11 13:28:42 volumio1 sudo[1437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Jan 11 13:28:42 volumio1 sudo[1437]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:42 volumio1 sudo[1437]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:42 volumio1 sudo[1439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Jan 11 13:28:42 volumio1 sudo[1439]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:43 volumio1 volumio[1171]: info: Preparing to generate the ALSA configuration file Jan 11 13:28:43 volumio1 sudo[1451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Jan 11 13:28:43 volumio1 sudo[1451]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:43 volumio1 sudo[1451]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:43 volumio1 volumio[1171]: info: Discovery: Connected to remote: 192.168.86.230 Jan 11 13:28:43 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:43 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:43 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:43 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:43 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:43 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:43 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:43 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:43 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:43 volumio1 volumio[1171]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jan 11 13:28:43 volumio1 volumio[1171]: info: Reading ALSA contributions from plugins. Jan 11 13:28:43 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:43 volumio1 volumio[1171]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 13:28:43 volumio1 volumio[1171]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Jan 11 13:28:43 volumio1 volumio[1171]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Jan 11 13:28:43 volumio1 volumio[1171]: info: MPD Permissions set Jan 11 13:28:43 volumio1 volumio[1171]: info: MPD Permissions set Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:44 volumio1 sudo[1457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 11 13:28:44 volumio1 sudo[1457]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:44 volumio1 volumio[1171]: info: Volumio called home Jan 11 13:28:44 volumio1 volumio[1171]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Jan 11 13:28:44 volumio1 volumio[1171]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 92 Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:44 volumio1 systemd[1]: Started UPnP Renderer front-end to MPD. Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:44 volumio1 sudo[1457]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 13:28:44 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:44 volumio1 volumio[1171]: info: Upmpdcli Daemon Started Jan 11 13:28:44 volumio1 volumio[1171]: info: Starting Shairport Sync Jan 11 13:28:44 volumio1 volumio[1171]: info: Starting Shairport Sync Jan 11 13:28:44 volumio1 volumio[1171]: info: Starting Shairport Sync Jan 11 13:28:44 volumio1 sudo[1472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 13:28:44 volumio1 sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:44 volumio1 sudo[1474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 13:28:44 volumio1 sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:44 volumio1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 11 13:28:44 volumio1 systemd[1]: shairport-sync.service: Succeeded. Jan 11 13:28:44 volumio1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 11 13:28:45 volumio1 sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 13:28:45 volumio1 sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:45 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 11 13:28:45 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 13:28:45 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 11 13:28:45 volumio1 sudo[1472]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:45 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:45 volumio1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 11 13:28:45 volumio1 systemd[1]: shairport-sync.service: Succeeded. Jan 11 13:28:45 volumio1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 11 13:28:45 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:45 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:45 volumio1 volumio[1171]: info: ir_controller: LIRC correctly updated. Jan 11 13:28:45 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 11 13:28:45 volumio1 volumio[1171]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver! Jan 11 13:28:45 volumio1 sudo[1474]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:45 volumio1 sudo[1477]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:45 volumio1 volumio[1171]: info: Shairport-Sync Started Jan 11 13:28:45 volumio1 volumio[1171]: Error adding Membership: Error: addMembership EINVAL Jan 11 13:28:45 volumio1 volumio[1171]: info: Asound.conf file unchanged, so no further update is needed Jan 11 13:28:45 volumio1 volumio[1171]: info: Output device has changed, restarting MPD Jan 11 13:28:45 volumio1 volumio[1171]: info: Output device has changed, restarting Shairport Sync Jan 11 13:28:45 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:45 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:28:45 volumio1 sudo[1496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 13:28:45 volumio1 sudo[1496]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:45 volumio1 sudo[1496]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:45 volumio1 sudo[1498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 13:28:45 volumio1 sudo[1498]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:45 volumio1 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 11 13:28:45 volumio1 systemd[1]: mpd.service: Succeeded. Jan 11 13:28:45 volumio1 systemd[1]: Stopped Music Player Daemon. Jan 11 13:28:45 volumio1 volumio[1171]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 13:28:45 volumio1 systemd[1]: Starting Music Player Daemon... Jan 11 13:28:45 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:45 volumio1 volumio[1171]: info: ir_controller: Raspberry Pi revision code: a21041 Jan 11 13:28:45 volumio1 volumio[1171]: info: Shairport-Sync Started Jan 11 13:28:45 volumio1 volumio[1171]: info: Shairport-Sync Started Jan 11 13:28:45 volumio1 volumio[1171]: info: MPD Permissions set Jan 11 13:28:46 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:46 volumio1 sudo[1504]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 13:28:46 volumio1 sudo[1504]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:46 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:46 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:46 volumio1 sudo[1504]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 11 13:28:46 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:46 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:46 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:46 volumio1 sudo[1504]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:46 volumio1 sudo[1512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r -1 Jan 11 13:28:46 volumio1 sudo[1512]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:46 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:28:46 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:28:46 volumio1 sudo[1512]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:46 volumio1 volumio[1171]: info: Starting Shairport Sync Jan 11 13:28:46 volumio1 sudo[1522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jan 11 13:28:46 volumio1 sudo[1522]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:46 volumio1 sudo[1524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 13:28:46 volumio1 sudo[1524]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:46 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:46 volumio1 systemd[1]: Starting lircd(8) initialization helper tool... Jan 11 13:28:46 volumio1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 11 13:28:46 volumio1 systemd[1]: shairport-sync.service: Succeeded. Jan 11 13:28:46 volumio1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 11 13:28:46 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 11 13:28:46 volumio1 sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-ir gpio_pin=26 gpio_pull=up Jan 11 13:28:46 volumio1 sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:46 volumio1 sudo[1524]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:46 volumio1 kernel: Registered IR keymap rc-rc6-mce Jan 11 13:28:47 volumio1 kernel: IR RC6 protocol handler initialized Jan 11 13:28:47 volumio1 sudo[1531]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:47 volumio1 kernel: rc rc0: gpio_ir_recv as /devices/platform/ir-receiver@1a/rc/rc0 Jan 11 13:28:47 volumio1 kernel: rc rc0: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter Jan 11 13:28:47 volumio1 kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@1a/rc/rc0/input0 Jan 11 13:28:47 volumio1 volumio[1171]: info: Shairport-Sync Started Jan 11 13:28:47 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:47 volumio1 volumio[1171]: info: ir_controller: Overlay gpio-ir gpio_pin=26 gpio_pull=up loaded. Jan 11 13:28:47 volumio1 sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jan 11 13:28:47 volumio1 sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:47 volumio1 systemd-udevd[1544]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 11 13:28:47 volumio1 systemd-logind[581]: Watching system buttons on /dev/input/event0 (gpio_ir_recv) Jan 11 13:28:47 volumio1 lircd-setup[1529]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Jan 11 13:28:47 volumio1 lircd-setup[1529]: parser = configparser.SafeConfigParser() Jan 11 13:28:47 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:47 volumio1 systemd[1]: lircd-setup.service: Succeeded. Jan 11 13:28:47 volumio1 systemd[1]: Started lircd(8) initialization helper tool. Jan 11 13:28:47 volumio1 systemd[1]: Starting Flexible IR remote input/output application support... Jan 11 13:28:47 volumio1 lircd-0.10.1[1590]: Info: lircd: Opening log, level: Info Jan 11 13:28:47 volumio1 lircd-0.10.1[1590]: Notice: Version: lircd 0.10.1 Jan 11 13:28:47 volumio1 lircd-0.10.1[1590]: Notice: System info: Linux volumio1 6.1.69-v7+ #1710 SMP Thu Dec 21 13:14:13 GMT 2023 armv7l GNU/Linux Jan 11 13:28:48 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Info: Initial device: /dev/lirc0 Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Info: Initial device: /dev/lirc0 Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Info: [lirc] protocol is enabled Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: driver: default Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: output: /var/run/lirc/lircd Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: nodaemon: 1 Jan 11 13:28:48 volumio1 lircd[1590]: lircd-0.10.1[1590]: Info: lircd: Opening log, level: Info Jan 11 13:28:48 volumio1 lircd[1590]: lircd-0.10.1[1590]: Notice: Using systemd fd Jan 11 13:28:48 volumio1 lircd[1590]: lircd-0.10.1[1590]: Warning: Running as root Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jan 11 13:28:48 volumio1 lircd[1590]: lircd-0.10.1[1590]: Info: Using remote: lircd.conf. Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: logfile: syslog Jan 11 13:28:48 volumio1 systemd[1]: Started Flexible IR remote input/output application support. Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: immediate-init: 0 Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: permission: 666 Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: driver-options: Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: listen: 0 Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: connect: (null) Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: userelease: 0 Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: effective_user: (null) Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: release_suffix: _EVUP Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: allow_simulate: 0 Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: repeat_max: 600 Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: configfile: /etc/lirc/lircd.conf Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Options: dynamic_codes: (null) Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Current driver: default Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Driver API version: 3 Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Driver version: 0.10.0 Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Info: lircd: Opening log, level: Info Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: Using systemd fd Jan 11 13:28:48 volumio1 lircd[1590]: lircd-0.10.1[1590]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Warning: Running as root Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Info: Using remote: lircd.conf. Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jan 11 13:28:48 volumio1 sudo[1522]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:48 volumio1 sudo[1563]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:48 volumio1 volumio[1171]: info: ir_controller: systemctl restart lircd.service succeeded. Jan 11 13:28:48 volumio1 volumio[1171]: info: ir_controller: systemctl restart lircd.service succeeded. Jan 11 13:28:48 volumio1 sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jan 11 13:28:48 volumio1 sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:48 volumio1 sudo[1600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jan 11 13:28:48 volumio1 sudo[1600]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:48 volumio1 systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Jan 11 13:28:48 volumio1 lircd[1590]: lircd-0.10.1[1590]: Notice: accepted new client on /var/run/lirc/lircd Jan 11 13:28:48 volumio1 lircd[1590]: lircd-0.10.1[1590]: Info: [lirc] protocol is enabled Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: accepted new client on /var/run/lirc/lircd Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Info: [lirc] protocol is enabled Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Info: removed client Jan 11 13:28:48 volumio1 lircd[1590]: lircd-0.10.1[1590]: Info: removed client Jan 11 13:28:48 volumio1 sudo[1598]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:48 volumio1 systemd[1]: Stopping Handle events from IR remotes decoded by lircd(8)... Jan 11 13:28:48 volumio1 systemd[1]: irexec.service: Main process exited, code=killed, status=15/TERM Jan 11 13:28:48 volumio1 systemd[1]: irexec.service: Succeeded. Jan 11 13:28:48 volumio1 systemd[1]: Stopped Handle events from IR remotes decoded by lircd(8). Jan 11 13:28:48 volumio1 volumio[1171]: info: ir_controller: systemctl restart irexec.service succeeded. Jan 11 13:28:48 volumio1 systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Jan 11 13:28:48 volumio1 sudo[1600]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:48 volumio1 lircd[1590]: lircd-0.10.1[1590]: Notice: accepted new client on /var/run/lirc/lircd Jan 11 13:28:48 volumio1 lircd[1590]: lircd-0.10.1[1590]: Info: [lirc] protocol is enabled Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Notice: accepted new client on /var/run/lirc/lircd Jan 11 13:28:48 volumio1 lircd-0.10.1[1590]: Info: [lirc] protocol is enabled Jan 11 13:28:48 volumio1 volumio[1171]: info: ir_controller: systemctl restart irexec.service succeeded. Jan 11 13:28:48 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 13:28:50 volumio1 mpd[1513]: Jan 11 13:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 13:28:51 volumio1 systemd[1]: Started Music Player Daemon. Jan 11 13:28:51 volumio1 sudo[1398]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:51 volumio1 sudo[1498]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:51 volumio1 sudo[1412]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:51 volumio1 volumio[1171]: Upnp client error: Error: This socket has been ended by the other party Jan 11 13:28:51 volumio1 volumio[1171]: Upnp client error: Error: This socket has been ended by the other party Jan 11 13:28:51 volumio1 volumio[1171]: info: Completed starting Core Plugins Jan 11 13:28:51 volumio1 volumio[1171]: info: ------------------------------------------- Jan 11 13:28:51 volumio1 volumio[1171]: info: ----- MyVolumio plugins startup ---- Jan 11 13:28:51 volumio1 volumio[1171]: info: ------------------------------------------- Jan 11 13:28:51 volumio1 volumio[1171]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 11 13:28:51 volumio1 systemd[1]: Started MPD OLED Plugin. Jan 11 13:28:51 volumio1 sudo[1439]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:51 volumio1 volumio[1171]: info: MPD running with PID1513 Jan 11 13:28:51 volumio1 volumio[1171]: ,establishing connection Jan 11 13:28:51 volumio1 volumio[1171]: info: [MPD_OLED Plugin] Started mpd_oled_plugin service Jan 11 13:28:51 volumio1 volumio[1171]: error: MPD error: The expression evaluated to a falsy value: Jan 11 13:28:51 volumio1 volumio[1171]: assert.ok(self.idling) Jan 11 13:28:51 volumio1 volumio[1171]: error: The expression evaluated to a falsy value: Jan 11 13:28:51 volumio1 volumio[1171]: assert.ok(self.idling) Jan 11 13:28:51 volumio1 volumio[1171]: error: MPD error: The expression evaluated to a falsy value: Jan 11 13:28:51 volumio1 volumio[1171]: assert.ok(self.idling) Jan 11 13:28:51 volumio1 volumio[1171]: error: The expression evaluated to a falsy value: Jan 11 13:28:51 volumio1 volumio[1171]: assert.ok(self.idling) Jan 11 13:28:51 volumio1 volumio[1171]: error: MPD error: The expression evaluated to a falsy value: Jan 11 13:28:51 volumio1 volumio[1171]: assert.ok(self.idling) Jan 11 13:28:51 volumio1 volumio[1171]: error: The expression evaluated to a falsy value: Jan 11 13:28:51 volumio1 volumio[1171]: assert.ok(self.idling) Jan 11 13:28:51 volumio1 volumio[1171]: error: updateQueue error: null Jan 11 13:28:51 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:51 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:51 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:51 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:51 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:51 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:52 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:52 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:52 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:52 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:52 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:52 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:52 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 13:28:53 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:53 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:53 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:53 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:53 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:53 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:53 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:53 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:54 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:54 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:54 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:54 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:54 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:54 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:54 volumio1 volumio[1459]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 11 13:28:54 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:54 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:55 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:55 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:55 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:55 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:55 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:55 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:55 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:55 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:56 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:56 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:56 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:56 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:56 volumio1 volumio[1171]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 11 13:28:57 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 13:28:57 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:57 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:58 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:58 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:58 volumio1 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jan 11 13:28:58 volumio1 ntpd[721]: Soliciting pool server 137.110.222.27 Jan 11 13:28:58 volumio1 ntpd[721]: Soliciting pool server 192.210.243.178 Jan 11 13:28:58 volumio1 sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/volumio Jan 11 13:28:58 volumio1 sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:28:58 volumio1 volumio[1171]: info: CoreCommandRouter::volumioGetState Jan 11 13:28:58 volumio1 volumio[1171]: info: CorePlayQueue::getTrack 0 Jan 11 13:28:58 volumio1 sudo[1659]: pam_unix(sudo:session): session closed for user root Jan 11 13:28:58 volumio1 volumio[1171]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 13:28:58 volumio1 volumio[1171]: Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/volumio' Jan 11 13:28:58 volumio1 volumio[1171]: at Object.rmdirSync (fs.js:890:10) Jan 11 13:28:58 volumio1 volumio[1171]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Jan 11 13:28:58 volumio1 volumio[1171]: at ChildProcess.exithandler (child_process.js:299:7) Jan 11 13:28:58 volumio1 volumio[1171]: at ChildProcess.emit (events.js:315:20) Jan 11 13:28:58 volumio1 volumio[1171]: at maybeClose (internal/child_process.js:1048:16) Jan 11 13:28:58 volumio1 volumio[1171]: at Socket. (internal/child_process.js:439:11) Jan 11 13:28:58 volumio1 volumio[1171]: at Socket.emit (events.js:315:20) Jan 11 13:28:58 volumio1 volumio[1171]: at Pipe. (net.js:673:12) { Jan 11 13:28:58 volumio1 volumio[1171]: errno: -16, Jan 11 13:28:58 volumio1 volumio[1171]: syscall: 'rmdir', Jan 11 13:28:58 volumio1 volumio[1171]: code: 'EBUSY', Jan 11 13:28:58 volumio1 volumio[1171]: path: '/mnt/NAS/volumio' Jan 11 13:28:58 volumio1 volumio[1171]: } Jan 11 13:28:58 volumio1 volumio[1171]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 13:28:59 volumio1 ntpd[721]: Soliciting pool server 23.186.168.3 Jan 11 13:28:59 volumio1 ntpd[721]: Soliciting pool server 212.227.240.160 Jan 11 13:28:59 volumio1 ntpd[721]: Soliciting pool server 216.229.4.66 Jan 11 13:28:59 volumio1 sudo[1674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-11 13:27 Jan 11 13:28:59 volumio1 sudo[1674]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:00 volumio1 sudo[1674]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:00 volumio1 ntpd[721]: Soliciting pool server 208.67.75.242 Jan 11 13:29:00 volumio1 ntpd[721]: Soliciting pool server 104.167.215.195 Jan 11 13:29:00 volumio1 ntpd[721]: Soliciting pool server 23.168.24.210 Jan 11 13:29:00 volumio1 ntpd[721]: Soliciting pool server 198.199.14.19 Jan 11 13:29:01 volumio1 volumio-remote-updater[565]: [2025-01-11 13:29:01] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 11 13:29:01 volumio1 volumio-remote-updater[565]: [2025-01-11 13:29:01] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 11 13:29:01 volumio1 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 11 13:29:01 volumio1 systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 11 13:29:01 volumio1 ntpd[721]: Soliciting pool server 23.155.40.38 Jan 11 13:29:01 volumio1 ntpd[721]: Soliciting pool server 144.202.62.209 Jan 11 13:29:01 volumio1 ntpd[721]: Soliciting pool server 24.229.44.105 Jan 11 13:29:01 volumio1 ntpd[721]: Soliciting pool server 69.164.213.136 Jan 11 13:29:01 volumio1 systemd[1]: Started dynamicswap service. Jan 11 13:29:01 volumio1 systemd[1]: dynamicswap.service: Succeeded. Jan 11 13:29:01 volumio1 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 11 13:29:01 volumio1 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jan 11 13:29:01 volumio1 systemd[1]: Started dynamicswap service. Jan 11 13:29:01 volumio1 systemd[1]: Stopped Volumio Backend Module. Jan 11 13:29:01 volumio1 systemd[1]: Started Volumio Backend Module. Jan 11 13:29:01 volumio1 systemd[1]: dynamicswap.service: Succeeded. Jan 11 13:29:02 volumio1 ntpd[721]: Soliciting pool server 23.142.248.8 Jan 11 13:29:02 volumio1 ntpd[721]: Soliciting pool server 67.217.240.178 Jan 11 13:29:02 volumio1 ntpd[721]: Soliciting pool server 216.240.36.24 Jan 11 13:29:02 volumio1 ntpd[721]: Soliciting pool server 208.113.130.146 Jan 11 13:29:05 volumio1 volumio[1695]: info: ------------------------------------------- Jan 11 13:29:05 volumio1 volumio[1695]: info: ----- Volumio3 ---- Jan 11 13:29:05 volumio1 volumio[1695]: info: ------------------------------------------- Jan 11 13:29:05 volumio1 volumio[1695]: info: ----- System startup ---- Jan 11 13:29:05 volumio1 volumio[1695]: info: ------------------------------------------- Jan 11 13:29:06 volumio1 volumio-remote-updater[565]: [2025-01-11 13:29:06] [connect] Successful connection Jan 11 13:29:33 volumio1 ntpd[721]: receive: Unexpected origin timestamp 0xeb2d6122.40ceba9b does not match aorg 0000000000.00000000 from server@208.67.75.242 xmt 0xeb2d613d.5a2f2420 Jan 11 13:29:33 volumio1 volumio[1695]: info: MYVOLUMIO Environment detected Jan 11 13:29:34 volumio1 volumio[1695]: info: Plugin folders cleanup Jan 11 13:29:34 volumio1 volumio[1695]: info: Scanning into folder /volumio/app/plugins/ Jan 11 13:29:34 volumio1 volumio[1695]: info: Scanning category audio_interface Jan 11 13:29:34 volumio1 volumio[1695]: info: Scanning category miscellanea Jan 11 13:29:34 volumio1 volumio[1695]: info: Scanning category music_service Jan 11 13:29:34 volumio1 volumio[1695]: info: Scanning category plugins.json Jan 11 13:29:34 volumio1 volumio[1695]: info: Scanning category system_controller Jan 11 13:29:34 volumio1 volumio[1695]: info: Scanning category user_interface Jan 11 13:29:34 volumio1 volumio[1695]: info: Scanning into folder /data/plugins/ Jan 11 13:29:34 volumio1 volumio[1695]: info: Scanning category system_hardware Jan 11 13:29:34 volumio1 volumio[1695]: info: Plugin folders cleanup completed Jan 11 13:29:34 volumio1 volumio[1695]: info: ------------------------------------------- Jan 11 13:29:34 volumio1 volumio[1695]: info: ----- Core plugins startup ---- Jan 11 13:29:34 volumio1 volumio[1695]: info: ------------------------------------------- Jan 11 13:29:34 volumio1 volumio[1695]: info: Loading plugins from folder /volumio/app/plugins/ Jan 11 13:29:34 volumio1 volumio[1695]: info: Adding plugin upnp to MyMusic Plugins Jan 11 13:29:34 volumio1 volumio[1695]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 11 13:29:34 volumio1 volumio[1695]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 11 13:29:34 volumio1 volumio[1695]: info: Loading plugins from folder /data/plugins/ Jan 11 13:29:34 volumio1 volumio[1695]: info: Loading plugin "system"... Jan 11 13:29:34 volumio1 volumio[1695]: info: Loading plugin "appearance"... Jan 11 13:29:36 volumio1 volumio[1695]: info: Loading plugin "network"... Jan 11 13:29:36 volumio1 volumio[1695]: info: Refreshing Cached IP Addresses Jan 11 13:29:36 volumio1 sudo[1742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 13:29:36 volumio1 sudo[1742]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:36 volumio1 sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 13:29:36 volumio1 volumio[1695]: info: Loading plugin "services"... Jan 11 13:29:36 volumio1 sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:36 volumio1 sudo[1742]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:36 volumio1 volumio[1695]: info: Loading plugin "alsa_controller"... Jan 11 13:29:36 volumio1 sudo[1744]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:36 volumio1 sudo[1748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 11 13:29:36 volumio1 sudo[1748]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:36 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 13:29:36 volumio1 volumio[1695]: info: Loading plugin "wizard"... Jan 11 13:29:36 volumio1 volumio[1695]: info: Loading plugin "networkfs"... Jan 11 13:29:36 volumio1 volumio[1695]: info: Cannot mount NAS volumio at system boot, trial number 1 ,retrying in 5 seconds Jan 11 13:29:36 volumio1 volumio[1695]: info: Cannot mount NAS volumio at system boot, trial number 1 ,retrying in 5 seconds Jan 11 13:29:36 volumio1 volumio[1695]: info: Starting Udev Watcher for removable devices Jan 11 13:29:36 volumio1 volumio[1695]: info: Ignoring mount for partition: boot Jan 11 13:29:36 volumio1 volumio[1695]: info: Ignoring mount for partition: volumio Jan 11 13:29:36 volumio1 volumio[1695]: info: Ignoring mount for partition: volumio_data Jan 11 13:29:36 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 13:29:36 volumio1 volumio[1695]: info: Loading plugin "volumio_command_line_client"... Jan 11 13:29:36 volumio1 volumio[1695]: info: Loading plugin "upnp"... Jan 11 13:29:36 volumio1 volumio[1695]: info: [1736630976874] Starting Upmpd Daemon Jan 11 13:29:36 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 13:29:36 volumio1 volumio[1695]: info: Loading plugin "my_music"... Jan 11 13:29:36 volumio1 volumio[1695]: info: Loading plugin "mpd"... Jan 11 13:29:37 volumio1 volumio[1695]: info: Loading plugin "upnp_browser"... Jan 11 13:29:38 volumio1 volumio[1695]: info: Loading plugin "alarm-clock"... Jan 11 13:29:38 volumio1 volumio[1695]: info: Loading plugin "airplay_emulation"... Jan 11 13:29:38 volumio1 volumio[1695]: info: Starting Shairport Sync Jan 11 13:29:38 volumio1 volumio[1695]: info: Loading plugin "last_100"... Jan 11 13:29:38 volumio1 volumio[1695]: info: Loading plugin "webradio"... Jan 11 13:29:38 volumio1 volumio[1695]: info: Loading plugin "i2s_dacs"... Jan 11 13:29:38 volumio1 volumio[1695]: info: Loading plugin "volumiodiscovery"... Jan 11 13:29:38 volumio1 volumio[1695]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 11 13:29:38 volumio1 volumio[1695]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 13:29:38 volumio1 node[1695]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 11 13:29:38 volumio1 volumio[1695]: *** WARNING *** For more information see Jan 11 13:29:38 volumio1 volumio[1695]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 11 13:29:38 volumio1 volumio[1695]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 13:29:38 volumio1 volumio[1695]: *** WARNING *** For more information see Jan 11 13:29:38 volumio1 node[1695]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 13:29:38 volumio1 node[1695]: *** WARNING *** For more information see Jan 11 13:29:38 volumio1 node[1695]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 11 13:29:38 volumio1 node[1695]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 13:29:38 volumio1 node[1695]: *** WARNING *** For more information see Jan 11 13:29:38 volumio1 volumio[1695]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 11 13:29:38 volumio1 volumio[1695]: info: Discovery: Started advertising with name: Volumio1 Jan 11 13:29:38 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 13:29:38 volumio1 volumio[1695]: info: Loading plugin "outputs"... Jan 11 13:29:39 volumio1 volumio[1695]: info: Loading plugin "albumart"... Jan 11 13:29:39 volumio1 volumio[1695]: info: Plugin example_plugin is not enabled Jan 11 13:29:39 volumio1 volumio[1695]: info: Loading plugin "inputs"... Jan 11 13:29:39 volumio1 volumio[1695]: info: Loading plugin "updater_comm"... Jan 11 13:29:39 volumio1 volumio[1695]: info: Plugin mpdemulation is not enabled Jan 11 13:29:39 volumio1 volumio[1695]: info: Loading plugin "rest_api"... Jan 11 13:29:39 volumio1 volumio[1695]: info: Loading plugin "websocket"... Jan 11 13:29:39 volumio1 volumio[1695]: info: Starting Socket.io Server version 2.3.0 Jan 11 13:29:39 volumio1 volumio[1695]: info: Loading plugin "ir_controller"... Jan 11 13:29:39 volumio1 sudo[1748]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:39 volumio1 volumio[1695]: info: Applying required configuration parameters for plugin ir_controller Jan 11 13:29:40 volumio1 volumio[1695]: Forking 3 albumart workers Jan 11 13:29:40 volumio1 volumio[1695]: info: Loading plugin "mpd_oled"... Jan 11 13:29:41 volumio1 volumio[1695]: info: Applying required configuration parameters for plugin mpd_oled Jan 11 13:29:41 volumio1 volumio[1695]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Jan 11 13:29:41 volumio1 volumio[1695]: info: Loading i18n strings for locale en Jan 11 13:29:41 volumio1 volumio[1695]: Updating browse sources language Jan 11 13:29:41 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 13:29:41 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:29:41 volumio1 volumio[1695]: info: CoreCommandRouter::initPlayerControls Jan 11 13:29:41 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:41 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:41 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:41 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:41 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:41 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:41 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:41 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:41 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:29:41 volumio1 volumio[1695]: Express server listening on port 3000 Jan 11 13:29:41 volumio1 volumio[1695]: [Metrics] WebUI: 10s 603.74ms Jan 11 13:29:41 volumio1 volumio[1695]: info: CoreStateMachine::resetVolumioState Jan 11 13:29:41 volumio1 volumio[1695]: info: CoreStateMachine::getcurrentVolume Jan 11 13:29:41 volumio1 volumio[1695]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 13:29:42 volumio1 volumio[1695]: info: Cannot mount NAS volumio at system boot, trial number 2 ,retrying in 5 seconds Jan 11 13:29:42 volumio1 volumio[1695]: info: Cannot mount NAS volumio at system boot, trial number 2 ,retrying in 5 seconds Jan 11 13:29:42 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 1 Jan 11 13:29:42 volumio1 volumio[1459]: .....................................................................................++++ Jan 11 13:29:42 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 2 Jan 11 13:29:42 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 3 Jan 11 13:29:42 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 4 Jan 11 13:29:42 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 5 Jan 11 13:29:42 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 6 Jan 11 13:29:42 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 7 Jan 11 13:29:42 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 8 Jan 11 13:29:43 volumio1 volumio[1695]: Starting albumart workers Jan 11 13:29:43 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 9 Jan 11 13:29:43 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30:3000 from 192.168.86.47 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 11 13:29:43 volumio1 volumio[1695]: Starting albumart workers Jan 11 13:29:43 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetState Jan 11 13:29:43 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:43 volumio1 volumio[1695]: Starting albumart workers Jan 11 13:29:43 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 11 Jan 11 13:29:43 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 12 Jan 11 13:29:43 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 13 Jan 11 13:29:43 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 14 Jan 11 13:29:43 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 15 Jan 11 13:29:43 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 16 Jan 11 13:29:43 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 17 Jan 11 13:29:43 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:29:43 volumio1 volumio[1695]: info: Reloading queue from file Jan 11 13:29:44 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 18 Jan 11 13:29:44 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 19 Jan 11 13:29:44 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetState Jan 11 13:29:44 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:44 volumio1 volumio[1695]: info: VolumeController:: Volume=67 Mute =false Jan 11 13:29:44 volumio1 volumio[1695]: info: CoreStateMachine::pushState Jan 11 13:29:44 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:44 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 13:29:44 volumio1 volumio[1695]: info: CoreCommandRouter::volumioPushState Jan 11 13:29:44 volumio1 volumio[1695]: info: CoreStateMachine::updateTrackBlock Jan 11 13:29:44 volumio1 volumio[1695]: info: CorePlayQueue::getTrackBlock Jan 11 13:29:44 volumio1 volumio[1695]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 13:29:44 volumio1 volumio[1695]: info: CoreStateMachine::setRepeat null single undefined Jan 11 13:29:44 volumio1 volumio[1695]: info: CoreStateMachine::pushState Jan 11 13:29:44 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:44 volumio1 volumio[1695]: info: CoreCommandRouter::volumioPushState Jan 11 13:29:44 volumio1 volumio[1695]: info: CoreStateMachine::setRandom null Jan 11 13:29:44 volumio1 volumio[1695]: info: CoreStateMachine::pushState Jan 11 13:29:44 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:44 volumio1 volumio[1695]: info: CoreCommandRouter::volumioPushState Jan 11 13:29:44 volumio1 volumio[1695]: info: Setting Device type: Raspberry PI Jan 11 13:29:44 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetState Jan 11 13:29:44 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:44 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 20 Jan 11 13:29:44 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 21 Jan 11 13:29:44 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 22 Jan 11 13:29:44 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 23 Jan 11 13:29:44 volumio1 volumio[1695]: info: Completed loading Core Plugins Jan 11 13:29:44 volumio1 volumio[1695]: info: Preparing to generate the ALSA configuration file Jan 11 13:29:44 volumio1 volumio[1695]: info: Discovery: adding 9989748c-6bf0-42e7-991a-53e40bdb6510 Jan 11 13:29:44 volumio1 volumio[1695]: info: Discovery: Found device Volumio2 Jan 11 13:29:45 volumio1 volumio[1695]: info: Discovery: Connecting to remote: 192.168.86.230 Jan 11 13:29:45 volumio1 volumio[1695]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jan 11 13:29:45 volumio1 volumio[1695]: info: Reading ALSA contributions from plugins. Jan 11 13:29:45 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 24 Jan 11 13:29:45 volumio1 volumio[1695]: info: Discovery: this is already registered, 9989748c-6bf0-42e7-991a-53e40bdb6510 Jan 11 13:29:45 volumio1 volumio[1695]: info: Discovery: Found device Volumio2 Jan 11 13:29:45 volumio1 volumio[1695]: info: Discovery: Connecting to remote: 192.168.86.230 Jan 11 13:29:45 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 25 Jan 11 13:29:45 volumio1 volumio[1695]: info: VolumeController:: Volume=67 Mute =false Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreStateMachine::pushState Jan 11 13:29:45 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::volumioPushState Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetState Jan 11 13:29:45 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:45 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.49 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 26 Jan 11 13:29:45 volumio1 volumio[1695]: info: Discovery: adding 1de27509-ac78-4d5e-9b65-e1341d99e8bc Jan 11 13:29:45 volumio1 volumio[1695]: info: Discovery: Found device Volumio1 Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetState Jan 11 13:29:45 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:45 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 27 Jan 11 13:29:45 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 28 Jan 11 13:29:45 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 29 Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetState Jan 11 13:29:45 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:45 volumio1 volumio-remote-updater[565]: [2025-01-11 13:29:45] [connect] Successful connection Jan 11 13:29:45 volumio1 volumio[1695]: info: Discovery: this is already registered, 1de27509-ac78-4d5e-9b65-e1341d99e8bc Jan 11 13:29:45 volumio1 volumio[1695]: info: Discovery: Found device Volumio1 Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetState Jan 11 13:29:45 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:45 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 30 Jan 11 13:29:45 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 31 Jan 11 13:29:45 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 32 Jan 11 13:29:45 volumio1 volumio[1695]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:133.0) Gecko/20100101 Firefox/133.0 Engine version: 3 Transport: polling Total Clients: 33 Jan 11 13:29:45 volumio1 volumio[1695]: info: Received Get System Info Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 13:29:45 volumio1 volumio[1695]: info: Discovery: Getting this device information Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetState Jan 11 13:29:45 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetState Jan 11 13:29:45 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetState Jan 11 13:29:45 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetState Jan 11 13:29:45 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 11 13:29:45 volumio1 volumio[1695]: info: Received Get System Info Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 13:29:45 volumio1 volumio[1695]: info: Discovery: Getting this device information Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetState Jan 11 13:29:45 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetState Jan 11 13:29:45 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:45 volumio1 volumio[1695]: info: Listing playlists Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetQueue Jan 11 13:29:45 volumio1 volumio[1695]: info: CoreStateMachine::getQueue Jan 11 13:29:45 volumio1 volumio[1695]: info: CorePlayQueue::getQueue Jan 11 13:29:46 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:29:46 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetState Jan 11 13:29:46 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:46 volumio1 volumio[1695]: info: Discovery: Connected to remote: 192.168.86.230 Jan 11 13:29:46 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:29:46 volumio1 volumio-remote-updater[565]: [2025-01-11 13:29:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736630985 101 Jan 11 13:29:46 volumio1 volumio[1695]: 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: 34 Jan 11 13:29:46 volumio1 volumio[1695]: info: Asound.conf file unchanged, so no further update is needed Jan 11 13:29:46 volumio1 volumio[1695]: info: Output device has changed, restarting MPD Jan 11 13:29:46 volumio1 volumio[1695]: info: Output device has changed, restarting Shairport Sync Jan 11 13:29:46 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:46 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:29:46 volumio1 sudo[1854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 13:29:46 volumio1 sudo[1854]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:46 volumio1 sudo[1854]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:46 volumio1 sudo[1856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 13:29:46 volumio1 sudo[1856]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:46 volumio1 systemd[1]: Stopping MPD OLED Plugin... Jan 11 13:29:46 volumio1 systemd[1]: mpd_oled_plugin.service: Main process exited, code=killed, status=15/TERM Jan 11 13:29:46 volumio1 systemd[1]: mpd_oled_plugin.service: Succeeded. Jan 11 13:29:46 volumio1 volumio[1695]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 13:29:46 volumio1 systemd[1]: Stopped MPD OLED Plugin. Jan 11 13:29:46 volumio1 volumio[1695]: info: ___________ START PLUGINS ___________ Jan 11 13:29:46 volumio1 systemd[1]: Stopping Music Player Daemon... Jan 11 13:29:46 volumio1 volumio[1695]: info: ControllerMpd::onStart: Initializing MPD Jan 11 13:29:46 volumio1 volumio[1695]: info: Creating MPD Configuration file Jan 11 13:29:46 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:29:46 volumio1 volumio[1695]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 13:29:46 volumio1 volumio[1695]: info: [1736630986971] CoreMusicLibrary::Adding element Media Servers Jan 11 13:29:46 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 13:29:46 volumio1 sudo[1863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 13:29:46 volumio1 sudo[1863]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:46 volumio1 sudo[1863]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:47 volumio1 sudo[1868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 13:29:47 volumio1 sudo[1868]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:47 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:47 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:29:47 volumio1 systemd[1]: mpd.service: Succeeded. Jan 11 13:29:47 volumio1 systemd[1]: Stopped Music Player Daemon. Jan 11 13:29:47 volumio1 volumio[1695]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 13:29:47 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:47 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:29:47 volumio1 systemd[1]: Starting Music Player Daemon... Jan 11 13:29:47 volumio1 volumio[1695]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 13:29:47 volumio1 volumio[1695]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 13:29:47 volumio1 volumio[1695]: info: [1736630987299] CoreMusicLibrary::Adding element Last_100 Jan 11 13:29:47 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 13:29:47 volumio1 volumio[1695]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 13:29:47 volumio1 volumio[1695]: info: [1736630987311] CoreMusicLibrary::Adding element Webradio Jan 11 13:29:47 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 13:29:47 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 13:29:47 volumio1 volumio[1695]: info: Initializing BBC Radios Jan 11 13:29:47 volumio1 sudo[1874]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 13:29:47 volumio1 sudo[1874]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:47 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 13:29:47 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:29:47 volumio1 sudo[1874]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:47 volumio1 volumio[1695]: info: Loading i18n strings for locale en Jan 11 13:29:47 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 13:29:47 volumio1 volumio[1695]: /usr/bin/mkfifo: cannot create fifo '/tmp/mpdoledfifo': File exists Jan 11 13:29:47 volumio1 volumio[1695]: error: [MPD_OLED Plugin] Failed to create mpdoledfifo: Error: Command failed: /usr/bin/mkfifo -m 646 /tmp/mpdoledfifo Jan 11 13:29:47 volumio1 volumio[1695]: /usr/bin/mkfifo: cannot create fifo '/tmp/mpdoledfifo': File exists Jan 11 13:29:47 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 11 13:29:47 volumio1 volumio[1695]: info: Volumio Calling Home Jan 11 13:29:47 volumio1 sudo[1893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Jan 11 13:29:47 volumio1 sudo[1893]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:47 volumio1 sudo[1893]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:47 volumio1 sudo[1895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Jan 11 13:29:47 volumio1 sudo[1895]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:48 volumio1 volumio[1695]: info: Preparing to generate the ALSA configuration file Jan 11 13:29:48 volumio1 sudo[1907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Jan 11 13:29:48 volumio1 sudo[1907]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:48 volumio1 sudo[1907]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:48 volumio1 volumio[1695]: info: Cannot mount NAS volumio at system boot, trial number 3 ,retrying in 5 seconds Jan 11 13:29:48 volumio1 volumio[1695]: info: Cannot mount NAS volumio at system boot, trial number 3 ,retrying in 5 seconds Jan 11 13:29:48 volumio1 sudo[1910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 13:29:48 volumio1 volumio[1695]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jan 11 13:29:48 volumio1 volumio[1695]: info: Reading ALSA contributions from plugins. Jan 11 13:29:48 volumio1 sudo[1910]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:48 volumio1 sudo[1910]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:48 volumio1 sudo[1912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 13:29:48 volumio1 sudo[1912]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:48 volumio1 sudo[1912]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:49 volumio1 volumio[1695]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Jan 11 13:29:49 volumio1 volumio[1695]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Jan 11 13:29:49 volumio1 volumio[1695]: info: MPD Permissions set Jan 11 13:29:49 volumio1 volumio[1695]: info: MPD Permissions set Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetState Jan 11 13:29:49 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:49 volumio1 volumio[1695]: info: Volumio called home Jan 11 13:29:49 volumio1 volumio[1695]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Jan 11 13:29:49 volumio1 volumio[1695]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 35 Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:29:49 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:29:50 volumio1 volumio[1695]: info: Starting Shairport Sync Jan 11 13:29:50 volumio1 volumio[1695]: info: Starting Shairport Sync Jan 11 13:29:50 volumio1 volumio[1695]: info: Starting Shairport Sync Jan 11 13:29:50 volumio1 sudo[1930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 13:29:50 volumio1 sudo[1930]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:50 volumio1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 11 13:29:50 volumio1 systemd[1]: shairport-sync.service: Succeeded. Jan 11 13:29:50 volumio1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 11 13:29:50 volumio1 sudo[1935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 13:29:50 volumio1 sudo[1932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 13:29:50 volumio1 sudo[1935]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:50 volumio1 sudo[1932]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:50 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 11 13:29:50 volumio1 volumio[1695]: info: CoreCommandRouter::volumioGetState Jan 11 13:29:50 volumio1 volumio[1695]: info: CorePlayQueue::getTrack 0 Jan 11 13:29:50 volumio1 sudo[1930]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:50 volumio1 volumio[1695]: info: ir_controller: LIRC correctly updated. Jan 11 13:29:50 volumio1 volumio[1695]: info: ir_controller: HAT already loaded /proc/device-tree/ir_receiver! Jan 11 13:29:50 volumio1 sudo[1937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 11 13:29:50 volumio1 sudo[1937]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:50 volumio1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 11 13:29:50 volumio1 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 11 13:29:50 volumio1 systemd[1]: shairport-sync.service: Succeeded. Jan 11 13:29:50 volumio1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 11 13:29:50 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jan 11 13:29:50 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 11 13:29:50 volumio1 sudo[1935]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:50 volumio1 volumio[1695]: info: Asound.conf file unchanged, so no further update is needed Jan 11 13:29:50 volumio1 volumio[1695]: info: Output device has changed, restarting MPD Jan 11 13:29:50 volumio1 sudo[1932]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:50 volumio1 sudo[1945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/volumio Jan 11 13:29:50 volumio1 sudo[1945]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:50 volumio1 volumio[1695]: info: Output device has changed, restarting Shairport Sync Jan 11 13:29:50 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:50 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:29:50 volumio1 sudo[1937]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:50 volumio1 sudo[1948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 13:29:50 volumio1 sudo[1948]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:50 volumio1 systemd[1]: mnt-NAS-volumio.mount: Succeeded. Jan 11 13:29:50 volumio1 sudo[1948]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:50 volumio1 sudo[1945]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:50 volumio1 sudo[1951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 13:29:50 volumio1 sudo[1951]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:50 volumio1 mpd[1889]: Jan 11 13:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 13:29:51 volumio1 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 11 13:29:51 volumio1 systemd[1]: mpd.service: Succeeded. Jan 11 13:29:51 volumio1 systemd[1]: Stopped Music Player Daemon. Jan 11 13:29:51 volumio1 volumio[1695]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 13:29:51 volumio1 systemd[1]: Starting Music Player Daemon... Jan 11 13:29:51 volumio1 volumio[1695]: info: Shairport-Sync Started Jan 11 13:29:51 volumio1 volumio[1695]: Error adding Membership: Error: addMembership EINVAL Jan 11 13:29:51 volumio1 volumio[1695]: info: MPD Permissions set Jan 11 13:29:51 volumio1 volumio[1695]: info: Share volumio successfully unmounted Jan 11 13:29:51 volumio1 volumio[1695]: info: Upmpdcli Daemon Started Jan 11 13:29:51 volumio1 volumio[1695]: info: Shairport-Sync Started Jan 11 13:29:51 volumio1 volumio[1695]: info: Shairport-Sync Started Jan 11 13:29:51 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:29:51 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:29:51 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:29:51 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:51 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:51 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:51 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:51 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:51 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:51 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:51 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:51 volumio1 sudo[1962]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 13:29:51 volumio1 sudo[1962]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:51 volumio1 sudo[1962]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:51 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:29:51 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:29:51 volumio1 sudo[1970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jan 11 13:29:51 volumio1 sudo[1970]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:51 volumio1 volumio[1695]: info: Starting Shairport Sync Jan 11 13:29:51 volumio1 lircd[1590]: lircd-0.10.1[1590]: Notice: caught signal Jan 11 13:29:51 volumio1 lircd-0.10.1[1590]: Notice: caught signal Jan 11 13:29:51 volumio1 systemd[1]: Stopping Flexible IR remote input/output application support... Jan 11 13:29:51 volumio1 systemd[1]: lircd.service: Main process exited, code=killed, status=15/TERM Jan 11 13:29:51 volumio1 systemd[1]: lircd.service: Succeeded. Jan 11 13:29:51 volumio1 systemd[1]: Stopped Flexible IR remote input/output application support. Jan 11 13:29:51 volumio1 systemd[1]: irexec.service: Succeeded. Jan 11 13:29:51 volumio1 sudo[1973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 13:29:51 volumio1 sudo[1973]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:51 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:29:51 volumio1 systemd[1]: Starting lircd(8) initialization helper tool... Jan 11 13:29:51 volumio1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 11 13:29:51 volumio1 systemd[1]: shairport-sync.service: Succeeded. Jan 11 13:29:51 volumio1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 11 13:29:52 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 11 13:29:52 volumio1 sudo[1973]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:52 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 13:29:52 volumio1 volumio[1695]: info: Shairport-Sync Started Jan 11 13:29:52 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 11 13:29:52 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:29:52 volumio1 lircd-setup[1974]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Jan 11 13:29:52 volumio1 lircd-setup[1974]: parser = configparser.SafeConfigParser() Jan 11 13:29:52 volumio1 systemd[1]: lircd-setup.service: Succeeded. Jan 11 13:29:52 volumio1 systemd[1]: Started lircd(8) initialization helper tool. Jan 11 13:29:52 volumio1 systemd[1]: Starting Flexible IR remote input/output application support... Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Info: lircd: Opening log, level: Info Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Version: lircd 0.10.1 Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: System info: Linux volumio1 6.1.69-v7+ #1710 SMP Thu Dec 21 13:14:13 GMT 2023 armv7l GNU/Linux Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Info: Initial device: /dev/lirc0 Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Info: [lirc] protocol is enabled Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Info: Initial device: /dev/lirc0 Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Info: [lirc] protocol is enabled Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: driver: default Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: output: /var/run/lirc/lircd Jan 11 13:29:52 volumio1 lircd[1985]: lircd-0.10.1[1985]: Info: lircd: Opening log, level: Info Jan 11 13:29:52 volumio1 lircd[1985]: lircd-0.10.1[1985]: Notice: Using systemd fd Jan 11 13:29:52 volumio1 lircd[1985]: lircd-0.10.1[1985]: Warning: Running as root Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: nodaemon: 1 Jan 11 13:29:52 volumio1 lircd[1985]: lircd-0.10.1[1985]: Info: Using remote: lircd.conf. Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jan 11 13:29:52 volumio1 systemd[1]: Started Flexible IR remote input/output application support. Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: logfile: syslog Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: immediate-init: 0 Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: permission: 666 Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: driver-options: Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: listen: 0 Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: connect: (null) Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: userelease: 0 Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: effective_user: (null) Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: release_suffix: _EVUP Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: allow_simulate: 0 Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: repeat_max: 600 Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: configfile: /etc/lirc/lircd.conf Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Options: dynamic_codes: (null) Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Current driver: default Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Driver API version: 3 Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Driver version: 0.10.0 Jan 11 13:29:52 volumio1 lircd[1985]: lircd-0.10.1[1985]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Info: lircd: Opening log, level: Info Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: Using systemd fd Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Warning: Running as root Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Info: Using remote: lircd.conf. Jan 11 13:29:52 volumio1 sudo[1970]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:52 volumio1 lircd-0.10.1[1985]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jan 11 13:29:52 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:29:52 volumio1 volumio[1695]: info: ir_controller: systemctl restart lircd.service succeeded. Jan 11 13:29:53 volumio1 sudo[1989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jan 11 13:29:53 volumio1 sudo[1989]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:53 volumio1 systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Jan 11 13:29:53 volumio1 lircd[1985]: lircd-0.10.1[1985]: Notice: accepted new client on /var/run/lirc/lircd Jan 11 13:29:53 volumio1 lircd-0.10.1[1985]: Notice: accepted new client on /var/run/lirc/lircd Jan 11 13:29:53 volumio1 lircd[1985]: lircd-0.10.1[1985]: Info: [lirc] protocol is enabled Jan 11 13:29:53 volumio1 lircd-0.10.1[1985]: Info: [lirc] protocol is enabled Jan 11 13:29:53 volumio1 sudo[1989]: pam_unix(sudo:session): session closed for user root Jan 11 13:29:53 volumio1 volumio[1695]: info: ir_controller: systemctl restart irexec.service succeeded. Jan 11 13:29:53 volumio1 volumio[1695]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 13:29:53 volumio1 volumio[1695]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 13:29:53 volumio1 volumio[1695]: TypeError: Cannot read property 'replace' of undefined Jan 11 13:29:53 volumio1 volumio[1695]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Jan 11 13:29:53 volumio1 volumio[1695]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Jan 11 13:29:53 volumio1 volumio[1695]: at listOnTimeout (internal/timers.js:554:17) Jan 11 13:29:53 volumio1 volumio[1695]: at processTimers (internal/timers.js:497:7) Jan 11 13:29:53 volumio1 volumio[1695]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 13:29:54 volumio1 sudo[2007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-11 13:28 Jan 11 13:29:54 volumio1 sudo[2007]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:29:54 volumio1 mpd[1965]: Jan 11 13:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"