-- Logs begin at Thu 2024-11-21 10:14:17 CST, end at Thu 2024-11-21 10:21:56 CST. -- Nov 21 10:20:03 volumio ntpd[3716]: Soliciting pool server 162.159.200.123 Nov 21 10:20:03 volumio ntpd[3716]: Soliciting pool server 202.112.31.197 Nov 21 10:20:39 volumio wpa_supplicant[1111]: wlan0: CTRL-EVENT-DISCONNECTED bssid=44:df:65:5f:2f:92 reason=0 locally_generated=1 Nov 21 10:20:39 volumio dhcpcd[913]: wlan0: carrier lost Nov 21 10:20:39 volumio wpa_supplicant[1111]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Nov 21 10:20:39 volumio wpa_supplicant[1111]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Nov 21 10:20:39 volumio avahi-daemon[767]: Withdrawing address record for 192.168.1.122 on wlan0. Nov 21 10:20:39 volumio avahi-daemon[767]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.122. Nov 21 10:20:39 volumio avahi-daemon[767]: Interface wlan0.IPv4 no longer relevant for mDNS. Nov 21 10:20:39 volumio volumio[1217]: info: Discovery: A device disappeared from network Nov 21 10:20:39 volumio volumio[1217]: info: Discovery: Device volumio disappeared from network Nov 21 10:20:39 volumio dhcpcd[913]: wlan0: deleting route to 192.168.1.0/24 Nov 21 10:20:39 volumio dhcpcd[913]: wlan0: deleting default route via 192.168.1.240 Nov 21 10:20:40 volumio wpa_supplicant[1111]: wlan0: Trying to associate with 44:df:65:5f:2f:92 (SSID='2702' freq=2462 MHz) Nov 21 10:20:40 volumio wpa_supplicant[1111]: wlan0: Associated with 44:df:65:5f:2f:92 Nov 21 10:20:40 volumio wpa_supplicant[1111]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 21 10:20:40 volumio wpa_supplicant[1111]: wlan0: WPA: Key negotiation completed with 44:df:65:5f:2f:92 [PTK=CCMP GTK=CCMP] Nov 21 10:20:40 volumio wpa_supplicant[1111]: wlan0: CTRL-EVENT-CONNECTED - Connection to 44:df:65:5f:2f:92 completed [id=0 id_str=] Nov 21 10:20:40 volumio dhcpcd[913]: wlan0: carrier acquired Nov 21 10:20:40 volumio dhcpcd[913]: wlan0: IAID d9:3b:ff:8d Nov 21 10:20:41 volumio dhcpcd[913]: wlan0: soliciting an IPv6 router Nov 21 10:20:41 volumio dhcpcd[913]: wlan0: rebinding lease of 192.168.1.122 Nov 21 10:20:41 volumio dhcpcd[913]: wlan0: probing address 192.168.1.122/24 Nov 21 10:20:42 volumio ntpd[3716]: Deleting interface #3 wlan0, 192.168.1.122#123, interface stats: received=3, sent=4, dropped=0, active_time=57 secs Nov 21 10:20:44 volumio volumio[1217]: info: Volumio Network Manager: Network status updated: 0 Nov 21 10:20:46 volumio dhcpcd[913]: wlan0: leased 192.168.1.122 for 43200 seconds Nov 21 10:20:46 volumio dhcpcd[913]: wlan0: adding route to 192.168.1.0/24 Nov 21 10:20:46 volumio dhcpcd[913]: wlan0: adding default route via 192.168.1.240 Nov 21 10:20:46 volumio avahi-daemon[767]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.122. Nov 21 10:20:46 volumio avahi-daemon[767]: New relevant interface wlan0.IPv4 for mDNS. Nov 21 10:20:46 volumio avahi-daemon[767]: Registering new address record for 192.168.1.122 on wlan0.IPv4. Nov 21 10:20:46 volumio ntpd[3716]: ntpd exiting on signal 15 (Terminated) Nov 21 10:20:46 volumio systemd[1]: Stopping Network Time Service... Nov 21 10:20:46 volumio systemd[1]: ntp.service: Succeeded. Nov 21 10:20:46 volumio systemd[1]: Stopped Network Time Service. Nov 21 10:20:46 volumio systemd[1]: Starting Network Time Service... Nov 21 10:20:46 volumio ntpd[4050]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 21 10:20:46 volumio ntpd[4050]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 21 10:20:46 volumio systemd[1]: Started Network Time Service. Nov 21 10:20:46 volumio ntpd[4057]: proto: precision = 0.252 usec (-22) Nov 21 10:20:46 volumio ntpd[4057]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 21 10:20:46 volumio ntpd[4057]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 21 10:20:46 volumio ntpd[4057]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 695 days ago Nov 21 10:20:46 volumio ntpd[4057]: Listen and drop on 0 v6wildcard [::]:123 Nov 21 10:20:46 volumio ntpd[4057]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 21 10:20:46 volumio ntpd[4057]: Listen normally on 2 lo 127.0.0.1:123 Nov 21 10:20:46 volumio ntpd[4057]: Listen normally on 3 wlan0 192.168.1.122:123 Nov 21 10:20:46 volumio ntpd[4057]: Listening on routing socket on fd #20 for interface updates Nov 21 10:20:46 volumio ntpd[4057]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 21 10:20:46 volumio ntpd[4057]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 21 10:20:47 volumio volumio[1217]: info: Discovery: adding 66abf326-144b-4c8b-bdde-ae358610a1b2 Nov 21 10:20:47 volumio volumio[1217]: info: Discovery: Found device Volumio Nov 21 10:20:47 volumio volumio[1217]: info: CoreCommandRouter::volumioGetState Nov 21 10:20:47 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:20:51 volumio ntpd[4057]: Soliciting pool server 162.159.200.1 Nov 21 10:20:51 volumio ntpd[4057]: Soliciting pool server 162.159.200.1 Nov 21 10:20:51 volumio ntpd[4057]: Soliciting pool server 162.159.200.123 Nov 21 10:20:51 volumio ntpd[4057]: Soliciting pool server 116.203.151.74 Nov 21 10:20:54 volumio volumio[1217]: info: Volumio Network Manager: Network status updated: 2 Nov 21 10:21:40 volumio wpa_supplicant[1111]: wlan0: CTRL-EVENT-DISCONNECTED bssid=44:df:65:5f:2f:92 reason=0 locally_generated=1 Nov 21 10:21:40 volumio dhcpcd[913]: wlan0: carrier lost Nov 21 10:21:40 volumio wpa_supplicant[1111]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Nov 21 10:21:40 volumio wpa_supplicant[1111]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Nov 21 10:21:40 volumio avahi-daemon[767]: Withdrawing address record for 192.168.1.122 on wlan0. Nov 21 10:21:40 volumio avahi-daemon[767]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.122. Nov 21 10:21:40 volumio avahi-daemon[767]: Interface wlan0.IPv4 no longer relevant for mDNS. Nov 21 10:21:40 volumio volumio[1217]: info: Discovery: A device disappeared from network Nov 21 10:21:40 volumio volumio[1217]: info: Discovery: Device volumio disappeared from network Nov 21 10:21:40 volumio dhcpcd[913]: wlan0: deleting route to 192.168.1.0/24 Nov 21 10:21:40 volumio dhcpcd[913]: wlan0: deleting default route via 192.168.1.240 Nov 21 10:21:41 volumio ntpd[4057]: Deleting interface #3 wlan0, 192.168.1.122#123, interface stats: received=0, sent=4, dropped=0, active_time=55 secs Nov 21 10:21:42 volumio wpa_supplicant[1111]: wlan0: Trying to associate with 44:df:65:5f:2f:92 (SSID='2702' freq=2462 MHz) Nov 21 10:21:42 volumio wpa_supplicant[1111]: wlan0: Associated with 44:df:65:5f:2f:92 Nov 21 10:21:42 volumio wpa_supplicant[1111]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 21 10:21:42 volumio wpa_supplicant[1111]: wlan0: WPA: Key negotiation completed with 44:df:65:5f:2f:92 [PTK=CCMP GTK=CCMP] Nov 21 10:21:42 volumio wpa_supplicant[1111]: wlan0: CTRL-EVENT-CONNECTED - Connection to 44:df:65:5f:2f:92 completed [id=0 id_str=] Nov 21 10:21:42 volumio dhcpcd[913]: wlan0: carrier acquired Nov 21 10:21:42 volumio dhcpcd[913]: wlan0: IAID d9:3b:ff:8d Nov 21 10:21:42 volumio dhcpcd[913]: wlan0: rebinding lease of 192.168.1.122 Nov 21 10:21:42 volumio dhcpcd[913]: wlan0: probing address 192.168.1.122/24 Nov 21 10:21:42 volumio dhcpcd[913]: wlan0: soliciting an IPv6 router Nov 21 10:21:44 volumio volumio[1217]: info: Volumio Network Manager: Network status updated: 0 Nov 21 10:21:44 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:44 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Nov 21 10:21:44 volumio volumio[1217]: info: Prefetching next song Nov 21 10:21:44 volumio volumio[1217]: info: DOING PREFETCH IN MPD Nov 21 10:21:44 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand add "NAS/音乐/王li宏无损歌曲/卢巧音&王力宏-好心分手(合唱版).flac" Nov 21 10:21:44 volumio volumio[1217]: info: sendMpdCommand add "NAS/音乐/王li宏无损歌曲/卢巧音&王力宏-好心分手(合唱版).flac" took 4 milliseconds Nov 21 10:21:44 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 21 10:21:44 volumio volumio[1217]: info: Nov 21 10:21:44 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Nov 21 10:21:44 volumio volumio[1217]: info: Ignoring MPD Status Update Nov 21 10:21:44 volumio volumio[1217]: info: Nov 21 10:21:44 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Nov 21 10:21:44 volumio volumio[1217]: info: Ignoring MPD Status Update Nov 21 10:21:44 volumio volumio[1217]: info: Nov 21 10:21:44 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Nov 21 10:21:44 volumio volumio[1217]: info: Ignoring MPD Status Update Nov 21 10:21:44 volumio volumio[1217]: info: Nov 21 10:21:44 volumio volumio[1217]: ---------------------------- MPD announces state update: options Nov 21 10:21:44 volumio volumio[1217]: info: sendMpdCommand consume 1 took 4 milliseconds Nov 21 10:21:44 volumio volumio[1217]: info: ControllerMpd::getState Nov 21 10:21:44 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Nov 21 10:21:44 volumio volumio[1217]: info: ------------------------------ 4ms Nov 21 10:21:44 volumio volumio[1217]: info: ------------------------------ 3ms Nov 21 10:21:44 volumio volumio[1217]: info: Nov 21 10:21:44 volumio volumio[1217]: ---------------------------- MPD announces state update: options Nov 21 10:21:44 volumio volumio[1217]: info: ControllerMpd::getState Nov 21 10:21:44 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Nov 21 10:21:44 volumio volumio[1217]: info: Nov 21 10:21:44 volumio volumio[1217]: ---------------------------- MPD announces state update: options Nov 21 10:21:44 volumio volumio[1217]: info: ControllerMpd::getState Nov 21 10:21:44 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Nov 21 10:21:44 volumio volumio[1217]: info: ------------------------------ 5ms Nov 21 10:21:44 volumio volumio[1217]: info: sendMpdCommand status took 3 milliseconds Nov 21 10:21:44 volumio volumio[1217]: info: sendMpdCommand status took 3 milliseconds Nov 21 10:21:44 volumio volumio[1217]: verbose: ControllerMpd::parseState Nov 21 10:21:44 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 10:21:44 volumio volumio[1217]: verbose: ControllerMpd::parseState Nov 21 10:21:44 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 10:21:44 volumio volumio[1217]: info: sendMpdCommand status took 3 milliseconds Nov 21 10:21:44 volumio volumio[1217]: verbose: ControllerMpd::parseState Nov 21 10:21:44 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 10:21:44 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 21 10:21:44 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 21 10:21:44 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Nov 21 10:21:44 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Nov 21 10:21:44 volumio volumio[1217]: info: ControllerMpd::pushState Nov 21 10:21:44 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Nov 21 10:21:44 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:44 volumio volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":297693,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"400 Kbps","isStreaming":false,"title":"北京,北京,我爱北京","artist":"关喆","album":"北京2008年奥运会歌曲专辑","uri":"NAS/音乐/王li宏无损歌曲/Rain&韩雪&王力宏&陈慧琳-北京，北京，我爱北京.flac","trackType":"flac"} Nov 21 10:21:44 volumio volumio[1217]: verbose: CURRENT POSITION 0 Nov 21 10:21:44 volumio volumio[1217]: info: CoreStateMachine::syncState stateService play Nov 21 10:21:44 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus play Nov 21 10:21:44 volumio volumio[1217]: info: Received an update from plugin. extracting info from payload Nov 21 10:21:44 volumio volumio[1217]: info: CoreStateMachine::pushState Nov 21 10:21:44 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:44 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 10:21:44 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Nov 21 10:21:44 volumio volumio[1217]: info: CoreStateMachine::pushState Nov 21 10:21:44 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:44 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Nov 21 10:21:44 volumio volumio[1217]: info: ControllerMpd::pushState Nov 21 10:21:44 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Nov 21 10:21:44 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:44 volumio volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":297693,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"400 Kbps","isStreaming":false,"title":"北京,北京,我爱北京","artist":"关喆","album":"北京2008年奥运会歌曲专辑","uri":"NAS/音乐/王li宏无损歌曲/Rain&韩雪&王力宏&陈慧琳-北京，北京，我爱北京.flac","trackType":"flac"} Nov 21 10:21:44 volumio volumio[1217]: verbose: CURRENT POSITION 0 Nov 21 10:21:44 volumio volumio[1217]: info: CoreStateMachine::syncState stateService play Nov 21 10:21:44 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus play Nov 21 10:21:44 volumio volumio[1217]: info: Received an update from plugin. extracting info from payload Nov 21 10:21:44 volumio volumio[1217]: info: CoreStateMachine::pushState Nov 21 10:21:44 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:44 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Nov 21 10:21:44 volumio volumio[1217]: info: CoreStateMachine::pushState Nov 21 10:21:44 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:44 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Nov 21 10:21:44 volumio volumio[1217]: info: ------------------------------ 41ms Nov 21 10:21:44 volumio volumio[1217]: info: ------------------------------ 40ms Nov 21 10:21:44 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 35 milliseconds Nov 21 10:21:44 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Nov 21 10:21:44 volumio volumio[1217]: info: ControllerMpd::pushState Nov 21 10:21:44 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Nov 21 10:21:44 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:44 volumio volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":297693,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"400 Kbps","isStreaming":false,"title":"北京,北京,我爱北京","artist":"关喆","album":"北京2008年奥运会歌曲专辑","uri":"NAS/音乐/王li宏无损歌曲/Rain&韩雪&王力宏&陈慧琳-北京，北京，我爱北京.flac","trackType":"flac"} Nov 21 10:21:44 volumio volumio[1217]: verbose: CURRENT POSITION 0 Nov 21 10:21:44 volumio volumio[1217]: info: CoreStateMachine::syncState stateService play Nov 21 10:21:44 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus play Nov 21 10:21:44 volumio volumio[1217]: info: Received an update from plugin. extracting info from payload Nov 21 10:21:44 volumio volumio[1217]: info: CoreStateMachine::pushState Nov 21 10:21:44 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:44 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Nov 21 10:21:44 volumio volumio[1217]: info: CoreStateMachine::pushState Nov 21 10:21:44 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:44 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Nov 21 10:21:44 volumio volumio[1217]: info: ------------------------------ 59ms Nov 21 10:21:47 volumio dhcpcd[913]: wlan0: leased 192.168.1.122 for 43200 seconds Nov 21 10:21:47 volumio dhcpcd[913]: wlan0: adding route to 192.168.1.0/24 Nov 21 10:21:47 volumio dhcpcd[913]: wlan0: adding default route via 192.168.1.240 Nov 21 10:21:47 volumio avahi-daemon[767]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.122. Nov 21 10:21:47 volumio avahi-daemon[767]: New relevant interface wlan0.IPv4 for mDNS. Nov 21 10:21:47 volumio avahi-daemon[767]: Registering new address record for 192.168.1.122 on wlan0.IPv4. Nov 21 10:21:47 volumio systemd[1]: Stopping Network Time Service... Nov 21 10:21:47 volumio ntpd[4057]: ntpd exiting on signal 15 (Terminated) Nov 21 10:21:47 volumio systemd[1]: ntp.service: Succeeded. Nov 21 10:21:47 volumio systemd[1]: Stopped Network Time Service. Nov 21 10:21:47 volumio systemd[1]: Starting Network Time Service... Nov 21 10:21:47 volumio ntpd[4393]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 21 10:21:47 volumio ntpd[4393]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 21 10:21:47 volumio systemd[1]: Started Network Time Service. Nov 21 10:21:47 volumio ntpd[4400]: proto: precision = 0.252 usec (-22) Nov 21 10:21:47 volumio ntpd[4400]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 21 10:21:47 volumio ntpd[4400]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 21 10:21:47 volumio ntpd[4400]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 695 days ago Nov 21 10:21:47 volumio ntpd[4400]: Listen and drop on 0 v6wildcard [::]:123 Nov 21 10:21:47 volumio ntpd[4400]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 21 10:21:47 volumio ntpd[4400]: Listen normally on 2 lo 127.0.0.1:123 Nov 21 10:21:47 volumio ntpd[4400]: Listen normally on 3 wlan0 192.168.1.122:123 Nov 21 10:21:47 volumio ntpd[4400]: Listening on routing socket on fd #20 for interface updates Nov 21 10:21:47 volumio ntpd[4400]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 21 10:21:47 volumio ntpd[4400]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 21 10:21:48 volumio volumio[1217]: info: Discovery: adding 66abf326-144b-4c8b-bdde-ae358610a1b2 Nov 21 10:21:48 volumio volumio[1217]: info: Discovery: Found device Volumio Nov 21 10:21:48 volumio volumio[1217]: info: CoreCommandRouter::volumioGetState Nov 21 10:21:48 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:48 volumio ntpd[4400]: Soliciting pool server 162.159.200.1 Nov 21 10:21:48 volumio volumio[1217]: info: Nov 21 10:21:48 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Nov 21 10:21:48 volumio volumio[1217]: info: Ignoring MPD Status Update Nov 21 10:21:48 volumio volumio[1217]: info: Nov 21 10:21:48 volumio volumio[1217]: ---------------------------- MPD announces state update: player Nov 21 10:21:48 volumio volumio[1217]: info: ControllerMpd::getState Nov 21 10:21:48 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Nov 21 10:21:48 volumio volumio[1217]: info: Nov 21 10:21:48 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Nov 21 10:21:48 volumio volumio[1217]: info: Ignoring MPD Status Update Nov 21 10:21:48 volumio volumio[1217]: info: Nov 21 10:21:48 volumio volumio[1217]: ---------------------------- MPD announces state update: player Nov 21 10:21:48 volumio volumio[1217]: info: ControllerMpd::getState Nov 21 10:21:48 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Nov 21 10:21:48 volumio volumio[1217]: info: Nov 21 10:21:48 volumio volumio[1217]: ---------------------------- MPD announces system playlist update Nov 21 10:21:48 volumio volumio[1217]: info: Ignoring MPD Status Update Nov 21 10:21:48 volumio volumio[1217]: info: Nov 21 10:21:48 volumio volumio[1217]: ---------------------------- MPD announces state update: player Nov 21 10:21:48 volumio volumio[1217]: info: ControllerMpd::getState Nov 21 10:21:48 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Nov 21 10:21:48 volumio volumio[1217]: info: ------------------------------ 5ms Nov 21 10:21:48 volumio volumio[1217]: info: sendMpdCommand status took 4 milliseconds Nov 21 10:21:48 volumio volumio[1217]: info: ------------------------------ 4ms Nov 21 10:21:48 volumio volumio[1217]: info: sendMpdCommand status took 3 milliseconds Nov 21 10:21:48 volumio volumio[1217]: info: ------------------------------ 4ms Nov 21 10:21:48 volumio volumio[1217]: verbose: ControllerMpd::parseState Nov 21 10:21:48 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 10:21:48 volumio volumio[1217]: verbose: ControllerMpd::parseState Nov 21 10:21:48 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 10:21:48 volumio volumio[1217]: info: sendMpdCommand status took 7 milliseconds Nov 21 10:21:48 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 21 10:21:48 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 21 10:21:48 volumio volumio[1217]: verbose: ControllerMpd::parseState Nov 21 10:21:48 volumio volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 10:21:48 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Nov 21 10:21:48 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Nov 21 10:21:48 volumio volumio[1217]: info: ControllerMpd::pushState Nov 21 10:21:48 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Nov 21 10:21:48 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:48 volumio volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"494 Kbps","isStreaming":false,"title":"好心分手","artist":"王力宏","album":"Dream Again","uri":"NAS/音乐/王li宏无损歌曲/卢巧音&王力宏-好心分手(合唱版).flac","trackType":"flac"} Nov 21 10:21:48 volumio volumio[1217]: verbose: CURRENT POSITION 0 Nov 21 10:21:48 volumio volumio[1217]: info: CoreStateMachine::syncState stateService play Nov 21 10:21:48 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus play Nov 21 10:21:48 volumio volumio[1217]: info: Received an update from plugin. extracting info from payload Nov 21 10:21:48 volumio volumio[1217]: info: CoreStateMachine::pushState Nov 21 10:21:48 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:48 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 10:21:48 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Nov 21 10:21:48 volumio volumio[1217]: info: CoreStateMachine::pushState Nov 21 10:21:48 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:48 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Nov 21 10:21:48 volumio volumio[1217]: info: ControllerMpd::pushState Nov 21 10:21:48 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Nov 21 10:21:48 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:48 volumio volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"494 Kbps","isStreaming":false,"title":"好心分手","artist":"王力宏","album":"Dream Again","uri":"NAS/音乐/王li宏无损歌曲/卢巧音&王力宏-好心分手(合唱版).flac","trackType":"flac"} Nov 21 10:21:48 volumio volumio[1217]: verbose: CURRENT POSITION 0 Nov 21 10:21:48 volumio volumio[1217]: info: CoreStateMachine::syncState stateService play Nov 21 10:21:48 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus play Nov 21 10:21:48 volumio volumio[1217]: info: Received an update from plugin. extracting info from payload Nov 21 10:21:48 volumio volumio[1217]: info: CoreStateMachine::pushState Nov 21 10:21:48 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:48 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Nov 21 10:21:48 volumio volumio[1217]: info: CoreStateMachine::pushState Nov 21 10:21:48 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:48 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Nov 21 10:21:48 volumio volumio[1217]: info: ------------------------------ 61ms Nov 21 10:21:48 volumio volumio[1217]: info: ------------------------------ 61ms Nov 21 10:21:48 volumio volumio[1217]: info: sendMpdCommand playlistinfo took 51 milliseconds Nov 21 10:21:48 volumio volumio[1217]: verbose: ControllerMpd::parseTrackInfo Nov 21 10:21:48 volumio volumio[1217]: info: ControllerMpd::pushState Nov 21 10:21:48 volumio volumio[1217]: info: CoreCommandRouter::servicePushState Nov 21 10:21:48 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:48 volumio volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"494 Kbps","isStreaming":false,"title":"好心分手","artist":"王力宏","album":"Dream Again","uri":"NAS/音乐/王li宏无损歌曲/卢巧音&王力宏-好心分手(合唱版).flac","trackType":"flac"} Nov 21 10:21:48 volumio volumio[1217]: verbose: CURRENT POSITION 0 Nov 21 10:21:48 volumio volumio[1217]: info: CoreStateMachine::syncState stateService play Nov 21 10:21:48 volumio volumio[1217]: info: CoreStateMachine::syncState currentStatus play Nov 21 10:21:48 volumio volumio[1217]: info: Received an update from plugin. extracting info from payload Nov 21 10:21:48 volumio volumio[1217]: info: CoreStateMachine::pushState Nov 21 10:21:48 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:48 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 10:21:48 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Nov 21 10:21:48 volumio volumio[1217]: info: CoreStateMachine::pushState Nov 21 10:21:48 volumio volumio[1217]: info: CorePlayQueue::getTrack 0 Nov 21 10:21:48 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Nov 21 10:21:48 volumio volumio[1217]: info: ------------------------------ 87ms Nov 21 10:21:48 volumio volumio[1217]: info: CoreStateMachine::startPlaybackTimer Nov 21 10:21:48 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Nov 21 10:21:49 volumio volumio[1217]: info: CoreStateMachine::pushState Nov 21 10:21:49 volumio volumio[1217]: info: CorePlayQueue::getTrack 1 Nov 21 10:21:49 volumio volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 10:21:49 volumio volumio[1217]: info: CoreCommandRouter::volumioPushState Nov 21 10:21:49 volumio ntpd[4400]: Soliciting pool server 162.159.200.1 Nov 21 10:21:52 volumio ntpd[4400]: Soliciting pool server 162.159.200.1 Nov 21 10:21:52 volumio ntpd[4400]: Soliciting pool server 202.112.31.197 Nov 21 10:21:53 volumio ntpd[4400]: Soliciting pool server 139.199.214.202 Nov 21 10:21:54 volumio volumio[1217]: info: Volumio Network Manager: Network status updated: 2 Nov 21 10:21:54 volumio ntpd[4400]: Soliciting pool server 202.118.1.130 Nov 21 10:21:55 volumio ntpd[4400]: Soliciting pool server 95.111.202.5 Nov 21 10:21:56 volumio volumio[1217]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 10:21:56 volumio volumio[1217]: Error: Client network socket disconnected before secure TLS connection was established Nov 21 10:21:56 volumio volumio[1217]: at connResetException (internal/errors.js:639:14) Nov 21 10:21:56 volumio volumio[1217]: at TLSSocket.onConnectEnd (_tls_wrap.js:1570:19) Nov 21 10:21:56 volumio volumio[1217]: at TLSSocket.emit (events.js:412:35) Nov 21 10:21:56 volumio volumio[1217]: at endReadableNT (internal/streams/readable.js:1333:12) Nov 21 10:21:56 volumio volumio[1217]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Nov 21 10:21:56 volumio volumio[1217]: code: 'ECONNRESET', Nov 21 10:21:56 volumio volumio[1217]: path: null, Nov 21 10:21:56 volumio volumio[1217]: host: 'lastfm.freetls.fastly.net', Nov 21 10:21:56 volumio volumio[1217]: port: 443, Nov 21 10:21:56 volumio volumio[1217]: localAddress: undefined Nov 21 10:21:56 volumio volumio[1217]: } Nov 21 10:21:56 volumio volumio[1217]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 10:21:56 volumio sudo[4449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 10:20 Nov 21 10:21:56 volumio sudo[4449]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"