-- Logs begin at Thu 2025-06-26 20:15:49 HKT, end at Thu 2025-06-26 20:20:44 HKT. -- Jun 26 20:19:01 volumio volumio[1178]: info: Volumio Network Manager: Network status updated: 0 Jun 26 20:19:03 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-ASSOC-REJECT status_code=1 Jun 26 20:19:03 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-ASSOC-REJECT status_code=1 Jun 26 20:19:05 volumio wpa_supplicant[1095]: wlan0: Trying to associate with 2c:61:04:38:ed:55 (SSID='3602' freq=2417 MHz) Jun 26 20:19:05 volumio wpa_supplicant[1095]: wlan0: Associated with 2c:61:04:38:ed:55 Jun 26 20:19:05 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 26 20:19:05 volumio wpa_supplicant[1095]: wlan0: WPA: Key negotiation completed with 2c:61:04:38:ed:55 [PTK=CCMP GTK=CCMP] Jun 26 20:19:05 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-CONNECTED - Connection to 2c:61:04:38:ed:55 completed [id=0 id_str=] Jun 26 20:19:05 volumio dhcpcd[983]: wlan0: carrier acquired Jun 26 20:19:05 volumio dhcpcd[983]: wlan0: IAID df:8a:f6:5d Jun 26 20:19:05 volumio dhcpcd[983]: wlan0: rebinding lease of 192.168.0.5 Jun 26 20:19:06 volumio dhcpcd[983]: wlan0: probing address 192.168.0.5/24 Jun 26 20:19:06 volumio dhcpcd[983]: wlan0: soliciting an IPv6 router Jun 26 20:19:11 volumio dhcpcd[983]: wlan0: leased 192.168.0.5 for 172800 seconds Jun 26 20:19:11 volumio dhcpcd[983]: wlan0: adding route to 192.168.0.0/24 Jun 26 20:19:11 volumio dhcpcd[983]: wlan0: adding default route via 192.168.0.1 Jun 26 20:19:11 volumio avahi-daemon[822]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.5. Jun 26 20:19:11 volumio avahi-daemon[822]: New relevant interface wlan0.IPv4 for mDNS. Jun 26 20:19:11 volumio avahi-daemon[822]: Registering new address record for 192.168.0.5 on wlan0.IPv4. Jun 26 20:19:11 volumio ntpd[2514]: ntpd exiting on signal 15 (Terminated) Jun 26 20:19:11 volumio systemd[1]: Stopping Network Time Service... Jun 26 20:19:11 volumio systemd[1]: ntp.service: Succeeded. Jun 26 20:19:11 volumio systemd[1]: Stopped Network Time Service. Jun 26 20:19:11 volumio systemd[1]: Starting Network Time Service... Jun 26 20:19:11 volumio ntpd[2780]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 26 20:19:11 volumio ntpd[2780]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 26 20:19:11 volumio systemd[1]: Started Network Time Service. Jun 26 20:19:11 volumio ntpd[2786]: proto: precision = 0.252 usec (-22) Jun 26 20:19:11 volumio ntpd[2786]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 26 20:19:11 volumio ntpd[2786]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 26 20:19:11 volumio ntpd[2786]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 912 days ago Jun 26 20:19:11 volumio ntpd[2786]: Listen and drop on 0 v6wildcard [::]:123 Jun 26 20:19:11 volumio ntpd[2786]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 26 20:19:11 volumio ntpd[2786]: Listen normally on 2 lo 127.0.0.1:123 Jun 26 20:19:11 volumio ntpd[2786]: Listen normally on 3 wlan0 192.168.0.5:123 Jun 26 20:19:11 volumio ntpd[2786]: Listening on routing socket on fd #20 for interface updates Jun 26 20:19:11 volumio ntpd[2786]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 26 20:19:11 volumio ntpd[2786]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 26 20:19:11 volumio volumio[1178]: info: Volumio Network Manager: Network status updated: 2 Jun 26 20:19:11 volumio volumio[1178]: info: Discovery: adding bc11b65a-6f4b-4197-8dfa-f77119e4ae23 Jun 26 20:19:11 volumio volumio[1178]: info: Discovery: Found device Volumio Jun 26 20:19:11 volumio volumio[1178]: info: CoreCommandRouter::volumioGetState Jun 26 20:19:12 volumio ntpd[2786]: Soliciting pool server 193.182.111.14 Jun 26 20:19:13 volumio ntpd[2786]: Soliciting pool server 81.168.83.93 Jun 26 20:19:13 volumio ntpd[2786]: Soliciting pool server 193.182.111.141 Jun 26 20:19:14 volumio ntpd[2786]: Soliciting pool server 193.182.111.142 Jun 26 20:19:14 volumio ntpd[2786]: Soliciting pool server 116.203.151.74 Jun 26 20:19:14 volumio ntpd[2786]: Soliciting pool server 193.182.111.12 Jun 26 20:19:15 volumio ntpd[2786]: Soliciting pool server 5.79.108.34 Jun 26 20:19:15 volumio ntpd[2786]: Soliciting pool server 162.159.200.123 Jun 26 20:19:15 volumio ntpd[2786]: Soliciting pool server 139.199.214.202 Jun 26 20:19:15 volumio ntpd[2786]: Soliciting pool server 185.209.85.222 Jun 26 20:19:16 volumio ntpd[2786]: Soliciting pool server 202.118.1.81 Jun 26 20:19:16 volumio ntpd[2786]: Soliciting pool server 84.16.67.12 Jun 26 20:19:17 volumio ntpd[2786]: Soliciting pool server 117.80.231.60 Jun 26 20:19:18 volumio ntpd[2786]: Soliciting pool server 2001:1600:3:6::123 Jun 26 20:19:37 volumio volumio[1178]: info: Clearing queue after UPNP request Jun 26 20:19:37 volumio volumio[1178]: info: CoreStateMachine::ClearQueue Jun 26 20:19:37 volumio volumio[1178]: info: CoreStateMachine::stop Jun 26 20:19:37 volumio volumio[1178]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 20:19:37 volumio volumio[1178]: info: CorePlayQueue::clearPlayQueue Jun 26 20:19:37 volumio volumio[1178]: info: CorePlayQueue::saveQueue Jun 26 20:19:37 volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jun 26 20:19:37 volumio volumio[1178]: info: CoreCommandRouter::volumioPushQueue Jun 26 20:19:38 volumio volumio[1178]: error: Upnp client error: Error: This socket has been ended by the other party Jun 26 20:19:39 volumio volumio[1178]: info: Starting UPNP Playback Jun 26 20:19:39 volumio volumio[1178]: info: Preparing playback through UPNP Jun 26 20:19:39 volumio volumio[1178]: info: CoreCommandRouter::volumioGetState Jun 26 20:19:39 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jun 26 20:19:39 volumio volumio[1178]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: ------------------------------ 3ms Jun 26 20:19:40 volumio volumio[1178]: info: ------------------------------ 2ms Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: ------------------------------ 3ms Jun 26 20:19:40 volumio volumio[1178]: info: ------------------------------ 2ms Jun 26 20:19:40 volumio volumio[1178]: info: ------------------------------ 2ms Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: ------------------------------ 4ms Jun 26 20:19:40 volumio volumio[1178]: info: ------------------------------ 3ms Jun 26 20:19:40 volumio volumio[1178]: info: ------------------------------ 2ms Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: ------------------------------ 3ms Jun 26 20:19:40 volumio volumio[1178]: info: ------------------------------ 2ms Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: ------------------------------ 9ms Jun 26 20:19:40 volumio volumio[1178]: info: ------------------------------ 8ms Jun 26 20:19:40 volumio volumio[1178]: info: ------------------------------ 9ms Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: Jun 26 20:19:40 volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jun 26 20:19:40 volumio volumio[1178]: info: Ignoring MPD Status Update Jun 26 20:19:40 volumio volumio[1178]: info: ------------------------------ 4ms Jun 26 20:19:40 volumio volumio[1178]: info: ------------------------------ 4ms Jun 26 20:19:40 volumio volumio[1178]: info: ------------------------------ 3ms Jun 26 20:19:48 volumio volumio[1178]: info: Jun 26 20:19:48 volumio volumio[1178]: ---------------------------- MPD announces state update: player Jun 26 20:19:48 volumio volumio[1178]: info: ControllerMpd::getState Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand status Jun 26 20:19:48 volumio volumio[1178]: info: Jun 26 20:19:48 volumio volumio[1178]: ---------------------------- MPD announces state update: player Jun 26 20:19:48 volumio volumio[1178]: info: ControllerMpd::getState Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand status Jun 26 20:19:48 volumio volumio[1178]: info: Jun 26 20:19:48 volumio volumio[1178]: ---------------------------- MPD announces state update: player Jun 26 20:19:48 volumio volumio[1178]: info: ControllerMpd::getState Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand status Jun 26 20:19:48 volumio volumio[1178]: info: sendMpdCommand status took 8 milliseconds Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::parseState Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 20:19:48 volumio volumio[1178]: info: Jun 26 20:19:48 volumio volumio[1178]: ---------------------------- MPD announces state update: player Jun 26 20:19:48 volumio volumio[1178]: info: ControllerMpd::getState Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand status Jun 26 20:19:48 volumio volumio[1178]: info: sendMpdCommand status took 17 milliseconds Jun 26 20:19:48 volumio volumio[1178]: info: sendMpdCommand status took 13 milliseconds Jun 26 20:19:48 volumio volumio[1178]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::parseState Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::parseState Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::parseTrackInfo Jun 26 20:19:48 volumio volumio[1178]: info: ControllerMpd::pushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::servicePushState Jun 26 20:19:48 volumio volumio[1178]: verbose: In UPNP mode Jun 26 20:19:48 volumio volumio[1178]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"英俊少年","artist":"欧翠峰","album":"永恒的号角","uri":"http://192.168.0.8:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2F%E6%88%91%E7%9A%84%E6%96%87%E4%BB%B6%2Fmusic%2F%E6%AC%A7%E7%BF%A0%E5%B3%B0-%E4%B8%AD%E5%9B%BD%E7%AC%AC%E4%B8%80%E5%B0%8F%E5%8F%B7%2F%E6%AC%A7%E7%BF%A0%E5%B3%B0+-+%E8%8B%B1%E4%BF%8A%E5%B0%91%E5%B9%B4.flac&decode=false","trackType":""} Jun 26 20:19:48 volumio volumio[1178]: verbose: CURRENT POSITION 0 Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::syncState stateService play Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::syncState currentStatus stop Jun 26 20:19:48 volumio volumio[1178]: info: ------------------------------ 32ms Jun 26 20:19:48 volumio volumio[1178]: info: Jun 26 20:19:48 volumio volumio[1178]: ---------------------------- MPD announces state update: player Jun 26 20:19:48 volumio volumio[1178]: info: ControllerMpd::getState Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand status Jun 26 20:19:48 volumio volumio[1178]: info: Jun 26 20:19:48 volumio volumio[1178]: ---------------------------- MPD announces state update: player Jun 26 20:19:48 volumio volumio[1178]: info: ControllerMpd::getState Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand status Jun 26 20:19:48 volumio volumio[1178]: info: sendMpdCommand status took 17 milliseconds Jun 26 20:19:48 volumio volumio[1178]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 26 20:19:48 volumio volumio[1178]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 26 20:19:48 volumio volumio[1178]: info: sendMpdCommand status took 7 milliseconds Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::parseState Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::parseTrackInfo Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::parseTrackInfo Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::parseState Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 20:19:48 volumio volumio[1178]: info: ControllerMpd::pushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::servicePushState Jun 26 20:19:48 volumio volumio[1178]: verbose: In UPNP mode Jun 26 20:19:48 volumio volumio[1178]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"英俊少年","artist":"欧翠峰","album":"永恒的号角","uri":"http://192.168.0.8:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2F%E6%88%91%E7%9A%84%E6%96%87%E4%BB%B6%2Fmusic%2F%E6%AC%A7%E7%BF%A0%E5%B3%B0-%E4%B8%AD%E5%9B%BD%E7%AC%AC%E4%B8%80%E5%B0%8F%E5%8F%B7%2F%E6%AC%A7%E7%BF%A0%E5%B3%B0+-+%E8%8B%B1%E4%BF%8A%E5%B0%91%E5%B9%B4.flac&decode=false","trackType":""} Jun 26 20:19:48 volumio volumio[1178]: verbose: CURRENT POSITION 0 Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::syncState stateService play Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::syncState currentStatus play Jun 26 20:19:48 volumio volumio[1178]: info: Received an update from plugin. extracting info from payload Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::pushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::pushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jun 26 20:19:48 volumio volumio[1178]: info: ControllerMpd::pushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::servicePushState Jun 26 20:19:48 volumio volumio[1178]: verbose: In UPNP mode Jun 26 20:19:48 volumio volumio[1178]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"英俊少年","artist":"欧翠峰","album":"永恒的号角","uri":"http://192.168.0.8:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2F%E6%88%91%E7%9A%84%E6%96%87%E4%BB%B6%2Fmusic%2F%E6%AC%A7%E7%BF%A0%E5%B3%B0-%E4%B8%AD%E5%9B%BD%E7%AC%AC%E4%B8%80%E5%B0%8F%E5%8F%B7%2F%E6%AC%A7%E7%BF%A0%E5%B3%B0+-+%E8%8B%B1%E4%BF%8A%E5%B0%91%E5%B9%B4.flac&decode=false","trackType":""} Jun 26 20:19:48 volumio volumio[1178]: verbose: CURRENT POSITION 0 Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::syncState stateService play Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::syncState currentStatus play Jun 26 20:19:48 volumio volumio[1178]: info: Received an update from plugin. extracting info from payload Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::pushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::pushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jun 26 20:19:48 volumio volumio[1178]: info: ------------------------------ 92ms Jun 26 20:19:48 volumio volumio[1178]: info: ------------------------------ 88ms Jun 26 20:19:48 volumio volumio[1178]: info: sendMpdCommand status took 61 milliseconds Jun 26 20:19:48 volumio volumio[1178]: info: sendMpdCommand playlistinfo took 57 milliseconds Jun 26 20:19:48 volumio volumio[1178]: info: sendMpdCommand playlistinfo took 55 milliseconds Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::parseState Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::parseTrackInfo Jun 26 20:19:48 volumio volumio[1178]: info: ControllerMpd::pushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::servicePushState Jun 26 20:19:48 volumio volumio[1178]: verbose: In UPNP mode Jun 26 20:19:48 volumio volumio[1178]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"英俊少年","artist":"欧翠峰","album":"永恒的号角","uri":"http://192.168.0.8:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2F%E6%88%91%E7%9A%84%E6%96%87%E4%BB%B6%2Fmusic%2F%E6%AC%A7%E7%BF%A0%E5%B3%B0-%E4%B8%AD%E5%9B%BD%E7%AC%AC%E4%B8%80%E5%B0%8F%E5%8F%B7%2F%E6%AC%A7%E7%BF%A0%E5%B3%B0+-+%E8%8B%B1%E4%BF%8A%E5%B0%91%E5%B9%B4.flac&decode=false","trackType":""} Jun 26 20:19:48 volumio volumio[1178]: verbose: CURRENT POSITION 0 Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::syncState stateService play Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::syncState currentStatus play Jun 26 20:19:48 volumio volumio[1178]: info: Received an update from plugin. extracting info from payload Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::pushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::pushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jun 26 20:19:48 volumio volumio[1178]: info: ControllerMpd::pushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::servicePushState Jun 26 20:19:48 volumio volumio[1178]: verbose: In UPNP mode Jun 26 20:19:48 volumio volumio[1178]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"英俊少年","artist":"欧翠峰","album":"永恒的号角","uri":"http://192.168.0.8:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2F%E6%88%91%E7%9A%84%E6%96%87%E4%BB%B6%2Fmusic%2F%E6%AC%A7%E7%BF%A0%E5%B3%B0-%E4%B8%AD%E5%9B%BD%E7%AC%AC%E4%B8%80%E5%B0%8F%E5%8F%B7%2F%E6%AC%A7%E7%BF%A0%E5%B3%B0+-+%E8%8B%B1%E4%BF%8A%E5%B0%91%E5%B9%B4.flac&decode=false","trackType":""} Jun 26 20:19:48 volumio volumio[1178]: verbose: CURRENT POSITION 0 Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::syncState stateService play Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::syncState currentStatus play Jun 26 20:19:48 volumio volumio[1178]: info: Received an update from plugin. extracting info from payload Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::pushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::pushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jun 26 20:19:48 volumio volumio[1178]: info: ------------------------------ 118ms Jun 26 20:19:48 volumio volumio[1178]: info: ------------------------------ 106ms Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CorePlayQueue::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CorePlayQueue::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CorePlayQueue::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CorePlayQueue::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CorePlayQueue::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CorePlayQueue::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CorePlayQueue::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CorePlayQueue::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: sendMpdCommand playlistinfo took 58 milliseconds Jun 26 20:19:48 volumio volumio[1178]: verbose: ControllerMpd::parseTrackInfo Jun 26 20:19:48 volumio volumio[1178]: info: ControllerMpd::pushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::servicePushState Jun 26 20:19:48 volumio volumio[1178]: verbose: In UPNP mode Jun 26 20:19:48 volumio volumio[1178]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"英俊少年","artist":"欧翠峰","album":"永恒的号角","uri":"http://192.168.0.8:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2F%E6%88%91%E7%9A%84%E6%96%87%E4%BB%B6%2Fmusic%2F%E6%AC%A7%E7%BF%A0%E5%B3%B0-%E4%B8%AD%E5%9B%BD%E7%AC%AC%E4%B8%80%E5%B0%8F%E5%8F%B7%2F%E6%AC%A7%E7%BF%A0%E5%B3%B0+-+%E8%8B%B1%E4%BF%8A%E5%B0%91%E5%B9%B4.flac&decode=false","trackType":""} Jun 26 20:19:48 volumio volumio[1178]: verbose: CURRENT POSITION 0 Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::syncState stateService play Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::syncState currentStatus play Jun 26 20:19:48 volumio volumio[1178]: info: Received an update from plugin. extracting info from payload Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::pushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::pushState Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jun 26 20:19:48 volumio volumio[1178]: info: ------------------------------ 147ms Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CorePlayQueue::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreCommandRouter::volumioGetQueue Jun 26 20:19:48 volumio volumio[1178]: info: CoreStateMachine::getQueue Jun 26 20:19:48 volumio volumio[1178]: info: CorePlayQueue::getQueue Jun 26 20:19:51 volumio volumio-remote-updater[817]: No test mode Jun 26 20:19:51 volumio volumio-remote-updater[817]: No alpha test mode Jun 26 20:19:51 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 26 20:19:52 volumio volumio[1178]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 26 20:19:52 volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 26 20:19:56 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-DISCONNECTED bssid=2c:61:04:38:ed:55 reason=0 locally_generated=1 Jun 26 20:19:56 volumio dhcpcd[983]: wlan0: carrier lost Jun 26 20:19:56 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 26 20:19:56 volumio avahi-daemon[822]: Withdrawing address record for 192.168.0.5 on wlan0. Jun 26 20:19:56 volumio avahi-daemon[822]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.5. Jun 26 20:19:56 volumio avahi-daemon[822]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 26 20:19:56 volumio volumio[1178]: info: Discovery: A device disappeared from network Jun 26 20:19:56 volumio volumio[1178]: info: Discovery: Device volumio disappeared from network Jun 26 20:19:57 volumio dhcpcd[983]: wlan0: deleting route to 192.168.0.0/24 Jun 26 20:19:57 volumio dhcpcd[983]: wlan0: deleting default route via 192.168.0.1 Jun 26 20:19:58 volumio ntpd[2786]: Deleting interface #3 wlan0, 192.168.0.5#123, interface stats: received=65, sent=70, dropped=0, active_time=47 secs Jun 26 20:19:58 volumio ntpd[2786]: 117.80.231.60 local addr 192.168.0.5 -> Jun 26 20:19:58 volumio ntpd[2786]: 84.16.67.12 local addr 192.168.0.5 -> Jun 26 20:19:58 volumio ntpd[2786]: 202.118.1.81 local addr 192.168.0.5 -> Jun 26 20:19:58 volumio ntpd[2786]: 5.79.108.34 local addr 192.168.0.5 -> Jun 26 20:19:58 volumio ntpd[2786]: 162.159.200.123 local addr 192.168.0.5 -> Jun 26 20:19:58 volumio ntpd[2786]: 139.199.214.202 local addr 192.168.0.5 -> Jun 26 20:19:58 volumio ntpd[2786]: 193.182.111.12 local addr 192.168.0.5 -> Jun 26 20:19:58 volumio ntpd[2786]: 116.203.151.74 local addr 192.168.0.5 -> Jun 26 20:19:58 volumio ntpd[2786]: 193.182.111.142 local addr 192.168.0.5 -> Jun 26 20:19:58 volumio ntpd[2786]: 81.168.83.93 local addr 192.168.0.5 -> Jun 26 20:19:58 volumio ntpd[2786]: 193.182.111.141 local addr 192.168.0.5 -> Jun 26 20:19:58 volumio ntpd[2786]: 193.182.111.14 local addr 192.168.0.5 -> Jun 26 20:19:58 volumio wpa_supplicant[1095]: wlan0: Trying to associate with 2c:61:04:38:ed:55 (SSID='3602' freq=2417 MHz) Jun 26 20:19:58 volumio wpa_supplicant[1095]: wlan0: Associated with 2c:61:04:38:ed:55 Jun 26 20:19:58 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 26 20:19:58 volumio wpa_supplicant[1095]: wlan0: WPA: Key negotiation completed with 2c:61:04:38:ed:55 [PTK=CCMP GTK=CCMP] Jun 26 20:19:58 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-CONNECTED - Connection to 2c:61:04:38:ed:55 completed [id=0 id_str=] Jun 26 20:19:58 volumio dhcpcd[983]: wlan0: carrier acquired Jun 26 20:19:58 volumio dhcpcd[983]: wlan0: IAID df:8a:f6:5d Jun 26 20:19:58 volumio dhcpcd[983]: wlan0: rebinding lease of 192.168.0.5 Jun 26 20:19:58 volumio dhcpcd[983]: wlan0: probing address 192.168.0.5/24 Jun 26 20:19:59 volumio dhcpcd[983]: wlan0: soliciting an IPv6 router Jun 26 20:20:01 volumio volumio[1178]: info: Volumio Network Manager: Network status updated: 0 Jun 26 20:20:04 volumio dhcpcd[983]: wlan0: leased 192.168.0.5 for 172800 seconds Jun 26 20:20:04 volumio dhcpcd[983]: wlan0: adding route to 192.168.0.0/24 Jun 26 20:20:04 volumio dhcpcd[983]: wlan0: adding default route via 192.168.0.1 Jun 26 20:20:04 volumio avahi-daemon[822]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.5. Jun 26 20:20:04 volumio avahi-daemon[822]: New relevant interface wlan0.IPv4 for mDNS. Jun 26 20:20:04 volumio avahi-daemon[822]: Registering new address record for 192.168.0.5 on wlan0.IPv4. Jun 26 20:20:04 volumio ntpd[2786]: ntpd exiting on signal 15 (Terminated) Jun 26 20:20:04 volumio systemd[1]: Stopping Network Time Service... Jun 26 20:20:04 volumio systemd[1]: ntp.service: Succeeded. Jun 26 20:20:04 volumio systemd[1]: Stopped Network Time Service. Jun 26 20:20:04 volumio systemd[1]: Starting Network Time Service... Jun 26 20:20:04 volumio ntpd[3079]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 26 20:20:04 volumio ntpd[3079]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 26 20:20:04 volumio systemd[1]: Started Network Time Service. Jun 26 20:20:04 volumio ntpd[3086]: proto: precision = 0.264 usec (-22) Jun 26 20:20:04 volumio ntpd[3086]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 26 20:20:04 volumio ntpd[3086]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 26 20:20:04 volumio ntpd[3086]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 912 days ago Jun 26 20:20:04 volumio ntpd[3086]: Listen and drop on 0 v6wildcard [::]:123 Jun 26 20:20:04 volumio ntpd[3086]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 26 20:20:04 volumio ntpd[3086]: Listen normally on 2 lo 127.0.0.1:123 Jun 26 20:20:04 volumio ntpd[3086]: Listen normally on 3 wlan0 192.168.0.5:123 Jun 26 20:20:04 volumio ntpd[3086]: Listening on routing socket on fd #20 for interface updates Jun 26 20:20:04 volumio ntpd[3086]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 26 20:20:04 volumio ntpd[3086]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 26 20:20:05 volumio volumio[1178]: info: Discovery: adding bc11b65a-6f4b-4197-8dfa-f77119e4ae23 Jun 26 20:20:05 volumio volumio[1178]: info: Discovery: Found device Volumio Jun 26 20:20:05 volumio volumio[1178]: info: CoreCommandRouter::volumioGetState Jun 26 20:20:05 volumio ntpd[3086]: Soliciting pool server 139.199.214.202 Jun 26 20:20:06 volumio ntpd[3086]: Soliciting pool server 84.16.67.12 Jun 26 20:20:07 volumio ntpd[3086]: Soliciting pool server 193.182.111.12 Jun 26 20:20:08 volumio ntpd[3086]: Soliciting pool server 5.79.108.34 Jun 26 20:20:08 volumio ntpd[3086]: Soliciting pool server 185.209.85.222 Jun 26 20:20:09 volumio ntpd[3086]: Soliciting pool server 202.118.1.81 Jun 26 20:20:10 volumio ntpd[3086]: Soliciting pool server 117.80.231.60 Jun 26 20:20:11 volumio ntpd[3086]: Soliciting pool server 2001:1600:3:6::123 Jun 26 20:20:11 volumio volumio[1178]: info: Volumio Network Manager: Network status updated: 2 Jun 26 20:20:44 volumio volumio[1178]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 20:20:44 volumio volumio[1178]: Error: connect ENETUNREACH 104.244.46.17:443 Jun 26 20:20:44 volumio volumio[1178]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 26 20:20:44 volumio volumio[1178]: errno: -101, Jun 26 20:20:44 volumio volumio[1178]: code: 'ENETUNREACH', Jun 26 20:20:44 volumio volumio[1178]: syscall: 'connect', Jun 26 20:20:44 volumio volumio[1178]: address: '104.244.46.17', Jun 26 20:20:44 volumio volumio[1178]: port: 443 Jun 26 20:20:44 volumio volumio[1178]: } Jun 26 20:20:44 volumio volumio[1178]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 20:20:44 volumio sudo[3254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-26 20:19 Jun 26 20:20:44 volumio sudo[3254]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"