-- Logs begin at Sat 2025-04-19 05:31:42 UTC, end at Sat 2025-04-19 06:08:49 UTC. -- Apr 19 06:07:01 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:07:05 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:07:07 volumio ntpd[1247]: Soliciting pool server 185.13.148.71 Apr 19 06:07:09 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:07:13 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:07:17 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:07:21 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:07:23 volumio ntpd[1247]: Soliciting pool server 94.130.191.198 Apr 19 06:07:25 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:07:25 volumio ntpd[1247]: receive: Unexpected origin timestamp 0xebadbc1c.f03ff1b0 does not match aorg 0000000000.00000000 from server@213.206.164.3 xmt 0xebadbc1d.0cff95f4 Apr 19 06:07:26 volumio ntpd[1247]: Soliciting pool server 194.50.19.117 Apr 19 06:07:28 volumio volumio[1199]: info: CorePlayQueue::getTrack 8 Apr 19 06:07:28 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:07:28 volumio volumio[1199]: info: Prefetching next song Apr 19 06:07:28 volumio volumio[1199]: info: DOING PREFETCH IN MPD Apr 19 06:07:28 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand add "NAS/5T/香港流行/吕方/吕方 - 听不到的说话/10 - Track 10.dsf" Apr 19 06:07:28 volumio volumio[1199]: info: Apr 19 06:07:28 volumio volumio[1199]: ---------------------------- MPD announces system playlist update Apr 19 06:07:28 volumio volumio[1199]: info: Ignoring MPD Status Update Apr 19 06:07:28 volumio volumio[1199]: info: sendMpdCommand add "NAS/5T/香港流行/吕方/吕方 - 听不到的说话/10 - Track 10.dsf" took 1 milliseconds Apr 19 06:07:28 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 19 06:07:28 volumio volumio[1199]: info: Apr 19 06:07:28 volumio volumio[1199]: ---------------------------- MPD announces system playlist update Apr 19 06:07:28 volumio volumio[1199]: info: Ignoring MPD Status Update Apr 19 06:07:28 volumio volumio[1199]: info: Apr 19 06:07:28 volumio volumio[1199]: ---------------------------- MPD announces system playlist update Apr 19 06:07:28 volumio volumio[1199]: info: Ignoring MPD Status Update Apr 19 06:07:28 volumio volumio[1199]: info: ------------------------------ 1ms Apr 19 06:07:28 volumio volumio[1199]: info: sendMpdCommand consume 1 took 1 milliseconds Apr 19 06:07:28 volumio volumio[1199]: info: ------------------------------ 0ms Apr 19 06:07:28 volumio volumio[1199]: info: ------------------------------ 0ms Apr 19 06:07:29 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:07:31 volumio volumio[1199]: info: Apr 19 06:07:31 volumio volumio[1199]: ---------------------------- MPD announces system playlist update Apr 19 06:07:31 volumio volumio[1199]: info: Ignoring MPD Status Update Apr 19 06:07:31 volumio volumio[1199]: info: Apr 19 06:07:31 volumio volumio[1199]: ---------------------------- MPD announces state update: player Apr 19 06:07:31 volumio volumio[1199]: info: ControllerMpd::getState Apr 19 06:07:31 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 19 06:07:31 volumio volumio[1199]: info: Apr 19 06:07:31 volumio volumio[1199]: ---------------------------- MPD announces system playlist update Apr 19 06:07:31 volumio volumio[1199]: info: Ignoring MPD Status Update Apr 19 06:07:31 volumio volumio[1199]: info: Apr 19 06:07:31 volumio volumio[1199]: ---------------------------- MPD announces state update: player Apr 19 06:07:31 volumio volumio[1199]: info: ControllerMpd::getState Apr 19 06:07:31 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 19 06:07:31 volumio volumio[1199]: info: Apr 19 06:07:31 volumio volumio[1199]: ---------------------------- MPD announces system playlist update Apr 19 06:07:31 volumio volumio[1199]: info: Ignoring MPD Status Update Apr 19 06:07:31 volumio volumio[1199]: info: Apr 19 06:07:31 volumio volumio[1199]: ---------------------------- MPD announces state update: player Apr 19 06:07:31 volumio volumio[1199]: info: ControllerMpd::getState Apr 19 06:07:31 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 19 06:07:31 volumio volumio[1199]: info: ------------------------------ 3ms Apr 19 06:07:31 volumio volumio[1199]: info: sendMpdCommand status took 2 milliseconds Apr 19 06:07:31 volumio volumio[1199]: info: ------------------------------ 2ms Apr 19 06:07:31 volumio volumio[1199]: info: sendMpdCommand status took 1 milliseconds Apr 19 06:07:31 volumio volumio[1199]: info: ------------------------------ 1ms Apr 19 06:07:31 volumio volumio[1199]: info: sendMpdCommand status took 1 milliseconds Apr 19 06:07:31 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 19 06:07:31 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 06:07:31 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 19 06:07:31 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 06:07:31 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 19 06:07:31 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 06:07:31 volumio volumio[1199]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 19 06:07:31 volumio volumio[1199]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 19 06:07:31 volumio volumio[1199]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 19 06:07:31 volumio volumio[1199]: verbose: ControllerMpd::parseTrackInfo Apr 19 06:07:31 volumio volumio[1199]: verbose: ControllerMpd::parseTrackInfo Apr 19 06:07:31 volumio volumio[1199]: verbose: ControllerMpd::parseTrackInfo Apr 19 06:07:31 volumio volumio[1199]: info: ControllerMpd::pushState Apr 19 06:07:31 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 19 06:07:31 volumio volumio[1199]: info: CorePlayQueue::getTrack 8 Apr 19 06:07:31 volumio volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17,"duration":228,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Track 10","artist":"Lui Fong","album":"EASACD010","uri":"NAS/5T/香港流行/吕方/吕方 - 听不到的说话/10 - Track 10.dsf","trackType":"dsf"} Apr 19 06:07:31 volumio volumio[1199]: verbose: CURRENT POSITION 8 Apr 19 06:07:31 volumio volumio[1199]: info: CoreStateMachine::syncState stateService play Apr 19 06:07:31 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus play Apr 19 06:07:31 volumio volumio[1199]: info: Received an update from plugin. extracting info from payload Apr 19 06:07:31 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:07:31 volumio volumio[1199]: info: CorePlayQueue::getTrack 8 Apr 19 06:07:31 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 06:07:31 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:07:31 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:07:31 volumio volumio[1199]: info: CorePlayQueue::getTrack 8 Apr 19 06:07:31 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:07:31 volumio volumio[1199]: info: ControllerMpd::pushState Apr 19 06:07:31 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 19 06:07:31 volumio volumio[1199]: info: CorePlayQueue::getTrack 8 Apr 19 06:07:31 volumio volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17,"duration":228,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Track 10","artist":"Lui Fong","album":"EASACD010","uri":"NAS/5T/香港流行/吕方/吕方 - 听不到的说话/10 - Track 10.dsf","trackType":"dsf"} Apr 19 06:07:31 volumio volumio[1199]: verbose: CURRENT POSITION 8 Apr 19 06:07:31 volumio volumio[1199]: info: CoreStateMachine::syncState stateService play Apr 19 06:07:31 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus play Apr 19 06:07:31 volumio volumio[1199]: info: Received an update from plugin. extracting info from payload Apr 19 06:07:31 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:07:31 volumio volumio[1199]: info: CorePlayQueue::getTrack 8 Apr 19 06:07:31 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:07:31 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:07:31 volumio volumio[1199]: info: CorePlayQueue::getTrack 8 Apr 19 06:07:31 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:07:31 volumio volumio[1199]: info: ControllerMpd::pushState Apr 19 06:07:31 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 19 06:07:31 volumio volumio[1199]: info: CorePlayQueue::getTrack 8 Apr 19 06:07:31 volumio volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17,"duration":228,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Track 10","artist":"Lui Fong","album":"EASACD010","uri":"NAS/5T/香港流行/吕方/吕方 - 听不到的说话/10 - Track 10.dsf","trackType":"dsf"} Apr 19 06:07:31 volumio volumio[1199]: verbose: CURRENT POSITION 8 Apr 19 06:07:31 volumio volumio[1199]: info: CoreStateMachine::syncState stateService play Apr 19 06:07:31 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus play Apr 19 06:07:31 volumio volumio[1199]: info: Received an update from plugin. extracting info from payload Apr 19 06:07:31 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:07:31 volumio volumio[1199]: info: CorePlayQueue::getTrack 8 Apr 19 06:07:31 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:07:31 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:07:31 volumio volumio[1199]: info: CorePlayQueue::getTrack 8 Apr 19 06:07:31 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:07:31 volumio volumio[1199]: info: ------------------------------ 20ms Apr 19 06:07:31 volumio volumio[1199]: info: ------------------------------ 18ms Apr 19 06:07:31 volumio volumio[1199]: info: ------------------------------ 18ms Apr 19 06:07:32 volumio nmbd[922]: [2025/04/19 06:07:32.041597, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 19 06:07:32 volumio nmbd[922]: query_name_response: Multiple (2) responses received for a query on subnet 10.0.0.25 for name WORKGROUP<1d>. Apr 19 06:07:32 volumio nmbd[922]: This response was from IP 10.0.0.1, reporting an IP address of 10.0.0.1. Apr 19 06:07:33 volumio volumio[1199]: info: CoreStateMachine::startPlaybackTimer Apr 19 06:07:33 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:07:33 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:07:33 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:07:33 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:07:33 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 06:07:33 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:07:37 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:07:41 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:07:43 volumio ntpd[1247]: Soliciting pool server 5.189.151.39 Apr 19 06:07:45 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:07:49 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:07:53 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:07:57 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:08:01 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:08:05 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:08:09 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:08:12 volumio ntpd[1247]: Soliciting pool server 87.238.174.133 Apr 19 06:08:13 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:08:17 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:08:21 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:08:25 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:08:29 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:08:30 volumio ntpd[1247]: Soliciting pool server 194.164.164.175 Apr 19 06:08:32 volumio ntpd[1247]: Soliciting pool server 129.250.35.250 Apr 19 06:08:33 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:08:41 volumio volumio[1199]: info: Airplay started streaming, receiving metadatas Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::volumioStop Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::stop Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::stPlaybackTimer Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::updateTrackBlock Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrackBlock Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::serviceStop Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::serviceStop Apr 19 06:08:41 volumio volumio[1199]: info: ControllerMpd::stop Apr 19 06:08:41 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 06:08:41 volumio volumio[1199]: info: Airplay started streaming, receiving metadatas Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::volumioStop Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::stop Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 06:08:41 volumio volumio[1199]: info: Airplay started streaming, receiving metadatas Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::volumioStop Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::stop Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 06:08:41 volumio volumio[1199]: info: sendMpdCommand stop took 4 milliseconds Apr 19 06:08:41 volumio volumio[1199]: info: Apr 19 06:08:41 volumio volumio[1199]: ---------------------------- MPD announces state update: player Apr 19 06:08:41 volumio volumio[1199]: info: ControllerMpd::getState Apr 19 06:08:41 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 19 06:08:41 volumio volumio[1199]: info: Apr 19 06:08:41 volumio volumio[1199]: ---------------------------- MPD announces state update: player Apr 19 06:08:41 volumio volumio[1199]: info: ControllerMpd::getState Apr 19 06:08:41 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 19 06:08:41 volumio volumio[1199]: info: Apr 19 06:08:41 volumio volumio[1199]: ---------------------------- MPD announces state update: player Apr 19 06:08:41 volumio volumio[1199]: info: ControllerMpd::getState Apr 19 06:08:41 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 19 06:08:41 volumio volumio[1199]: info: sendMpdCommand status took 1 milliseconds Apr 19 06:08:41 volumio volumio[1199]: info: sendMpdCommand status took 1 milliseconds Apr 19 06:08:41 volumio volumio[1199]: info: sendMpdCommand status took 0 milliseconds Apr 19 06:08:41 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 19 06:08:41 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 06:08:41 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 19 06:08:41 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 06:08:41 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 19 06:08:41 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 06:08:41 volumio volumio[1199]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 19 06:08:41 volumio volumio[1199]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 19 06:08:41 volumio volumio[1199]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 19 06:08:41 volumio volumio[1199]: verbose: ControllerMpd::parseTrackInfo Apr 19 06:08:41 volumio volumio[1199]: verbose: ControllerMpd::parseTrackInfo Apr 19 06:08:41 volumio volumio[1199]: verbose: ControllerMpd::parseTrackInfo Apr 19 06:08:41 volumio volumio[1199]: info: ControllerMpd::pushState Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 10","artist":"Lui Fong","album":"EASACD010","uri":"NAS/5T/香港流行/吕方/吕方 - 听不到的说话/10 - Track 10.dsf","trackType":"dsf"} Apr 19 06:08:41 volumio volumio[1199]: verbose: CURRENT POSITION 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:41 volumio volumio[1199]: info: No code Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:41 volumio volumio[1199]: info: ControllerMpd::pushState Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 10","artist":"Lui Fong","album":"EASACD010","uri":"NAS/5T/香港流行/吕方/吕方 - 听不到的说话/10 - Track 10.dsf","trackType":"dsf"} Apr 19 06:08:41 volumio volumio[1199]: verbose: CURRENT POSITION 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:41 volumio volumio[1199]: info: No code Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:41 volumio volumio[1199]: info: ControllerMpd::pushState Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 10","artist":"Lui Fong","album":"EASACD010","uri":"NAS/5T/香港流行/吕方/吕方 - 听不到的说话/10 - Track 10.dsf","trackType":"dsf"} Apr 19 06:08:41 volumio volumio[1199]: verbose: CURRENT POSITION 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:41 volumio volumio[1199]: info: No code Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:41 volumio shairport-sync[1761]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 19 06:08:41 volumio shairport-sync[1761]: Dload Upload Total Spent Left Speed Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:41 volumio volumio[1199]: info: ------------------------------ 30ms Apr 19 06:08:41 volumio volumio[1199]: info: ------------------------------ 30ms Apr 19 06:08:41 volumio volumio[1199]: info: ------------------------------ 30ms Apr 19 06:08:41 volumio volumio[1199]: info: Apr 19 06:08:41 volumio volumio[1199]: ---------------------------- Client requests Start Airplay PlaybackRoutine Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Apr 19 06:08:41 volumio volumio[1199]: info: Airplay playback start Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::volumioStop Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::stop Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 06:08:41 volumio shairport-sync[1761]: [158B blob data] Apr 19 06:08:41 volumio volumio[1199]: info: Updating volume from AirPlay: -20; 33% Apr 19 06:08:41 volumio volumio[1199]: info: VolumeController::SetAlsaVolume33 Apr 19 06:08:41 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:41 volumio volumio[1199]: info: CorePlayQueue::getTrack 9 Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 06:08:41 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:42 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:08:42 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 19 06:08:42 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:42 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 06:08:42 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:42 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 19 06:08:42 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:42 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:42 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 19 06:08:42 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:42 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:42 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 19 06:08:42 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:42 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 06:08:43 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 06:08:43 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:43 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:45 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 06:08:45 volumio volumio[1199]: info: Updating volume from AirPlay: -18.12; 40% Apr 19 06:08:45 volumio volumio[1199]: info: VolumeController::SetAlsaVolume40 Apr 19 06:08:45 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:45 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 06:08:45 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:47 volumio volumio[1199]: info: Updating volume from AirPlay: -16.25; 46% Apr 19 06:08:47 volumio volumio[1199]: info: VolumeController::SetAlsaVolume46 Apr 19 06:08:47 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:47 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 06:08:47 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:49 volumio volumio[1199]: info: Updating volume from AirPlay: -14.37; 52% Apr 19 06:08:49 volumio volumio[1199]: info: VolumeController::SetAlsaVolume52 Apr 19 06:08:49 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 19 06:08:49 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 06:08:49 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 19 06:08:49 volumio volumio[1199]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 06:08:49 volumio volumio[1199]: Error: Client network socket disconnected before secure TLS connection was established Apr 19 06:08:49 volumio volumio[1199]: at connResetException (internal/errors.js:639:14) Apr 19 06:08:49 volumio volumio[1199]: at TLSSocket.onConnectEnd (_tls_wrap.js:1570:19) Apr 19 06:08:49 volumio volumio[1199]: at TLSSocket.emit (events.js:412:35) Apr 19 06:08:49 volumio volumio[1199]: at endReadableNT (internal/streams/readable.js:1333:12) Apr 19 06:08:49 volumio volumio[1199]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Apr 19 06:08:49 volumio volumio[1199]: code: 'ECONNRESET', Apr 19 06:08:49 volumio volumio[1199]: path: null, Apr 19 06:08:49 volumio volumio[1199]: host: 'lastfm.freetls.fastly.net', Apr 19 06:08:49 volumio volumio[1199]: port: 443, Apr 19 06:08:49 volumio volumio[1199]: localAddress: undefined Apr 19 06:08:49 volumio volumio[1199]: } Apr 19 06:08:49 volumio volumio[1199]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 06:08:49 volumio sudo[11889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 06:07 Apr 19 06:08:49 volumio sudo[11889]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"