-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Mon 2025-06-02 19:52:48 CST. -- Jun 02 19:51:00 smsl-n100 wireless.js[2283]: trying... Jun 02 19:51:00 smsl-n100 sudo[2648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:00 smsl-n100 sudo[2648]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:00 smsl-n100 sudo[2648]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:01 smsl-n100 wireless.js[2283]: trying... Jun 02 19:51:01 smsl-n100 sudo[2651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:01 smsl-n100 sudo[2651]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:01 smsl-n100 sudo[2651]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:02 smsl-n100 wireless.js[2283]: trying... Jun 02 19:51:02 smsl-n100 sudo[2654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:02 smsl-n100 sudo[2654]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:02 smsl-n100 sudo[2654]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:03 smsl-n100 dhcpcd[967]: wlan0: leased 192.168.0.12 for 172800 seconds Jun 02 19:51:03 smsl-n100 avahi-daemon[570]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.12. Jun 02 19:51:03 smsl-n100 avahi-daemon[570]: New relevant interface wlan0.IPv4 for mDNS. Jun 02 19:51:03 smsl-n100 dhcpcd[967]: wlan0: adding route to 192.168.0.0/24 Jun 02 19:51:03 smsl-n100 dhcpcd[967]: wlan0: adding default route via 192.168.0.1 Jun 02 19:51:03 smsl-n100 avahi-daemon[570]: Registering new address record for 192.168.0.12 on wlan0.IPv4. Jun 02 19:51:03 smsl-n100 ntpd[977]: ntpd exiting on signal 15 (Terminated) Jun 02 19:51:03 smsl-n100 ntpd[977]: 202.118.1.130 local addr 192.168.0.11 -> Jun 02 19:51:03 smsl-n100 systemd[1]: Stopping Network Time Service... Jun 02 19:51:03 smsl-n100 ntpd[977]: 211.68.71.26 local addr 192.168.0.11 -> Jun 02 19:51:03 smsl-n100 ntpd[977]: 162.159.200.123 local addr 192.168.0.11 -> Jun 02 19:51:03 smsl-n100 ntpd[977]: 193.182.111.143 local addr 192.168.0.11 -> Jun 02 19:51:03 smsl-n100 systemd[1]: ntp.service: Succeeded. Jun 02 19:51:03 smsl-n100 ntpd[977]: 202.118.1.81 local addr 192.168.0.11 -> Jun 02 19:51:03 smsl-n100 ntpd[977]: 45.76.221.157 local addr 192.168.0.11 -> Jun 02 19:51:03 smsl-n100 ntpd[977]: 117.80.112.205 local addr 192.168.0.11 -> Jun 02 19:51:03 smsl-n100 ntpd[977]: 116.203.151.74 local addr 192.168.0.11 -> Jun 02 19:51:03 smsl-n100 systemd[1]: Stopped Network Time Service. Jun 02 19:51:03 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:51:03 smsl-n100 systemd[1]: Starting Network Time Service... Jun 02 19:51:03 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 02 19:51:03 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:51:03 smsl-n100 ntpd[2689]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 02 19:51:03 smsl-n100 ntpd[2689]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 02 19:51:03 smsl-n100 systemd[1]: Started Network Time Service. Jun 02 19:51:03 smsl-n100 ntpd[2695]: proto: precision = 1.166 usec (-20) Jun 02 19:51:03 smsl-n100 ntpd[2695]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 02 19:51:03 smsl-n100 ntpd[2695]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 02 19:51:03 smsl-n100 ntpd[2695]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 888 days ago Jun 02 19:51:03 smsl-n100 ntpd[2695]: Listen and drop on 0 v6wildcard [::]:123 Jun 02 19:51:03 smsl-n100 ntpd[2695]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 02 19:51:03 smsl-n100 ntpd[2695]: Listen normally on 2 lo 127.0.0.1:123 Jun 02 19:51:03 smsl-n100 ntpd[2695]: Listen normally on 3 eth0 192.168.0.11:123 Jun 02 19:51:03 smsl-n100 ntpd[2695]: Listen normally on 4 wlan0 192.168.0.12:123 Jun 02 19:51:03 smsl-n100 ntpd[2695]: Listening on routing socket on fd #21 for interface updates Jun 02 19:51:03 smsl-n100 ntpd[2695]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Jun 02 19:51:03 smsl-n100 ntpd[2695]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Jun 02 19:51:03 smsl-n100 wireless.js[2283]: Overtime, starting plan B Jun 02 19:51:03 smsl-n100 wireless.js[2283]: STARTING HOTSPOT Jun 02 19:51:04 smsl-n100 volumio[887]: info: Discovery: this is already registered, 6341edbf-d91f-45c3-aa19-afd787bc47be Jun 02 19:51:04 smsl-n100 volumio[887]: info: Discovery: Found device SMSL N100 Jun 02 19:51:04 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:51:04 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:04 smsl-n100 wpa_supplicant[2338]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f4:2a:7d:d8:0f:b7 reason=3 locally_generated=1 Jun 02 19:51:04 smsl-n100 dhcpcd[967]: wlan0: carrier lost Jun 02 19:51:04 smsl-n100 wpa_supplicant[2338]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jun 02 19:51:04 smsl-n100 avahi-daemon[570]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 02 19:51:04 smsl-n100 avahi-daemon[570]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.12. Jun 02 19:51:04 smsl-n100 wpa_supplicant[2338]: wlan0: CTRL-EVENT-TERMINATING Jun 02 19:51:04 smsl-n100 avahi-daemon[570]: Withdrawing address record for 192.168.0.12 on wlan0. Jun 02 19:51:04 smsl-n100 volumio[887]: info: Discovery: A device disappeared from network Jun 02 19:51:04 smsl-n100 dhcpcd[967]: wlan0: deleting route to 192.168.0.0/24 Jun 02 19:51:04 smsl-n100 dhcpcd[967]: wlan0: deleting default route via 192.168.0.1 Jun 02 19:51:04 smsl-n100 ntpd[2695]: Soliciting pool server 193.182.111.141 Jun 02 19:51:05 smsl-n100 ntpd[2695]: Soliciting pool server 193.182.111.14 Jun 02 19:51:05 smsl-n100 ntpd[2695]: Deleting interface #4 wlan0, 192.168.0.12#123, interface stats: received=0, sent=0, dropped=0, active_time=2 secs Jun 02 19:51:05 smsl-n100 ntpd[2695]: Soliciting pool server 193.182.111.143 Jun 02 19:51:06 smsl-n100 ntpd[2695]: Soliciting pool server 162.159.200.1 Jun 02 19:51:06 smsl-n100 volumio[887]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Jun 02 19:51:06 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Jun 02 19:51:06 smsl-n100 sudo[2747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Jun 02 19:51:06 smsl-n100 sudo[2747]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:06 smsl-n100 sudo[2747]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:06 smsl-n100 sudo[2749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Jun 02 19:51:06 smsl-n100 sudo[2749]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:06 smsl-n100 ntpd[2695]: Soliciting pool server 211.68.71.26 Jun 02 19:51:06 smsl-n100 sudo[2749]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:06 smsl-n100 volumio[887]: info: Permissions for /etc/network/interfaces set Jun 02 19:51:06 smsl-n100 volumio[887]: info: Restarting Wired Network Jun 02 19:51:06 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 02 19:51:06 smsl-n100 volumio[887]: info: Discovery: Restarting Advertising due to device name change Jun 02 19:51:06 smsl-n100 sudo[2755]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 02 19:51:06 smsl-n100 sudo[2755]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:06 smsl-n100 sudo[2756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Jun 02 19:51:06 smsl-n100 sudo[2756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:06 smsl-n100 sudo[2755]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:06 smsl-n100 avahi-daemon[570]: Withdrawing address record for 192.168.0.11 on eth0. Jun 02 19:51:06 smsl-n100 avahi-daemon[570]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.11. Jun 02 19:51:06 smsl-n100 avahi-daemon[570]: Interface eth0.IPv4 no longer relevant for mDNS. Jun 02 19:51:06 smsl-n100 dhcpcd[967]: eth0: pid 2758 deleted IP address 192.168.0.11/24 Jun 02 19:51:06 smsl-n100 dhcpcd[967]: eth0: deleting route to 192.168.0.0/24 Jun 02 19:51:06 smsl-n100 dhcpcd[967]: eth0: deleting default route via 192.168.0.1 Jun 02 19:51:06 smsl-n100 dhcpcd[978]: eth0: pid 2758 deleted IP address 192.168.0.11/24 Jun 02 19:51:06 smsl-n100 dhcpcd[978]: eth0: deleting route to 192.168.0.0/24 Jun 02 19:51:06 smsl-n100 dhcpcd[978]: eth0: deleting default route via 192.168.0.1 Jun 02 19:51:06 smsl-n100 sudo[2756]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:06 smsl-n100 volumio[887]: info: Discovery: A device disappeared from network Jun 02 19:51:06 smsl-n100 sudo[2759]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 02 19:51:06 smsl-n100 sudo[2759]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:07 smsl-n100 sudo[2759]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:07 smsl-n100 sudo[2762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Jun 02 19:51:07 smsl-n100 sudo[2762]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:07 smsl-n100 dhcpcd[967]: eth0: soliciting a DHCP lease Jun 02 19:51:07 smsl-n100 dhcpcd[967]: eth0: offered 192.168.0.11 from 192.168.0.1 Jun 02 19:51:07 smsl-n100 dhcpcd[978]: eth0: soliciting a DHCP lease Jun 02 19:51:07 smsl-n100 dhcpcd[967]: eth0: probing address 192.168.0.11/24 Jun 02 19:51:07 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Jun 02 19:51:07 smsl-n100 dhcpcd[978]: eth0: offered 192.168.0.11 from 192.168.0.1 Jun 02 19:51:07 smsl-n100 dhcpcd[978]: eth0: carrier lost Jun 02 19:51:07 smsl-n100 dhcpcd[967]: eth0: carrier lost Jun 02 19:51:07 smsl-n100 sudo[2762]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:07 smsl-n100 avahi-daemon[570]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 02 19:51:07 smsl-n100 avahi-daemon[570]: New relevant interface wlan0.IPv4 for mDNS. Jun 02 19:51:07 smsl-n100 avahi-daemon[570]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jun 02 19:51:07 smsl-n100 sudo[2788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Jun 02 19:51:07 smsl-n100 sudo[2788]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:07 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PHY [stmmac-0:01] driver [Generic PHY] Jun 02 19:51:07 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: No Safety Features support found Jun 02 19:51:07 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PTP not supported by HW Jun 02 19:51:07 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: configuring for phy/rgmii link mode Jun 02 19:51:07 smsl-n100 sudo[2788]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:07 smsl-n100 systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jun 02 19:51:07 smsl-n100 dhcpcd[967]: wlan0: carrier acquired Jun 02 19:51:07 smsl-n100 dhcpcd[967]: wlan0: confile file changed, expiring leases Jun 02 19:51:07 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:51:07 smsl-n100 dhcpcd[967]: wlan0: IAID 2b:ee:c8:89 Jun 02 19:51:07 smsl-n100 hostapd[2815]: Configuration file: /etc/hostapd/hostapd.conf Jun 02 19:51:07 smsl-n100 hostapd[2815]: Using interface wlan0 with hwaddr 28:f5:2b:ee:c8:89 and ssid "Volumio-88681" Jun 02 19:51:07 smsl-n100 hostapd[2815]: wlan0: interface state UNINITIALIZED->ENABLED Jun 02 19:51:07 smsl-n100 hostapd[2815]: wlan0: AP-ENABLED Jun 02 19:51:07 smsl-n100 systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jun 02 19:51:07 smsl-n100 systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jun 02 19:51:07 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:51:07 smsl-n100 dnsmasq[2825]: dnsmasq: syntax check OK. Jun 02 19:51:07 smsl-n100 iwconfig[2827]: Error for wireless request "Set Power Management" (8B2C) : Jun 02 19:51:07 smsl-n100 iwconfig[2827]: SET failed on device wlan0 ; Operation not permitted. Jun 02 19:51:07 smsl-n100 systemd[1]: wireless.service: Succeeded. Jun 02 19:51:07 smsl-n100 systemd[1]: Started Wireless Services. Jun 02 19:51:07 smsl-n100 sudo[2072]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:07 smsl-n100 sudo[2281]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:07 smsl-n100 dhcpcd[967]: wlan0: rebinding lease of 192.168.0.12 Jun 02 19:51:07 smsl-n100 ntpd[2695]: Soliciting pool server 162.159.200.123 Jun 02 19:51:07 smsl-n100 ntpd[2695]: Soliciting pool server 45.76.221.157 Jun 02 19:51:07 smsl-n100 ntpd[2695]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 02 19:51:07 smsl-n100 dnsmasq[2833]: started, version 2.80 cachesize 150 Jun 02 19:51:07 smsl-n100 dnsmasq[2833]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jun 02 19:51:07 smsl-n100 dnsmasq-dhcp[2833]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jun 02 19:51:07 smsl-n100 dnsmasq[2833]: reading /etc/resolv.conf Jun 02 19:51:07 smsl-n100 dnsmasq[2833]: using nameserver 208.67.222.222#53 Jun 02 19:51:07 smsl-n100 dnsmasq[2833]: using nameserver 208.67.220.220#53 Jun 02 19:51:07 smsl-n100 dnsmasq[2833]: read /etc/hosts - 5 addresses Jun 02 19:51:07 smsl-n100 systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jun 02 19:51:07 smsl-n100 ifplugd(eth0)[767]: Link beat lost. Jun 02 19:51:07 smsl-n100 dhcpcd[967]: wlan0: soliciting an IPv6 router Jun 02 19:51:08 smsl-n100 ntpd[2695]: Listen normally on 5 wlan0 192.168.211.1:123 Jun 02 19:51:08 smsl-n100 ntpd[2695]: Deleting interface #3 eth0, 192.168.0.11#123, interface stats: received=5, sent=6, dropped=2, active_time=5 secs Jun 02 19:51:08 smsl-n100 ntpd[2695]: 211.68.71.26 local addr 192.168.0.11 -> Jun 02 19:51:08 smsl-n100 ntpd[2695]: 162.159.200.1 local addr 192.168.0.11 -> Jun 02 19:51:08 smsl-n100 ntpd[2695]: 193.182.111.143 local addr 192.168.0.11 -> Jun 02 19:51:08 smsl-n100 ntpd[2695]: 193.182.111.141 local addr 192.168.0.11 -> Jun 02 19:51:08 smsl-n100 ntpd[2695]: new interface(s) found: waking up resolver Jun 02 19:51:10 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Jun 02 19:51:10 smsl-n100 dhcpcd[967]: eth0: carrier acquired Jun 02 19:51:10 smsl-n100 dhcpcd[978]: eth0: carrier acquired Jun 02 19:51:10 smsl-n100 dhcpcd[967]: eth0: confile file changed, expiring leases Jun 02 19:51:10 smsl-n100 dhcpcd[978]: eth0: confile file changed, expiring leases Jun 02 19:51:10 smsl-n100 dhcpcd[967]: eth0: IAID 01:e1:97:06 Jun 02 19:51:10 smsl-n100 dhcpcd[978]: eth0: IAID 01:e1:97:06 Jun 02 19:51:10 smsl-n100 dhcpcd[967]: eth0: soliciting an IPv6 router Jun 02 19:51:10 smsl-n100 dhcpcd[978]: eth0: soliciting a DHCP lease Jun 02 19:51:10 smsl-n100 dhcpcd[978]: eth0: offered 192.168.0.11 from 192.168.0.1 Jun 02 19:51:10 smsl-n100 dhcpcd[978]: eth0: probing address 192.168.0.11/24 Jun 02 19:51:10 smsl-n100 dhcpcd[978]: eth0: soliciting an IPv6 router Jun 02 19:51:10 smsl-n100 ifplugd(eth0)[767]: Link beat detected. Jun 02 19:51:11 smsl-n100 dhcpcd[967]: eth0: soliciting a DHCP lease Jun 02 19:51:11 smsl-n100 dhcpcd[967]: eth0: offered 192.168.0.11 from 192.168.0.1 Jun 02 19:51:11 smsl-n100 dhcpcd[967]: eth0: probing address 192.168.0.11/24 Jun 02 19:51:11 smsl-n100 volumio[887]: info: Discovery: Started advertising with name: SMSL N100 Jun 02 19:51:12 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jun 02 19:51:12 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jun 02 19:51:12 smsl-n100 dhcpcd[967]: wlan0: probing for an IPv4LL address Jun 02 19:51:12 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jun 02 19:51:12 smsl-n100 dhcpcd[967]: wlan0: DHCP lease expired Jun 02 19:51:12 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jun 02 19:51:12 smsl-n100 sudo[2846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:51:12 smsl-n100 dhcpcd[967]: wlan0: soliciting a DHCP lease Jun 02 19:51:12 smsl-n100 sudo[2846]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:12 smsl-n100 sudo[2846]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:12 smsl-n100 sudo[2856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:51:12 smsl-n100 sudo[2856]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:12 smsl-n100 sudo[2856]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:12 smsl-n100 volumio[887]: info: Discovery: adding 6341edbf-d91f-45c3-aa19-afd787bc47be Jun 02 19:51:12 smsl-n100 volumio[887]: info: Discovery: Found device SMSL N100 Jun 02 19:51:12 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:51:12 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:14 smsl-n100 volumio[887]: info: Reporting MCU Network Status: 0 Jun 02 19:51:14 smsl-n100 volumio[887]: info: Volumio Network Manager: Network status updated: 0 Jun 02 19:51:14 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:51:14 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:14 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:51:14 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:51:14 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:51:14 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:51:14 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 100, message: undefined Jun 02 19:51:14 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: MAXVOL, message: Jun 02 19:51:14 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 100, message: undefined Jun 02 19:51:14 smsl-n100 volumio[887]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jun 02 19:51:14 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 0, message: undefined Jun 02 19:51:15 smsl-n100 dhcpcd[967]: eth0: leased 192.168.0.11 for 172800 seconds Jun 02 19:51:15 smsl-n100 dhcpcd[967]: eth0: adding route to 192.168.0.0/24 Jun 02 19:51:15 smsl-n100 dhcpcd[967]: eth0: adding default route via 192.168.0.1 Jun 02 19:51:15 smsl-n100 avahi-daemon[570]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.11. Jun 02 19:51:15 smsl-n100 avahi-daemon[570]: New relevant interface eth0.IPv4 for mDNS. Jun 02 19:51:15 smsl-n100 avahi-daemon[570]: Registering new address record for 192.168.0.11 on eth0.IPv4. Jun 02 19:51:15 smsl-n100 dhcpcd[978]: eth0: leased 192.168.0.11 for 172800 seconds Jun 02 19:51:15 smsl-n100 dhcpcd[978]: eth0: adding route to 192.168.0.0/24 Jun 02 19:51:15 smsl-n100 dhcpcd[978]: eth0: adding default route via 192.168.0.1 Jun 02 19:51:15 smsl-n100 dnsmasq[2833]: reading /etc/resolv.conf Jun 02 19:51:15 smsl-n100 dnsmasq[2833]: using nameserver 8.8.8.8#53 Jun 02 19:51:15 smsl-n100 dnsmasq[2833]: using nameserver 192.168.1.1#53 Jun 02 19:51:15 smsl-n100 dnsmasq[2833]: using nameserver 208.67.222.222#53 Jun 02 19:51:15 smsl-n100 dnsmasq[2833]: using nameserver 208.67.220.220#53 Jun 02 19:51:16 smsl-n100 ntpd[2695]: ntpd exiting on signal 15 (Terminated) Jun 02 19:51:16 smsl-n100 systemd[1]: Stopping Network Time Service... Jun 02 19:51:16 smsl-n100 systemd[1]: ntp.service: Succeeded. Jun 02 19:51:16 smsl-n100 systemd[1]: Stopped Network Time Service. Jun 02 19:51:16 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:51:16 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 02 19:51:16 smsl-n100 systemd[1]: Starting Network Time Service... Jun 02 19:51:16 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:51:16 smsl-n100 systemd[1]: ntp.service: Control process exited, code=killed, status=15/TERM Jun 02 19:51:16 smsl-n100 systemd[1]: ntp.service: Succeeded. Jun 02 19:51:16 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 02 19:51:16 smsl-n100 systemd[1]: Stopped Network Time Service. Jun 02 19:51:16 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:51:16 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 02 19:51:16 smsl-n100 systemd[1]: Starting Network Time Service... Jun 02 19:51:16 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:51:16 smsl-n100 ntpd[2938]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 02 19:51:16 smsl-n100 ntpd[2938]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 02 19:51:16 smsl-n100 systemd[1]: Started Network Time Service. Jun 02 19:51:16 smsl-n100 ntpd[2944]: proto: precision = 0.875 usec (-20) Jun 02 19:51:16 smsl-n100 ntpd[2944]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 02 19:51:16 smsl-n100 ntpd[2944]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 02 19:51:16 smsl-n100 ntpd[2944]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 888 days ago Jun 02 19:51:16 smsl-n100 ntpd[2944]: Listen and drop on 0 v6wildcard [::]:123 Jun 02 19:51:16 smsl-n100 ntpd[2944]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 02 19:51:16 smsl-n100 ntpd[2944]: Listen normally on 2 lo 127.0.0.1:123 Jun 02 19:51:16 smsl-n100 ntpd[2944]: Listen normally on 3 eth0 192.168.0.11:123 Jun 02 19:51:16 smsl-n100 ntpd[2944]: Listen normally on 4 wlan0 192.168.211.1:123 Jun 02 19:51:16 smsl-n100 ntpd[2944]: Listening on routing socket on fd #21 for interface updates Jun 02 19:51:16 smsl-n100 ntpd[2944]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 02 19:51:16 smsl-n100 ntpd[2944]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 02 19:51:17 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 02 19:51:17 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 02 19:51:17 smsl-n100 volumio[887]: info: Refreshing Cached IP Addresses Jun 02 19:51:17 smsl-n100 sudo[2947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 02 19:51:17 smsl-n100 sudo[2947]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:17 smsl-n100 sudo[2947]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:17 smsl-n100 sudo[2950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 19:51:17 smsl-n100 sudo[2950]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:17 smsl-n100 sudo[2950]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:17 smsl-n100 volumio[887]: error: Upnp client error: Error: This socket has been ended by the other party Jun 02 19:51:17 smsl-n100 sudo[2952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:51:17 smsl-n100 sudo[2952]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:17 smsl-n100 sudo[2952]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:17 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 02 19:51:17 smsl-n100 ntpd[2944]: Soliciting pool server 193.182.111.141 Jun 02 19:51:17 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 02 19:51:17 smsl-n100 volumio[887]: info: Refreshing Cached IP Addresses Jun 02 19:51:17 smsl-n100 sudo[2956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 02 19:51:17 smsl-n100 sudo[2956]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:17 smsl-n100 sudo[2956]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:17 smsl-n100 sudo[2959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 19:51:17 smsl-n100 sudo[2959]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:17 smsl-n100 sudo[2959]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:17 smsl-n100 volumio[887]: error: Upnp client error: Error: This socket has been ended by the other party Jun 02 19:51:17 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 02 19:51:17 smsl-n100 sudo[2961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:51:17 smsl-n100 sudo[2961]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:17 smsl-n100 sudo[2961]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:17 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 02 19:51:17 smsl-n100 volumio[887]: info: Refreshing Cached IP Addresses Jun 02 19:51:17 smsl-n100 sudo[2965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 02 19:51:17 smsl-n100 sudo[2965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:17 smsl-n100 dhcpcd[967]: wlan0: using IPv4LL address 169.254.29.192 Jun 02 19:51:17 smsl-n100 avahi-daemon[570]: Registering new address record for 169.254.29.192 on wlan0.IPv4. Jun 02 19:51:17 smsl-n100 dhcpcd[967]: wlan0: adding route to 169.254.0.0/16 Jun 02 19:51:17 smsl-n100 sudo[2965]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:17 smsl-n100 sudo[2969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 19:51:17 smsl-n100 sudo[2969]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:17 smsl-n100 volumio[887]: error: Upnp client error: Error: This socket has been ended by the other party Jun 02 19:51:17 smsl-n100 sudo[2969]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:17 smsl-n100 sudo[2976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:51:17 smsl-n100 sudo[2976]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:17 smsl-n100 sudo[2976]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:18 smsl-n100 volumio[887]: verbose: New Socket.io Connection to 192.168.0.11 from 192.168.0.10 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 Jun 02 19:51:18 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 02 19:51:18 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 19:51:18 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 19:51:18 smsl-n100 ntpd[2944]: Soliciting pool server 202.118.1.130 Jun 02 19:51:18 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:51:18 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:18 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 02 19:51:18 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 02 19:51:18 smsl-n100 volumio[887]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 02 19:51:18 smsl-n100 volumio[887]: info: Received Get System Info Jun 02 19:51:18 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 19:51:18 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 19:51:18 smsl-n100 volumio[887]: info: Discovery: Getting this device information Jun 02 19:51:18 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:51:18 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:18 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 19:51:18 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:51:18 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:18 smsl-n100 volumio[887]: info: Listing playlists Jun 02 19:51:18 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 02 19:51:18 smsl-n100 volumio[887]: info: VolumeController::SetAlsaVolume0 Jun 02 19:51:18 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:51:18 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:18 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:51:18 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:51:18 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:51:18 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:51:18 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:51:19 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jun 02 19:51:19 smsl-n100 volumio[887]: info: Saving new wireless network Jun 02 19:51:19 smsl-n100 sudo[2993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Jun 02 19:51:19 smsl-n100 sudo[2993]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:19 smsl-n100 sudo[2993]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:19 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 02 19:51:19 smsl-n100 volumio[887]: info: Discovery: Restarting Advertising due to device name change Jun 02 19:51:19 smsl-n100 sudo[2996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 02 19:51:19 smsl-n100 sudo[2996]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:19 smsl-n100 ntpd[2944]: Soliciting pool server 116.203.151.74 Jun 02 19:51:19 smsl-n100 ntpd[2944]: Listen normally on 5 wlan0 169.254.29.192:123 Jun 02 19:51:19 smsl-n100 ntpd[2944]: new interface(s) found: waking up resolver Jun 02 19:51:19 smsl-n100 systemd[1]: Starting Wireless Services... Jun 02 19:51:19 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:51:20 smsl-n100 wireless.js[2999]: Cleaning previous... Jun 02 19:51:20 smsl-n100 systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jun 02 19:51:20 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:51:20 smsl-n100 volumio[887]: info: Discovery: A device disappeared from network Jun 02 19:51:20 smsl-n100 volumio[887]: info: Discovery: A device disappeared from network Jun 02 19:51:20 smsl-n100 dnsmasq[2833]: exiting on receipt of SIGTERM Jun 02 19:51:20 smsl-n100 systemd[1]: dnsmasq.service: Succeeded. Jun 02 19:51:20 smsl-n100 systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jun 02 19:51:20 smsl-n100 systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jun 02 19:51:20 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:51:20 smsl-n100 systemd[1]: hostapd.service: Succeeded. Jun 02 19:51:20 smsl-n100 systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jun 02 19:51:20 smsl-n100 sudo[3017]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 02 19:51:20 smsl-n100 sudo[3017]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:20 smsl-n100 avahi-daemon[570]: Withdrawing address record for 192.168.211.1 on wlan0. Jun 02 19:51:20 smsl-n100 avahi-daemon[570]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 02 19:51:20 smsl-n100 avahi-daemon[570]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.29.192. Jun 02 19:51:20 smsl-n100 sudo[3017]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:20 smsl-n100 avahi-daemon[570]: Withdrawing address record for 169.254.29.192 on wlan0. Jun 02 19:51:20 smsl-n100 avahi-daemon[570]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.29.192. Jun 02 19:51:20 smsl-n100 avahi-daemon[570]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 02 19:51:20 smsl-n100 sudo[3019]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 02 19:51:20 smsl-n100 sudo[3019]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:20 smsl-n100 dhcpcd[967]: wlan0: carrier lost Jun 02 19:51:20 smsl-n100 sudo[3019]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:20 smsl-n100 dhcpcd[967]: wlan0: deleting route to 169.254.0.0/16 Jun 02 19:51:20 smsl-n100 wireless.js[2999]: Stopped aP Jun 02 19:51:20 smsl-n100 sudo[3055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 02 19:51:20 smsl-n100 sudo[3055]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:20 smsl-n100 sudo[3055]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:20 smsl-n100 sudo[3068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 02 19:51:20 smsl-n100 sudo[3068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:20 smsl-n100 sudo[3068]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:20 smsl-n100 sudo[3077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 02 19:51:20 smsl-n100 sudo[3077]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:20 smsl-n100 sudo[3077]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:20 smsl-n100 sudo[3079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 02 19:51:20 smsl-n100 sudo[3079]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:21 smsl-n100 ntpd[2944]: Soliciting pool server 108.59.2.24 Jun 02 19:51:21 smsl-n100 dhcpcd[967]: wlan0: carrier acquired Jun 02 19:51:21 smsl-n100 dhcpcd[967]: wlan0: IAID 2b:ee:c8:89 Jun 02 19:51:21 smsl-n100 volumio[887]: info: Discovery: adding 6341edbf-d91f-45c3-aa19-afd787bc47be Jun 02 19:51:21 smsl-n100 volumio[887]: info: Discovery: Found device SMSL N100 Jun 02 19:51:21 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:51:21 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:22 smsl-n100 dhcpcd[967]: wlan0: soliciting a DHCP lease Jun 02 19:51:22 smsl-n100 dhcpcd[967]: wlan0: soliciting an IPv6 router Jun 02 19:51:23 smsl-n100 ntpd[2944]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=7 secs Jun 02 19:51:23 smsl-n100 ntpd[2944]: Deleting interface #5 wlan0, 169.254.29.192#123, interface stats: received=0, sent=0, dropped=0, active_time=4 secs Jun 02 19:51:24 smsl-n100 volumio[887]: info: Discovery: Started advertising with name: SMSL N100 Jun 02 19:51:24 smsl-n100 ntpd[2944]: Soliciting pool server 211.68.71.26 Jun 02 19:51:24 smsl-n100 volumio[887]: info: Reporting MCU Network Status: 1 Jun 02 19:51:24 smsl-n100 volumio[887]: info: Volumio Network Manager: Network status updated: 1 Jun 02 19:51:24 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:51:24 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:24 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:51:24 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:51:24 smsl-n100 ntpd[2944]: Soliciting pool server 162.159.200.123 Jun 02 19:51:24 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:51:24 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:51:24 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 100, message: undefined Jun 02 19:51:24 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: MAXVOL, message: Jun 02 19:51:24 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 100, message: undefined Jun 02 19:51:24 smsl-n100 volumio[887]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jun 02 19:51:24 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 1, message: undefined Jun 02 19:51:24 smsl-n100 volumio[887]: info: VolumeController::SetAlsaVolume0 Jun 02 19:51:24 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:51:24 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:24 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:51:24 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:51:24 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:51:24 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:51:25 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:51:25 smsl-n100 volumio[887]: info: Discovery: this is already registered, 6341edbf-d91f-45c3-aa19-afd787bc47be Jun 02 19:51:25 smsl-n100 volumio[887]: info: Discovery: Found device SMSL N100 Jun 02 19:51:25 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:51:25 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:25 smsl-n100 ntpd[2944]: Soliciting pool server 95.111.202.5 Jun 02 19:51:25 smsl-n100 ntpd[2944]: Soliciting pool server 117.80.112.205 Jun 02 19:51:25 smsl-n100 sudo[3079]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:25 smsl-n100 wireless.js[2999]: Start wireless flow Jun 02 19:51:25 smsl-n100 wireless.js[2999]: Stopped hotspot (if there).. Jun 02 19:51:25 smsl-n100 sudo[3101]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 02 19:51:25 smsl-n100 sudo[3101]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:25 smsl-n100 sudo[3101]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:25 smsl-n100 sudo[3103]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 02 19:51:25 smsl-n100 sudo[3103]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:25 smsl-n100 dhcpcd[967]: wlan0: carrier lost Jun 02 19:51:25 smsl-n100 sudo[3103]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:25 smsl-n100 wireless.js[2999]: DHCP IP Jun 02 19:51:25 smsl-n100 wireless.js[2999]: Start ap Jun 02 19:51:25 smsl-n100 wpa_supplicant[3109]: Successfully initialized wpa_supplicant Jun 02 19:51:25 smsl-n100 sudo[3112]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 02 19:51:25 smsl-n100 sudo[3112]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:25 smsl-n100 dhcpcd[3121]: sending commands to master dhcpcd process Jun 02 19:51:25 smsl-n100 sudo[3112]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:25 smsl-n100 dhcpcd[967]: wlan0: carrier acquired Jun 02 19:51:25 smsl-n100 dhcpcd[967]: wlan0: IAID 2b:ee:c8:89 Jun 02 19:51:25 smsl-n100 dhcpcd[967]: control command: /sbin/dhcpcd Jun 02 19:51:25 smsl-n100 dhcpcd[967]: wlan0: carrier lost Jun 02 19:51:26 smsl-n100 ntpd[2944]: Soliciting pool server 2a01:4f8:c2c:477d::2 Jun 02 19:51:26 smsl-n100 wireless.js[2999]: trying... Jun 02 19:51:26 smsl-n100 sudo[3153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:26 smsl-n100 sudo[3153]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:26 smsl-n100 sudo[3153]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:27 smsl-n100 sudo[3156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 19:51:27 smsl-n100 sudo[3156]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:27 smsl-n100 sudo[3156]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:27 smsl-n100 sudo[3159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:51:27 smsl-n100 sudo[3159]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:27 smsl-n100 sudo[3159]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:27 smsl-n100 wireless.js[2999]: trying... Jun 02 19:51:27 smsl-n100 sudo[3162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 02 19:51:27 smsl-n100 sudo[3162]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:27 smsl-n100 sudo[3164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:27 smsl-n100 sudo[3164]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:27 smsl-n100 sudo[3162]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:27 smsl-n100 sudo[3164]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:27 smsl-n100 volumio[887]: info: Upmpdcli Daemon Started Jun 02 19:51:27 smsl-n100 sudo[3168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 19:51:27 smsl-n100 sudo[3168]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:27 smsl-n100 sudo[3168]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:27 smsl-n100 sudo[3170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:51:27 smsl-n100 sudo[3170]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:27 smsl-n100 sudo[3170]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:27 smsl-n100 sudo[3174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 02 19:51:27 smsl-n100 sudo[3174]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:27 smsl-n100 sudo[3174]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:27 smsl-n100 sudo[3177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 19:51:27 smsl-n100 sudo[3177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:27 smsl-n100 volumio[887]: info: Upmpdcli Daemon Started Jun 02 19:51:27 smsl-n100 sudo[3177]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:27 smsl-n100 sudo[3179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:51:27 smsl-n100 sudo[3179]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:27 smsl-n100 sudo[3179]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:28 smsl-n100 sudo[3183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 02 19:51:28 smsl-n100 sudo[3183]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:28 smsl-n100 sudo[3183]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:28 smsl-n100 volumio[887]: info: Upmpdcli Daemon Started Jun 02 19:51:28 smsl-n100 wireless.js[2999]: trying... Jun 02 19:51:28 smsl-n100 sudo[3189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:28 smsl-n100 sudo[3189]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:28 smsl-n100 sudo[3189]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:29 smsl-n100 volumio[887]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 19:51:29 smsl-n100 wireless.js[2999]: trying... Jun 02 19:51:29 smsl-n100 sudo[3192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:29 smsl-n100 sudo[3192]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:29 smsl-n100 sudo[3192]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:29 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 02 19:51:29 smsl-n100 ntpd[2944]: Soliciting pool server 193.182.111.14 Jun 02 19:51:29 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 02 19:51:29 smsl-n100 sudo[3197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 02 19:51:29 smsl-n100 sudo[3197]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:29 smsl-n100 sudo[3197]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:29 smsl-n100 sudo[3202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 02 19:51:29 smsl-n100 sudo[3202]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:29 smsl-n100 sudo[3202]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:29 smsl-n100 sudo[3210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 02 19:51:29 smsl-n100 sudo[3210]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:30 smsl-n100 sudo[3210]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:30 smsl-n100 sudo[3216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 02 19:51:30 smsl-n100 sudo[3216]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:30 smsl-n100 sudo[3216]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:30 smsl-n100 sudo[3222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 19:51:30 smsl-n100 sudo[3222]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:30 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 02 19:51:30 smsl-n100 sudo[3222]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:30 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 02 19:51:30 smsl-n100 sudo[3226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:51:30 smsl-n100 sudo[3226]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:30 smsl-n100 sudo[3226]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:30 smsl-n100 sudo[3229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 02 19:51:30 smsl-n100 sudo[3229]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:30 smsl-n100 wireless.js[2999]: trying... Jun 02 19:51:30 smsl-n100 sudo[3232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:30 smsl-n100 sudo[3232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:30 smsl-n100 sudo[3232]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:31 smsl-n100 wpa_supplicant[3126]: wlan0: Trying to associate with f4:2a:7d:d8:0f:b9 (SSID='TP-LINK_0FB7' freq=5805 MHz) Jun 02 19:51:31 smsl-n100 wpa_supplicant[3126]: wlan0: Associated with f4:2a:7d:d8:0f:b9 Jun 02 19:51:31 smsl-n100 wpa_supplicant[3126]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 02 19:51:31 smsl-n100 wpa_supplicant[3126]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Jun 02 19:51:31 smsl-n100 sudo[3229]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:31 smsl-n100 wpa_supplicant[3126]: wlan0: WPA: Key negotiation completed with f4:2a:7d:d8:0f:b9 [PTK=CCMP GTK=CCMP] Jun 02 19:51:31 smsl-n100 wpa_supplicant[3126]: wlan0: CTRL-EVENT-CONNECTED - Connection to f4:2a:7d:d8:0f:b9 completed [id=0 id_str=] Jun 02 19:51:31 smsl-n100 dhcpcd[967]: wlan0: carrier acquired Jun 02 19:51:31 smsl-n100 dhcpcd[967]: wlan0: IAID 2b:ee:c8:89 Jun 02 19:51:31 smsl-n100 wireless.js[2999]: trying... Jun 02 19:51:31 smsl-n100 sudo[3238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:31 smsl-n100 sudo[3238]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:31 smsl-n100 sudo[3238]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:31 smsl-n100 dhcpcd[967]: wlan0: soliciting an IPv6 router Jun 02 19:51:32 smsl-n100 dhcpcd[967]: wlan0: soliciting a DHCP lease Jun 02 19:51:32 smsl-n100 dhcpcd[967]: wlan0: offered 192.168.0.12 from 192.168.0.1 Jun 02 19:51:32 smsl-n100 dhcpcd[967]: wlan0: probing address 192.168.0.12/24 Jun 02 19:51:32 smsl-n100 wireless.js[2999]: trying... Jun 02 19:51:32 smsl-n100 sudo[3276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:32 smsl-n100 sudo[3276]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:32 smsl-n100 sudo[3276]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:33 smsl-n100 wireless.js[2999]: trying... Jun 02 19:51:33 smsl-n100 sudo[3279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:33 smsl-n100 sudo[3279]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:33 smsl-n100 sudo[3279]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:34 smsl-n100 wireless.js[2999]: trying... Jun 02 19:51:34 smsl-n100 sudo[3282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:34 smsl-n100 sudo[3282]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:34 smsl-n100 sudo[3282]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:35 smsl-n100 wireless.js[2999]: trying... Jun 02 19:51:35 smsl-n100 sudo[3299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:35 smsl-n100 sudo[3299]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:35 smsl-n100 sudo[3299]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:36 smsl-n100 wireless.js[2999]: trying... Jun 02 19:51:36 smsl-n100 sudo[3302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:36 smsl-n100 sudo[3302]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:36 smsl-n100 sudo[3302]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:37 smsl-n100 wireless.js[2999]: trying... Jun 02 19:51:37 smsl-n100 sudo[3305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:37 smsl-n100 sudo[3305]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:37 smsl-n100 sudo[3305]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:37 smsl-n100 dhcpcd[967]: wlan0: leased 192.168.0.12 for 172800 seconds Jun 02 19:51:37 smsl-n100 dhcpcd[967]: wlan0: adding route to 192.168.0.0/24 Jun 02 19:51:37 smsl-n100 avahi-daemon[570]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.12. Jun 02 19:51:37 smsl-n100 avahi-daemon[570]: New relevant interface wlan0.IPv4 for mDNS. Jun 02 19:51:37 smsl-n100 dhcpcd[967]: wlan0: adding default route via 192.168.0.1 Jun 02 19:51:37 smsl-n100 avahi-daemon[570]: Registering new address record for 192.168.0.12 on wlan0.IPv4. Jun 02 19:51:38 smsl-n100 ntpd[2944]: ntpd exiting on signal 15 (Terminated) Jun 02 19:51:38 smsl-n100 ntpd[2944]: 202.118.1.130 local addr 192.168.0.11 -> Jun 02 19:51:38 smsl-n100 ntpd[2944]: 116.203.151.74 local addr 192.168.0.11 -> Jun 02 19:51:38 smsl-n100 systemd[1]: Stopping Network Time Service... Jun 02 19:51:38 smsl-n100 ntpd[2944]: 108.59.2.24 local addr 192.168.0.11 -> Jun 02 19:51:38 smsl-n100 ntpd[2944]: 211.68.71.26 local addr 192.168.0.11 -> Jun 02 19:51:38 smsl-n100 systemd[1]: ntp.service: Succeeded. Jun 02 19:51:38 smsl-n100 ntpd[2944]: 162.159.200.123 local addr 192.168.0.11 -> Jun 02 19:51:38 smsl-n100 ntpd[2944]: 117.80.112.205 local addr 192.168.0.11 -> Jun 02 19:51:38 smsl-n100 ntpd[2944]: 95.111.202.5 local addr 192.168.0.11 -> Jun 02 19:51:38 smsl-n100 ntpd[2944]: 193.182.111.14 local addr 192.168.0.11 -> Jun 02 19:51:38 smsl-n100 systemd[1]: Stopped Network Time Service. Jun 02 19:51:38 smsl-n100 systemd[1]: Starting Network Time Service... Jun 02 19:51:38 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:51:38 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 02 19:51:38 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:51:38 smsl-n100 ntpd[3340]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 02 19:51:38 smsl-n100 ntpd[3340]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 02 19:51:38 smsl-n100 systemd[1]: Started Network Time Service. Jun 02 19:51:38 smsl-n100 ntpd[3346]: proto: precision = 1.166 usec (-20) Jun 02 19:51:38 smsl-n100 ntpd[3346]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 02 19:51:38 smsl-n100 ntpd[3346]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 02 19:51:38 smsl-n100 ntpd[3346]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 888 days ago Jun 02 19:51:38 smsl-n100 ntpd[3346]: Listen and drop on 0 v6wildcard [::]:123 Jun 02 19:51:38 smsl-n100 ntpd[3346]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 02 19:51:38 smsl-n100 ntpd[3346]: Listen normally on 2 lo 127.0.0.1:123 Jun 02 19:51:38 smsl-n100 ntpd[3346]: Listen normally on 3 eth0 192.168.0.11:123 Jun 02 19:51:38 smsl-n100 ntpd[3346]: Listen normally on 4 wlan0 192.168.0.12:123 Jun 02 19:51:38 smsl-n100 ntpd[3346]: Listening on routing socket on fd #21 for interface updates Jun 02 19:51:38 smsl-n100 ntpd[3346]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 02 19:51:38 smsl-n100 ntpd[3346]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 02 19:51:38 smsl-n100 wireless.js[2999]: trying... Jun 02 19:51:38 smsl-n100 sudo[3352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:38 smsl-n100 sudo[3352]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:38 smsl-n100 sudo[3352]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:38 smsl-n100 wireless.js[2999]: Connected to: ----TP-LINK_0FB7 Jun 02 19:51:38 smsl-n100 wireless.js[2999]: ---- Jun 02 19:51:38 smsl-n100 sudo[3355]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:51:38 smsl-n100 sudo[3355]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:38 smsl-n100 sudo[3355]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:38 smsl-n100 wireless.js[2999]: ... joined AP, wlan0 IPv4 is 192.168.0.12, ipV6 is undefined Jun 02 19:51:38 smsl-n100 wireless.js[2999]: It's done! AP Jun 02 19:51:38 smsl-n100 iwconfig[3359]: Error for wireless request "Set Power Management" (8B2C) : Jun 02 19:51:38 smsl-n100 iwconfig[3359]: SET failed on device wlan0 ; Operation not permitted. Jun 02 19:51:38 smsl-n100 systemd[1]: Started Wireless Services. Jun 02 19:51:38 smsl-n100 sudo[2996]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:38 smsl-n100 volumio[887]: info: Discovery: this is already registered, 6341edbf-d91f-45c3-aa19-afd787bc47be Jun 02 19:51:38 smsl-n100 volumio[887]: info: Discovery: Found device SMSL N100 Jun 02 19:51:38 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:51:38 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:39 smsl-n100 ntpd[3346]: Soliciting pool server 193.182.111.14 Jun 02 19:51:40 smsl-n100 ntpd[3346]: Soliciting pool server 193.182.111.141 Jun 02 19:51:40 smsl-n100 ntpd[3346]: Soliciting pool server 202.118.1.130 Jun 02 19:51:40 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioSeek Jun 02 19:51:40 smsl-n100 volumio[887]: info: CoreStateMachine::seek Jun 02 19:51:40 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:41 smsl-n100 ntpd[3346]: Soliciting pool server 108.59.2.24 Jun 02 19:51:41 smsl-n100 ntpd[3346]: Soliciting pool server 162.159.200.1 Jun 02 19:51:41 smsl-n100 ntpd[3346]: Soliciting pool server 202.118.1.81 Jun 02 19:51:41 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jun 02 19:51:41 smsl-n100 volumio[887]: info: Saving new wireless network Jun 02 19:51:41 smsl-n100 sudo[3362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Jun 02 19:51:41 smsl-n100 sudo[3362]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:41 smsl-n100 sudo[3362]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:42 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 02 19:51:42 smsl-n100 volumio[887]: info: Discovery: Restarting Advertising due to device name change Jun 02 19:51:42 smsl-n100 sudo[3365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 02 19:51:42 smsl-n100 sudo[3365]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:42 smsl-n100 systemd[1]: Stopping Wireless Services... Jun 02 19:51:42 smsl-n100 wpa_supplicant[3126]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f4:2a:7d:d8:0f:b9 reason=3 locally_generated=1 Jun 02 19:51:42 smsl-n100 dhcpcd[967]: wlan0: carrier lost Jun 02 19:51:42 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:51:42 smsl-n100 wpa_supplicant[3126]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jun 02 19:51:42 smsl-n100 avahi-daemon[570]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 02 19:51:42 smsl-n100 avahi-daemon[570]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.12. Jun 02 19:51:42 smsl-n100 avahi-daemon[570]: Withdrawing address record for 192.168.0.12 on wlan0. Jun 02 19:51:42 smsl-n100 wpa_supplicant[3126]: wlan0: CTRL-EVENT-TERMINATING Jun 02 19:51:42 smsl-n100 systemd[1]: wireless.service: Succeeded. Jun 02 19:51:42 smsl-n100 volumio[887]: info: Discovery: A device disappeared from network Jun 02 19:51:42 smsl-n100 systemd[1]: Stopped Wireless Services. Jun 02 19:51:42 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 02 19:51:42 smsl-n100 systemd[1]: Starting Wireless Services... Jun 02 19:51:42 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:51:42 smsl-n100 dhcpcd[967]: wlan0: deleting route to 192.168.0.0/24 Jun 02 19:51:42 smsl-n100 dhcpcd[967]: wlan0: deleting default route via 192.168.0.1 Jun 02 19:51:42 smsl-n100 ntpd[3346]: Soliciting pool server 117.80.112.205 Jun 02 19:51:42 smsl-n100 ntpd[3346]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 02 19:51:43 smsl-n100 volumio[887]: info: Discovery: A device disappeared from network Jun 02 19:51:43 smsl-n100 wireless.js[3376]: Cleaning previous... Jun 02 19:51:43 smsl-n100 sudo[3412]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 02 19:51:43 smsl-n100 sudo[3412]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:43 smsl-n100 sudo[3412]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:43 smsl-n100 sudo[3414]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 02 19:51:43 smsl-n100 sudo[3414]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:43 smsl-n100 ntpd[3346]: Soliciting pool server 240e:980:b65:e900:bd10:673a:3f2a:f0e2 Jun 02 19:51:43 smsl-n100 ntpd[3346]: Deleting interface #4 wlan0, 192.168.0.12#123, interface stats: received=0, sent=0, dropped=0, active_time=5 secs Jun 02 19:51:43 smsl-n100 sudo[3414]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:43 smsl-n100 wireless.js[3376]: Stopped aP Jun 02 19:51:43 smsl-n100 sudo[3421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 02 19:51:43 smsl-n100 sudo[3421]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:43 smsl-n100 dhcpcd[967]: wlan0: carrier acquired Jun 02 19:51:43 smsl-n100 sudo[3421]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:43 smsl-n100 dhcpcd[967]: wlan0: IAID 2b:ee:c8:89 Jun 02 19:51:43 smsl-n100 sudo[3424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 02 19:51:43 smsl-n100 sudo[3424]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:43 smsl-n100 sudo[3424]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:43 smsl-n100 sudo[3431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 02 19:51:43 smsl-n100 sudo[3431]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:43 smsl-n100 sudo[3431]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:43 smsl-n100 sudo[3433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 02 19:51:43 smsl-n100 sudo[3433]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:43 smsl-n100 dhcpcd[967]: wlan0: soliciting an IPv6 router Jun 02 19:51:43 smsl-n100 dhcpcd[967]: wlan0: rebinding lease of 192.168.0.12 Jun 02 19:51:43 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jun 02 19:51:43 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jun 02 19:51:43 smsl-n100 sudo[3438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:51:43 smsl-n100 sudo[3438]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:43 smsl-n100 sudo[3438]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:47 smsl-n100 volumio[887]: info: Discovery: Started advertising with name: SMSL N100 Jun 02 19:51:47 smsl-n100 ntpd[3346]: Soliciting pool server 108.59.2.24 Jun 02 19:51:48 smsl-n100 volumio[887]: info: Discovery: adding 6341edbf-d91f-45c3-aa19-afd787bc47be Jun 02 19:51:48 smsl-n100 volumio[887]: info: Discovery: Found device SMSL N100 Jun 02 19:51:48 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:51:48 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:48 smsl-n100 sudo[3433]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:48 smsl-n100 wireless.js[3376]: Start wireless flow Jun 02 19:51:48 smsl-n100 wireless.js[3376]: Stopped hotspot (if there).. Jun 02 19:51:48 smsl-n100 sudo[3457]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 02 19:51:48 smsl-n100 sudo[3457]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:48 smsl-n100 sudo[3457]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:48 smsl-n100 sudo[3459]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 02 19:51:48 smsl-n100 sudo[3459]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:48 smsl-n100 dhcpcd[967]: wlan0: carrier lost Jun 02 19:51:48 smsl-n100 sudo[3459]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:48 smsl-n100 wireless.js[3376]: DHCP IP Jun 02 19:51:48 smsl-n100 wireless.js[3376]: Start ap Jun 02 19:51:48 smsl-n100 wpa_supplicant[3465]: Successfully initialized wpa_supplicant Jun 02 19:51:48 smsl-n100 sudo[3468]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 02 19:51:48 smsl-n100 sudo[3468]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:48 smsl-n100 dhcpcd[3478]: sending commands to master dhcpcd process Jun 02 19:51:48 smsl-n100 ntpd[3346]: Soliciting pool server 117.80.231.60 Jun 02 19:51:48 smsl-n100 sudo[3468]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:48 smsl-n100 dhcpcd[967]: wlan0: carrier acquired Jun 02 19:51:48 smsl-n100 dhcpcd[967]: wlan0: IAID 2b:ee:c8:89 Jun 02 19:51:48 smsl-n100 dhcpcd[967]: control command: /sbin/dhcpcd Jun 02 19:51:48 smsl-n100 dhcpcd[967]: wlan0: carrier lost Jun 02 19:51:48 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 02 19:51:48 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 02 19:51:48 smsl-n100 volumio[887]: info: Refreshing Cached IP Addresses Jun 02 19:51:49 smsl-n100 wireless.js[3376]: trying... Jun 02 19:51:49 smsl-n100 sudo[3512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 02 19:51:49 smsl-n100 sudo[3512]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:49 smsl-n100 sudo[3514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 19:51:49 smsl-n100 sudo[3516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:51:49 smsl-n100 sudo[3514]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:49 smsl-n100 sudo[3516]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:49 smsl-n100 sudo[3518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:49 smsl-n100 sudo[3518]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:49 smsl-n100 volumio[887]: error: Upnp client error: Error: This socket has been ended by the other party Jun 02 19:51:49 smsl-n100 sudo[3516]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:49 smsl-n100 sudo[3512]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:49 smsl-n100 sudo[3514]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:49 smsl-n100 sudo[3518]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:49 smsl-n100 sudo[3524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 19:51:49 smsl-n100 sudo[3524]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:49 smsl-n100 sudo[3524]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:49 smsl-n100 sudo[3527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:51:49 smsl-n100 sudo[3527]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:49 smsl-n100 sudo[3527]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:49 smsl-n100 volumio[887]: verbose: New Socket.io Connection to 192.168.0.11 from 192.168.0.10 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 7 Jun 02 19:51:49 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 02 19:51:49 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 19:51:49 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 19:51:49 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:51:49 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:49 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 02 19:51:49 smsl-n100 volumio[887]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 19:51:49 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 02 19:51:49 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 02 19:51:49 smsl-n100 volumio[887]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 02 19:51:49 smsl-n100 volumio[887]: info: Received Get System Info Jun 02 19:51:49 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 19:51:49 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 19:51:49 smsl-n100 volumio[887]: info: Discovery: Getting this device information Jun 02 19:51:49 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:51:49 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:49 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 19:51:49 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:51:49 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:49 smsl-n100 volumio[887]: info: Listing playlists Jun 02 19:51:49 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 02 19:51:49 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 02 19:51:49 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 02 19:51:50 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 02 19:51:50 smsl-n100 sudo[3532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 02 19:51:50 smsl-n100 sudo[3532]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:50 smsl-n100 sudo[3532]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:50 smsl-n100 sudo[3537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 02 19:51:50 smsl-n100 sudo[3537]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:50 smsl-n100 sudo[3537]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:50 smsl-n100 sudo[3545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 02 19:51:50 smsl-n100 sudo[3545]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:50 smsl-n100 wireless.js[3376]: trying... Jun 02 19:51:50 smsl-n100 sudo[3545]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:50 smsl-n100 sudo[3551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 02 19:51:50 smsl-n100 sudo[3551]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:50 smsl-n100 sudo[3558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:50 smsl-n100 sudo[3558]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:50 smsl-n100 sudo[3551]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:50 smsl-n100 sudo[3558]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:50 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 02 19:51:50 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 02 19:51:50 smsl-n100 sudo[3559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 19:51:50 smsl-n100 sudo[3559]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:50 smsl-n100 sudo[3559]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:50 smsl-n100 sudo[3563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:51:50 smsl-n100 sudo[3563]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:50 smsl-n100 sudo[3563]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:50 smsl-n100 sudo[3567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 02 19:51:50 smsl-n100 sudo[3567]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:51 smsl-n100 wireless.js[3376]: trying... Jun 02 19:51:51 smsl-n100 sudo[3570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:51 smsl-n100 sudo[3570]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:51 smsl-n100 sudo[3570]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:51 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 19:51:51 smsl-n100 volumio[887]: info: Received Get System Info Jun 02 19:51:51 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 19:51:51 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 19:51:51 smsl-n100 volumio[887]: info: Discovery: Getting this device information Jun 02 19:51:51 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:51:51 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:51:51 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 19:51:51 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 02 19:51:52 smsl-n100 wireless.js[3376]: trying... Jun 02 19:51:52 smsl-n100 sudo[3573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:52 smsl-n100 sudo[3573]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:52 smsl-n100 sudo[3573]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:53 smsl-n100 wireless.js[3376]: trying... Jun 02 19:51:53 smsl-n100 sudo[3576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:53 smsl-n100 sudo[3576]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:53 smsl-n100 sudo[3576]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:53 smsl-n100 wpa_supplicant[3482]: wlan0: Trying to associate with f4:2a:7d:d8:0f:b9 (SSID='TP-LINK_0FB7' freq=5805 MHz) Jun 02 19:51:54 smsl-n100 wpa_supplicant[3482]: wlan0: Associated with f4:2a:7d:d8:0f:b9 Jun 02 19:51:54 smsl-n100 wpa_supplicant[3482]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 02 19:51:54 smsl-n100 wpa_supplicant[3482]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Jun 02 19:51:54 smsl-n100 sudo[3567]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:54 smsl-n100 wireless.js[3376]: trying... Jun 02 19:51:54 smsl-n100 wpa_supplicant[3482]: wlan0: WPA: Key negotiation completed with f4:2a:7d:d8:0f:b9 [PTK=CCMP GTK=CCMP] Jun 02 19:51:54 smsl-n100 wpa_supplicant[3482]: wlan0: CTRL-EVENT-CONNECTED - Connection to f4:2a:7d:d8:0f:b9 completed [id=0 id_str=] Jun 02 19:51:54 smsl-n100 dhcpcd[967]: wlan0: carrier acquired Jun 02 19:51:54 smsl-n100 sudo[3581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:54 smsl-n100 sudo[3581]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:54 smsl-n100 dhcpcd[967]: wlan0: IAID 2b:ee:c8:89 Jun 02 19:51:54 smsl-n100 sudo[3581]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:54 smsl-n100 dhcpcd[967]: wlan0: soliciting an IPv6 router Jun 02 19:51:55 smsl-n100 dhcpcd[967]: wlan0: rebinding lease of 192.168.0.12 Jun 02 19:51:55 smsl-n100 dhcpcd[967]: wlan0: probing address 192.168.0.12/24 Jun 02 19:51:55 smsl-n100 wireless.js[3376]: trying... Jun 02 19:51:55 smsl-n100 sudo[3599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:55 smsl-n100 sudo[3599]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:55 smsl-n100 sudo[3599]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:56 smsl-n100 wireless.js[3376]: trying... Jun 02 19:51:56 smsl-n100 sudo[3602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:56 smsl-n100 sudo[3602]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:56 smsl-n100 sudo[3602]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:57 smsl-n100 wireless.js[3376]: trying... Jun 02 19:51:57 smsl-n100 sudo[3605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:57 smsl-n100 sudo[3605]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:57 smsl-n100 sudo[3605]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:58 smsl-n100 wireless.js[3376]: trying... Jun 02 19:51:58 smsl-n100 sudo[3608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:58 smsl-n100 sudo[3608]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:58 smsl-n100 sudo[3608]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:59 smsl-n100 wireless.js[3376]: trying... Jun 02 19:51:59 smsl-n100 sudo[3614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:51:59 smsl-n100 sudo[3614]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:59 smsl-n100 sudo[3614]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:59 smsl-n100 sudo[3618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 19:51:59 smsl-n100 sudo[3618]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:59 smsl-n100 sudo[3618]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:59 smsl-n100 sudo[3621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:51:59 smsl-n100 sudo[3621]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:59 smsl-n100 sudo[3621]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:59 smsl-n100 sudo[3624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 02 19:51:59 smsl-n100 sudo[3624]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:51:59 smsl-n100 sudo[3624]: pam_unix(sudo:session): session closed for user root Jun 02 19:51:59 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 02 19:51:59 smsl-n100 volumio[887]: info: Upmpdcli Daemon Started Jun 02 19:52:00 smsl-n100 wireless.js[3376]: trying... Jun 02 19:52:00 smsl-n100 sudo[3627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:00 smsl-n100 sudo[3627]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:00 smsl-n100 sudo[3627]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:00 smsl-n100 dhcpcd[967]: wlan0: leased 192.168.0.12 for 172800 seconds Jun 02 19:52:00 smsl-n100 dhcpcd[967]: wlan0: adding route to 192.168.0.0/24 Jun 02 19:52:00 smsl-n100 dhcpcd[967]: wlan0: adding default route via 192.168.0.1 Jun 02 19:52:00 smsl-n100 avahi-daemon[570]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.12. Jun 02 19:52:00 smsl-n100 avahi-daemon[570]: New relevant interface wlan0.IPv4 for mDNS. Jun 02 19:52:00 smsl-n100 avahi-daemon[570]: Registering new address record for 192.168.0.12 on wlan0.IPv4. Jun 02 19:52:00 smsl-n100 ntpd[3346]: ntpd exiting on signal 15 (Terminated) Jun 02 19:52:00 smsl-n100 ntpd[3346]: 193.182.111.14 local addr 192.168.0.11 -> Jun 02 19:52:00 smsl-n100 systemd[1]: Stopping Network Time Service... Jun 02 19:52:00 smsl-n100 ntpd[3346]: 202.118.1.130 local addr 192.168.0.11 -> Jun 02 19:52:00 smsl-n100 ntpd[3346]: 193.182.111.141 local addr 192.168.0.11 -> Jun 02 19:52:00 smsl-n100 systemd[1]: ntp.service: Succeeded. Jun 02 19:52:00 smsl-n100 ntpd[3346]: 162.159.200.1 local addr 192.168.0.11 -> Jun 02 19:52:00 smsl-n100 ntpd[3346]: 202.118.1.81 local addr 192.168.0.11 -> Jun 02 19:52:00 smsl-n100 ntpd[3346]: 117.80.112.205 local addr 192.168.0.11 -> Jun 02 19:52:00 smsl-n100 ntpd[3346]: 108.59.2.24 local addr 192.168.0.11 -> Jun 02 19:52:00 smsl-n100 ntpd[3346]: 117.80.231.60 local addr 192.168.0.11 -> Jun 02 19:52:00 smsl-n100 systemd[1]: Stopped Network Time Service. Jun 02 19:52:00 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:52:00 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 02 19:52:00 smsl-n100 systemd[1]: Starting Network Time Service... Jun 02 19:52:00 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:52:00 smsl-n100 ntpd[3662]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 02 19:52:00 smsl-n100 ntpd[3662]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 02 19:52:00 smsl-n100 systemd[1]: Started Network Time Service. Jun 02 19:52:00 smsl-n100 ntpd[3668]: proto: precision = 1.166 usec (-20) Jun 02 19:52:00 smsl-n100 ntpd[3668]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 02 19:52:00 smsl-n100 ntpd[3668]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 02 19:52:00 smsl-n100 ntpd[3668]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 888 days ago Jun 02 19:52:00 smsl-n100 ntpd[3668]: Listen and drop on 0 v6wildcard [::]:123 Jun 02 19:52:00 smsl-n100 ntpd[3668]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 02 19:52:00 smsl-n100 ntpd[3668]: Listen normally on 2 lo 127.0.0.1:123 Jun 02 19:52:00 smsl-n100 ntpd[3668]: Listen normally on 3 eth0 192.168.0.11:123 Jun 02 19:52:00 smsl-n100 ntpd[3668]: Listen normally on 4 wlan0 192.168.0.12:123 Jun 02 19:52:00 smsl-n100 ntpd[3668]: Listening on routing socket on fd #21 for interface updates Jun 02 19:52:00 smsl-n100 ntpd[3668]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 02 19:52:00 smsl-n100 ntpd[3668]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 02 19:52:01 smsl-n100 wireless.js[3376]: trying... Jun 02 19:52:01 smsl-n100 sudo[3671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:01 smsl-n100 sudo[3671]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:01 smsl-n100 sudo[3671]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:01 smsl-n100 wireless.js[3376]: Connected to: ----TP-LINK_0FB7 Jun 02 19:52:01 smsl-n100 wireless.js[3376]: ---- Jun 02 19:52:01 smsl-n100 sudo[3674]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:52:01 smsl-n100 sudo[3674]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:01 smsl-n100 sudo[3674]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:01 smsl-n100 volumio[887]: info: Discovery: this is already registered, 6341edbf-d91f-45c3-aa19-afd787bc47be Jun 02 19:52:01 smsl-n100 volumio[887]: info: Discovery: Found device SMSL N100 Jun 02 19:52:01 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:52:01 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:01 smsl-n100 wireless.js[3376]: ... joined AP, wlan0 IPv4 is 192.168.0.12, ipV6 is undefined Jun 02 19:52:01 smsl-n100 wireless.js[3376]: It's done! AP Jun 02 19:52:01 smsl-n100 iwconfig[3678]: Error for wireless request "Set Power Management" (8B2C) : Jun 02 19:52:01 smsl-n100 iwconfig[3678]: SET failed on device wlan0 ; Operation not permitted. Jun 02 19:52:01 smsl-n100 systemd[1]: Started Wireless Services. Jun 02 19:52:01 smsl-n100 sudo[3365]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:02 smsl-n100 ntpd[3668]: Soliciting pool server 193.182.111.141 Jun 02 19:52:02 smsl-n100 ntpd[3668]: Soliciting pool server 117.80.231.60 Jun 02 19:52:02 smsl-n100 ntpd[3668]: Soliciting pool server 202.118.1.130 Jun 02 19:52:03 smsl-n100 ntpd[3668]: Soliciting pool server 116.203.151.74 Jun 02 19:52:03 smsl-n100 ntpd[3668]: Soliciting pool server 108.59.2.24 Jun 02 19:52:03 smsl-n100 ntpd[3668]: Soliciting pool server 211.68.71.26 Jun 02 19:52:04 smsl-n100 ntpd[3668]: Soliciting pool server 162.159.200.123 Jun 02 19:52:04 smsl-n100 ntpd[3668]: Soliciting pool server 193.182.111.143 Jun 02 19:52:04 smsl-n100 ntpd[3668]: Soliciting pool server 193.182.111.14 Jun 02 19:52:04 smsl-n100 volumio[887]: info: Reporting MCU Network Status: 3 Jun 02 19:52:04 smsl-n100 volumio[887]: info: Volumio Network Manager: Network status updated: 3 Jun 02 19:52:04 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:04 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:04 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:52:04 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:04 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:04 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:04 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 100, message: undefined Jun 02 19:52:04 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: MAXVOL, message: Jun 02 19:52:04 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 100, message: undefined Jun 02 19:52:04 smsl-n100 volumio[887]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jun 02 19:52:04 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 3, message: undefined Jun 02 19:52:05 smsl-n100 volumio[887]: info: VolumeController::SetAlsaVolume0 Jun 02 19:52:05 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:05 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:05 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:52:05 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:05 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:05 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:05 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:52:05 smsl-n100 ntpd[3668]: Soliciting pool server 193.182.111.12 Jun 02 19:52:05 smsl-n100 ntpd[3668]: Soliciting pool server 117.80.112.205 Jun 02 19:52:05 smsl-n100 ntpd[3668]: Soliciting pool server 162.159.200.1 Jun 02 19:52:06 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jun 02 19:52:06 smsl-n100 volumio[887]: info: Saving new wireless network Jun 02 19:52:06 smsl-n100 sudo[3694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Jun 02 19:52:06 smsl-n100 sudo[3694]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:06 smsl-n100 sudo[3694]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:06 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 02 19:52:06 smsl-n100 volumio[887]: info: Discovery: Restarting Advertising due to device name change Jun 02 19:52:06 smsl-n100 sudo[3697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 02 19:52:06 smsl-n100 sudo[3697]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:06 smsl-n100 systemd[1]: Stopping Wireless Services... Jun 02 19:52:06 smsl-n100 wpa_supplicant[3482]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f4:2a:7d:d8:0f:b9 reason=3 locally_generated=1 Jun 02 19:52:06 smsl-n100 dhcpcd[967]: wlan0: carrier lost Jun 02 19:52:06 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jun 02 19:52:06 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jun 02 19:52:06 smsl-n100 wpa_supplicant[3482]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jun 02 19:52:06 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:52:06 smsl-n100 avahi-daemon[570]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 02 19:52:06 smsl-n100 avahi-daemon[570]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.12. Jun 02 19:52:06 smsl-n100 avahi-daemon[570]: Withdrawing address record for 192.168.0.12 on wlan0. Jun 02 19:52:06 smsl-n100 volumio[887]: info: Discovery: A device disappeared from network Jun 02 19:52:06 smsl-n100 wpa_supplicant[3482]: wlan0: CTRL-EVENT-TERMINATING Jun 02 19:52:06 smsl-n100 dhcpcd[967]: wlan0: deleting route to 192.168.0.0/24 Jun 02 19:52:06 smsl-n100 dhcpcd[967]: wlan0: deleting default route via 192.168.0.1 Jun 02 19:52:06 smsl-n100 systemd[1]: wireless.service: Succeeded. Jun 02 19:52:06 smsl-n100 sudo[3710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:52:06 smsl-n100 systemd[1]: Stopped Wireless Services. Jun 02 19:52:06 smsl-n100 sudo[3710]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:06 smsl-n100 systemd[1]: Starting Wireless Services... Jun 02 19:52:06 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 02 19:52:06 smsl-n100 sudo[3710]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:06 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:52:06 smsl-n100 ntpd[3668]: Soliciting pool server 45.76.221.157 Jun 02 19:52:06 smsl-n100 ntpd[3668]: Soliciting pool server 2001:da8:215:4020::118:123 Jun 02 19:52:07 smsl-n100 volumio[887]: info: Discovery: A device disappeared from network Jun 02 19:52:07 smsl-n100 wireless.js[3718]: Cleaning previous... Jun 02 19:52:07 smsl-n100 sudo[3747]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 02 19:52:07 smsl-n100 sudo[3747]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:07 smsl-n100 sudo[3747]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:07 smsl-n100 sudo[3749]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 02 19:52:07 smsl-n100 sudo[3749]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:07 smsl-n100 sudo[3749]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:07 smsl-n100 wireless.js[3718]: Stopped aP Jun 02 19:52:07 smsl-n100 ntpd[3668]: Deleting interface #4 wlan0, 192.168.0.12#123, interface stats: received=0, sent=0, dropped=0, active_time=7 secs Jun 02 19:52:07 smsl-n100 sudo[3756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 02 19:52:07 smsl-n100 sudo[3756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:07 smsl-n100 dhcpcd[967]: wlan0: carrier acquired Jun 02 19:52:07 smsl-n100 sudo[3756]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:07 smsl-n100 dhcpcd[967]: wlan0: IAID 2b:ee:c8:89 Jun 02 19:52:07 smsl-n100 ntpd[3668]: Soliciting pool server 95.111.202.5 Jun 02 19:52:07 smsl-n100 sudo[3759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 02 19:52:07 smsl-n100 sudo[3759]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:07 smsl-n100 sudo[3759]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:07 smsl-n100 dhcpcd[967]: wlan0: rebinding lease of 192.168.0.12 Jun 02 19:52:07 smsl-n100 sudo[3766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 02 19:52:07 smsl-n100 sudo[3766]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:07 smsl-n100 sudo[3766]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:07 smsl-n100 sudo[3768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 02 19:52:07 smsl-n100 sudo[3768]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:08 smsl-n100 dhcpcd[967]: wlan0: soliciting an IPv6 router Jun 02 19:52:08 smsl-n100 ntpd[3668]: Soliciting pool server 116.203.151.74 Jun 02 19:52:11 smsl-n100 volumio[887]: info: Discovery: Started advertising with name: SMSL N100 Jun 02 19:52:11 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 02 19:52:11 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 02 19:52:11 smsl-n100 volumio[887]: info: Refreshing Cached IP Addresses Jun 02 19:52:11 smsl-n100 sudo[3777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 02 19:52:11 smsl-n100 sudo[3777]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:11 smsl-n100 sudo[3777]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:11 smsl-n100 sudo[3779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 19:52:11 smsl-n100 sudo[3779]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:11 smsl-n100 sudo[3779]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:11 smsl-n100 volumio[887]: error: Upnp client error: Error: This socket has been ended by the other party Jun 02 19:52:11 smsl-n100 sudo[3782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:52:11 smsl-n100 sudo[3782]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:11 smsl-n100 sudo[3782]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:12 smsl-n100 sudo[3786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 19:52:12 smsl-n100 sudo[3786]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:12 smsl-n100 sudo[3786]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:12 smsl-n100 sudo[3789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:52:12 smsl-n100 sudo[3789]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:12 smsl-n100 sudo[3789]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:12 smsl-n100 volumio[887]: info: Discovery: adding 6341edbf-d91f-45c3-aa19-afd787bc47be Jun 02 19:52:12 smsl-n100 volumio[887]: info: Discovery: Found device SMSL N100 Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:52:12 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:12 smsl-n100 volumio[887]: verbose: New Socket.io Connection to 192.168.0.11 from 192.168.0.10 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:52:12 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 19:52:12 smsl-n100 sudo[3768]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 02 19:52:12 smsl-n100 volumio[887]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 02 19:52:12 smsl-n100 volumio[887]: info: Received Get System Info Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 19:52:12 smsl-n100 volumio[887]: info: Discovery: Getting this device information Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:52:12 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:52:12 smsl-n100 wireless.js[3718]: Start wireless flow Jun 02 19:52:12 smsl-n100 wireless.js[3718]: Stopped hotspot (if there).. Jun 02 19:52:12 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:12 smsl-n100 volumio[887]: info: Listing playlists Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 02 19:52:12 smsl-n100 sudo[3795]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 02 19:52:12 smsl-n100 sudo[3795]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 02 19:52:12 smsl-n100 sudo[3795]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:12 smsl-n100 sudo[3797]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 02 19:52:12 smsl-n100 sudo[3797]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:12 smsl-n100 dhcpcd[967]: wlan0: carrier lost Jun 02 19:52:12 smsl-n100 sudo[3797]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:12 smsl-n100 wireless.js[3718]: DHCP IP Jun 02 19:52:12 smsl-n100 wireless.js[3718]: Start ap Jun 02 19:52:12 smsl-n100 wpa_supplicant[3803]: Successfully initialized wpa_supplicant Jun 02 19:52:12 smsl-n100 sudo[3806]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 02 19:52:12 smsl-n100 sudo[3806]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:12 smsl-n100 dhcpcd[3816]: sending commands to master dhcpcd process Jun 02 19:52:12 smsl-n100 sudo[3806]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:12 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 02 19:52:12 smsl-n100 dhcpcd[967]: wlan0: carrier acquired Jun 02 19:52:12 smsl-n100 dhcpcd[967]: wlan0: IAID 2b:ee:c8:89 Jun 02 19:52:13 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 02 19:52:13 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 02 19:52:13 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:13 smsl-n100 dhcpcd[967]: control command: /sbin/dhcpcd Jun 02 19:52:13 smsl-n100 dhcpcd[967]: wlan0: carrier lost Jun 02 19:52:13 smsl-n100 sudo[3830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 02 19:52:13 smsl-n100 sudo[3830]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:13 smsl-n100 sudo[3855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:52:13 smsl-n100 sudo[3838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 02 19:52:13 smsl-n100 sudo[3855]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:13 smsl-n100 sudo[3838]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:13 smsl-n100 sudo[3830]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:13 smsl-n100 sudo[3848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 02 19:52:13 smsl-n100 sudo[3844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 02 19:52:13 smsl-n100 sudo[3848]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:13 smsl-n100 sudo[3844]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:13 smsl-n100 sudo[3848]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:13 smsl-n100 sudo[3857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 02 19:52:13 smsl-n100 sudo[3838]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:13 smsl-n100 sudo[3844]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:13 smsl-n100 sudo[3859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:13 smsl-n100 sudo[3857]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:13 smsl-n100 sudo[3853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 19:52:13 smsl-n100 sudo[3859]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:13 smsl-n100 sudo[3853]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:13 smsl-n100 sudo[3855]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:13 smsl-n100 sudo[3859]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:13 smsl-n100 sudo[3853]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:14 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 19:52:14 smsl-n100 volumio[887]: info: Received Get System Info Jun 02 19:52:14 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 19:52:14 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 19:52:14 smsl-n100 volumio[887]: info: Discovery: Getting this device information Jun 02 19:52:14 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:52:14 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:14 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 19:52:14 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 02 19:52:14 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:14 smsl-n100 sudo[3885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:14 smsl-n100 sudo[3885]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:14 smsl-n100 sudo[3885]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:15 smsl-n100 volumio[887]: info: Reporting MCU Network Status: 1 Jun 02 19:52:15 smsl-n100 volumio[887]: info: Volumio Network Manager: Network status updated: 1 Jun 02 19:52:15 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:15 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:15 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:52:15 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:15 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:15 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:15 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 100, message: undefined Jun 02 19:52:15 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: MAXVOL, message: Jun 02 19:52:15 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 100, message: undefined Jun 02 19:52:15 smsl-n100 volumio[887]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jun 02 19:52:15 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 1, message: undefined Jun 02 19:52:15 smsl-n100 volumio[887]: info: VolumeController::SetAlsaVolume0 Jun 02 19:52:15 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:15 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:15 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:52:15 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:15 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:15 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:15 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:52:15 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:15 smsl-n100 sudo[3902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:15 smsl-n100 sudo[3902]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:15 smsl-n100 sudo[3902]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:16 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:16 smsl-n100 sudo[3905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:16 smsl-n100 sudo[3905]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:16 smsl-n100 sudo[3905]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:16 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetQueue Jun 02 19:52:16 smsl-n100 volumio[887]: info: CoreStateMachine::getQueue Jun 02 19:52:16 smsl-n100 volumio[887]: info: CorePlayQueue::getQueue Jun 02 19:52:17 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:17 smsl-n100 sudo[3908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:17 smsl-n100 sudo[3908]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:17 smsl-n100 sudo[3908]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:18 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 02 19:52:18 smsl-n100 volumio[887]: info: CURURI: music-library Jun 02 19:52:18 smsl-n100 wpa_supplicant[3820]: wlan0: Trying to associate with f4:2a:7d:d8:0f:b9 (SSID='TP-LINK_0FB7' freq=5805 MHz) Jun 02 19:52:18 smsl-n100 volumio[887]: info: Preload queue cleared Jun 02 19:52:18 smsl-n100 wpa_supplicant[3820]: wlan0: Associated with f4:2a:7d:d8:0f:b9 Jun 02 19:52:18 smsl-n100 wpa_supplicant[3820]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 02 19:52:18 smsl-n100 sudo[3857]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:18 smsl-n100 wpa_supplicant[3820]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Jun 02 19:52:18 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:18 smsl-n100 sudo[3916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:18 smsl-n100 sudo[3916]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:18 smsl-n100 sudo[3916]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:19 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:19 smsl-n100 sudo[3920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:19 smsl-n100 sudo[3920]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:19 smsl-n100 sudo[3920]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:20 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jun 02 19:52:20 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand update Jun 02 19:52:20 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jun 02 19:52:20 smsl-n100 volumio[887]: info: Jun 02 19:52:20 smsl-n100 volumio[887]: ---------------------------- MPD announces state update: update Jun 02 19:52:20 smsl-n100 volumio[887]: info: sendMpdCommand update took 20 milliseconds Jun 02 19:52:20 smsl-n100 volumio[887]: info: ControllerMpd::getState Jun 02 19:52:20 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jun 02 19:52:20 smsl-n100 volumio[887]: info: sendMpdCommand status took 19 milliseconds Jun 02 19:52:20 smsl-n100 volumio[887]: info: sendMpdCommand status took 7 milliseconds Jun 02 19:52:20 smsl-n100 volumio[887]: verbose: ControllerMpd::parseState Jun 02 19:52:20 smsl-n100 volumio[887]: info: Command Router : Notfying DB Updatetrue Jun 02 19:52:20 smsl-n100 volumio[887]: verbose: ControllerMpd::parseState Jun 02 19:52:20 smsl-n100 volumio[887]: info: ControllerMpd::pushState Jun 02 19:52:20 smsl-n100 volumio[887]: info: CoreCommandRouter::servicePushState Jun 02 19:52:20 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:20 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:20 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:20 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:20 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:20 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:20 smsl-n100 volumio[887]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 02 19:52:20 smsl-n100 volumio[887]: verbose: CURRENT POSITION 0 Jun 02 19:52:20 smsl-n100 volumio[887]: info: CoreStateMachine::syncState stateService stop Jun 02 19:52:20 smsl-n100 volumio[887]: info: CoreStateMachine::syncState currentStatus stop Jun 02 19:52:20 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:20 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:20 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:52:20 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:20 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:20 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:20 smsl-n100 volumio[887]: info: No code Jun 02 19:52:20 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:20 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:20 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:20 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:20 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:20 smsl-n100 volumio[887]: info: ------------------------------ 231ms Jun 02 19:52:20 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:52:20 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:52:20 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:52:20 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:20 smsl-n100 sudo[3926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:20 smsl-n100 sudo[3926]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:20 smsl-n100 sudo[3926]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:21 smsl-n100 volumio[887]: info: MPD Database updated - AlbumList cache refreshed Jun 02 19:52:21 smsl-n100 volumio[887]: info: Jun 02 19:52:21 smsl-n100 volumio[887]: ---------------------------- MPD announces state update: database Jun 02 19:52:21 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jun 02 19:52:21 smsl-n100 volumio[887]: info: Jun 02 19:52:21 smsl-n100 volumio[887]: ---------------------------- MPD announces state update: update Jun 02 19:52:21 smsl-n100 volumio[887]: info: ControllerMpd::getState Jun 02 19:52:21 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jun 02 19:52:21 smsl-n100 volumio[887]: info: ControllerMpd::getState Jun 02 19:52:21 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jun 02 19:52:21 smsl-n100 volumio[887]: info: sendMpdCommand status took 21 milliseconds Jun 02 19:52:21 smsl-n100 volumio[887]: info: sendMpdCommand status took 18 milliseconds Jun 02 19:52:21 smsl-n100 volumio[887]: info: sendMpdCommand status took 17 milliseconds Jun 02 19:52:21 smsl-n100 volumio[887]: verbose: ControllerMpd::parseState Jun 02 19:52:21 smsl-n100 volumio[887]: info: Command Router : Notfying DB Updatefalse Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreCommandRouter::Close All Modals sent Jun 02 19:52:21 smsl-n100 volumio[887]: verbose: ControllerMpd::parseState Jun 02 19:52:21 smsl-n100 volumio[887]: verbose: ControllerMpd::parseState Jun 02 19:52:21 smsl-n100 volumio[887]: info: ControllerMpd::pushState Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreCommandRouter::servicePushState Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:21 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:21 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:21 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:21 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:21 smsl-n100 volumio[887]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 02 19:52:21 smsl-n100 volumio[887]: verbose: CURRENT POSITION 0 Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreStateMachine::syncState stateService stop Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreStateMachine::syncState currentStatus stop Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:21 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:21 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:21 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:21 smsl-n100 volumio[887]: info: No code Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:21 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:21 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:21 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:21 smsl-n100 volumio[887]: info: ControllerMpd::pushState Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreCommandRouter::servicePushState Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:21 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:21 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:21 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:21 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:21 smsl-n100 volumio[887]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 02 19:52:21 smsl-n100 volumio[887]: verbose: CURRENT POSITION 0 Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreStateMachine::syncState stateService stop Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreStateMachine::syncState currentStatus stop Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:21 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:21 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:21 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:21 smsl-n100 volumio[887]: info: No code Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:21 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:21 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:21 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:21 smsl-n100 volumio[887]: info: ------------------------------ 284ms Jun 02 19:52:21 smsl-n100 volumio[887]: info: ------------------------------ 285ms Jun 02 19:52:21 smsl-n100 volumio[887]: info: CoreCommandRouter::Close All Modals sent Jun 02 19:52:21 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:52:21 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:52:21 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:52:21 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:52:21 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:52:21 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:52:21 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:21 smsl-n100 wpa_supplicant[3820]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f4:2a:7d:d8:0f:b9 reason=15 Jun 02 19:52:21 smsl-n100 wpa_supplicant[3820]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect Jun 02 19:52:21 smsl-n100 wpa_supplicant[3820]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="TP-LINK_0FB7" auth_failures=1 duration=10 reason=WRONG_KEY Jun 02 19:52:21 smsl-n100 wpa_supplicant[3820]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 02 19:52:21 smsl-n100 wpa_supplicant[3820]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Jun 02 19:52:21 smsl-n100 sudo[3931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:21 smsl-n100 sudo[3931]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:21 smsl-n100 sudo[3931]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:21 smsl-n100 sudo[3933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 19:52:21 smsl-n100 sudo[3933]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:21 smsl-n100 sudo[3933]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:21 smsl-n100 sudo[3936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:52:21 smsl-n100 sudo[3936]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:21 smsl-n100 sudo[3936]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:21 smsl-n100 sudo[3940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 02 19:52:21 smsl-n100 sudo[3940]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:21 smsl-n100 sudo[3940]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:21 smsl-n100 volumio[887]: info: Upmpdcli Daemon Started Jun 02 19:52:22 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 02 19:52:22 smsl-n100 volumio[887]: info: CURURI: music-library/USB Jun 02 19:52:22 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 02 19:52:22 smsl-n100 volumio[887]: info: Preload queue cleared Jun 02 19:52:22 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:22 smsl-n100 sudo[3943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:22 smsl-n100 sudo[3943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:22 smsl-n100 sudo[3943]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:23 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:23 smsl-n100 sudo[3946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:23 smsl-n100 sudo[3946]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:23 smsl-n100 sudo[3946]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:23 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 02 19:52:23 smsl-n100 volumio[887]: info: CURURI: music-library/USB/kingston Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preload queue cleared Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/kingston/013_DSD64 天使.dff Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/kingston/014_DSD128 5.6 MHZ兄弟抱一下.dsf Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/kingston/015_7.dff Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/kingston/016_DSD512.dff Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/kingston/019_SW_滚滚红尘.wav Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/kingston/02左右声道测试.wav Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/kingston/DJ - 中英文沈阳精心打造酒吧嗨曲House慢摇串烧 DJ欧文 - 2017年2月收录.mp3 Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/kingston/検品用.mp3 Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/kingston/错位星空.flac Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/kingston/03 - 甜蜜蜜.flac Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/kingston/草蜢 - 半点心 [mqms2].flac Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/kingston/DSD128 勇气.dsf Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/kingston/杨培安 - 爱上你是一个错 [mqms2].flac Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/kingston/梁静茹 - 勇气.mp3 Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/kingston/01 - 千千闕歌.flac Jun 02 19:52:23 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/kingston/周传雄 - 青花 [mqms2].flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/013_DSD64 天使.dff in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F013_DSD64%20%E5%A4%A9%E4%BD%BF.dff&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/013_DSD64 天使.dff Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/014_DSD128 5.6 MHZ兄弟抱一下.dsf in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F014_DSD128%205.6%20MHZ%E5%85%84%E5%BC%9F%E6%8A%B1%E4%B8%80%E4%B8%8B.dsf&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/014_DSD128 5.6 MHZ兄弟抱一下.dsf Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/015_7.dff in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F015_7.dff&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/015_7.dff Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/016_DSD512.dff in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F016_DSD512.dff&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/016_DSD512.dff Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/019_SW_滚滚红尘.wav in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F019_SW_%E6%BB%9A%E6%BB%9A%E7%BA%A2%E5%B0%98.wav&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/019_SW_滚滚红尘.wav Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/02左右声道测试.wav in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F02%E5%B7%A6%E5%8F%B3%E5%A3%B0%E9%81%93%E6%B5%8B%E8%AF%95.wav&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/02左右声道测试.wav Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/DJ - 中英文沈阳精心打造酒吧嗨曲House慢摇串烧 DJ欧文 - 2017年2月收录.mp3 in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2FDJ%20-%20%E4%B8%AD%E8%8B%B1%E6%96%87%E6%B2%88%E9%98%B3%E7%B2%BE%E5%BF%83%E6%89%93%E9%80%A0%E9%85%92%E5%90%A7%E5%97%A8%E6%9B%B2House%E6%85%A2%E6%91%87%E4%B8%B2%E7%83%A7%20DJ%E6%AC%A7%E6%96%87%20-%202017%E5%B9%B42%E6%9C%88%E6%94%B6%E5%BD%95.mp3&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/DJ - 中英文沈阳精心打造酒吧嗨曲House慢摇串烧 DJ欧文 - 2017年2月收录.mp3 Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/検品用.mp3 in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=Takeshi%20Susumu//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%A4%9C%E5%93%81%E7%94%A8.mp3&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/検品用.mp3 Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/错位星空.flac in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E9%94%99%E4%BD%8D%E6%98%9F%E7%A9%BA.flac&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/错位星空.flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/03 - 甜蜜蜜.flac in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%E9%84%A7%E9%BA%97%E5%90%9B/15%E9%80%B1%E5%B9%B4%20%5BMQS%5D/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F03%20-%20%E7%94%9C%E8%9C%9C%E8%9C%9C.flac&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/03 - 甜蜜蜜.flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/草蜢 - 半点心 [mqms2].flac in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%E8%8D%89%E8%9C%A2/Grasshopper%20IV/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E8%8D%89%E8%9C%A2%20-%20%E5%8D%8A%E7%82%B9%E5%BF%83%20%5Bmqms2%5D.flac&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/草蜢 - 半点心 [mqms2].flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/DSD128 勇气.dsf in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%E6%A2%81%E9%9D%99%E8%8C%B9/%E5%8B%87%E6%B0%94/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2FDSD128%20%E5%8B%87%E6%B0%94.dsf&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/DSD128 勇气.dsf Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/杨培安 - 爱上你是一个错 [mqms2].flac in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%E6%9D%A8%E5%9F%B9%E5%AE%89/%E5%8D%95%E8%BA%AB%E5%BF%85%E5%90%AC%E6%83%85%E6%AD%8C/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%9D%A8%E5%9F%B9%E5%AE%89%20-%20%E7%88%B1%E4%B8%8A%E4%BD%A0%E6%98%AF%E4%B8%80%E4%B8%AA%E9%94%99%20%5Bmqms2%5D.flac&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/杨培安 - 爱上你是一个错 [mqms2].flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/梁静茹 - 勇气.mp3 in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%E6%A2%81%E9%9D%99%E8%8C%B9/%E5%9B%BD%E8%AF%AD%E7%BB%8F%E5%85%B8/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%A2%81%E9%9D%99%E8%8C%B9%20-%20%E5%8B%87%E6%B0%94.mp3&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/梁静茹 - 勇气.mp3 Jun 02 19:52:24 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:24 smsl-n100 sudo[3949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:24 smsl-n100 sudo[3949]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:24 smsl-n100 volumio[887]: info: Preload queue cleared Jun 02 19:52:24 smsl-n100 sudo[3949]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreStateMachine::ClearQueue Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreStateMachine::stop Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 19:52:24 smsl-n100 volumio[887]: info: CorePlayQueue::clearPlayQueue Jun 02 19:52:24 smsl-n100 volumio[887]: info: CorePlayQueue::saveQueue Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushQueue Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreStateMachine::addQueueItems Jun 02 19:52:24 smsl-n100 volumio[887]: info: CorePlayQueue::addQueueItems Jun 02 19:52:24 smsl-n100 volumio[887]: info: Preload queue cleared Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/013_DSD64 天使.dff Jun 02 19:52:24 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/kingston/013_DSD64 天使.dff Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushQueue Jun 02 19:52:24 smsl-n100 volumio[887]: info: CorePlayQueue::saveQueue Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreStateMachine::updateTrackBlock Jun 02 19:52:24 smsl-n100 volumio[887]: info: CorePlayQueue::getTrackBlock Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPlay Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreStateMachine::play index 0 Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreStateMachine::addQueueItems Jun 02 19:52:24 smsl-n100 volumio[887]: info: CorePlayQueue::addQueueItems Jun 02 19:52:24 smsl-n100 volumio[887]: info: Preload queue cleared Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/014_DSD128 5.6 MHZ兄弟抱一下.dsf Jun 02 19:52:24 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/kingston/014_DSD128 5.6 MHZ兄弟抱一下.dsf Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/015_7.dff Jun 02 19:52:24 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/kingston/015_7.dff Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/016_DSD512.dff Jun 02 19:52:24 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/kingston/016_DSD512.dff Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/019_SW_滚滚红尘.wav Jun 02 19:52:24 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/kingston/019_SW_滚滚红尘.wav Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/02左右声道测试.wav Jun 02 19:52:24 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/kingston/02左右声道测试.wav Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/DJ - 中英文沈阳精心打造酒吧嗨曲House慢摇串烧 DJ欧文 - 2017年2月收录.mp3 Jun 02 19:52:24 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/kingston/DJ - 中英文沈阳精心打造酒吧嗨曲House慢摇串烧 DJ欧文 - 2017年2月收录.mp3 Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/検品用.mp3 Jun 02 19:52:24 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/kingston/検品用.mp3 Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/错位星空.flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/kingston/错位星空.flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/03 - 甜蜜蜜.flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/kingston/03 - 甜蜜蜜.flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/草蜢 - 半点心 [mqms2].flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/kingston/草蜢 - 半点心 [mqms2].flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/DSD128 勇气.dsf Jun 02 19:52:24 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/kingston/DSD128 勇气.dsf Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/杨培安 - 爱上你是一个错 [mqms2].flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/kingston/杨培安 - 爱上你是一个错 [mqms2].flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/梁静茹 - 勇气.mp3 Jun 02 19:52:24 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/kingston/梁静茹 - 勇气.mp3 Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/01 - 千千闕歌.flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/01 - 千千闕歌.flac in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/周传雄 - 青花 [mqms2].flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/周传雄 - 青花 [mqms2].flac in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/kingston/測試曲目 Jun 02 19:52:24 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/kingston/測試曲目 in service mpd Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreStateMachine::stop Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreStateMachine::play index undefined Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 19:52:24 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreStateMachine::startPlaybackTimer Jun 02 19:52:24 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 19:52:24 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 02 19:52:24 smsl-n100 volumio[887]: verbose: ControllerMpd::clearAddPlayTracks USB/kingston/013_DSD64 天使.dff Jun 02 19:52:24 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand stop Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%E9%99%B3%E6%85%A7%E5%AB%BB/%E6%B0%B8%E9%81%A0%E6%98%AF%E4%BD%A0%E7%9A%84%E9%99%B3%E6%85%A7%E5%AB%BB%20%5B%E7%89%B9%E5%88%A5%E7%89%88%5D%20%5BMQS%5D/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F01%20-%20%E5%8D%83%E5%8D%83%E9%97%95%E6%AD%8C.flac&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/01 - 千千闕歌.flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%E5%91%A8%E4%BC%A0%E9%9B%84/%E8%93%9D%E8%89%B2%E5%9C%9F%E8%80%B3%E5%85%B6/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E5%91%A8%E4%BC%A0%E9%9B%84%20-%20%E9%9D%92%E8%8A%B1%20%5Bmqms2%5D.flac&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/周传雄 - 青花 [mqms2].flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F010_384k_PerfectTest.wav&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/010_384k_PerfectTest.wav Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%C2%8F%C2%88%C3%89%C3%98%C2%BA%C2%AD/Ang%205.0/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F011_705.6k_32bit.wav&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/011_705.6k_32bit.wav Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F012_768k%E6%9C%88%E4%BA%AE%E6%83%B9%E7%9A%84%E7%A5%B8-32bit.wav&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/012_768k月亮惹的祸-32bit.wav Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F013_DSD64%20%E5%A4%A9%E4%BD%BF.dff&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/013_DSD64 天使.dff Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%E5%BA%9E%E9%BE%99/%E7%BE%8E%E5%A5%BD%20%E5%AE%98%E6%96%B9%E6%AD%A3%E5%BC%8F%E7%89%88/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F014_DSD128%205.6%20MHZ%E5%85%84%E5%BC%9F%E6%8A%B1%E4%B8%80%E4%B8%8B.dsf&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/014_DSD128 5.6 MHZ兄弟抱一下.dsf Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F015_7.dff&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/015_7.dff Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F016_DSD512.dff&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/016_DSD512.dff Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=Marianne%20Thorsen%20%26%20TrondheimSolistene/MOZART%20Violin%20Concertos%20(MQA%20remix%202016)/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F017-MQA-Lan_352800_stereo.bluemqa.flac&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/017-MQA-Lan_352800_stereo.bluemqa.flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F017_MQA_Lan_Original%20Rate%20352800.blue.mqa.flac&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/017_MQA_Lan_Original Rate 352800.blue.mqa.flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F018_MQA_Lu_Original%20Rate%2044100.green.mqa.flac&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/018_MQA_Lu_Original Rate 44100.green.mqa.flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=Hoff%20Ensemble%3A%20Jan%20Gunnar%20Hoff%2C%20Audun%20Kleive%20%26%20Anders%20Jormin/POLARITY%20%E2%80%94%20an%20acoustic%20jazz%20project/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F018_MQA_Lu_stereo.mqacd%2044100.green.mqa.flac&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/018_MQA_Lu_stereo.mqacd 44100.green.mqa.flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=Various%20Artists/Apache/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F019_SW-%20Tatanka.mp3&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/019_SW- Tatanka.mp3 Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F019_SW_%E6%BB%9A%E6%BB%9A%E7%BA%A2%E5%B0%98.wav&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/019_SW_滚滚红尘.wav Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%3F%3F%3F%3F/%3F%3F%20%3F%3F%3F%20%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F020_Yinliangzhayin%20%E5%91%AA%E3%82%8F%E3%82%8C%E3%81%9F%E7%A5%9E%E5%B7%9E%E5%B9%B3%E5%8E%9F.wav&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/020_Yinliangzhayin 呪われた神州平原.wav Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F020_yinliangzhayin%20%E6%AD%B3%E6%9C%88-%E9%9B%B2%E6%B5%81%E3%82%8C-.wav&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/020_yinliangzhayin 歳月-雲流れ-.wav Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F02%E5%B7%A6%E5%8F%B3%E5%A3%B0%E9%81%93%E6%B5%8B%E8%AF%95.wav&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/02左右声道测试.wav Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F03_44k_Rachmaninov_%20Symphony%20%232%20In%20E%20Min.flac&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/03_44k_Rachmaninov_ Symphony #2 In E Min.flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F04_48k_PerfectTest.wav&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/04_48k_PerfectTest.wav Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%E9%83%91%E5%B0%91%E7%A7%8B/%E9%83%91%E5%B0%91%E7%A7%8B-%E5%A8%B1%E4%B9%90SACD%E7%BB%8F%E5%85%B8/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F05%20-88k_%20%E7%AC%91%E7%9C%8B%E9%A3%8E%E4%BA%91.flac&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/05 -88k_ 笑看风云.flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%E8%91%89%E5%80%A9%E6%96%87/SACD%20COLLECTION%EF%BC%88%E9%99%90%E9%87%8F%E7%89%88%EF%BC%89/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F05_88k_%E7%84%9A%E5%BF%83%E4%BC%BC%E7%81%AB%EF%BC%88%E5%9C%8B%E8%AA%9E%E7%89%88%EF%BC%89.flac&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/05_88k_焚心似火（國語版）.flac Jun 02 19:52:24 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F06_96k_PerfectTest.wav&metadata=false Jun 02 19:52:24 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/06_96k_PerfectTest.wav Jun 02 19:52:25 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F07_176k_PerfectTest.wav&metadata=false Jun 02 19:52:25 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/07_176k_PerfectTest.wav Jun 02 19:52:25 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F08_192k_PerfectTest.wav&metadata=false Jun 02 19:52:25 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/08_192k_PerfectTest.wav Jun 02 19:52:25 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F09_352k_PerfectTest.wav&metadata=false Jun 02 19:52:25 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/09_352k_PerfectTest.wav Jun 02 19:52:25 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=Engeg%C3%A5rd%20Quartet/STRING%20QUARTETS%20vol.%20I%20Haydn%20-%20Solberg%20-%20Grieg/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F09_352k_%E7%BA%A6%E7%91%9F%E5%A4%AB%E6%B5%B7%E9%A1%BF%EF%BC%9AD%E5%BC%A6%E4%B9%90%E5%9B%9B%E9%87%8D%E5%A5%8F%EF%BC%8C%E4%BD%9C%E5%93%81%E3%80%82%E7%AC%AC%E4%BA%94%E5%90%8D%20-%20Finale%20-%202L-053_04_stereo-DXD.flac&metadata=false Jun 02 19:52:25 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/09_352k_约瑟夫海顿：D弦乐四重奏，作品。第五名 - Finale - 2L-053_04_stereo-DXD.flac Jun 02 19:52:25 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=MQA%20Ltd/000%20UI/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F22_384K-Fenhong-MAGENTA%20037%20Original%20Rate%20384000%2024bit.flac&metadata=false Jun 02 19:52:25 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/22_384K-Fenhong-MAGENTA 037 Original Rate 384000 24bit.flac Jun 02 19:52:25 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2FDJ%20-%20%E4%B8%AD%E8%8B%B1%E6%96%87%E6%B2%88%E9%98%B3%E7%B2%BE%E5%BF%83%E6%89%93%E9%80%A0%E9%85%92%E5%90%A7%E5%97%A8%E6%9B%B2House%E6%85%A2%E6%91%87%E4%B8%B2%E7%83%A7%20DJ%E6%AC%A7%E6%96%87%20-%202017%E5%B9%B42%E6%9C%88%E6%94%B6%E5%BD%95.mp3&metadata=false Jun 02 19:52:25 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/DJ - 中英文沈阳精心打造酒吧嗨曲House慢摇串烧 DJ欧文 - 2017年2月收录.mp3 Jun 02 19:52:25 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%E6%A2%81%E9%9D%99%E8%8C%B9/%E5%8B%87%E6%B0%94/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2FDSD128%20%E5%8B%87%E6%B0%94.dsf&metadata=false Jun 02 19:52:25 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/DSD128 勇气.dsf Jun 02 19:52:25 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=MQA%20Ltd/000%20UI/extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2FMAGENTA%20037%20Original%20Rate%20384000%2024bit.flac&metadata=false Jun 02 19:52:25 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/MAGENTA 037 Original Rate 384000 24bit.flac Jun 02 19:52:25 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=Takeshi%20Susumu//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F%E6%A4%9C%E5%93%81%E7%94%A8.mp3&metadata=false Jun 02 19:52:25 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/検品用.mp3 Jun 02 19:52:25 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2Fkingston%2F%E6%B8%AC%E8%A9%A6%E6%9B%B2%E7%9B%AE%2F%E9%94%99%E4%BD%8D%E6%98%9F%E7%A9%BA.flac&metadata=false Jun 02 19:52:25 smsl-n100 volumio[887]: info: URI /mnt/USB/kingston/測試曲目/错位星空.flac Jun 02 19:52:25 smsl-n100 volumio[887]: info: sendMpdCommand stop took 93 milliseconds Jun 02 19:52:25 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand clear Jun 02 19:52:25 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushQueue Jun 02 19:52:25 smsl-n100 volumio[887]: info: CorePlayQueue::saveQueue Jun 02 19:52:25 smsl-n100 volumio[887]: info: CoreStateMachine::updateTrackBlock Jun 02 19:52:25 smsl-n100 volumio[887]: info: CorePlayQueue::getTrackBlock Jun 02 19:52:25 smsl-n100 volumio[887]: info: Jun 02 19:52:25 smsl-n100 volumio[887]: ---------------------------- MPD announces system playlist update Jun 02 19:52:25 smsl-n100 volumio[887]: info: Ignoring MPD Status Update Jun 02 19:52:25 smsl-n100 volumio[887]: info: sendMpdCommand clear took 92 milliseconds Jun 02 19:52:25 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand add "USB/kingston/013_DSD64 天使.dff" Jun 02 19:52:25 smsl-n100 volumio[887]: error: updateQueue error: null Jun 02 19:52:25 smsl-n100 volumio[887]: info: Jun 02 19:52:25 smsl-n100 volumio[887]: ---------------------------- MPD announces system playlist update Jun 02 19:52:25 smsl-n100 volumio[887]: info: Ignoring MPD Status Update Jun 02 19:52:25 smsl-n100 volumio[887]: info: ------------------------------ 21ms Jun 02 19:52:25 smsl-n100 volumio[887]: info: sendMpdCommand add "USB/kingston/013_DSD64 天使.dff" took 15 milliseconds Jun 02 19:52:25 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand play Jun 02 19:52:25 smsl-n100 volumio[887]: info: ------------------------------ 16ms Jun 02 19:52:25 smsl-n100 volumio[887]: info: sendMpdCommand play took 27 milliseconds Jun 02 19:52:25 smsl-n100 volumio[887]: info: Jun 02 19:52:25 smsl-n100 volumio[887]: ---------------------------- MPD announces state update: player Jun 02 19:52:25 smsl-n100 volumio[887]: info: ControllerMpd::getState Jun 02 19:52:25 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jun 02 19:52:25 smsl-n100 volumio[887]: info: Jun 02 19:52:25 smsl-n100 volumio[887]: ---------------------------- MPD announces state update: player Jun 02 19:52:25 smsl-n100 volumio[887]: info: sendMpdCommand status took 14 milliseconds Jun 02 19:52:25 smsl-n100 volumio[887]: info: ControllerMpd::getState Jun 02 19:52:25 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jun 02 19:52:25 smsl-n100 volumio[887]: verbose: ControllerMpd::parseState Jun 02 19:52:25 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 19:52:25 smsl-n100 volumio[887]: info: sendMpdCommand status took 17 milliseconds Jun 02 19:52:25 smsl-n100 volumio[887]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 02 19:52:25 smsl-n100 volumio[887]: verbose: ControllerMpd::parseState Jun 02 19:52:25 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 19:52:25 smsl-n100 volumio[887]: verbose: ControllerMpd::parseTrackInfo Jun 02 19:52:25 smsl-n100 volumio[887]: info: ControllerMpd::pushState Jun 02 19:52:25 smsl-n100 volumio[887]: info: CoreCommandRouter::servicePushState Jun 02 19:52:25 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:25 smsl-n100 volumio[887]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"013_DSD64 天使.dff","artist":null,"album":null,"uri":"USB/kingston/013_DSD64 天使.dff","trackType":"dff"} Jun 02 19:52:25 smsl-n100 volumio[887]: verbose: CURRENT POSITION 0 Jun 02 19:52:25 smsl-n100 volumio[887]: info: CoreStateMachine::syncState stateService play Jun 02 19:52:25 smsl-n100 volumio[887]: info: CoreStateMachine::syncState currentStatus stop Jun 02 19:52:25 smsl-n100 volumio[887]: info: ------------------------------ 78ms Jun 02 19:52:25 smsl-n100 volumio[887]: info: sendMpdCommand playlistinfo took 75 milliseconds Jun 02 19:52:25 smsl-n100 volumio[887]: verbose: ControllerMpd::parseTrackInfo Jun 02 19:52:25 smsl-n100 volumio[887]: info: ControllerMpd::pushState Jun 02 19:52:25 smsl-n100 volumio[887]: info: CoreCommandRouter::servicePushState Jun 02 19:52:25 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:25 smsl-n100 volumio[887]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"013_DSD64 天使.dff","artist":null,"album":null,"uri":"USB/kingston/013_DSD64 天使.dff","trackType":"dff"} Jun 02 19:52:25 smsl-n100 volumio[887]: verbose: CURRENT POSITION 0 Jun 02 19:52:25 smsl-n100 volumio[887]: info: CoreStateMachine::syncState stateService play Jun 02 19:52:25 smsl-n100 volumio[887]: info: CoreStateMachine::syncState currentStatus play Jun 02 19:52:25 smsl-n100 volumio[887]: info: Received an update from plugin. extracting info from payload Jun 02 19:52:25 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:25 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:25 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:52:25 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:25 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:25 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:25 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:25 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:25 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:25 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:25 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:25 smsl-n100 volumio[887]: info: ------------------------------ 343ms Jun 02 19:52:25 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:25 smsl-n100 sudo[3972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:25 smsl-n100 sudo[3972]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:25 smsl-n100 sudo[3972]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:25 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:52:25 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:52:26 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:26 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:26 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:52:26 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:26 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:26 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:26 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 100, message: undefined Jun 02 19:52:26 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: MAXVOL, message: Jun 02 19:52:26 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 100, message: undefined Jun 02 19:52:26 smsl-n100 volumio[887]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jun 02 19:52:26 smsl-n100 volumio[887]: info: VolumeController::SetAlsaVolume0 Jun 02 19:52:26 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:26 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:26 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:52:26 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:26 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:26 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:26 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:52:26 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:26 smsl-n100 sudo[3975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:26 smsl-n100 sudo[3975]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:26 smsl-n100 sudo[3975]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:27 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:27 smsl-n100 sudo[3978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:27 smsl-n100 sudo[3978]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:27 smsl-n100 sudo[3978]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:28 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:28 smsl-n100 sudo[3984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:28 smsl-n100 sudo[3984]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:28 smsl-n100 sudo[3984]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:29 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 02 19:52:29 smsl-n100 volumio[887]: info: CURURI: music-library/USB/KINGSTON Jun 02 19:52:29 smsl-n100 volumio[887]: info: Preload queue cleared Jun 02 19:52:29 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/KINGSTON/(9) 768k.wav Jun 02 19:52:29 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/KINGSTON/左右声道测试.wav Jun 02 19:52:29 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/KINGSTON/63372200381090822.flac Jun 02 19:52:29 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/KINGSTON/16 - 焚心似火（國語版）.flac Jun 02 19:52:29 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/KINGSTON/梁静茹 - 勇气.mp3 Jun 02 19:52:29 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/KINGSTON/月半小夜曲.flac Jun 02 19:52:29 smsl-n100 volumio[887]: info: Preloading song: music-library/USB/KINGSTON/01 - 千千闕歌.flac Jun 02 19:52:29 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/KINGSTON/(9) 768k.wav in service mpd Jun 02 19:52:29 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2F(9)%20768k.wav&metadata=false Jun 02 19:52:29 smsl-n100 volumio[887]: info: URI /mnt/USB/KINGSTON/(9) 768k.wav Jun 02 19:52:29 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/KINGSTON/左右声道测试.wav in service mpd Jun 02 19:52:29 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2F%E5%B7%A6%E5%8F%B3%E5%A3%B0%E9%81%93%E6%B5%8B%E8%AF%95.wav&metadata=false Jun 02 19:52:29 smsl-n100 volumio[887]: info: URI /mnt/USB/KINGSTON/左右声道测试.wav Jun 02 19:52:29 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/KINGSTON/63372200381090822.flac in service mpd Jun 02 19:52:29 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2F63372200381090822.flac&metadata=false Jun 02 19:52:29 smsl-n100 volumio[887]: info: URI /mnt/USB/KINGSTON/63372200381090822.flac Jun 02 19:52:29 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/KINGSTON/16 - 焚心似火（國語版）.flac in service mpd Jun 02 19:52:29 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%E8%91%89%E5%80%A9%E6%96%87/SACD%20COLLECTION%EF%BC%88%E9%99%90%E9%87%8F%E7%89%88%EF%BC%89/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2F16%20-%20%E7%84%9A%E5%BF%83%E4%BC%BC%E7%81%AB%EF%BC%88%E5%9C%8B%E8%AA%9E%E7%89%88%EF%BC%89.flac&metadata=false Jun 02 19:52:29 smsl-n100 volumio[887]: info: URI /mnt/USB/KINGSTON/16 - 焚心似火（國語版）.flac Jun 02 19:52:29 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/KINGSTON/梁静茹 - 勇气.mp3 in service mpd Jun 02 19:52:29 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%E6%A2%81%E9%9D%99%E8%8C%B9/%E5%9B%BD%E8%AF%AD%E7%BB%8F%E5%85%B8/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2F%E6%A2%81%E9%9D%99%E8%8C%B9%20-%20%E5%8B%87%E6%B0%94.mp3&metadata=false Jun 02 19:52:29 smsl-n100 volumio[887]: info: URI /mnt/USB/KINGSTON/梁静茹 - 勇气.mp3 Jun 02 19:52:29 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/KINGSTON/月半小夜曲.flac in service mpd Jun 02 19:52:29 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%E6%9D%8E%E5%85%8B%E5%8B%A4/%E6%97%A0%E6%8D%9F/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2F%E6%9C%88%E5%8D%8A%E5%B0%8F%E5%A4%9C%E6%9B%B2.flac&metadata=false Jun 02 19:52:29 smsl-n100 volumio[887]: info: URI /mnt/USB/KINGSTON/月半小夜曲.flac Jun 02 19:52:29 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/KINGSTON/01 - 千千闕歌.flac in service mpd Jun 02 19:52:29 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=%E9%99%B3%E6%85%A7%E5%AB%BB/%E6%B0%B8%E9%81%A0%E6%98%AF%E4%BD%A0%E7%9A%84%E9%99%B3%E6%85%A7%E5%AB%BB%20%5B%E7%89%B9%E5%88%A5%E7%89%88%5D%20%5BMQS%5D/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2F01%20-%20%E5%8D%83%E5%8D%83%E9%97%95%E6%AD%8C.flac&metadata=false Jun 02 19:52:29 smsl-n100 volumio[887]: info: URI /mnt/USB/KINGSTON/01 - 千千闕歌.flac Jun 02 19:52:29 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:29 smsl-n100 sudo[3987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:29 smsl-n100 sudo[3987]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:29 smsl-n100 sudo[3987]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:30 smsl-n100 volumio[887]: info: Preload queue cleared Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::ClearQueue Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::stop Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::stPlaybackTimer Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::updateTrackBlock Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::getTrackBlock Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:30 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:30 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::serviceStop Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 0 Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::serviceStop Jun 02 19:52:30 smsl-n100 volumio[887]: info: ControllerMpd::stop Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand stop Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::clearPlayQueue Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::saveQueue Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushQueue Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::addQueueItems Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::addQueueItems Jun 02 19:52:30 smsl-n100 volumio[887]: info: Preload queue cleared Jun 02 19:52:30 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/KINGSTON/(9) 768k.wav Jun 02 19:52:30 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/KINGSTON/(9) 768k.wav Jun 02 19:52:30 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/KINGSTON/左右声道测试.wav Jun 02 19:52:30 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/KINGSTON/左右声道测试.wav Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushQueue Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::saveQueue Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::updateTrackBlock Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::getTrackBlock Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPlay Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::play index 1 Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::addQueueItems Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::addQueueItems Jun 02 19:52:30 smsl-n100 volumio[887]: info: Preload queue cleared Jun 02 19:52:30 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/KINGSTON/63372200381090822.flac Jun 02 19:52:30 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/KINGSTON/63372200381090822.flac Jun 02 19:52:30 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/KINGSTON/16 - 焚心似火（國語版）.flac Jun 02 19:52:30 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/KINGSTON/16 - 焚心似火（國語版）.flac Jun 02 19:52:30 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/KINGSTON/梁静茹 - 勇气.mp3 Jun 02 19:52:30 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/KINGSTON/梁静茹 - 勇气.mp3 Jun 02 19:52:30 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/KINGSTON/月半小夜曲.flac Jun 02 19:52:30 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/KINGSTON/月半小夜曲.flac Jun 02 19:52:30 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/KINGSTON/01 - 千千闕歌.flac Jun 02 19:52:30 smsl-n100 volumio[887]: info: Using cached record of: music-library/USB/KINGSTON/01 - 千千闕歌.flac Jun 02 19:52:30 smsl-n100 volumio[887]: info: Adding Item to queue: music-library/USB/KINGSTON/测试曲目 Jun 02 19:52:30 smsl-n100 volumio[887]: info: Exploding uri music-library/USB/KINGSTON/测试曲目 in service mpd Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::stop Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::play index undefined Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 1 Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::startPlaybackTimer Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 1 Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/左右声道测试.wav Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand stop Jun 02 19:52:30 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:52:30 smsl-n100 volumio[887]: info: Jun 02 19:52:30 smsl-n100 volumio[887]: ---------------------------- MPD announces state update: player Jun 02 19:52:30 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2F%E6%B5%8B%E8%AF%95%E6%9B%B2%E7%9B%AE%2F18_256%20ClariS%20-%20%E3%83%AB%E3%83%9F%E3%83%8A%E3%82%B9%20%5BDSD256-11.2MHz%5D.dff&metadata=false Jun 02 19:52:30 smsl-n100 volumio[887]: info: URI /mnt/USB/KINGSTON/测试曲目/18_256 ClariS - ルミナス [DSD256-11.2MHz].dff Jun 02 19:52:30 smsl-n100 volumio[887]: info: ALBUMART /albumart?cacheid=187&web=//extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2F%E6%B5%8B%E8%AF%95%E6%9B%B2%E7%9B%AE%2F%E5%B7%A6%E5%8F%B3%E5%A3%B0%E9%81%93%E6%B5%8B%E8%AF%95.wav&metadata=false Jun 02 19:52:30 smsl-n100 volumio[887]: info: URI /mnt/USB/KINGSTON/测试曲目/左右声道测试.wav Jun 02 19:52:30 smsl-n100 volumio[887]: info: sendMpdCommand stop took 112 milliseconds Jun 02 19:52:30 smsl-n100 volumio[887]: info: ControllerMpd::getState Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jun 02 19:52:30 smsl-n100 volumio[887]: info: sendMpdCommand stop took 38 milliseconds Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand clear Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushQueue Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::saveQueue Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::updateTrackBlock Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::getTrackBlock Jun 02 19:52:30 smsl-n100 volumio[887]: info: Jun 02 19:52:30 smsl-n100 volumio[887]: ---------------------------- MPD announces system playlist update Jun 02 19:52:30 smsl-n100 volumio[887]: info: Ignoring MPD Status Update Jun 02 19:52:30 smsl-n100 volumio[887]: info: sendMpdCommand status took 44 milliseconds Jun 02 19:52:30 smsl-n100 volumio[887]: info: sendMpdCommand clear took 41 milliseconds Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::parseState Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/左右声道测试.wav" Jun 02 19:52:30 smsl-n100 volumio[887]: error: updateQueue error: null Jun 02 19:52:30 smsl-n100 volumio[887]: info: Jun 02 19:52:30 smsl-n100 volumio[887]: ---------------------------- MPD announces system playlist update Jun 02 19:52:30 smsl-n100 volumio[887]: info: Ignoring MPD Status Update Jun 02 19:52:30 smsl-n100 volumio[887]: info: ------------------------------ 28ms Jun 02 19:52:30 smsl-n100 volumio[887]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 02 19:52:30 smsl-n100 volumio[887]: info: sendMpdCommand add "USB/KINGSTON/左右声道测试.wav" took 16 milliseconds Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::parseTrackInfo Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand play Jun 02 19:52:30 smsl-n100 volumio[887]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 02 19:52:30 smsl-n100 volumio[887]: info: ------------------------------ 79ms Jun 02 19:52:30 smsl-n100 volumio[887]: info: Jun 02 19:52:30 smsl-n100 volumio[887]: ---------------------------- MPD announces state update: player Jun 02 19:52:30 smsl-n100 volumio[887]: info: ------------------------------ 23ms Jun 02 19:52:30 smsl-n100 volumio[887]: info: sendMpdCommand play took 16 milliseconds Jun 02 19:52:30 smsl-n100 volumio[887]: info: ControllerMpd::getState Jun 02 19:52:30 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jun 02 19:52:30 smsl-n100 volumio[887]: info: Jun 02 19:52:30 smsl-n100 volumio[887]: ---------------------------- MPD announces state update: player Jun 02 19:52:30 smsl-n100 volumio[887]: info: ControllerMpd::getState Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 1 Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:30 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:30 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:30 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 100, message: undefined Jun 02 19:52:30 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: MAXVOL, message: Jun 02 19:52:30 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 100, message: undefined Jun 02 19:52:30 smsl-n100 volumio[887]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jun 02 19:52:30 smsl-n100 sudo[3990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:30 smsl-n100 sudo[3990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:30 smsl-n100 sudo[3990]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:30 smsl-n100 volumio[887]: info: sendMpdCommand status took 78 milliseconds Jun 02 19:52:30 smsl-n100 volumio[887]: info: sendMpdCommand status took 69 milliseconds Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::parseState Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::parseState Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 19:52:30 smsl-n100 volumio[887]: info: sendMpdCommand playlistinfo took 27 milliseconds Jun 02 19:52:30 smsl-n100 volumio[887]: info: sendMpdCommand playlistinfo took 26 milliseconds Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::parseTrackInfo Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: ControllerMpd::parseTrackInfo Jun 02 19:52:30 smsl-n100 volumio[887]: info: ControllerMpd::pushState Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::servicePushState Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 1 Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"左右声道测试.wav","artist":null,"album":null,"uri":"USB/KINGSTON/左右声道测试.wav","trackType":"wav"} Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: CURRENT POSITION 1 Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::syncState stateService play Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::syncState currentStatus stop Jun 02 19:52:30 smsl-n100 volumio[887]: info: ControllerMpd::pushState Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::servicePushState Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 1 Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"左右声道测试.wav","artist":null,"album":null,"uri":"USB/KINGSTON/左右声道测试.wav","trackType":"wav"} Jun 02 19:52:30 smsl-n100 volumio[887]: verbose: CURRENT POSITION 1 Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::syncState stateService play Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::syncState currentStatus play Jun 02 19:52:30 smsl-n100 volumio[887]: info: Received an update from plugin. extracting info from payload Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 1 Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:30 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:30 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:30 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 1 Jun 02 19:52:30 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:30 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:30 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:30 smsl-n100 volumio[887]: info: ------------------------------ 172ms Jun 02 19:52:30 smsl-n100 volumio[887]: info: ------------------------------ 192ms Jun 02 19:52:31 smsl-n100 volumio[887]: info: VolumeController::SetAlsaVolume0 Jun 02 19:52:31 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:31 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 1 Jun 02 19:52:31 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:52:31 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:31 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:31 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:31 smsl-n100 volumio[887]: info: Updating RAAT Signal Path Jun 02 19:52:31 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:31 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 1 Jun 02 19:52:31 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:52:31 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:31 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:31 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:31 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 100, message: undefined Jun 02 19:52:31 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: MAXVOL, message: Jun 02 19:52:31 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 100, message: undefined Jun 02 19:52:31 smsl-n100 volumio[887]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jun 02 19:52:31 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:32 smsl-n100 sudo[3993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:32 smsl-n100 sudo[3993]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:32 smsl-n100 sudo[3993]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:32 smsl-n100 wireless.js[3718]: trying... Jun 02 19:52:32 smsl-n100 sudo[4031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 02 19:52:32 smsl-n100 sudo[4031]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:32 smsl-n100 sudo[4031]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:33 smsl-n100 wireless.js[3718]: Overtime, starting plan B Jun 02 19:52:33 smsl-n100 iwconfig[4035]: Error for wireless request "Set Power Management" (8B2C) : Jun 02 19:52:33 smsl-n100 iwconfig[4035]: SET failed on device wlan0 ; Operation not permitted. Jun 02 19:52:33 smsl-n100 systemd[1]: Started Wireless Services. Jun 02 19:52:33 smsl-n100 sudo[3697]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:34 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Jun 02 19:52:34 smsl-n100 dhcpcd[967]: eth0: carrier lost Jun 02 19:52:34 smsl-n100 dhcpcd[978]: eth0: carrier lost Jun 02 19:52:34 smsl-n100 avahi-daemon[570]: Withdrawing address record for 192.168.0.11 on eth0. Jun 02 19:52:34 smsl-n100 avahi-daemon[570]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.11. Jun 02 19:52:34 smsl-n100 avahi-daemon[570]: Interface eth0.IPv4 no longer relevant for mDNS. Jun 02 19:52:34 smsl-n100 volumio[887]: info: Discovery: A device disappeared from network Jun 02 19:52:34 smsl-n100 dhcpcd[967]: eth0: deleting route to 192.168.0.0/24 Jun 02 19:52:34 smsl-n100 dhcpcd[967]: eth0: deleting default route via 192.168.0.1 Jun 02 19:52:34 smsl-n100 dhcpcd[978]: eth0: deleting route to 192.168.0.0/24 Jun 02 19:52:34 smsl-n100 dhcpcd[978]: eth0: deleting default route via 192.168.0.1 Jun 02 19:52:34 smsl-n100 ifplugd(eth0)[767]: Link beat lost. Jun 02 19:52:35 smsl-n100 volumio[887]: info: Reporting MCU Network Status: 0 Jun 02 19:52:35 smsl-n100 volumio[887]: info: Volumio Network Manager: Network status updated: 0 Jun 02 19:52:35 smsl-n100 volumio[887]: info: CoreStateMachine::pushState Jun 02 19:52:35 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 1 Jun 02 19:52:35 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 19:52:35 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioPushState Jun 02 19:52:35 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output update for this device Jun 02 19:52:35 smsl-n100 volumio[887]: info: MRS: Pushing multiroomSync output Jun 02 19:52:35 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 100, message: undefined Jun 02 19:52:35 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: MAXVOL, message: Jun 02 19:52:35 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 100, message: undefined Jun 02 19:52:35 smsl-n100 volumio[887]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jun 02 19:52:35 smsl-n100 volumio[887]: error: Serial API: Failed to decode command: 0, message: undefined Jun 02 19:52:35 smsl-n100 ntpd[3668]: Deleting interface #3 eth0, 192.168.0.11#123, interface stats: received=83, sent=99, dropped=0, active_time=35 secs Jun 02 19:52:35 smsl-n100 ntpd[3668]: 116.203.151.74 local addr 192.168.0.11 -> Jun 02 19:52:35 smsl-n100 ntpd[3668]: 95.111.202.5 local addr 192.168.0.11 -> Jun 02 19:52:35 smsl-n100 ntpd[3668]: 45.76.221.157 local addr 192.168.0.11 -> Jun 02 19:52:35 smsl-n100 ntpd[3668]: 193.182.111.12 local addr 192.168.0.11 -> Jun 02 19:52:35 smsl-n100 ntpd[3668]: 162.159.200.1 local addr 192.168.0.11 -> Jun 02 19:52:35 smsl-n100 ntpd[3668]: 117.80.112.205 local addr 192.168.0.11 -> Jun 02 19:52:35 smsl-n100 ntpd[3668]: 193.182.111.14 local addr 192.168.0.11 -> Jun 02 19:52:35 smsl-n100 ntpd[3668]: 162.159.200.123 local addr 192.168.0.11 -> Jun 02 19:52:35 smsl-n100 ntpd[3668]: 193.182.111.143 local addr 192.168.0.11 -> Jun 02 19:52:35 smsl-n100 ntpd[3668]: 108.59.2.24 local addr 192.168.0.11 -> Jun 02 19:52:35 smsl-n100 ntpd[3668]: 211.68.71.26 local addr 192.168.0.11 -> Jun 02 19:52:35 smsl-n100 ntpd[3668]: 202.118.1.130 local addr 192.168.0.11 -> Jun 02 19:52:35 smsl-n100 ntpd[3668]: 117.80.231.60 local addr 192.168.0.11 -> Jun 02 19:52:35 smsl-n100 ntpd[3668]: 193.182.111.141 local addr 192.168.0.11 -> Jun 02 19:52:37 smsl-n100 wpa_supplicant[3820]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="TP-LINK_0FB7" Jun 02 19:52:37 smsl-n100 wpa_supplicant[3820]: wlan0: Trying to associate with f4:2a:7d:d8:0f:b7 (SSID='TP-LINK_0FB7' freq=2462 MHz) Jun 02 19:52:37 smsl-n100 wpa_supplicant[3820]: wlan0: CTRL-EVENT-ASSOC-REJECT status_code=1 Jun 02 19:52:37 smsl-n100 wpa_supplicant[3820]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 02 19:52:37 smsl-n100 wpa_supplicant[3820]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Jun 02 19:52:38 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jun 02 19:52:38 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jun 02 19:52:38 smsl-n100 sudo[4106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:52:38 smsl-n100 sudo[4106]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:38 smsl-n100 sudo[4106]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:41 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Jun 02 19:52:41 smsl-n100 dhcpcd[978]: eth0: carrier acquired Jun 02 19:52:41 smsl-n100 dhcpcd[967]: eth0: carrier acquired Jun 02 19:52:41 smsl-n100 dhcpcd[978]: eth0: IAID 01:e1:97:06 Jun 02 19:52:41 smsl-n100 dhcpcd[967]: eth0: IAID 01:e1:97:06 Jun 02 19:52:41 smsl-n100 ifplugd(eth0)[767]: Link beat detected. Jun 02 19:52:41 smsl-n100 dhcpcd[967]: eth0: soliciting an IPv6 router Jun 02 19:52:42 smsl-n100 wpa_supplicant[3820]: wlan0: Trying to associate with f4:2a:7d:d8:0f:b9 (SSID='TP-LINK_0FB7' freq=5805 MHz) Jun 02 19:52:42 smsl-n100 dhcpcd[978]: eth0: rebinding lease of 192.168.0.11 Jun 02 19:52:42 smsl-n100 wpa_supplicant[3820]: wlan0: Associated with f4:2a:7d:d8:0f:b9 Jun 02 19:52:42 smsl-n100 wpa_supplicant[3820]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 02 19:52:42 smsl-n100 dhcpcd[978]: eth0: probing address 192.168.0.11/24 Jun 02 19:52:42 smsl-n100 wpa_supplicant[3820]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Jun 02 19:52:42 smsl-n100 dhcpcd[978]: eth0: soliciting an IPv6 router Jun 02 19:52:42 smsl-n100 dhcpcd[967]: eth0: rebinding lease of 192.168.0.11 Jun 02 19:52:42 smsl-n100 dhcpcd[967]: eth0: probing address 192.168.0.11/24 Jun 02 19:52:43 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 02 19:52:43 smsl-n100 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 02 19:52:43 smsl-n100 volumio[887]: info: Refreshing Cached IP Addresses Jun 02 19:52:43 smsl-n100 sudo[4114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 02 19:52:43 smsl-n100 sudo[4114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:43 smsl-n100 sudo[4114]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:43 smsl-n100 sudo[4117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 19:52:43 smsl-n100 sudo[4117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:43 smsl-n100 sudo[4117]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:43 smsl-n100 volumio[887]: error: Upnp client error: Error: This socket has been ended by the other party Jun 02 19:52:43 smsl-n100 sudo[4120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 19:52:43 smsl-n100 sudo[4120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 19:52:43 smsl-n100 sudo[4120]: pam_unix(sudo:session): session closed for user root Jun 02 19:52:45 smsl-n100 wpa_supplicant[3820]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f4:2a:7d:d8:0f:b9 reason=15 Jun 02 19:52:45 smsl-n100 wpa_supplicant[3820]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect Jun 02 19:52:45 smsl-n100 wpa_supplicant[3820]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="TP-LINK_0FB7" auth_failures=2 duration=23 reason=WRONG_KEY Jun 02 19:52:45 smsl-n100 wpa_supplicant[3820]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 02 19:52:45 smsl-n100 wpa_supplicant[3820]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Jun 02 19:52:46 smsl-n100 dhcpcd[967]: eth0: leased 192.168.0.11 for 172800 seconds Jun 02 19:52:46 smsl-n100 dhcpcd[967]: eth0: adding route to 192.168.0.0/24 Jun 02 19:52:46 smsl-n100 dhcpcd[967]: eth0: adding default route via 192.168.0.1 Jun 02 19:52:46 smsl-n100 avahi-daemon[570]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.11. Jun 02 19:52:46 smsl-n100 avahi-daemon[570]: New relevant interface eth0.IPv4 for mDNS. Jun 02 19:52:46 smsl-n100 avahi-daemon[570]: Registering new address record for 192.168.0.11 on eth0.IPv4. Jun 02 19:52:46 smsl-n100 dhcpcd[978]: eth0: leased 192.168.0.11 for 172800 seconds Jun 02 19:52:46 smsl-n100 dhcpcd[978]: eth0: adding route to 192.168.0.0/24 Jun 02 19:52:46 smsl-n100 dhcpcd[978]: eth0: adding default route via 192.168.0.1 Jun 02 19:52:46 smsl-n100 volumio[887]: verbose: New Socket.io Connection to 192.168.0.11 from 192.168.0.10 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 Jun 02 19:52:47 smsl-n100 ntpd[3668]: ntpd exiting on signal 15 (Terminated) Jun 02 19:52:47 smsl-n100 systemd[1]: Stopping Network Time Service... Jun 02 19:52:47 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:52:47 smsl-n100 systemd[1]: ntp.service: Succeeded. Jun 02 19:52:47 smsl-n100 systemd[1]: Stopped Network Time Service. Jun 02 19:52:47 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 02 19:52:47 smsl-n100 systemd[1]: Starting Network Time Service... Jun 02 19:52:47 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:52:47 smsl-n100 ntpd[4196]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 02 19:52:47 smsl-n100 ntpd[4196]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 02 19:52:47 smsl-n100 ntpd[4204]: proto: precision = 1.166 usec (-20) Jun 02 19:52:47 smsl-n100 ntpd[4204]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 02 19:52:47 smsl-n100 ntpd[4204]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 02 19:52:47 smsl-n100 ntpd[4204]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 888 days ago Jun 02 19:52:47 smsl-n100 ntpd[4204]: Listen and drop on 0 v6wildcard [::]:123 Jun 02 19:52:47 smsl-n100 ntpd[4204]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 02 19:52:47 smsl-n100 ntpd[4204]: Listen normally on 2 lo 127.0.0.1:123 Jun 02 19:52:47 smsl-n100 systemd[1]: Started Network Time Service. Jun 02 19:52:47 smsl-n100 ntpd[4204]: Listen normally on 3 eth0 192.168.0.11:123 Jun 02 19:52:47 smsl-n100 ntpd[4204]: Listening on routing socket on fd #20 for interface updates Jun 02 19:52:47 smsl-n100 ntpd[4204]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 02 19:52:47 smsl-n100 ntpd[4204]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 02 19:52:47 smsl-n100 ntpd[4204]: ntpd exiting on signal 15 (Terminated) Jun 02 19:52:47 smsl-n100 systemd[1]: Stopping Network Time Service... Jun 02 19:52:47 smsl-n100 systemd[1]: ntp.service: Succeeded. Jun 02 19:52:47 smsl-n100 systemd[1]: Stopped Network Time Service. Jun 02 19:52:47 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:52:47 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 02 19:52:47 smsl-n100 systemd[1]: Starting Network Time Service... Jun 02 19:52:47 smsl-n100 volumio[887]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 19:52:47 smsl-n100 ntpd[4208]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 02 19:52:47 smsl-n100 ntpd[4208]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 02 19:52:47 smsl-n100 systemd[1]: Started Network Time Service. Jun 02 19:52:47 smsl-n100 ntpd[4214]: proto: precision = 0.875 usec (-20) Jun 02 19:52:47 smsl-n100 ntpd[4214]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 02 19:52:47 smsl-n100 ntpd[4214]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 02 19:52:47 smsl-n100 ntpd[4214]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 888 days ago Jun 02 19:52:47 smsl-n100 ntpd[4214]: Listen and drop on 0 v6wildcard [::]:123 Jun 02 19:52:47 smsl-n100 ntpd[4214]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 02 19:52:47 smsl-n100 ntpd[4214]: Listen normally on 2 lo 127.0.0.1:123 Jun 02 19:52:47 smsl-n100 ntpd[4214]: Listen normally on 3 eth0 192.168.0.11:123 Jun 02 19:52:47 smsl-n100 ntpd[4214]: Listening on routing socket on fd #20 for interface updates Jun 02 19:52:47 smsl-n100 ntpd[4214]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 02 19:52:47 smsl-n100 ntpd[4214]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 02 19:52:47 smsl-n100 volumio[887]: info: Discovery: adding 6341edbf-d91f-45c3-aa19-afd787bc47be Jun 02 19:52:47 smsl-n100 volumio[887]: info: Discovery: Found device SMSL N100 Jun 02 19:52:47 smsl-n100 volumio[887]: info: CoreCommandRouter::volumioGetState Jun 02 19:52:47 smsl-n100 volumio[887]: info: CorePlayQueue::getTrack 1 Jun 02 19:52:47 smsl-n100 volumio[887]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 19:52:47 smsl-n100 volumio[887]: Error: The value NaN is not a number Jun 02 19:52:47 smsl-n100 volumio[887]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Jun 02 19:52:47 smsl-n100 volumio[887]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Jun 02 19:52:47 smsl-n100 volumio[887]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Jun 02 19:52:47 smsl-n100 volumio[887]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Jun 02 19:52:47 smsl-n100 volumio[887]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Jun 02 19:52:47 smsl-n100 volumio[887]: at Browser.emit (events.js:400:28) Jun 02 19:52:47 smsl-n100 volumio[887]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jun 02 19:52:47 smsl-n100 volumio[887]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jun 02 19:52:47 smsl-n100 volumio[887]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Jun 02 19:52:47 smsl-n100 volumio[887]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Jun 02 19:52:47 smsl-n100 volumio[887]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Jun 02 19:52:47 smsl-n100 volumio[887]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Jun 02 19:52:47 smsl-n100 volumio[887]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Jun 02 19:52:47 smsl-n100 volumio[887]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 19:52:48 smsl-n100 ntpd[4214]: Soliciting pool server 202.118.1.81 Jun 02 19:52:48 smsl-n100 sudo[4228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-02 19:51 Jun 02 19:52:48 smsl-n100 sudo[4228]: 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="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"