Jan 11 15:36:00 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:00 volumio-home1 sudo[2549]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:00 volumio-home1 sudo[2549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:00 volumio-home1 sudo[2549]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:01 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:01 volumio-home1 sudo[2553]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:01 volumio-home1 sudo[2553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:01 volumio-home1 sudo[2553]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:02 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:02 volumio-home1 sudo[2557]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:02 volumio-home1 sudo[2557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:02 volumio-home1 sudo[2557]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:03 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:03 volumio-home1 sudo[2561]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:03 volumio-home1 sudo[2561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:03 volumio-home1 sudo[2561]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:04 volumio-home1 wpa_supplicant[2500]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Jan 11 15:36:04 volumio-home1 wpa_supplicant[2500]: wlan0: Trying to associate with d2:75:0c:02:e6:b4 (SSID='TP-Link_E6B8_5G' freq=5240 MHz) Jan 11 15:36:04 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:04 volumio-home1 sudo[2565]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:04 volumio-home1 sudo[2565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:04 volumio-home1 sudo[2565]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:05 volumio-home1 wpa_supplicant[2500]: wlan0: Associated with d2:75:0c:02:e6:b4 Jan 11 15:36:05 volumio-home1 wpa_supplicant[2500]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Jan 11 15:36:05 volumio-home1 wpa_supplicant[2500]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 11 15:36:05 volumio-home1 wpa_supplicant[2500]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jan 11 15:36:05 volumio-home1 wpa_supplicant[2500]: wlan0: WPA: Key negotiation completed with d2:75:0c:02:e6:b4 [PTK=CCMP GTK=CCMP] Jan 11 15:36:05 volumio-home1 wpa_supplicant[2500]: wlan0: CTRL-EVENT-CONNECTED - Connection to d2:75:0c:02:e6:b4 completed [id=0 id_str=] Jan 11 15:36:05 volumio-home1 dhcpcd[853]: wlan0: carrier acquired Jan 11 15:36:05 volumio-home1 dhcpcd[853]: wlan0: connected to Access Point: TP-Link_E6B8_5G Jan 11 15:36:05 volumio-home1 dhcpcd[853]: wlan0: IAID 32:ae:c5:5d Jan 11 15:36:05 volumio-home1 dhcpcd[853]: wlan0: adding address fe80::dea6:32ff:feae:c55d Jan 11 15:36:05 volumio-home1 dhcpcd[853]: ipv6_addaddr1: Permission denied Jan 11 15:36:05 volumio-home1 dhcpcd[853]: wlan0: soliciting an IPv6 router Jan 11 15:36:05 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:05 volumio-home1 sudo[2573]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:05 volumio-home1 sudo[2573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:05 volumio-home1 sudo[2573]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:06 volumio-home1 dhcpcd[853]: wlan0: soliciting a DHCP lease Jan 11 15:36:06 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:06 volumio-home1 sudo[2589]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:06 volumio-home1 sudo[2589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:06 volumio-home1 sudo[2589]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:07 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:07 volumio-home1 sudo[2593]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:07 volumio-home1 sudo[2593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:07 volumio-home1 sudo[2593]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:08 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:08 volumio-home1 sudo[2597]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:08 volumio-home1 sudo[2597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:08 volumio-home1 sudo[2597]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:09 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:09 volumio-home1 sudo[2601]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:09 volumio-home1 sudo[2601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:09 volumio-home1 sudo[2601]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:10 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:10 volumio-home1 sudo[2605]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:10 volumio-home1 sudo[2605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:10 volumio-home1 sudo[2605]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:11 volumio-home1 dhcpcd[853]: wlan0: offered 192.168.0.128 from 192.168.0.1 Jan 11 15:36:11 volumio-home1 dhcpcd[853]: wlan0: probing address 192.168.0.128/24 Jan 11 15:36:11 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:11 volumio-home1 sudo[2610]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:11 volumio-home1 sudo[2610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:11 volumio-home1 sudo[2610]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:12 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:12 volumio-home1 sudo[2614]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:12 volumio-home1 sudo[2614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:12 volumio-home1 sudo[2614]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:13 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:13 volumio-home1 sudo[2618]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:13 volumio-home1 sudo[2618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:13 volumio-home1 sudo[2618]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:14 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:14 volumio-home1 sudo[2622]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:14 volumio-home1 sudo[2622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:14 volumio-home1 sudo[2622]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:15 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:15 volumio-home1 sudo[2626]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:15 volumio-home1 sudo[2626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:15 volumio-home1 sudo[2626]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:16 volumio-home1 dhcpcd[853]: wlan0: leased 192.168.0.128 for 7200 seconds Jan 11 15:36:16 volumio-home1 avahi-daemon[752]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.128. Jan 11 15:36:16 volumio-home1 avahi-daemon[752]: New relevant interface wlan0.IPv4 for mDNS. Jan 11 15:36:16 volumio-home1 avahi-daemon[752]: Registering new address record for 192.168.0.128 on wlan0.IPv4. Jan 11 15:36:16 volumio-home1 dhcpcd[853]: wlan0: adding route to 192.168.0.0/24 Jan 11 15:36:16 volumio-home1 systemd[1]: welcome.service: Deactivated successfully. Jan 11 15:36:16 volumio-home1 systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 11 15:36:16 volumio-home1 systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 11 15:36:16 volumio-home1 systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 11 15:36:16 volumio-home1 welcome[2654]: Resolved ip:[1] 192.168.0.128 Jan 11 15:36:16 volumio-home1 dhcpcd[853]: wlan0: adding default route via 192.168.0.1 Jan 11 15:36:16 volumio-home1 systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 11 15:36:16 volumio-home1 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 11 15:36:16 volumio-home1 wireless.js[770]: WIRELESS.JS: trying... Jan 11 15:36:16 volumio-home1 sudo[2674]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 11 15:36:16 volumio-home1 sudo[2674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:16 volumio-home1 sudo[2674]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:16 volumio-home1 wireless.js[770]: WIRELESS.JS: Connected to: ----TP-Link_E6B8_5G---- Jan 11 15:36:16 volumio-home1 sudo[2677]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 15:36:16 volumio-home1 sudo[2677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 11 15:36:16 volumio-home1 sudo[2677]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:17 volumio-home1 wireless.js[770]: WIRELESS.JS: ... joined AP, wlan0 IPv4 is 192.168.0.128, ipV6 is undefined Jan 11 15:36:17 volumio-home1 wireless.js[770]: WIRELESS.JS: It's done! AP Jan 11 15:36:17 volumio-home1 wireless.js[770]: WIRELESS.JS: Restarting avahi-daemon... Jan 11 15:36:17 volumio-home1 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 11 15:36:17 volumio-home1 systemd[1]: shairport-sync.service: Deactivated successfully. Jan 11 15:36:17 volumio-home1 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 15:36:17 volumio-home1 systemd[1]: shairport-sync.service: Consumed 2.519s CPU time. Jan 11 15:36:17 volumio-home1 avahi-daemon[752]: Got SIGTERM, quitting. Jan 11 15:36:17 volumio-home1 avahi-daemon[752]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.128. Jan 11 15:36:17 volumio-home1 wireless.js[770]: WIRELESS.JS: Notified systemd about wireless ready Jan 11 15:36:17 volumio-home1 systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jan 11 15:36:17 volumio-home1 avahi-daemon[752]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jan 11 15:36:17 volumio-home1 volumio[1155]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jan 11 15:36:17 volumio-home1 volumio[1155]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jan 11 15:36:17 volumio-home1 volumio[1155]: error: Discovery: Advertisement error: Error: dns service error: unknown Jan 11 15:36:17 volumio-home1 volumio[1155]: error: Discovery: advertisement error: Error: dns service error: unknown Jan 11 15:36:17 volumio-home1 volumio[1155]: info: Discovery: Advertisement raised the following error Error: dns service error: unknown Jan 11 15:36:17 volumio-home1 systemd[1]: avahi-daemon.service: Deactivated successfully. Jan 11 15:36:17 volumio-home1 avahi-daemon[752]: avahi-daemon 0.8 exiting. Jan 11 15:36:17 volumio-home1 systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jan 11 15:36:17 volumio-home1 dbus-daemon[754]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.21' (uid=0 pid=1642 comm="/usr/sbin/smbd --foreground --no-process-group") Jan 11 15:36:17 volumio-home1 systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jan 11 15:36:17 volumio-home1 avahi-daemon[2683]: Process 752 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Jan 11 15:36:17 volumio-home1 avahi-daemon[2683]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Jan 11 15:36:17 volumio-home1 avahi-daemon[2683]: Successfully dropped root privileges. Jan 11 15:36:17 volumio-home1 avahi-daemon[2683]: avahi-daemon 0.8 starting up. Jan 11 15:36:17 volumio-home1 dbus-daemon[754]: [system] Successfully activated service 'org.freedesktop.Avahi' Jan 11 15:36:17 volumio-home1 systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jan 11 15:36:17 volumio-home1 avahi-daemon[2683]: Successfully called chroot(). Jan 11 15:36:17 volumio-home1 avahi-daemon[2683]: Successfully dropped remaining capabilities. Jan 11 15:36:17 volumio-home1 avahi-daemon[2683]: Loading service file /services/volumio.service. Jan 11 15:36:17 volumio-home1 avahi-daemon[2683]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.128. Jan 11 15:36:17 volumio-home1 avahi-daemon[2683]: New relevant interface wlan0.IPv4 for mDNS. Jan 11 15:36:17 volumio-home1 avahi-daemon[2683]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jan 11 15:36:17 volumio-home1 avahi-daemon[2683]: New relevant interface lo.IPv4 for mDNS. Jan 11 15:36:17 volumio-home1 avahi-daemon[2683]: Network interface enumeration completed. Jan 11 15:36:17 volumio-home1 avahi-daemon[2683]: Registering new address record for 192.168.0.128 on wlan0.IPv4. Jan 11 15:36:17 volumio-home1 avahi-daemon[2683]: Registering new address record for 127.0.0.1 on lo.IPv4. Jan 11 15:36:17 volumio-home1 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 15:36:18 volumio-home1 ntpd[1025]: IO: Listen normally on 4 wlan0 192.168.0.128:123 Jan 11 15:36:18 volumio-home1 ntpd[1025]: IO: new interface(s) found: waking up resolver Jan 11 15:36:18 volumio-home1 ntpd[1025]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 11 15:36:18 volumio-home1 avahi-daemon[2683]: Server startup complete. Host name is volumio-home1.local. Local service cookie is 4090068606. Jan 11 15:36:18 volumio-home1 kernel: hwmon hwmon1: Undervoltage detected! Jan 11 15:36:19 volumio-home1 avahi-daemon[2683]: Service "Volumio-home1" (/services/volumio.service) successfully established. Jan 11 15:36:22 volumio-home1 volumio[1155]: info: Discovery: Started advertising with name: Volumio-home1 Jan 11 15:36:22 volumio-home1 kernel: hwmon hwmon1: Voltage normalised Jan 11 15:36:23 volumio-home1 ntpd[1025]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jan 11 15:36:23 volumio-home1 ntpd[1025]: DNS: Pool skipping: 193.123.243.2 Jan 11 15:36:23 volumio-home1 ntpd[1025]: DNS: Pool skipping: 175.210.18.47 Jan 11 15:36:23 volumio-home1 ntpd[1025]: DNS: Pool skipping: 158.247.202.103 Jan 11 15:36:23 volumio-home1 ntpd[1025]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Jan 11 15:36:24 volumio-home1 ntpd[1025]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 11 15:36:24 volumio-home1 ntpd[1025]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jan 11 15:36:24 volumio-home1 ntpd[1025]: DNS: Pool skipping: 203.32.26.46 Jan 11 15:36:24 volumio-home1 ntpd[1025]: DNS: Pool skipping: 211.108.117.211 Jan 11 15:36:24 volumio-home1 ntpd[1025]: DNS: Pool skipping: 121.174.142.81 Jan 11 15:36:24 volumio-home1 ntpd[1025]: DNS: Pool skipping: 240b:400d:3:3300:aeda:71da:9779:d4f1 Jan 11 15:36:24 volumio-home1 ntpd[1025]: DNS: Pool skipping: 240b:400d:3:3300:aeda:71da:9779:d8f1 Jan 11 15:36:24 volumio-home1 ntpd[1025]: DNS: Pool taking: 2605:e440:44::de Jan 11 15:36:24 volumio-home1 ntpd[1025]: DNS: Pool skipping: 2401:c080:1c00:24a1:5400:5ff:fe04:720 Jan 11 15:36:24 volumio-home1 ntpd[1025]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Jan 11 15:36:25 volumio-home1 ntpd[1025]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 11 15:36:25 volumio-home1 ntpd[1025]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jan 11 15:36:25 volumio-home1 ntpd[1025]: DNS: Pool skipping: 194.0.5.123 Jan 11 15:36:25 volumio-home1 ntpd[1025]: DNS: Pool skipping: 121.134.215.104 Jan 11 15:36:25 volumio-home1 ntpd[1025]: DNS: Pool skipping: 3.39.176.65 Jan 11 15:36:25 volumio-home1 ntpd[1025]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jan 11 15:36:25 volumio-home1 volumio[1155]: info: Volumio Network Manager: Network status updated: 2 Jan 11 15:36:26 volumio-home1 ntpd[1025]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 11 15:36:26 volumio-home1 ntpd[1025]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jan 11 15:36:26 volumio-home1 ntpd[1025]: DNS: Pool taking: 221.151.118.78 Jan 11 15:36:26 volumio-home1 ntpd[1025]: DNS: Pool skipping: 27.102.137.217 Jan 11 15:36:26 volumio-home1 ntpd[1025]: DNS: Pool taking: 121.174.142.82 Jan 11 15:36:26 volumio-home1 ntpd[1025]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Jan 11 15:36:40 volumio-home1 dhcpcd[853]: eth0: carrier acquired Jan 11 15:36:40 volumio-home1 dhcpcd[853]: eth0: IAID 32:ae:c5:5c Jan 11 15:36:40 volumio-home1 kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jan 11 15:36:40 volumio-home1 dhcpcd[853]: eth0: soliciting an IPv6 router Jan 11 15:36:40 volumio-home1 ifplugd(eth0)[1013]: Link beat detected. Jan 11 15:36:41 volumio-home1 ifplugd(eth0)[1013]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 11 15:36:41 volumio-home1 wireless.js[770]: WIRELESS.JS: Wired network status changed to: ---connected--- Jan 11 15:36:41 volumio-home1 wireless.js[770]: WIRELESS.JS: Wireless.js initializing wireless flow Jan 11 15:36:41 volumio-home1 wireless.js[770]: WIRELESS.JS: Cleaning previous... Jan 11 15:36:41 volumio-home1 dhcpcd[853]: ps_ctl_dispatch: cannot handle another client Jan 11 15:36:41 volumio-home1 ifplugd(eth0)[1013]: client: sending commands to dhcpcd process Jan 11 15:36:41 volumio-home1 dhcpcd[853]: control_free: No such file or directory Jan 11 15:36:41 volumio-home1 sudo[2767]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jan 11 15:36:41 volumio-home1 sudo[2767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 11 15:36:41 volumio-home1 avahi-daemon[2683]: Withdrawing address record for 192.168.0.128 on wlan0. Jan 11 15:36:41 volumio-home1 avahi-daemon[2683]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.128. Jan 11 15:36:41 volumio-home1 dhcpcd[853]: wlan0: pid 2776 deleted IP address 192.168.0.128/24 Jan 11 15:36:41 volumio-home1 dhcpcd[853]: wlan0: deleting route to 192.168.0.0/24 Jan 11 15:36:41 volumio-home1 avahi-daemon[2683]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 11 15:36:41 volumio-home1 dhcpcd[853]: wlan0: deleting default route via 192.168.0.1 Jan 11 15:36:41 volumio-home1 sudo[2767]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:41 volumio-home1 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 11 15:36:41 volumio-home1 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jan 11 15:36:41 volumio-home1 systemd[1]: welcome.service: Deactivated successfully. Jan 11 15:36:41 volumio-home1 systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 11 15:36:41 volumio-home1 systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 11 15:36:41 volumio-home1 sudo[2790]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jan 11 15:36:41 volumio-home1 sudo[2790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 11 15:36:41 volumio-home1 wpa_supplicant[2500]: wlan0: CTRL-EVENT-DISCONNECTED bssid=d2:75:0c:02:e6:b4 reason=3 locally_generated=1 Jan 11 15:36:41 volumio-home1 systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 11 15:36:41 volumio-home1 dhcpcd[853]: wlan0: rebinding lease of 192.168.0.128 Jan 11 15:36:41 volumio-home1 ifplugd(eth0)[1013]: Program executed successfully. Jan 11 15:36:41 volumio-home1 dhcpcd[853]: wlan0: carrier lost Jan 11 15:36:41 volumio-home1 sudo[2790]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:41 volumio-home1 welcome[2803]: Resolved ip:[0] Jan 11 15:36:41 volumio-home1 wpa_supplicant[2500]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jan 11 15:36:41 volumio-home1 wpa_supplicant[2500]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=KR Jan 11 15:36:41 volumio-home1 systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 11 15:36:41 volumio-home1 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 11 15:36:41 volumio-home1 wireless.js[770]: WIRELESS.JS: Stopped aP Jan 11 15:36:41 volumio-home1 wpa_supplicant[2500]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jan 11 15:36:41 volumio-home1 wpa_supplicant[2500]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jan 11 15:36:41 volumio-home1 wpa_supplicant[2500]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Jan 11 15:36:41 volumio-home1 sudo[2841]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 11 15:36:41 volumio-home1 dhcpcd[853]: eth0: soliciting a DHCP lease Jan 11 15:36:41 volumio-home1 sudo[2841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:41 volumio-home1 sudo[2841]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:41 volumio-home1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 11 15:36:41 volumio-home1 sudo[2844]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 11 15:36:41 volumio-home1 sudo[2844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:41 volumio-home1 wpa_supplicant[2500]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Jan 11 15:36:41 volumio-home1 wpa_supplicant[2500]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jan 11 15:36:41 volumio-home1 sudo[2844]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:41 volumio-home1 sudo[2852]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 11 15:36:41 volumio-home1 sudo[2852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:41 volumio-home1 dhcpcd[853]: eth0: offered 192.168.0.127 from 192.168.0.1 Jan 11 15:36:41 volumio-home1 sudo[2852]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:41 volumio-home1 sudo[2854]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 11 15:36:41 volumio-home1 sudo[2854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:41 volumio-home1 dhcpcd[853]: eth0: probing address 192.168.0.127/24 Jan 11 15:36:41 volumio-home1 wpa_supplicant[2500]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jan 11 15:36:41 volumio-home1 wpa_supplicant[2500]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 11 15:36:42 volumio-home1 wpa_supplicant[2500]: wlan0: CTRL-EVENT-TERMINATING Jan 11 15:36:42 volumio-home1 sudo[2854]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:42 volumio-home1 wireless.js[770]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Jan 11 15:36:42 volumio-home1 wireless.js[770]: WIRELESS.JS: Notified systemd about wireless ready Jan 11 15:36:44 volumio-home1 ntpd[1025]: IO: Deleting interface #4 wlan0, 192.168.0.128#123, interface stats: received=15, sent=15, dropped=0, active_time=26 secs Jan 11 15:36:44 volumio-home1 ntpd[1025]: PROTO: 121.174.142.82 unlink local addr 192.168.0.128 -> Jan 11 15:36:44 volumio-home1 ntpd[1025]: PROTO: 221.151.118.78 unlink local addr 192.168.0.128 -> Jan 11 15:36:44 volumio-home1 ntpd[1025]: PROTO: 193.123.243.2 unlink local addr 192.168.0.128 -> Jan 11 15:36:44 volumio-home1 ntpd[1025]: PROTO: 175.210.18.47 unlink local addr 192.168.0.128 -> Jan 11 15:36:44 volumio-home1 ntpd[1025]: PROTO: 158.247.202.103 unlink local addr 192.168.0.128 -> Jan 11 15:36:44 volumio-home1 ntpd[1025]: PROTO: 203.32.26.46 unlink local addr 192.168.0.128 -> Jan 11 15:36:44 volumio-home1 ntpd[1025]: PROTO: 211.108.117.211 unlink local addr 192.168.0.128 -> Jan 11 15:36:44 volumio-home1 ntpd[1025]: PROTO: 27.102.137.217 unlink local addr 192.168.0.128 -> Jan 11 15:36:44 volumio-home1 ntpd[1025]: PROTO: 194.0.5.123 unlink local addr 192.168.0.128 -> Jan 11 15:36:44 volumio-home1 ntpd[1025]: PROTO: 121.174.142.81 unlink local addr 192.168.0.128 -> Jan 11 15:36:44 volumio-home1 ntpd[1025]: PROTO: 3.39.176.65 unlink local addr 192.168.0.128 -> Jan 11 15:36:44 volumio-home1 ntpd[1025]: PROTO: 121.134.215.104 unlink local addr 192.168.0.128 -> Jan 11 15:36:46 volumio-home1 volumio[1155]: info: Volumio Network Manager: Network status updated: 0 Jan 11 15:36:47 volumio-home1 dhcpcd[853]: eth0: leased 192.168.0.127 for 7200 seconds Jan 11 15:36:47 volumio-home1 avahi-daemon[2683]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.127. Jan 11 15:36:47 volumio-home1 avahi-daemon[2683]: New relevant interface eth0.IPv4 for mDNS. Jan 11 15:36:47 volumio-home1 avahi-daemon[2683]: Registering new address record for 192.168.0.127 on eth0.IPv4. Jan 11 15:36:47 volumio-home1 dhcpcd[853]: eth0: adding route to 192.168.0.0/24 Jan 11 15:36:47 volumio-home1 dhcpcd[853]: eth0: adding default route via 192.168.0.1 Jan 11 15:36:47 volumio-home1 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 15:36:47 volumio-home1 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 15:36:47 volumio-home1 systemd[1]: welcome.service: Deactivated successfully. Jan 11 15:36:47 volumio-home1 systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 11 15:36:47 volumio-home1 systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 11 15:36:47 volumio-home1 systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 11 15:36:47 volumio-home1 welcome[2890]: Resolved ip:[1] 192.168.0.127 Jan 11 15:36:47 volumio-home1 systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 11 15:36:47 volumio-home1 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 15:36:47 volumio-home1 volumio[1155]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.19 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Jan 11 15:36:47 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 15:36:47 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 15:36:47 volumio-home1 volumio[1155]: info: Discovery: Getting this device information Jan 11 15:36:47 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioGetState Jan 11 15:36:47 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:36:47 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 15:36:49 volumio-home1 ntpd[1025]: IO: Listen normally on 5 eth0 192.168.0.127:123 Jan 11 15:36:49 volumio-home1 ntpd[1025]: IO: new interface(s) found: waking up resolver Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 15:36:49 volumio-home1 volumio[1155]: info: Discovery: Getting this device information Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioGetState Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 11 15:36:49 volumio-home1 sudo[2904]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 11 15:36:49 volumio-home1 sudo[2904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:49 volumio-home1 sudo[2909]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 11 15:36:49 volumio-home1 sudo[2909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:49 volumio-home1 sudo[2909]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:49 volumio-home1 sudo[2916]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 11 15:36:49 volumio-home1 sudo[2916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:49 volumio-home1 sudo[2917]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 11 15:36:49 volumio-home1 sudo[2917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:49 volumio-home1 sudo[2916]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:49 volumio-home1 sudo[2917]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:49 volumio-home1 sudo[2926]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 15:36:49 volumio-home1 sudo[2904]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:49 volumio-home1 sudo[2926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:49 volumio-home1 sudo[2926]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:49 volumio-home1 sudo[2931]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 15:36:49 volumio-home1 sudo[2931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:49 volumio-home1 sudo[2931]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 15:36:49 volumio-home1 volumio[1155]: info: Discovery: Getting this device information Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioGetState Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 15:36:49 volumio-home1 volumio[1155]: info: Discovery: Getting this device information Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioGetState Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioGetState Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:36:49 volumio-home1 volumio[1155]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.19 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 15:36:49 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 15:36:50 volumio-home1 volumio[1155]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.19 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jan 11 15:36:50 volumio-home1 sudo[2936]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 15:36:50 volumio-home1 sudo[2936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:50 volumio-home1 sudo[2936]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:50 volumio-home1 sudo[2938]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 15:36:50 volumio-home1 sudo[2938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:50 volumio-home1 sudo[2938]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:51 volumio-home1 volumio[1155]: verbose: New Socket.io Connection to 192.168.0.127 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Jan 11 15:36:51 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 15:36:51 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 15:36:51 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioGetState Jan 11 15:36:51 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:36:51 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 11 15:36:51 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 15:36:51 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 15:36:51 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 15:36:51 volumio-home1 sudo[2944]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 15:36:51 volumio-home1 sudo[2944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:51 volumio-home1 sudo[2944]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:51 volumio-home1 sudo[2946]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 15:36:51 volumio-home1 sudo[2946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:36:51 volumio-home1 sudo[2946]: pam_unix(sudo:session): session closed for user root Jan 11 15:36:51 volumio-home1 volumio[1155]: verbose: New Socket.io Connection to 192.168.0.127 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Jan 11 15:36:51 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioGetState Jan 11 15:36:51 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:36:51 volumio-home1 volumio[1155]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.19 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jan 11 15:36:51 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 11 15:36:51 volumio-home1 volumio[1155]: info: Listing playlists Jan 11 15:36:51 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 11 15:36:51 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 15:36:51 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 15:36:51 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 15:36:53 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 15:36:53 volumio-home1 volumio[1155]: info: Received Get System Info Jan 11 15:36:53 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 15:36:53 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 15:36:53 volumio-home1 volumio[1155]: info: Discovery: Getting this device information Jan 11 15:36:53 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioGetState Jan 11 15:36:53 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:36:53 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 15:36:55 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 15:36:55 volumio-home1 volumio[1155]: info: Received Get System Info Jan 11 15:36:55 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 15:36:55 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 15:36:55 volumio-home1 volumio[1155]: info: Discovery: Getting this device information Jan 11 15:36:55 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioGetState Jan 11 15:36:55 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:36:55 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 15:36:55 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioPlay Jan 11 15:36:55 volumio-home1 volumio[1155]: info: CoreStateMachine::play index undefined Jan 11 15:36:55 volumio-home1 volumio[1155]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:36:55 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:36:55 volumio-home1 volumio[1155]: info: CoreStateMachine::startPlaybackTimer Jan 11 15:36:55 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:36:55 volumio-home1 volumio[1155]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22D-Zvjhz3bRs%22%2C%22playlistId%22%3A%22RDD-Zvjhz3bRs%22%2C%22params%22%3A%22OAHAAQGqAwbsp7jspoi6AwoI6_Tq_Z3kvKEHugMKCJenp9iVwN2ob7oDCwiPsLn-57DE9ZkBugMLCJiN8ferw-ukggG6AwgSBlJEQVRneboDCgi24PCyt4fml126AwsIysvar571lcLXAboDCwiOmqPyue-enoIBugMLCIyhj5Satdya_QG6AwsInouOz_aN4560AboDCgjkuZrG3-7xtFi6AxQSElBMOEY2QjA3NTNCMkNDQTEyOLoDCwi994iEi9qQ5pQBugMKCJWWhbjPmeOAJLoDCwjgpLHNq8rDtNoBugMLCJ38qILxjez8tQG6AwsIgOrcqaa-_Nb6AboDCgiz6dvNkpHhwAHyAwUN-yZGP7gFAQ%253D%253D%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22%ED%96%A5%EC%88%98%EB%A5%BC%20%EB%B6%88%EB%9F%AC%EC%9D%BC%EC%9C%BC%ED%82%A4%EB%8A%94%20%EB%B9%88%ED%8B%B0%EC%A7%80%20%EC%9E%AC%EC%A6%88%20%ED%94%8C%EB%A0%88%EC%9D%B4%EB%A6%AC%EC%8A%A4%ED%8A%B8%20%F0%9F%8E%B7%201930-1940%EB%85%84%EB%8C%80%20%EC%9E%AC%EC%A6%88%20%EB%B0%94%20%26%20%EB%9D%BC%EC%9A%B4%EC%A7%80%20%EC%9D%8C%EC%95%85%20%7C%20%ED%8E%B8%EC%95%88%ED%95%9C%20%EB%B6%84%EC%9C%84%EA%B8%B0%20%7C%20%EC%9E%91%EC%97%85%2C%20%EA%B3%B5%EB%B6%80%2C%20%ED%9C%B4%EC%8B%9D%EC%9A%A9%20%EB%B0%B0%EA%B2%BD%EC%9D%8C%EC%95%85%22%2C%22artist%22%3A%22Vintage%20Old%20Jazz%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FD-Zvjhz3bRs%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLDkG0LdGC1YRYCYkxWaCVkJ98c17w%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22D-Zvjhz3bRs%22%2C%22playlistId%22%3A%22RDD-Zvjhz3bRs%22%2C%22params%22%3A%22OAHAAQGqAwbsp7jspoi6AwoI6_Tq_Z3kvKEHugMKCJenp9iVwN2ob7oDCwiPsLn-57DE9ZkBugMLCJiN8ferw-ukggG6AwgSBlJEQVRneboDCgi24PCyt4fml126AwsIysvar571lcLXAboDCwiOmqPyue-enoIBugMLCIyhj5Satdya_QG6AwsInouOz_aN4560AboDCgjkuZrG3-7xtFi6AxQSElBMOEY2QjA3NTNCMkNDQTEyOLoDCwi994iEi9qQ5pQBugMKCJWWhbjPmeOAJLoDCwjgpLHNq8rDtNoBugMLCJ38qILxjez8tQG6AwsIgOrcqaa-_Nb6AboDCgiz6dvNkpHhwAHyAwUN-yZGP7gFAQ%253D%253D%22%7D%7D%7D Jan 11 15:37:01 volumio-home1 volumio[1155]: npm notice Jan 11 15:37:01 volumio-home1 volumio[1155]: npm notice New major version of npm available! 9.8.0 -> 11.7.0 Jan 11 15:37:01 volumio-home1 volumio[1155]: npm notice Changelog: Jan 11 15:37:01 volumio-home1 volumio[1155]: npm notice Run `npm install -g npm@11.7.0` to update! Jan 11 15:37:01 volumio-home1 volumio[1155]: npm notice Jan 11 15:37:01 volumio-home1 volumio[1155]: info: [youtube2] Innertube support service: Deno not installed or otherwise failed to start: Command failed: npx --no-install --yes deno --version Jan 11 15:37:01 volumio-home1 volumio[1155]: npm notice Jan 11 15:37:01 volumio-home1 volumio[1155]: npm notice New major version of npm available! 9.8.0 -> 11.7.0 Jan 11 15:37:01 volumio-home1 volumio[1155]: npm notice Changelog: Jan 11 15:37:01 volumio-home1 volumio[1155]: npm notice Run `npm install -g npm@11.7.0` to update! Jan 11 15:37:01 volumio-home1 volumio[1155]: npm notice Jan 11 15:37:01 volumio-home1 volumio[1155]: info: [youtube2] Innertube support service: Start service with Node Jan 11 15:37:01 volumio-home1 volumio[1155]: info: Volumio Network Manager: Network status updated: 1 Jan 11 15:37:01 volumio-home1 volumio[1155]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.19 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jan 11 15:37:04 volumio-home1 volumio[1155]: info: [youtube2] Innertube support service: result: {"status":"started","server":{"address":"127.0.0.1","port":32985}} Jan 11 15:37:04 volumio-home1 volumio[1155]: info: [youtube2] Innertube support service running at http://127.0.0.1:32985 Jan 11 15:37:04 volumio-home1 volumio[1155]: info: VolumeController::SetAlsaVolume65 Jan 11 15:37:04 volumio-home1 volumio[1155]: info: CoreStateMachine::pushState Jan 11 15:37:04 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:37:04 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:37:04 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioPushState Jan 11 15:37:04 volumio-home1 volumio[1155]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '2'. Jan 11 15:37:09 volumio-home1 volumio[1155]: info: [youtube2] Obtained session PO token using visitorData (expires in 43199 seconds) Jan 11 15:37:12 volumio-home1 volumio[1155]: info: [youtube2] Going to refresh session PO token in 43099 seconds Jan 11 15:37:12 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 15:37:12 volumio-home1 volumio[1155]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 11 15:37:12 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 11 15:37:12 volumio-home1 volumio[1155]: info: Received Get System Version Jan 11 15:37:12 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 15:37:12 volumio-home1 volumio[1155]: info: Received Get System Info Jan 11 15:37:12 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 15:37:12 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 15:37:12 volumio-home1 volumio[1155]: info: Discovery: Getting this device information Jan 11 15:37:12 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioGetState Jan 11 15:37:12 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:37:12 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 15:37:12 volumio-home1 volumio[1155]: info: [ytmusic] Obtained PO token for video #D-Zvjhz3bRs: MlWXsuXCmQEDL5eMmFxCqJupPBC0iomjF--adDgPn22hYjB0lFJs30U3WJNUGlLWJ7YDBllJIEEe0nYDuGtwLih7Hd8Wr9mMWFapw3-tP323aPnQLXtx Jan 11 15:37:13 volumio-home1 volumio[1155]: info: [ytmusic] Obtained PO token for video #D-Zvjhz3bRs: MlWFoezuAne-N4WfkXDZ3iaxLgO9phLVqveIZzEjBBsceiJnnX73qfgvSoBdNsmgmq4RFVBluzejymQQsUfrWJVjD8wfg0L65U4b03um344pxFSnZci1 Jan 11 15:37:13 volumio-home1 volumio[1155]: info: [youtube2] VideoModel.getInfo(D-Zvjhz3bRs): validating stream URL "https://rr4---sn-3u-bh2zd.googlevideo.com/videoplayback?expire=1768135033&ei=GUVjafTBD_Pnpt8P8uemoQ0&ip=220.87.152.65&id=o-AOYju3XDSHU4FTO4NLg2PBuBFCsqPNJ849F-RcLNzIkN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=3&met=1768113433%2C&mh=2k&mm=31%2C26&mn=sn-3u-bh2zd%2Csn-oguelnlz&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=4268750&bui=AW-iu_pveIaw5b-tBSGWAoM1howJ5xKB2mB9YpkOQw3kFXdSofvI7n_c-ZCzBcFnmOPOPUXIesklujn8&spc=q5xjPNRDSaMVwTWjmnP0xltX0oC_AHJPF2_q9vcwYxwQwsxL_lzCqg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=sfksLxeqhE5wJOu_vB0aSmER&rqh=1&gir=yes&clen=113071627&dur=6592.541&lmt=1748172556003806&mt=1768112933&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4432534&n=aOOtZz1_NyLM_Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALa7fBy8Drm5jt0tZNSbMwNaaF-zHOThJxV0B9hlhrCnAiEAzsCAPWpTKqHYUIlGTBMgsalJL703Nkcqjn8QjRk5yKU%3D&sig=AJfQdSswRgIhANMpiUDeGnkjqmMGgGl0puT6OAeLyHzuFl5hMyXKQhktAiEAn97mYTj8eiNMvV7MpzEQo8J1963E_KOdgMqoEw-KtyM%3D&pot=MlWFoezuAne-N4WfkXDZ3iaxLgO9phLVqveIZzEjBBsceiJnnX73qfgvSoBdNsmgmq4RFVBluzejymQQsUfrWJVjD8wfg0L65U4b03um344pxFSnZci1&cver=1.20250219.01.00"... Jan 11 15:37:13 volumio-home1 volumio[1155]: info: [youtube2] VideoModel.getInfo(D-Zvjhz3bRs): stream validated in 0.046s. Jan 11 15:37:13 volumio-home1 volumio[1155]: info: [youtube2] (AutoplayManager) Added volumioStateListener Jan 11 15:37:13 volumio-home1 volumio[1155]: info: [youtube2] (AutoplayManager) Enabled Jan 11 15:37:13 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 15:37:13 volumio-home1 volumio[1155]: info: sendMpdCommand stop took 3 milliseconds Jan 11 15:37:13 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Jan 11 15:37:13 volumio-home1 volumio[1155]: ---------------------------- MPD announces system playlist update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Ignoring MPD Status Update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: sendMpdCommand clear took 2 milliseconds Jan 11 15:37:13 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand addid "https://rr4---sn-3u-bh2zd.googlevideo.com/videoplayback?expire=1768135033&ei=GUVjafTBD_Pnpt8P8uemoQ0&ip=220.87.152.65&id=o-AOYju3XDSHU4FTO4NLg2PBuBFCsqPNJ849F-RcLNzIkN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=3&met=1768113433%2C&mh=2k&mm=31%2C26&mn=sn-3u-bh2zd%2Csn-oguelnlz&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=4268750&bui=AW-iu_pveIaw5b-tBSGWAoM1howJ5xKB2mB9YpkOQw3kFXdSofvI7n_c-ZCzBcFnmOPOPUXIesklujn8&spc=q5xjPNRDSaMVwTWjmnP0xltX0oC_AHJPF2_q9vcwYxwQwsxL_lzCqg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=sfksLxeqhE5wJOu_vB0aSmER&rqh=1&gir=yes&clen=113071627&dur=6592.541&lmt=1748172556003806&mt=1768112933&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4432534&n=aOOtZz1_NyLM_Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALa7fBy8Drm5jt0tZNSbMwNaaF-zHOThJxV0B9hlhrCnAiEAzsCAPWpTKqHYUIlGTBMgsalJL703Nkcqjn8QjRk5yKU%3D&sig=AJfQdSswRgIhANMpiUDeGnkjqmMGgGl0puT6OAeLyHzuFl5hMyXKQhktAiEAn97mYTj8eiNMvV7MpzEQo8J1963E_KOdgMqoEw-KtyM%3D&pot=MlWFoezuAne-N4WfkXDZ3iaxLgO9phLVqveIZzEjBBsceiJnnX73qfgvSoBdNsmgmq4RFVBluzejymQQsUfrWJVjD8wfg0L65U4b03um344pxFSnZci1&cver=1.20250219.01.00&t.YouTube" Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Jan 11 15:37:13 volumio-home1 volumio[1155]: ---------------------------- MPD announces system playlist update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Ignoring MPD Status Update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Jan 11 15:37:13 volumio-home1 volumio[1155]: ---------------------------- MPD announces system playlist update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Ignoring MPD Status Update Jan 11 15:37:13 volumio-home1 volumio[1155]: error: updateQueue error: null Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Jan 11 15:37:13 volumio-home1 volumio[1155]: ---------------------------- MPD announces system playlist update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Ignoring MPD Status Update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: ------------------------------ 7ms Jan 11 15:37:13 volumio-home1 volumio[1155]: info: sendMpdCommand addid "https://rr4---sn-3u-bh2zd.googlevideo.com/videoplayback?expire=1768135033&ei=GUVjafTBD_Pnpt8P8uemoQ0&ip=220.87.152.65&id=o-AOYju3XDSHU4FTO4NLg2PBuBFCsqPNJ849F-RcLNzIkN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=3&met=1768113433%2C&mh=2k&mm=31%2C26&mn=sn-3u-bh2zd%2Csn-oguelnlz&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=4268750&bui=AW-iu_pveIaw5b-tBSGWAoM1howJ5xKB2mB9YpkOQw3kFXdSofvI7n_c-ZCzBcFnmOPOPUXIesklujn8&spc=q5xjPNRDSaMVwTWjmnP0xltX0oC_AHJPF2_q9vcwYxwQwsxL_lzCqg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=sfksLxeqhE5wJOu_vB0aSmER&rqh=1&gir=yes&clen=113071627&dur=6592.541&lmt=1748172556003806&mt=1768112933&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4432534&n=aOOtZz1_NyLM_Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALa7fBy8Drm5jt0tZNSbMwNaaF-zHOThJxV0B9hlhrCnAiEAzsCAPWpTKqHYUIlGTBMgsalJL703Nkcqjn8QjRk5yKU%3D&sig=AJfQdSswRgIhANMpiUDeGnkjqmMGgGl0puT6OAeLyHzuFl5hMyXKQhktAiEAn97mYTj8eiNMvV7MpzEQo8J1963E_KOdgMqoEw-KtyM%3D&pot=MlWFoezuAne-N4WfkXDZ3iaxLgO9phLVqveIZzEjBBsceiJnnX73qfgvSoBdNsmgmq4RFVBluzejymQQsUfrWJVjD8wfg0L65U4b03um344pxFSnZci1&cver=1.20250219.01.00&t.YouTube" took 5 milliseconds Jan 11 15:37:13 volumio-home1 volumio[1155]: info: ------------------------------ 6ms Jan 11 15:37:13 volumio-home1 volumio[1155]: info: ------------------------------ 5ms Jan 11 15:37:13 volumio-home1 volumio[1155]: verbose: MPD COMMAND [object Object] Jan 11 15:37:13 volumio-home1 volumio[1155]: verbose: MPD COMMAND [object Object] Jan 11 15:37:13 volumio-home1 volumio[1155]: verbose: MPD COMMAND [object Object] Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Jan 11 15:37:13 volumio-home1 volumio[1155]: ---------------------------- MPD announces system playlist update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Ignoring MPD Status Update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Jan 11 15:37:13 volumio-home1 volumio[1155]: ---------------------------- MPD announces system playlist update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Ignoring MPD Status Update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Jan 11 15:37:13 volumio-home1 volumio[1155]: ---------------------------- MPD announces system playlist update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Ignoring MPD Status Update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: ------------------------------ 8ms Jan 11 15:37:13 volumio-home1 volumio[1155]: info: ------------------------------ 4ms Jan 11 15:37:13 volumio-home1 volumio[1155]: info: ------------------------------ 3ms Jan 11 15:37:13 volumio-home1 volumio[1155]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 15:37:13 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand play Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Jan 11 15:37:13 volumio-home1 volumio[1155]: ---------------------------- MPD announces system playlist update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Ignoring MPD Status Update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Jan 11 15:37:13 volumio-home1 volumio[1155]: ---------------------------- MPD announces system playlist update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: Ignoring MPD Status Update Jan 11 15:37:13 volumio-home1 volumio[1155]: info: ------------------------------ 5ms Jan 11 15:37:13 volumio-home1 volumio[1155]: info: sendMpdCommand play took 4 milliseconds Jan 11 15:37:13 volumio-home1 volumio[1155]: info: ------------------------------ 4ms Jan 11 15:37:13 volumio-home1 volumio[1155]: info: ------------------------------ 3ms Jan 11 15:37:14 volumio-home1 volumio[1155]: info: Jan 11 15:37:14 volumio-home1 volumio[1155]: ---------------------------- MPD announces state update: player Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ControllerMpd::getState Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:37:14 volumio-home1 volumio[1155]: info: Jan 11 15:37:14 volumio-home1 volumio[1155]: ---------------------------- MPD announces state update: player Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ControllerMpd::getState Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:37:14 volumio-home1 volumio[1155]: info: Jan 11 15:37:14 volumio-home1 volumio[1155]: ---------------------------- MPD announces state update: player Jan 11 15:37:14 volumio-home1 volumio[1155]: info: Jan 11 15:37:14 volumio-home1 volumio[1155]: ---------------------------- MPD announces state update: player Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ControllerMpd::getState Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 15:37:14 volumio-home1 volumio[1155]: info: sendMpdCommand status took 10 milliseconds Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ControllerMpd::getState Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::parseState Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:37:14 volumio-home1 volumio[1155]: info: Jan 11 15:37:14 volumio-home1 volumio[1155]: ---------------------------- MPD announces state update: player Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ControllerMpd::getState Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:37:14 volumio-home1 volumio[1155]: info: Jan 11 15:37:14 volumio-home1 volumio[1155]: ---------------------------- MPD announces state update: player Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ControllerMpd::getState Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 15:37:14 volumio-home1 volumio[1155]: info: sendMpdCommand status took 21 milliseconds Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 15:37:14 volumio-home1 volumio[1155]: info: sendMpdCommand status took 19 milliseconds Jan 11 15:37:14 volumio-home1 volumio[1155]: info: sendMpdCommand clearerror took 14 milliseconds Jan 11 15:37:14 volumio-home1 volumio[1155]: info: sendMpdCommand status took 13 milliseconds Jan 11 15:37:14 volumio-home1 volumio[1155]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 11 15:37:14 volumio-home1 volumio[1155]: info: sendMpdCommand status took 10 milliseconds Jan 11 15:37:14 volumio-home1 volumio[1155]: info: sendMpdCommand status took 8 milliseconds Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::parseState Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::parseState Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::parseState Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::parseState Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::parseState Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ControllerMpd::pushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreCommandRouter::servicePushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6593,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nostalgic Vintage Jazz Playlist 🎷 1930s - 1940s Jazz Bar & Lounge Music","artist":"Vintage Old Jazz","album":"YouTube","uri":"https://rr4---sn-3u-bh2zd.googlevideo.com/videoplayback?expire=1768135033&ei=GUVjafTBD_Pnpt8P8uemoQ0&ip=220.87.152.65&id=o-AOYju3XDSHU4FTO4NLg2PBuBFCsqPNJ849F-RcLNzIkN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=3&met=1768113433%2C&mh=2k&mm=31%2C26&mn=sn-3u-bh2zd%2Csn-oguelnlz&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=4268750&bui=AW-iu_pveIaw5b-tBSGWAoM1howJ5xKB2mB9YpkOQw3kFXdSofvI7n_c-ZCzBcFnmOPOPUXIesklujn8&spc=q5xjPNRDSaMVwTWjmnP0xltX0oC_AHJPF2_q9vcwYxwQwsxL_lzCqg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=sfksLxeqhE5wJOu_vB0aSmER&rqh=1&gir=yes&clen=113071627&dur=6592.541&lmt=1748172556003806&mt=1768112933&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4432534&n=aOOtZz1_NyLM_Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALa7fBy8Drm5jt0tZNSbMwNaaF-zHOThJxV0B9hlhrCnAiEAzsCAPWpTKqHYUIlGTBMgsalJL703Nkcqjn8QjRk5yKU%3D&sig=AJfQdSswRgIhANMpiUDeGnkjqmMGgGl0puT6OAeLyHzuFl5hMyXKQhktAiEAn97mYTj8eiNMvV7MpzEQo8J1963E_KOdgMqoEw-KtyM%3D&pot=MlWFoezuAne-N4WfkXDZ3iaxLgO9phLVqveIZzEjBBsceiJnnX73qfgvSoBdNsmgmq4RFVBluzejymQQsUfrWJVjD8wfg0L65U4b03um344pxFSnZci1&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: CURRENT POSITION 0 Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::syncState stateService pause Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::syncState currentStatus stop Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::pushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioPushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ------------------------------ 51ms Jan 11 15:37:14 volumio-home1 volumio[1155]: info: sendMpdCommand clearerror took 32 milliseconds Jan 11 15:37:14 volumio-home1 volumio[1155]: info: sendMpdCommand clearerror took 31 milliseconds Jan 11 15:37:14 volumio-home1 volumio[1155]: info: sendMpdCommand playlistinfo took 25 milliseconds Jan 11 15:37:14 volumio-home1 volumio[1155]: info: sendMpdCommand playlistinfo took 24 milliseconds Jan 11 15:37:14 volumio-home1 volumio[1155]: info: sendMpdCommand playlistinfo took 24 milliseconds Jan 11 15:37:14 volumio-home1 volumio[1155]: info: sendMpdCommand playlistinfo took 23 milliseconds Jan 11 15:37:14 volumio-home1 volumio[1155]: info: sendMpdCommand playlistinfo took 22 milliseconds Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ControllerMpd::pushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreCommandRouter::servicePushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6593,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nostalgic Vintage Jazz Playlist 🎷 1930s - 1940s Jazz Bar & Lounge Music","artist":"Vintage Old Jazz","album":"YouTube","uri":"https://rr4---sn-3u-bh2zd.googlevideo.com/videoplayback?expire=1768135033&ei=GUVjafTBD_Pnpt8P8uemoQ0&ip=220.87.152.65&id=o-AOYju3XDSHU4FTO4NLg2PBuBFCsqPNJ849F-RcLNzIkN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=3&met=1768113433%2C&mh=2k&mm=31%2C26&mn=sn-3u-bh2zd%2Csn-oguelnlz&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=4268750&bui=AW-iu_pveIaw5b-tBSGWAoM1howJ5xKB2mB9YpkOQw3kFXdSofvI7n_c-ZCzBcFnmOPOPUXIesklujn8&spc=q5xjPNRDSaMVwTWjmnP0xltX0oC_AHJPF2_q9vcwYxwQwsxL_lzCqg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=sfksLxeqhE5wJOu_vB0aSmER&rqh=1&gir=yes&clen=113071627&dur=6592.541&lmt=1748172556003806&mt=1768112933&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4432534&n=aOOtZz1_NyLM_Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALa7fBy8Drm5jt0tZNSbMwNaaF-zHOThJxV0B9hlhrCnAiEAzsCAPWpTKqHYUIlGTBMgsalJL703Nkcqjn8QjRk5yKU%3D&sig=AJfQdSswRgIhANMpiUDeGnkjqmMGgGl0puT6OAeLyHzuFl5hMyXKQhktAiEAn97mYTj8eiNMvV7MpzEQo8J1963E_KOdgMqoEw-KtyM%3D&pot=MlWFoezuAne-N4WfkXDZ3iaxLgO9phLVqveIZzEjBBsceiJnnX73qfgvSoBdNsmgmq4RFVBluzejymQQsUfrWJVjD8wfg0L65U4b03um344pxFSnZci1&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: CURRENT POSITION 0 Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::syncState stateService pause Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::syncState currentStatus stop Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::pushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioPushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ControllerMpd::pushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreCommandRouter::servicePushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6593,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nostalgic Vintage Jazz Playlist 🎷 1930s - 1940s Jazz Bar & Lounge Music","artist":"Vintage Old Jazz","album":"YouTube","uri":"https://rr4---sn-3u-bh2zd.googlevideo.com/videoplayback?expire=1768135033&ei=GUVjafTBD_Pnpt8P8uemoQ0&ip=220.87.152.65&id=o-AOYju3XDSHU4FTO4NLg2PBuBFCsqPNJ849F-RcLNzIkN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=3&met=1768113433%2C&mh=2k&mm=31%2C26&mn=sn-3u-bh2zd%2Csn-oguelnlz&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=4268750&bui=AW-iu_pveIaw5b-tBSGWAoM1howJ5xKB2mB9YpkOQw3kFXdSofvI7n_c-ZCzBcFnmOPOPUXIesklujn8&spc=q5xjPNRDSaMVwTWjmnP0xltX0oC_AHJPF2_q9vcwYxwQwsxL_lzCqg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=sfksLxeqhE5wJOu_vB0aSmER&rqh=1&gir=yes&clen=113071627&dur=6592.541&lmt=1748172556003806&mt=1768112933&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4432534&n=aOOtZz1_NyLM_Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALa7fBy8Drm5jt0tZNSbMwNaaF-zHOThJxV0B9hlhrCnAiEAzsCAPWpTKqHYUIlGTBMgsalJL703Nkcqjn8QjRk5yKU%3D&sig=AJfQdSswRgIhANMpiUDeGnkjqmMGgGl0puT6OAeLyHzuFl5hMyXKQhktAiEAn97mYTj8eiNMvV7MpzEQo8J1963E_KOdgMqoEw-KtyM%3D&pot=MlWFoezuAne-N4WfkXDZ3iaxLgO9phLVqveIZzEjBBsceiJnnX73qfgvSoBdNsmgmq4RFVBluzejymQQsUfrWJVjD8wfg0L65U4b03um344pxFSnZci1&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: CURRENT POSITION 0 Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::syncState stateService pause Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::syncState currentStatus stop Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::pushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioPushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ControllerMpd::pushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreCommandRouter::servicePushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6593,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nostalgic Vintage Jazz Playlist 🎷 1930s - 1940s Jazz Bar & Lounge Music","artist":"Vintage Old Jazz","album":"YouTube","uri":"https://rr4---sn-3u-bh2zd.googlevideo.com/videoplayback?expire=1768135033&ei=GUVjafTBD_Pnpt8P8uemoQ0&ip=220.87.152.65&id=o-AOYju3XDSHU4FTO4NLg2PBuBFCsqPNJ849F-RcLNzIkN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=3&met=1768113433%2C&mh=2k&mm=31%2C26&mn=sn-3u-bh2zd%2Csn-oguelnlz&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=4268750&bui=AW-iu_pveIaw5b-tBSGWAoM1howJ5xKB2mB9YpkOQw3kFXdSofvI7n_c-ZCzBcFnmOPOPUXIesklujn8&spc=q5xjPNRDSaMVwTWjmnP0xltX0oC_AHJPF2_q9vcwYxwQwsxL_lzCqg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=sfksLxeqhE5wJOu_vB0aSmER&rqh=1&gir=yes&clen=113071627&dur=6592.541&lmt=1748172556003806&mt=1768112933&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4432534&n=aOOtZz1_NyLM_Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALa7fBy8Drm5jt0tZNSbMwNaaF-zHOThJxV0B9hlhrCnAiEAzsCAPWpTKqHYUIlGTBMgsalJL703Nkcqjn8QjRk5yKU%3D&sig=AJfQdSswRgIhANMpiUDeGnkjqmMGgGl0puT6OAeLyHzuFl5hMyXKQhktAiEAn97mYTj8eiNMvV7MpzEQo8J1963E_KOdgMqoEw-KtyM%3D&pot=MlWFoezuAne-N4WfkXDZ3iaxLgO9phLVqveIZzEjBBsceiJnnX73qfgvSoBdNsmgmq4RFVBluzejymQQsUfrWJVjD8wfg0L65U4b03um344pxFSnZci1&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: CURRENT POSITION 0 Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::syncState stateService pause Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::syncState currentStatus stop Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::pushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioPushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ControllerMpd::pushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreCommandRouter::servicePushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6593,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nostalgic Vintage Jazz Playlist 🎷 1930s - 1940s Jazz Bar & Lounge Music","artist":"Vintage Old Jazz","album":"YouTube","uri":"https://rr4---sn-3u-bh2zd.googlevideo.com/videoplayback?expire=1768135033&ei=GUVjafTBD_Pnpt8P8uemoQ0&ip=220.87.152.65&id=o-AOYju3XDSHU4FTO4NLg2PBuBFCsqPNJ849F-RcLNzIkN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=3&met=1768113433%2C&mh=2k&mm=31%2C26&mn=sn-3u-bh2zd%2Csn-oguelnlz&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=4268750&bui=AW-iu_pveIaw5b-tBSGWAoM1howJ5xKB2mB9YpkOQw3kFXdSofvI7n_c-ZCzBcFnmOPOPUXIesklujn8&spc=q5xjPNRDSaMVwTWjmnP0xltX0oC_AHJPF2_q9vcwYxwQwsxL_lzCqg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=sfksLxeqhE5wJOu_vB0aSmER&rqh=1&gir=yes&clen=113071627&dur=6592.541&lmt=1748172556003806&mt=1768112933&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4432534&n=aOOtZz1_NyLM_Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALa7fBy8Drm5jt0tZNSbMwNaaF-zHOThJxV0B9hlhrCnAiEAzsCAPWpTKqHYUIlGTBMgsalJL703Nkcqjn8QjRk5yKU%3D&sig=AJfQdSswRgIhANMpiUDeGnkjqmMGgGl0puT6OAeLyHzuFl5hMyXKQhktAiEAn97mYTj8eiNMvV7MpzEQo8J1963E_KOdgMqoEw-KtyM%3D&pot=MlWFoezuAne-N4WfkXDZ3iaxLgO9phLVqveIZzEjBBsceiJnnX73qfgvSoBdNsmgmq4RFVBluzejymQQsUfrWJVjD8wfg0L65U4b03um344pxFSnZci1&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: CURRENT POSITION 0 Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::syncState stateService pause Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::syncState currentStatus stop Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::pushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioPushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ControllerMpd::pushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreCommandRouter::servicePushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CorePlayQueue::getTrack 0 Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":6593,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nostalgic Vintage Jazz Playlist 🎷 1930s - 1940s Jazz Bar & Lounge Music","artist":"Vintage Old Jazz","album":"YouTube","uri":"https://rr4---sn-3u-bh2zd.googlevideo.com/videoplayback?expire=1768135033&ei=GUVjafTBD_Pnpt8P8uemoQ0&ip=220.87.152.65&id=o-AOYju3XDSHU4FTO4NLg2PBuBFCsqPNJ849F-RcLNzIkN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=3&met=1768113433%2C&mh=2k&mm=31%2C26&mn=sn-3u-bh2zd%2Csn-oguelnlz&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=4268750&bui=AW-iu_pveIaw5b-tBSGWAoM1howJ5xKB2mB9YpkOQw3kFXdSofvI7n_c-ZCzBcFnmOPOPUXIesklujn8&spc=q5xjPNRDSaMVwTWjmnP0xltX0oC_AHJPF2_q9vcwYxwQwsxL_lzCqg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=sfksLxeqhE5wJOu_vB0aSmER&rqh=1&gir=yes&clen=113071627&dur=6592.541&lmt=1748172556003806&mt=1768112933&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4432534&n=aOOtZz1_NyLM_Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALa7fBy8Drm5jt0tZNSbMwNaaF-zHOThJxV0B9hlhrCnAiEAzsCAPWpTKqHYUIlGTBMgsalJL703Nkcqjn8QjRk5yKU%3D&sig=AJfQdSswRgIhANMpiUDeGnkjqmMGgGl0puT6OAeLyHzuFl5hMyXKQhktAiEAn97mYTj8eiNMvV7MpzEQo8J1963E_KOdgMqoEw-KtyM%3D&pot=MlWFoezuAne-N4WfkXDZ3iaxLgO9phLVqveIZzEjBBsceiJnnX73qfgvSoBdNsmgmq4RFVBluzejymQQsUfrWJVjD8wfg0L65U4b03um344pxFSnZci1&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jan 11 15:37:14 volumio-home1 volumio[1155]: verbose: CURRENT POSITION 0 Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::syncState stateService pause Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::syncState currentStatus stop Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreStateMachine::pushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioPushState Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ------------------------------ 110ms Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ------------------------------ 108ms Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ------------------------------ 108ms Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ------------------------------ 98ms Jan 11 15:37:14 volumio-home1 volumio[1155]: info: ------------------------------ 97ms Jan 11 15:37:20 volumio-home1 sudo[3073]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 15:37:20 volumio-home1 sudo[3073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:37:20 volumio-home1 sudo[3073]: pam_unix(sudo:session): session closed for user root Jan 11 15:37:20 volumio-home1 sudo[3075]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 15:37:20 volumio-home1 sudo[3075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:37:20 volumio-home1 sudo[3075]: pam_unix(sudo:session): session closed for user root Jan 11 15:37:20 volumio-home1 volumio[1155]: verbose: New Socket.io Connection to 192.168.0.127 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Jan 11 15:37:20 volumio-home1 volumio[1155]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 15:37:20 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 11 15:37:21 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 11 15:37:21 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 11 15:37:21 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jan 11 15:37:21 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioGetState Jan 11 15:37:21 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 11 15:37:21 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 15:37:21 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 15:37:21 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 15:37:21 volumio-home1 volumio[1155]: info: Received Get System Version Jan 11 15:37:21 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 15:37:21 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Jan 11 15:37:22 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 15:37:22 volumio-home1 volumio[1155]: info: Received Get System Info Jan 11 15:37:22 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 15:37:22 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 15:37:22 volumio-home1 volumio[1155]: info: Discovery: Getting this device information Jan 11 15:37:22 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioGetState Jan 11 15:37:22 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 15:37:23 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 15:37:23 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 15:37:23 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:37:23 volumio-home1 volumio[1155]: info: CoreStateMachine::getQueue Jan 11 15:37:23 volumio-home1 volumio[1155]: info: CorePlayQueue::getQueue Jan 11 15:37:23 volumio-home1 volumio[1155]: info: Listing playlists Jan 11 15:37:23 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 15:37:23 volumio-home1 volumio[1155]: info: Received Get System Info Jan 11 15:37:23 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 15:37:23 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 15:37:23 volumio-home1 volumio[1155]: info: Discovery: Getting this device information Jan 11 15:37:23 volumio-home1 volumio[1155]: info: CoreCommandRouter::volumioGetState Jan 11 15:37:23 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 15:37:27 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jan 11 15:37:28 volumio-home1 volumio[1155]: info: Preload queue cleared Jan 11 15:37:30 volumio-home1 volumio[1155]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jan 11 15:37:31 volumio-home1 volumio[1155]: info: Preload queue cleared Jan 11 15:37:31 volumio-home1 volumio[1155]: info: Preloading song: webmbc/0 Jan 11 15:37:31 volumio-home1 volumio[1155]: info: Preloading song: webmbc/1 Jan 11 15:37:31 volumio-home1 volumio[1155]: info: Preloading song: webmbc/2 Jan 11 15:37:31 volumio-home1 volumio[1155]: info: Exploding uri webmbc/0 in service personal_radio Jan 11 15:37:31 volumio-home1 volumio[1155]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 15:37:31 volumio-home1 volumio[1155]: TypeError: Cannot read properties of null (reading 'startsWith') Jan 11 15:37:31 volumio-home1 volumio[1155]: at /data/plugins/music_service/personal_radio/index.js:675:41 Jan 11 15:37:31 volumio-home1 volumio[1155]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jan 11 15:37:31 volumio-home1 volumio[1155]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 15:37:31 volumio-home1 sudo[3130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 15:36' Jan 11 15:37:31 volumio-home1 sudo[3130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"