-- Logs begin at Mon 2025-06-16 07:07:45 UTC, end at Mon 2025-06-16 07:13:50 UTC. -- Jun 16 07:12:01 volumio volumio-time-update[706]: volumio-time-update-util: Fetching time from Volumio... Jun 16 07:12:01 volumio volumio-time-update[706]: volumio-time-update-util: Date not found in response Jun 16 07:12:01 volumio volumio-time-update[706]: volumio-time-update-util: Retrying in 5 seconds... Jun 16 07:12:05 volumio ntpd[860]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 16 07:12:06 volumio volumio-time-update[706]: volumio-time-update-util: Fetching time from Volumio... Jun 16 07:12:06 volumio volumio-time-update[706]: volumio-time-update-util: Date not found in response Jun 16 07:12:06 volumio volumio-time-update[706]: volumio-time-update-util: Retrying in 5 seconds... Jun 16 07:12:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jun 16 07:12:10 volumio volumio[987]: info: Saving new wireless network Jun 16 07:12:10 volumio sudo[2887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Jun 16 07:12:10 volumio sudo[2887]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:10 volumio sudo[2887]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 16 07:12:10 volumio volumio[987]: info: Discovery: Restarting Advertising due to device name change Jun 16 07:12:10 volumio sudo[2890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 16 07:12:10 volumio sudo[2890]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:10 volumio systemd[1]: Starting Wireless Services... Jun 16 07:12:10 volumio wireless.js[2892]: Cleaning previous... Jun 16 07:12:10 volumio sudo[2906]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 16 07:12:10 volumio sudo[2906]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:10 volumio sudo[2906]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:10 volumio sudo[2908]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 16 07:12:10 volumio sudo[2908]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:10 volumio sudo[2908]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:10 volumio wireless.js[2892]: Stopped aP Jun 16 07:12:10 volumio sudo[2915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 16 07:12:10 volumio sudo[2915]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:11 volumio sudo[2915]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:11 volumio sudo[2918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 16 07:12:11 volumio sudo[2918]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:11 volumio sudo[2918]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:11 volumio sudo[2925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 16 07:12:11 volumio sudo[2925]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:11 volumio sudo[2925]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:11 volumio sudo[2927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 16 07:12:11 volumio sudo[2927]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:11 volumio ntpd[860]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 16 07:12:11 volumio ntpd[860]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 16 07:12:11 volumio volumio-time-update[706]: volumio-time-update-util: Fetching time from Volumio... Jun 16 07:12:11 volumio volumio-time-update[706]: volumio-time-update-util: Date not found in response Jun 16 07:12:11 volumio volumio-time-update[706]: volumio-time-update-util: Retrying in 5 seconds... Jun 16 07:12:12 volumio sudo[2927]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:12 volumio wireless.js[2892]: Start wireless flow Jun 16 07:12:12 volumio wireless.js[2892]: Stopped hotspot (if there).. Jun 16 07:12:12 volumio sudo[2937]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 16 07:12:12 volumio sudo[2937]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:12 volumio sudo[2937]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:12 volumio sudo[2939]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 16 07:12:12 volumio sudo[2939]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:12 volumio sudo[2939]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:12 volumio wireless.js[2892]: DHCP IP Jun 16 07:12:12 volumio wireless.js[2892]: Start ap Jun 16 07:12:12 volumio wpa_supplicant[2941]: Successfully initialized wpa_supplicant Jun 16 07:12:12 volumio sudo[2942]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 16 07:12:12 volumio sudo[2942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:12 volumio dhcpcd[2943]: sending commands to master dhcpcd process Jun 16 07:12:12 volumio dhcpcd[1527]: control command: /sbin/dhcpcd Jun 16 07:12:12 volumio sudo[2942]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:13 volumio wireless.js[2892]: trying... Jun 16 07:12:13 volumio sudo[2960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 16 07:12:13 volumio sudo[2960]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:13 volumio sudo[2960]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:14 volumio wpa_supplicant[2958]: wlan0: SME: Trying to authenticate with 18:e8:29:cd:5c:35 (SSID='Linksys' freq=2472 MHz) Jun 16 07:12:14 volumio kernel: wlan0: authenticate with 18:e8:29:cd:5c:35 Jun 16 07:12:14 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Jun 16 07:12:14 volumio kernel: wlan0: send auth to 18:e8:29:cd:5c:35 (try 1/3) Jun 16 07:12:14 volumio wireless.js[2892]: trying... Jun 16 07:12:14 volumio wpa_supplicant[2958]: wlan0: Trying to associate with 18:e8:29:cd:5c:35 (SSID='Linksys' freq=2472 MHz) Jun 16 07:12:14 volumio kernel: wlan0: authenticated Jun 16 07:12:14 volumio kernel: wlan0: associate with 18:e8:29:cd:5c:35 (try 1/3) Jun 16 07:12:14 volumio sudo[2963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 16 07:12:14 volumio sudo[2963]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:14 volumio sudo[2963]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:14 volumio wpa_supplicant[2958]: wlan0: Associated with 18:e8:29:cd:5c:35 Jun 16 07:12:14 volumio wpa_supplicant[2958]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 16 07:12:14 volumio wpa_supplicant[2958]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=VN Jun 16 07:12:14 volumio kernel: wlan0: RX AssocResp from 18:e8:29:cd:5c:35 (capab=0x1431 status=0 aid=1) Jun 16 07:12:14 volumio kernel: wlan0: associated Jun 16 07:12:14 volumio wpa_supplicant[2958]: wlan0: WPA: Key negotiation completed with 18:e8:29:cd:5c:35 [PTK=CCMP GTK=CCMP] Jun 16 07:12:14 volumio wpa_supplicant[2958]: wlan0: CTRL-EVENT-CONNECTED - Connection to 18:e8:29:cd:5c:35 completed [id=0 id_str=] Jun 16 07:12:14 volumio dhcpcd[1527]: wlan0: carrier acquired Jun 16 07:12:14 volumio dhcpcd[1527]: wlan0: confile file changed, expiring leases Jun 16 07:12:14 volumio dhcpcd[1527]: wlan0: IAID aa:d2:8e:62 Jun 16 07:12:14 volumio dhcpcd[1527]: wlan0: soliciting an IPv6 router Jun 16 07:12:14 volumio kernel: wlan0: Limiting TX power to 20 (20 - 0) dBm as advertised by 18:e8:29:cd:5c:35 Jun 16 07:12:15 volumio volumio[987]: info: Discovery: Started advertising with name: Volumio Jun 16 07:12:15 volumio wireless.js[2892]: trying... Jun 16 07:12:15 volumio sudo[2983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 16 07:12:15 volumio sudo[2983]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:15 volumio sudo[2983]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:15 volumio dhcpcd[1527]: wlan0: soliciting a DHCP lease Jun 16 07:12:15 volumio ntpd[860]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 16 07:12:16 volumio wireless.js[2892]: trying... Jun 16 07:12:16 volumio sudo[2986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 16 07:12:16 volumio sudo[2986]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:16 volumio sudo[2986]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:16 volumio dhcpcd[1527]: wlan0: offered 192.168.1.150 from 192.168.1.1 Jun 16 07:12:16 volumio dhcpcd[1527]: wlan0: probing address 192.168.1.150/24 Jun 16 07:12:16 volumio volumio-time-update[706]: volumio-time-update-util: Fetching time from Volumio... Jun 16 07:12:16 volumio volumio-time-update[706]: volumio-time-update-util: Date not found in response Jun 16 07:12:16 volumio volumio-time-update[706]: volumio-time-update-util: Retrying in 5 seconds... Jun 16 07:12:17 volumio wireless.js[2892]: trying... Jun 16 07:12:17 volumio sudo[2994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 16 07:12:17 volumio sudo[2994]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:17 volumio sudo[2994]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:18 volumio wireless.js[2892]: trying... Jun 16 07:12:18 volumio sudo[2997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 16 07:12:18 volumio sudo[2997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:18 volumio sudo[2997]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:19 volumio wireless.js[2892]: trying... Jun 16 07:12:19 volumio sudo[3000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 16 07:12:19 volumio sudo[3000]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:19 volumio sudo[3000]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:20 volumio wireless.js[2892]: trying... Jun 16 07:12:20 volumio sudo[3003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 16 07:12:20 volumio sudo[3003]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:20 volumio sudo[3003]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:20 volumio dhcpcd[1527]: wlan0: leased 192.168.1.150 for 86400 seconds Jun 16 07:12:20 volumio dhcpcd[1527]: wlan0: adding route to 192.168.1.0/24 Jun 16 07:12:20 volumio dhcpcd[1527]: wlan0: adding default route via 192.168.1.1 Jun 16 07:12:20 volumio avahi-daemon[694]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.150. Jun 16 07:12:20 volumio avahi-daemon[694]: New relevant interface wlan0.IPv4 for mDNS. Jun 16 07:12:20 volumio avahi-daemon[694]: Registering new address record for 192.168.1.150 on wlan0.IPv4. Jun 16 07:12:20 volumio ntpd[860]: ntpd exiting on signal 15 (Terminated) Jun 16 07:12:20 volumio systemd[1]: Stopping Network Time Service... Jun 16 07:12:20 volumio systemd[1]: ntp.service: Succeeded. Jun 16 07:12:20 volumio systemd[1]: Stopped Network Time Service. Jun 16 07:12:20 volumio systemd[1]: Starting Network Time Service... Jun 16 07:12:20 volumio ntpd[3038]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 16 07:12:20 volumio ntpd[3038]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 16 07:12:20 volumio systemd[1]: Started Network Time Service. Jun 16 07:12:20 volumio ntpd[3044]: proto: precision = 0.073 usec (-24) Jun 16 07:12:20 volumio ntpd[3044]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 16 07:12:20 volumio ntpd[3044]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 16 07:12:20 volumio ntpd[3044]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 902 days ago Jun 16 07:12:20 volumio ntpd[3044]: Listen and drop on 0 v6wildcard [::]:123 Jun 16 07:12:20 volumio ntpd[3044]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 16 07:12:20 volumio ntpd[3044]: Listen normally on 2 lo 127.0.0.1:123 Jun 16 07:12:20 volumio ntpd[3044]: Listen normally on 3 wlan0 192.168.1.150:123 Jun 16 07:12:20 volumio ntpd[3044]: Listening on routing socket on fd #20 for interface updates Jun 16 07:12:20 volumio ntpd[3044]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 16 07:12:20 volumio ntpd[3044]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 16 07:12:21 volumio wireless.js[2892]: trying... Jun 16 07:12:21 volumio sudo[3048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 16 07:12:21 volumio sudo[3048]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:21 volumio sudo[3048]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:21 volumio wireless.js[2892]: Connected to: ----Linksys Jun 16 07:12:21 volumio wireless.js[2892]: ---- Jun 16 07:12:21 volumio sudo[3051]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 16 07:12:21 volumio sudo[3051]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:21 volumio sudo[3051]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:21 volumio wireless.js[2892]: ... joined AP, wlan0 IPv4 is 192.168.1.150, ipV6 is undefined Jun 16 07:12:21 volumio wireless.js[2892]: It's done! AP Jun 16 07:12:21 volumio iwconfig[3055]: Error for wireless request "Set Power Management" (8B2C) : Jun 16 07:12:21 volumio iwconfig[3055]: SET failed on device wlan0 ; Operation not supported. Jun 16 07:12:21 volumio systemd[1]: Started Wireless Services. Jun 16 07:12:21 volumio sudo[2890]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:21 volumio volumio-time-update[706]: volumio-time-update-util: Fetching time from Volumio... Jun 16 07:12:21 volumio ntpd[3044]: Soliciting pool server 103.184.124.254 Jun 16 07:12:21 volumio volumio[987]: info: Discovery: adding 45290d9f-244a-4e06-b84b-45ed243a37f7 Jun 16 07:12:21 volumio volumio[987]: info: Discovery: Found device Volumio Jun 16 07:12:21 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 16 07:12:21 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 16 07:12:22 volumio volumio-time-update[706]: volumio-time-update-util: Setting system time to: 2025-06-16 07:12:21 Jun 16 07:12:22 volumio sudo[3062]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-16 07:12:21 Jun 16 07:12:22 volumio sudo[3062]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:22 volumio dbus-daemon[690]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.20' (uid=0 pid=3063 comm="timedatectl set-time 2025-06-16 07:12:21 ") Jun 16 07:12:22 volumio systemd[1]: Starting Time & Date Service... Jun 16 07:12:22 volumio dbus-daemon[690]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 16 07:12:22 volumio systemd[1]: Started Time & Date Service. Jun 16 07:12:21 volumio systemd-timedated[3064]: Changed local time to Mon Jun 16 07:12:21 2025 Jun 16 07:12:21 volumio sudo[3062]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:21 volumio volumio-time-update[706]: volumio-time-update-util: System time updated successfully. Jun 16 07:12:21 volumio systemd[1]: Started Volumio Time Update Utility. Jun 16 07:12:21 volumio systemd[1]: Reached target Multi-User System. Jun 16 07:12:21 volumio systemd[1]: Reached target Graphical Interface. Jun 16 07:12:21 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 16 07:12:21 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 16 07:12:21 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 16 07:12:21 volumio systemd[1]: Startup finished in 6.506s (kernel) + 4min 37.703s (userspace) = 4min 44.210s. Jun 16 07:12:21 volumio volumio[987]: info: Volumio Network Manager: Network status updated: 2 Jun 16 07:12:21 volumio ntpd[3044]: Soliciting pool server 115.165.161.155 Jun 16 07:12:21 volumio ntpd[3044]: Soliciting pool server 103.188.82.179 Jun 16 07:12:22 volumio ntpd[3044]: Soliciting pool server 103.199.19.135 Jun 16 07:12:22 volumio ntpd[3044]: Soliciting pool server 27.71.27.209 Jun 16 07:12:22 volumio ntpd[3044]: Soliciting pool server 103.70.115.65 Jun 16 07:12:23 volumio ntpd[3044]: Soliciting pool server 2401:5b60:0:2::21 Jun 16 07:12:24 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jun 16 07:12:24 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jun 16 07:12:24 volumio sudo[3088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 16 07:12:24 volumio sudo[3088]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:24 volumio sudo[3088]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:24 volumio sudo[3091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 16 07:12:24 volumio sudo[3091]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:24 volumio sudo[3091]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 16 07:12:26 volumio volumio[987]: info: Adding plugin bluetooth to MyMusic Plugins Jun 16 07:12:26 volumio volumio[987]: info: Adding plugin multiroom to MyMusic Plugins Jun 16 07:12:26 volumio volumio[987]: info: Adding plugin metavolumio to MyMusic Plugins Jun 16 07:12:26 volumio volumio[987]: info: Adding plugin cd_controller to MyMusic Plugins Jun 16 07:12:26 volumio volumio[987]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 16 07:12:26 volumio volumio[987]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 16 07:12:26 volumio volumio[987]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 16 07:12:26 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 07:12:26 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 07:12:26 volumio volumio[987]: info: Starting MyVolumio Remote Streaming Endpoints Jun 16 07:12:26 volumio volumio[987]: info: MyVolumio login type: Token Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 16 07:12:26 volumio volumio[987]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 16 07:12:27 volumio volumio[987]: info: Starting Streaming Service Transparent Proxy Jun 16 07:12:27 volumio volumio[987]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 16 07:12:27 volumio volumio[987]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 16 07:12:27 volumio volumio[987]: info: Streaming services startup Jun 16 07:12:27 volumio volumio[987]: info: Starting Streaming Daemon Jun 16 07:12:27 volumio sudo[3118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 16 07:12:27 volumio sudo[3118]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:27 volumio volumio[987]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 16 07:12:27 volumio sudo[3118]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:27 volumio volumio[987]: error: Cannot start Volumio Streaming Daemon Jun 16 07:12:27 volumio volumio[987]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 16 07:12:27 volumio volumio[987]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 16 07:12:27 volumio volumio[987]: STREAMING PROXY: Starting server on port 3245 Jun 16 07:12:27 volumio volumio[987]: Node JS runtime: 14 Jun 16 07:12:28 volumio volumio[987]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 16 07:12:29 volumio volumio[987]: info: MyVolumio token set successfully Jun 16 07:12:29 volumio volumio[987]: info: MYVOLUMIO: Adding device Jun 16 07:12:29 volumio volumio[987]: info: MYVOLUMIO: Evaluating Server Jun 16 07:12:30 volumio volumio[987]: info: MyVolumio status changed Jun 16 07:12:30 volumio volumio[987]: info: Streaming services startup Jun 16 07:12:30 volumio volumio[987]: info: Starting Streaming Daemon Jun 16 07:12:30 volumio volumio[987]: info: Removing browser output: myVolumio user plan is not superstar Jun 16 07:12:30 volumio volumio[987]: info: Removing audio output: Jun 16 07:12:30 volumio volumio[987]: info: Stoppping Tunnel 1 Jun 16 07:12:30 volumio sudo[3142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 16 07:12:30 volumio sudo[3142]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:30 volumio sudo[3142]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:30 volumio volumio[987]: error: Cannot start Volumio Streaming Daemon Jun 16 07:12:30 volumio sudo[3145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 16 07:12:30 volumio volumio[987]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 16 07:12:30 volumio volumio[987]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 16 07:12:30 volumio sudo[3145]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:30 volumio sudo[3145]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:30 volumio volumio[987]: info: Remote SSH Stopped Jun 16 07:12:30 volumio volumio[987]: info: Setting Geolocation for MyVolumio to as1 Jun 16 07:12:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 07:12:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 07:12:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 07:12:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 16 07:12:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 16 07:12:30 volumio volumio[987]: info: Refreshing Cached IP Addresses Jun 16 07:12:30 volumio sudo[3148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 16 07:12:30 volumio sudo[3148]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:30 volumio sudo[3148]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:30 volumio sudo[3151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 16 07:12:30 volumio sudo[3151]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:30 volumio sudo[3151]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:30 volumio volumio[987]: error: Cannot kill upmpdcli Error: Command failed: /usr/bin/sudo /usr/bin/killall upmpdcli Jun 16 07:12:30 volumio volumio[987]: upmpdcli: no process found Jun 16 07:12:30 volumio sudo[3154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 16 07:12:30 volumio sudo[3154]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:30 volumio sudo[3154]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:31 volumio volumio[987]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 16 07:12:31 volumio volumio[987]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::volumioGetVisibleSources Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 16 07:12:31 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 16 07:12:31 volumio volumio[987]: info: Received Get System Info Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 07:12:31 volumio volumio[987]: info: Discovery: Getting this device information Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 16 07:12:31 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 16 07:12:31 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 16 07:12:31 volumio volumio[987]: info: Listing playlists Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 16 07:12:31 volumio volumio[987]: info: Updating MyVolumio device info Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 16 07:12:31 volumio sudo[3163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 16 07:12:31 volumio sudo[3163]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:31 volumio sudo[3163]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:31 volumio sudo[3168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 16 07:12:31 volumio sudo[3168]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:31 volumio sudo[3168]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:31 volumio sudo[3176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 16 07:12:31 volumio sudo[3176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:31 volumio sudo[3176]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:31 volumio sudo[3189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 16 07:12:31 volumio sudo[3182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 16 07:12:31 volumio sudo[3182]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:31 volumio sudo[3189]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:31 volumio sudo[3182]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:31 volumio sudo[3189]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:31 volumio sudo[3190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 16 07:12:31 volumio sudo[3190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:31 volumio sudo[3190]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 16 07:12:31 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 16 07:12:32 volumio sudo[3195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 16 07:12:32 volumio sudo[3195]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:32 volumio volumio[987]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 16 07:12:33 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 16 07:12:33 volumio volumio[987]: info: Received Get System Info Jun 16 07:12:33 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 07:12:33 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 07:12:33 volumio volumio[987]: info: Discovery: Getting this device information Jun 16 07:12:33 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 16 07:12:33 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 16 07:12:33 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 07:12:33 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 16 07:12:33 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 16 07:12:35 volumio volumio[987]: info: MYVOLUMIO: Adding device Jun 16 07:12:35 volumio volumio[987]: info: MYVOLUMIO: Evaluating Server Jun 16 07:12:35 volumio sudo[3195]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:36 volumio volumio[987]: info: Setting Geolocation for MyVolumio to as1 Jun 16 07:12:36 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 07:12:36 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 07:12:36 volumio volumio[987]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 16 07:12:37 volumio volumio[987]: info: Updating MyVolumio device info Jun 16 07:12:37 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 07:12:37 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 16 07:12:37 volumio volumio[987]: info: Received Get System Info Jun 16 07:12:37 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 07:12:37 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 07:12:37 volumio volumio[987]: info: Discovery: Getting this device information Jun 16 07:12:37 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 16 07:12:37 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 16 07:12:37 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 07:12:37 volumio volumio[987]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 16 07:12:41 volumio sudo[3227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 16 07:12:41 volumio sudo[3227]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:41 volumio sudo[3227]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:41 volumio sudo[3230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 16 07:12:41 volumio sudo[3230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:41 volumio sudo[3230]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:41 volumio sudo[3233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 16 07:12:41 volumio sudo[3233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 07:12:41 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 16 07:12:41 volumio sudo[3233]: pam_unix(sudo:session): session closed for user root Jun 16 07:12:41 volumio volumio[987]: info: Upmpdcli Daemon Started Jun 16 07:12:41 volumio volumio[3235]: writing RSA key Jun 16 07:12:41 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 16 07:12:42 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 16 07:12:42 volumio volumio[987]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 16 07:12:42 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 16 07:12:42 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 16 07:12:46 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 16 07:12:46 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 16 07:12:47 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 07:12:47 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 07:12:47 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 16 07:12:47 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 16 07:12:47 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 16 07:12:47 volumio volumio[987]: info: CoreCommandRouter::volumioGetBrowseSources Jun 16 07:12:47 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 16 07:12:50 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 16 07:12:50 volumio volumio[987]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 16 07:12:50 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 16 07:12:50 volumio volumio[987]: info: Received Get System Version Jun 16 07:12:50 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 16 07:12:50 volumio volumio[987]: info: Received Get System Info Jun 16 07:12:50 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 07:12:50 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 07:12:50 volumio volumio[987]: info: Discovery: Getting this device information Jun 16 07:12:50 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jun 16 07:12:50 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Jun 16 07:12:50 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 07:12:52 volumio systemd[1]: systemd-timedated.service: Succeeded. Jun 16 07:13:02 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Jun 16 07:13:02 volumio volumio[987]: info: CoreStateMachine::getQueue Jun 16 07:13:02 volumio volumio[987]: info: CorePlayQueue::getQueue Jun 16 07:13:04 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 16 07:13:04 volumio volumio[987]: info: CURURI: music-library Jun 16 07:13:05 volumio volumio[987]: info: Preload queue cleared Jun 16 07:13:09 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 16 07:13:09 volumio volumio[987]: info: CURURI: music-library Jun 16 07:13:09 volumio volumio[987]: info: Preload queue cleared Jun 16 07:13:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 16 07:13:10 volumio volumio[987]: info: CURURI: music-library/USB Jun 16 07:13:10 volumio volumio[987]: info: Preload queue cleared Jun 16 07:13:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 16 07:13:11 volumio volumio[987]: info: CURURI: music-library/USB/UUI Jun 16 07:13:11 volumio volumio[987]: info: Preload queue cleared Jun 16 07:13:16 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 16 07:13:16 volumio volumio[987]: info: Preload queue cleared Jun 16 07:13:18 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 16 07:13:18 volumio volumio[987]: info: Preload queue cleared Jun 16 07:13:22 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 16 07:13:22 volumio volumio[987]: info: Preload queue cleared Jun 16 07:13:27 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 16 07:13:27 volumio volumio[987]: info: Preload queue cleared Jun 16 07:13:32 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 16 07:13:32 volumio volumio[987]: info: Preload queue cleared Jun 16 07:13:32 volumio volumio[987]: info: Preloading song: upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@28 Jun 16 07:13:32 volumio volumio[987]: info: Preloading song: upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@29 Jun 16 07:13:32 volumio volumio[987]: info: Preloading song: upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@30 Jun 16 07:13:32 volumio volumio[987]: info: Preloading song: upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@31 Jun 16 07:13:32 volumio volumio[987]: info: Preloading song: upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@32 Jun 16 07:13:32 volumio volumio[987]: info: Preloading song: upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@33 Jun 16 07:13:32 volumio volumio[987]: info: Preloading song: upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@34 Jun 16 07:13:32 volumio volumio[987]: info: Preloading song: upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@35 Jun 16 07:13:32 volumio volumio[987]: info: Preloading song: upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@36 Jun 16 07:13:32 volumio volumio[987]: info: Exploding uri upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@28 in service upnp_browser Jun 16 07:13:32 volumio volumio[987]: info: Exploding uri upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@29 in service upnp_browser Jun 16 07:13:32 volumio volumio[987]: info: Exploding uri upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@30 in service upnp_browser Jun 16 07:13:32 volumio volumio[987]: info: Exploding uri upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@31 in service upnp_browser Jun 16 07:13:32 volumio volumio[987]: info: Exploding uri upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@32 in service upnp_browser Jun 16 07:13:32 volumio volumio[987]: info: Exploding uri upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@33 in service upnp_browser Jun 16 07:13:32 volumio volumio[987]: info: Exploding uri upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@34 in service upnp_browser Jun 16 07:13:32 volumio volumio[987]: info: Exploding uri upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@35 in service upnp_browser Jun 16 07:13:32 volumio volumio[987]: info: Exploding uri upnp/http://192.168.1.198:50001/ContentDirectory/control@23$@36 in service upnp_browser Jun 16 07:13:44 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 16 07:13:44 volumio volumio[987]: info: Preload queue cleared Jun 16 07:13:45 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 16 07:13:45 volumio volumio[987]: info: Preload queue cleared Jun 16 07:13:46 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 16 07:13:46 volumio volumio[987]: info: Preload queue cleared Jun 16 07:13:50 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 16 07:13:50 volumio volumio[987]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 07:13:50 volumio volumio[987]: TypeError: Cannot read property 'length' of undefined Jun 16 07:13:50 volumio volumio[987]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 16 07:13:50 volumio volumio[987]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 16 07:13:50 volumio volumio[987]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 16 07:13:50 volumio volumio[987]: at Parser.emit (events.js:400:28) Jun 16 07:13:50 volumio volumio[987]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 16 07:13:50 volumio volumio[987]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 16 07:13:50 volumio volumio[987]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 16 07:13:50 volumio volumio[987]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 16 07:13:50 volumio volumio[987]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 16 07:13:50 volumio volumio[987]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 16 07:13:50 volumio volumio[987]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 16 07:13:50 volumio volumio[987]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 16 07:13:50 volumio volumio[987]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 16 07:13:50 volumio volumio[987]: at IncomingMessage.emit (events.js:412:35) Jun 16 07:13:50 volumio volumio[987]: at endReadableNT (internal/streams/readable.js:1333:12) Jun 16 07:13:50 volumio volumio[987]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 16 07:13:50 volumio volumio[987]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 07:13:50 volumio sudo[3484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-16 07:12 Jun 16 07:13:50 volumio sudo[3484]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"