-- Logs begin at Wed 2025-05-21 14:20:19 UTC, end at Wed 2025-05-21 14:36:55 UTC. -- May 21 14:35:12 volumio volumio[5018]: info: May 21 14:35:12 volumio volumio[5018]: ---------------------------- MPD announces state update: player May 21 14:35:12 volumio volumio[5018]: info: ControllerMpd::getState May 21 14:35:12 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand status May 21 14:35:12 volumio volumio[5018]: info: sendMpdCommand status took 1 milliseconds May 21 14:35:12 volumio volumio[5018]: verbose: ControllerMpd::parseState May 21 14:35:12 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:35:12 volumio volumio[5018]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 14:35:12 volumio volumio[5018]: verbose: ControllerMpd::parseTrackInfo May 21 14:35:12 volumio volumio[5018]: info: ControllerMpd::pushState May 21 14:35:12 volumio volumio[5018]: info: CoreCommandRouter::servicePushState May 21 14:35:12 volumio volumio[5018]: info: CoreStateMachine::pushState May 21 14:35:12 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:35:12 volumio volumio[5018]: info: CorePlayQueue::getTrack 0 May 21 14:35:12 volumio volumio[5018]: 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":"平静的喜悦","artist":"巫娜","album":"天禅","uri":"http://m801.music.126.net/20250521225946/145081ec266cd4c700cf6b45b118e05a/jdyyaac/5359/5253/515c/d38c4a2e64e9e0d92aedf6fad251f868.m4a?vuutv=hz1iN2WocD6R25UlXzFauDuJALtt76Jik28Nrha7zlTXp7ggrKfWxJ3vOT+KcEByeOCFozZkX5+N1X9K/GL1toaQFEYqo0PlsfrOQpTB3QQ=","trackType":""} May 21 14:35:12 volumio volumio[5018]: verbose: CURRENT POSITION 0 May 21 14:35:12 volumio volumio[5018]: info: CoreStateMachine::syncState stateService stop May 21 14:35:12 volumio volumio[5018]: info: CoreStateMachine::syncState currentStatus play May 21 14:35:12 volumio volumio[5018]: info: CoreStateMachine::play index undefined May 21 14:35:12 volumio volumio[5018]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 14:35:12 volumio volumio[5018]: info: CoreStateMachine::pushState May 21 14:35:12 volumio volumio[5018]: info: CorePlayQueue::getTrack 0 May 21 14:35:12 volumio volumio[5018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:35:12 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:35:12 volumio volumio[5018]: info: CorePlayQueue::getTrack 0 May 21 14:35:12 volumio volumio[5018]: info: ------------------------------ 19ms May 21 14:35:12 volumio volumio[5018]: info: CoreCommandRouter::volumioGetQueue May 21 14:35:12 volumio volumio[5018]: info: CoreStateMachine::getQueue May 21 14:35:12 volumio volumio[5018]: info: CorePlayQueue::getQueue May 21 14:35:12 volumio volumio[5018]: info: Clearing queue after UPNP request May 21 14:35:12 volumio volumio[5018]: info: CoreStateMachine::ClearQueue May 21 14:35:12 volumio volumio[5018]: info: CoreStateMachine::stop May 21 14:35:12 volumio volumio[5018]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 14:35:12 volumio volumio[5018]: info: CorePlayQueue::clearPlayQueue May 21 14:35:12 volumio volumio[5018]: info: CorePlayQueue::saveQueue May 21 14:35:12 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:35:12 volumio volumio[5018]: info: CoreCommandRouter::volumioPushQueue May 21 14:35:12 volumio volumio[5018]: info: May 21 14:35:12 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:35:12 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:35:12 volumio volumio[5018]: error: updateQueue error: null May 21 14:35:12 volumio volumio[5018]: info: ------------------------------ 11ms May 21 14:35:12 volumio volumio[5018]: info: Starting UPNP Playback May 21 14:35:12 volumio volumio[5018]: info: Preparing playback through UPNP May 21 14:35:12 volumio volumio[5018]: info: CoreCommandRouter::volumioGetState May 21 14:35:12 volumio volumio[5018]: info: CorePlayQueue::getTrack 0 May 21 14:35:12 volumio volumio[5018]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 14:35:13 volumio volumio[5018]: info: May 21 14:35:13 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:35:13 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:35:13 volumio volumio[5018]: info: ------------------------------ 1ms May 21 14:35:13 volumio volumio[5018]: info: May 21 14:35:13 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:35:13 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:35:13 volumio volumio[5018]: info: ------------------------------ 2ms May 21 14:35:13 volumio volumio[5018]: info: May 21 14:35:13 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:35:13 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:35:13 volumio volumio[5018]: info: ------------------------------ 1ms May 21 14:35:13 volumio volumio[5018]: info: May 21 14:35:13 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:35:13 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:35:13 volumio volumio[5018]: info: ------------------------------ 2ms May 21 14:35:13 volumio volumio[5018]: info: May 21 14:35:13 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:35:13 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:35:13 volumio volumio[5018]: info: ------------------------------ 1ms May 21 14:35:13 volumio volumio[5018]: info: May 21 14:35:13 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:35:13 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:35:13 volumio volumio[5018]: info: ------------------------------ 2ms May 21 14:35:13 volumio volumio[5018]: info: May 21 14:35:13 volumio volumio[5018]: ---------------------------- MPD announces state update: player May 21 14:35:13 volumio volumio[5018]: info: ControllerMpd::getState May 21 14:35:13 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand status May 21 14:35:13 volumio volumio[5018]: info: May 21 14:35:13 volumio volumio[5018]: ---------------------------- MPD announces state update: player May 21 14:35:13 volumio volumio[5018]: info: sendMpdCommand status took 4 milliseconds May 21 14:35:13 volumio volumio[5018]: info: ControllerMpd::getState May 21 14:35:13 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand status May 21 14:35:13 volumio volumio[5018]: verbose: ControllerMpd::parseState May 21 14:35:13 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:35:13 volumio volumio[5018]: info: sendMpdCommand status took 2 milliseconds May 21 14:35:13 volumio volumio[5018]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 14:35:13 volumio volumio[5018]: verbose: ControllerMpd::parseState May 21 14:35:13 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:35:13 volumio volumio[5018]: verbose: ControllerMpd::parseTrackInfo May 21 14:35:13 volumio volumio[5018]: info: ControllerMpd::pushState May 21 14:35:13 volumio volumio[5018]: info: CoreCommandRouter::servicePushState May 21 14:35:13 volumio volumio[5018]: verbose: In UPNP mode May 21 14:35:13 volumio volumio[5018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2038,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"精品串烧 六点半进行曲DJ串烧2025","artist":"DJ小健Mix","album":"未知专辑","uri":"http://m7.music.126.net/20250521230909/47d1a9f966c24f234ed5ce2fd62855bd/ymusic/obj/w5zDlMODwrDDiGjCn8Ky/59808724252/9929/c849/2090/8c87e4f1ec07e7e9435afd7a40cf28ff.mp3","trackType":"mp3"} May 21 14:35:13 volumio volumio[5018]: verbose: CURRENT POSITION 0 May 21 14:35:13 volumio volumio[5018]: info: CoreStateMachine::syncState stateService play May 21 14:35:13 volumio volumio[5018]: info: CoreStateMachine::syncState currentStatus stop May 21 14:35:13 volumio volumio[5018]: info: ------------------------------ 14ms May 21 14:35:13 volumio volumio[5018]: info: sendMpdCommand playlistinfo took 4 milliseconds May 21 14:35:13 volumio volumio[5018]: verbose: ControllerMpd::parseTrackInfo May 21 14:35:13 volumio volumio[5018]: info: ControllerMpd::pushState May 21 14:35:13 volumio volumio[5018]: info: CoreCommandRouter::servicePushState May 21 14:35:13 volumio volumio[5018]: verbose: In UPNP mode May 21 14:35:13 volumio volumio[5018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2038,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"精品串烧 六点半进行曲DJ串烧2025","artist":"DJ小健Mix","album":"未知专辑","uri":"http://m7.music.126.net/20250521230909/47d1a9f966c24f234ed5ce2fd62855bd/ymusic/obj/w5zDlMODwrDDiGjCn8Ky/59808724252/9929/c849/2090/8c87e4f1ec07e7e9435afd7a40cf28ff.mp3","trackType":"mp3"} May 21 14:35:13 volumio volumio[5018]: verbose: CURRENT POSITION 0 May 21 14:35:13 volumio volumio[5018]: info: CoreStateMachine::syncState stateService play May 21 14:35:13 volumio volumio[5018]: info: CoreStateMachine::syncState currentStatus play May 21 14:35:13 volumio volumio[5018]: info: Received an update from plugin. extracting info from payload May 21 14:35:13 volumio volumio[5018]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 14:35:13 volumio volumio[5018]: info: CoreStateMachine::pushState May 21 14:35:13 volumio volumio[5018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:35:13 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:35:13 volumio volumio[5018]: info: CoreStateMachine::pushState May 21 14:35:13 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:35:13 volumio volumio[5018]: info: ------------------------------ 51ms May 21 14:35:13 volumio volumio[5018]: info: CoreCommandRouter::volumioGetQueue May 21 14:35:13 volumio volumio[5018]: info: CoreStateMachine::getQueue May 21 14:35:13 volumio volumio[5018]: info: CorePlayQueue::getQueue May 21 14:35:13 volumio volumio[5018]: info: CoreCommandRouter::volumioGetQueue May 21 14:35:13 volumio volumio[5018]: info: CoreStateMachine::getQueue May 21 14:35:13 volumio volumio[5018]: info: CorePlayQueue::getQueue May 21 14:35:26 volumio volumio[5018]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 21 14:35:26 volumio volumio[5018]: info: Retrying Login Due to Network Error May 21 14:35:26 volumio systemd[1]: Starting Cleanup of Temporary Directories... May 21 14:35:26 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. May 21 14:35:26 volumio systemd[1]: Started Cleanup of Temporary Directories. May 21 14:35:28 volumio volumio[5018]: info: May 21 14:35:28 volumio volumio[5018]: ---------------------------- MPD announces state update: player May 21 14:35:28 volumio volumio[5018]: info: ControllerMpd::getState May 21 14:35:28 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand status May 21 14:35:28 volumio volumio[5018]: info: sendMpdCommand status took 1 milliseconds May 21 14:35:28 volumio volumio[5018]: verbose: ControllerMpd::parseState May 21 14:35:28 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:35:28 volumio volumio[5018]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 14:35:28 volumio volumio[5018]: verbose: ControllerMpd::parseTrackInfo May 21 14:35:28 volumio volumio[5018]: info: ControllerMpd::pushState May 21 14:35:28 volumio volumio[5018]: info: CoreCommandRouter::servicePushState May 21 14:35:28 volumio volumio[5018]: info: CoreStateMachine::pushState May 21 14:35:28 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:35:28 volumio volumio[5018]: info: CorePlayQueue::getTrack 0 May 21 14:35:28 volumio volumio[5018]: 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":"精品串烧 六点半进行曲DJ串烧2025","artist":"DJ小健Mix","album":"未知专辑","uri":"http://m7.music.126.net/20250521230909/47d1a9f966c24f234ed5ce2fd62855bd/ymusic/obj/w5zDlMODwrDDiGjCn8Ky/59808724252/9929/c849/2090/8c87e4f1ec07e7e9435afd7a40cf28ff.mp3","trackType":"mp3"} May 21 14:35:28 volumio volumio[5018]: verbose: CURRENT POSITION 0 May 21 14:35:28 volumio volumio[5018]: info: CoreStateMachine::syncState stateService stop May 21 14:35:28 volumio volumio[5018]: info: CoreStateMachine::syncState currentStatus play May 21 14:35:28 volumio volumio[5018]: info: CoreStateMachine::play index undefined May 21 14:35:28 volumio volumio[5018]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 14:35:28 volumio volumio[5018]: info: CoreStateMachine::pushState May 21 14:35:28 volumio volumio[5018]: info: CorePlayQueue::getTrack 0 May 21 14:35:28 volumio volumio[5018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:35:28 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:35:28 volumio volumio[5018]: info: CorePlayQueue::getTrack 0 May 21 14:35:28 volumio volumio[5018]: info: ------------------------------ 24ms May 21 14:35:28 volumio volumio[5018]: info: CoreCommandRouter::volumioGetQueue May 21 14:35:28 volumio volumio[5018]: info: CoreStateMachine::getQueue May 21 14:35:28 volumio volumio[5018]: info: CorePlayQueue::getQueue May 21 14:35:30 volumio volumio[5018]: info: Clearing queue after UPNP request May 21 14:35:30 volumio volumio[5018]: info: CoreStateMachine::ClearQueue May 21 14:35:30 volumio volumio[5018]: info: CoreStateMachine::stop May 21 14:35:30 volumio volumio[5018]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 14:35:30 volumio volumio[5018]: info: CorePlayQueue::clearPlayQueue May 21 14:35:30 volumio volumio[5018]: info: CorePlayQueue::saveQueue May 21 14:35:30 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:35:30 volumio volumio[5018]: info: CoreCommandRouter::volumioPushQueue May 21 14:35:30 volumio volumio[5018]: info: May 21 14:35:30 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:35:30 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:35:30 volumio volumio[5018]: error: updateQueue error: null May 21 14:35:30 volumio volumio[5018]: info: ------------------------------ 1ms May 21 14:35:30 volumio volumio[5018]: info: Starting UPNP Playback May 21 14:35:30 volumio volumio[5018]: info: Preparing playback through UPNP May 21 14:35:30 volumio volumio[5018]: info: CoreCommandRouter::volumioGetState May 21 14:35:30 volumio volumio[5018]: info: CorePlayQueue::getTrack 0 May 21 14:35:30 volumio volumio[5018]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 14:35:31 volumio volumio[5018]: info: May 21 14:35:31 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:35:31 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:35:31 volumio volumio[5018]: info: ------------------------------ 2ms May 21 14:35:31 volumio volumio[5018]: info: May 21 14:35:31 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:35:31 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:35:31 volumio volumio[5018]: info: ------------------------------ 2ms May 21 14:35:31 volumio volumio[5018]: info: May 21 14:35:31 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:35:31 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:35:31 volumio volumio[5018]: info: ------------------------------ 2ms May 21 14:35:31 volumio volumio[5018]: info: May 21 14:35:31 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:35:31 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:35:31 volumio volumio[5018]: info: ------------------------------ 1ms May 21 14:35:31 volumio volumio[5018]: info: May 21 14:35:31 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:35:31 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:35:31 volumio volumio[5018]: info: ------------------------------ 2ms May 21 14:35:31 volumio volumio[5018]: info: May 21 14:35:31 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:35:31 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:35:31 volumio volumio[5018]: info: ------------------------------ 1ms May 21 14:35:31 volumio volumio[5018]: info: May 21 14:35:31 volumio volumio[5018]: ---------------------------- MPD announces state update: player May 21 14:35:31 volumio volumio[5018]: info: ControllerMpd::getState May 21 14:35:31 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand status May 21 14:35:31 volumio volumio[5018]: info: May 21 14:35:31 volumio volumio[5018]: ---------------------------- MPD announces state update: player May 21 14:35:31 volumio volumio[5018]: info: ControllerMpd::getState May 21 14:35:31 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand status May 21 14:35:31 volumio volumio[5018]: info: sendMpdCommand status took 2 milliseconds May 21 14:35:31 volumio volumio[5018]: verbose: ControllerMpd::parseState May 21 14:35:31 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:35:31 volumio volumio[5018]: info: sendMpdCommand status took 2 milliseconds May 21 14:35:31 volumio volumio[5018]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 14:35:31 volumio volumio[5018]: verbose: ControllerMpd::parseState May 21 14:35:31 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:35:31 volumio volumio[5018]: verbose: ControllerMpd::parseTrackInfo May 21 14:35:31 volumio volumio[5018]: info: ControllerMpd::pushState May 21 14:35:31 volumio volumio[5018]: info: CoreCommandRouter::servicePushState May 21 14:35:31 volumio volumio[5018]: verbose: In UPNP mode May 21 14:35:31 volumio volumio[5018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":363,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"将进酒 (live)","artist":"陈楚生","album":"歌手2025 第1期","uri":"http://m801.music.126.net/20250521230029/915c9d953522cf93782c6a374c49cfb7/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/60006955831/ab67/a8a2/1e27/2584ecdecc348896112d38c4477b0e7b.m4a?vuutv=JYlS7P9lhEK+McykVgwPVe99l3bc4JZ+fnOLE/nangrCT51Mf94XDymUQ0o+YXjMykXHCQ/V/6Z2szWrgZNxTUMCMYdB+qx4b5nPIT3TQbw=","trackType":""} May 21 14:35:31 volumio volumio[5018]: verbose: CURRENT POSITION 0 May 21 14:35:31 volumio volumio[5018]: info: CoreStateMachine::syncState stateService play May 21 14:35:31 volumio volumio[5018]: info: CoreStateMachine::syncState currentStatus stop May 21 14:35:31 volumio volumio[5018]: info: ------------------------------ 13ms May 21 14:35:31 volumio volumio[5018]: info: sendMpdCommand playlistinfo took 5 milliseconds May 21 14:35:31 volumio volumio[5018]: verbose: ControllerMpd::parseTrackInfo May 21 14:35:31 volumio volumio[5018]: info: ControllerMpd::pushState May 21 14:35:31 volumio volumio[5018]: info: CoreCommandRouter::servicePushState May 21 14:35:31 volumio volumio[5018]: verbose: In UPNP mode May 21 14:35:31 volumio volumio[5018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":363,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"将进酒 (live)","artist":"陈楚生","album":"歌手2025 第1期","uri":"http://m801.music.126.net/20250521230029/915c9d953522cf93782c6a374c49cfb7/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/60006955831/ab67/a8a2/1e27/2584ecdecc348896112d38c4477b0e7b.m4a?vuutv=JYlS7P9lhEK+McykVgwPVe99l3bc4JZ+fnOLE/nangrCT51Mf94XDymUQ0o+YXjMykXHCQ/V/6Z2szWrgZNxTUMCMYdB+qx4b5nPIT3TQbw=","trackType":""} May 21 14:35:31 volumio volumio[5018]: verbose: CURRENT POSITION 0 May 21 14:35:31 volumio volumio[5018]: info: CoreStateMachine::syncState stateService play May 21 14:35:31 volumio volumio[5018]: info: CoreStateMachine::syncState currentStatus play May 21 14:35:31 volumio volumio[5018]: info: Received an update from plugin. extracting info from payload May 21 14:35:31 volumio volumio[5018]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 14:35:31 volumio volumio[5018]: info: CoreStateMachine::pushState May 21 14:35:31 volumio volumio[5018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:35:31 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:35:31 volumio volumio[5018]: info: CoreStateMachine::pushState May 21 14:35:31 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:35:31 volumio volumio[5018]: info: ------------------------------ 38ms May 21 14:35:31 volumio volumio[5018]: info: CoreCommandRouter::volumioGetQueue May 21 14:35:31 volumio volumio[5018]: info: CoreStateMachine::getQueue May 21 14:35:31 volumio volumio[5018]: info: CorePlayQueue::getQueue May 21 14:35:31 volumio volumio[5018]: info: CoreCommandRouter::volumioGetQueue May 21 14:35:31 volumio volumio[5018]: info: CoreStateMachine::getQueue May 21 14:35:31 volumio volumio[5018]: info: CorePlayQueue::getQueue May 21 14:35:36 volumio volumio[5018]: info: MyVolumio login type: Token May 21 14:36:00 volumio volumio[5018]: info: May 21 14:36:00 volumio volumio[5018]: ---------------------------- MPD announces state update: player May 21 14:36:00 volumio volumio[5018]: info: ControllerMpd::getState May 21 14:36:00 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand status May 21 14:36:00 volumio volumio[5018]: info: sendMpdCommand status took 3 milliseconds May 21 14:36:00 volumio volumio[5018]: verbose: ControllerMpd::parseState May 21 14:36:00 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:36:00 volumio volumio[5018]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 14:36:00 volumio volumio[5018]: verbose: ControllerMpd::parseTrackInfo May 21 14:36:00 volumio volumio[5018]: info: ControllerMpd::pushState May 21 14:36:00 volumio volumio[5018]: info: CoreCommandRouter::servicePushState May 21 14:36:00 volumio volumio[5018]: info: CoreStateMachine::pushState May 21 14:36:00 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:36:00 volumio volumio[5018]: info: CorePlayQueue::getTrack 0 May 21 14:36:00 volumio volumio[5018]: 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":"将进酒 (live)","artist":"陈楚生","album":"歌手2025 第1期","uri":"http://m801.music.126.net/20250521230029/915c9d953522cf93782c6a374c49cfb7/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/60006955831/ab67/a8a2/1e27/2584ecdecc348896112d38c4477b0e7b.m4a?vuutv=JYlS7P9lhEK+McykVgwPVe99l3bc4JZ+fnOLE/nangrCT51Mf94XDymUQ0o+YXjMykXHCQ/V/6Z2szWrgZNxTUMCMYdB+qx4b5nPIT3TQbw=","trackType":""} May 21 14:36:00 volumio volumio[5018]: verbose: CURRENT POSITION 0 May 21 14:36:00 volumio volumio[5018]: info: CoreStateMachine::syncState stateService stop May 21 14:36:00 volumio volumio[5018]: info: CoreStateMachine::syncState currentStatus play May 21 14:36:00 volumio volumio[5018]: info: CoreStateMachine::play index undefined May 21 14:36:00 volumio volumio[5018]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 14:36:00 volumio volumio[5018]: info: CoreStateMachine::pushState May 21 14:36:00 volumio volumio[5018]: info: CorePlayQueue::getTrack 0 May 21 14:36:00 volumio volumio[5018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:36:00 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:36:00 volumio volumio[5018]: info: CorePlayQueue::getTrack 0 May 21 14:36:00 volumio volumio[5018]: info: ------------------------------ 22ms May 21 14:36:00 volumio volumio[5018]: info: CoreCommandRouter::volumioGetQueue May 21 14:36:00 volumio volumio[5018]: info: CoreStateMachine::getQueue May 21 14:36:00 volumio volumio[5018]: info: CorePlayQueue::getQueue May 21 14:36:00 volumio volumio[5018]: info: Clearing queue after UPNP request May 21 14:36:00 volumio volumio[5018]: info: CoreStateMachine::ClearQueue May 21 14:36:00 volumio volumio[5018]: info: CoreStateMachine::stop May 21 14:36:00 volumio volumio[5018]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 14:36:00 volumio volumio[5018]: info: CorePlayQueue::clearPlayQueue May 21 14:36:00 volumio volumio[5018]: info: CorePlayQueue::saveQueue May 21 14:36:00 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:36:00 volumio volumio[5018]: info: CoreCommandRouter::volumioPushQueue May 21 14:36:00 volumio volumio[5018]: info: May 21 14:36:00 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:36:00 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:36:00 volumio volumio[5018]: error: updateQueue error: null May 21 14:36:00 volumio volumio[5018]: info: ------------------------------ 2ms May 21 14:36:00 volumio volumio[5018]: info: Starting UPNP Playback May 21 14:36:00 volumio volumio[5018]: info: Preparing playback through UPNP May 21 14:36:00 volumio volumio[5018]: info: CoreCommandRouter::volumioGetState May 21 14:36:00 volumio volumio[5018]: info: CorePlayQueue::getTrack 0 May 21 14:36:00 volumio volumio[5018]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 14:36:01 volumio volumio[5018]: info: May 21 14:36:01 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:36:01 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:36:01 volumio volumio[5018]: info: ------------------------------ 2ms May 21 14:36:01 volumio volumio[5018]: info: May 21 14:36:01 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:36:01 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:36:01 volumio volumio[5018]: info: ------------------------------ 1ms May 21 14:36:01 volumio volumio[5018]: info: May 21 14:36:01 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:36:01 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:36:01 volumio volumio[5018]: info: ------------------------------ 1ms May 21 14:36:01 volumio volumio[5018]: info: May 21 14:36:01 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:36:01 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:36:01 volumio volumio[5018]: info: ------------------------------ 1ms May 21 14:36:01 volumio volumio[5018]: info: May 21 14:36:01 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:36:01 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:36:01 volumio volumio[5018]: info: ------------------------------ 1ms May 21 14:36:01 volumio volumio[5018]: info: May 21 14:36:01 volumio volumio[5018]: ---------------------------- MPD announces system playlist update May 21 14:36:01 volumio volumio[5018]: info: Ignoring MPD Status Update May 21 14:36:01 volumio volumio[5018]: info: ------------------------------ 1ms May 21 14:36:01 volumio volumio[5018]: info: May 21 14:36:01 volumio volumio[5018]: ---------------------------- MPD announces state update: player May 21 14:36:01 volumio volumio[5018]: info: ControllerMpd::getState May 21 14:36:01 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand status May 21 14:36:01 volumio volumio[5018]: info: sendMpdCommand status took 3 milliseconds May 21 14:36:01 volumio volumio[5018]: verbose: ControllerMpd::parseState May 21 14:36:01 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:36:01 volumio volumio[5018]: info: May 21 14:36:01 volumio volumio[5018]: ---------------------------- MPD announces state update: player May 21 14:36:01 volumio volumio[5018]: info: ControllerMpd::getState May 21 14:36:01 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand status May 21 14:36:01 volumio volumio[5018]: info: sendMpdCommand playlistinfo took 5 milliseconds May 21 14:36:01 volumio volumio[5018]: verbose: ControllerMpd::parseTrackInfo May 21 14:36:01 volumio volumio[5018]: info: ControllerMpd::pushState May 21 14:36:01 volumio volumio[5018]: info: CoreCommandRouter::servicePushState May 21 14:36:01 volumio volumio[5018]: verbose: In UPNP mode May 21 14:36:01 volumio volumio[5018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":308,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"梅花三弄","artist":"中央民族乐团","album":"古筝传奇","uri":"http://m801.music.126.net/20250521230100/fbdbeb19918eaeeb8ccd2da61a7ddfd7/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/36507326650/febd/1b96/27c8/d1a498d1cc820cd2809ce35a418e088b.m4a?vuutv=LbV0MfIGKVC4zODLKt9QhdYf4LgPLm5/uUl88oUHoACJfLv0GoBpM8nA+leobt0yX8Zw2X6OXwt7JIcOS6CMaCDrFe6x4RVR9JenV7iywVs=","trackType":""} May 21 14:36:01 volumio volumio[5018]: verbose: CURRENT POSITION 0 May 21 14:36:01 volumio volumio[5018]: info: CoreStateMachine::syncState stateService play May 21 14:36:01 volumio volumio[5018]: info: CoreStateMachine::syncState currentStatus stop May 21 14:36:01 volumio volumio[5018]: info: ------------------------------ 14ms May 21 14:36:01 volumio volumio[5018]: info: sendMpdCommand status took 6 milliseconds May 21 14:36:01 volumio volumio[5018]: verbose: ControllerMpd::parseState May 21 14:36:01 volumio volumio[5018]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 14:36:01 volumio volumio[5018]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 14:36:01 volumio volumio[5018]: verbose: ControllerMpd::parseTrackInfo May 21 14:36:01 volumio volumio[5018]: info: ControllerMpd::pushState May 21 14:36:01 volumio volumio[5018]: info: CoreCommandRouter::servicePushState May 21 14:36:01 volumio volumio[5018]: verbose: In UPNP mode May 21 14:36:01 volumio volumio[5018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":308,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"梅花三弄","artist":"中央民族乐团","album":"古筝传奇","uri":"http://m801.music.126.net/20250521230100/fbdbeb19918eaeeb8ccd2da61a7ddfd7/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/36507326650/febd/1b96/27c8/d1a498d1cc820cd2809ce35a418e088b.m4a?vuutv=LbV0MfIGKVC4zODLKt9QhdYf4LgPLm5/uUl88oUHoACJfLv0GoBpM8nA+leobt0yX8Zw2X6OXwt7JIcOS6CMaCDrFe6x4RVR9JenV7iywVs=","trackType":""} May 21 14:36:01 volumio volumio[5018]: verbose: CURRENT POSITION 0 May 21 14:36:01 volumio volumio[5018]: info: CoreStateMachine::syncState stateService play May 21 14:36:01 volumio volumio[5018]: info: CoreStateMachine::syncState currentStatus play May 21 14:36:01 volumio volumio[5018]: info: Received an update from plugin. extracting info from payload May 21 14:36:01 volumio volumio[5018]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 14:36:01 volumio volumio[5018]: info: CoreStateMachine::pushState May 21 14:36:01 volumio volumio[5018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:36:01 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:36:01 volumio volumio[5018]: info: CoreStateMachine::pushState May 21 14:36:01 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:36:01 volumio volumio[5018]: info: ------------------------------ 34ms May 21 14:36:01 volumio volumio[5018]: info: CoreCommandRouter::volumioGetQueue May 21 14:36:01 volumio volumio[5018]: info: CoreStateMachine::getQueue May 21 14:36:01 volumio volumio[5018]: info: CorePlayQueue::getQueue May 21 14:36:01 volumio volumio[5018]: info: CoreCommandRouter::volumioGetQueue May 21 14:36:01 volumio volumio[5018]: info: CoreStateMachine::getQueue May 21 14:36:01 volumio volumio[5018]: info: CorePlayQueue::getQueue May 21 14:36:04 volumio volumio[1884]: 20 May 21 14:36:04 volumio volumio[1884]: % Total % Received % Xferd Average Speed Time Time Time Current May 21 14:36:04 volumio volumio[1884]: Dload Upload Total Spent Left Speed May 21 14:36:04 volumio volumio[5018]: info: May 21 14:36:04 volumio volumio[5018]: ---------------------------- Client requests Volume 20 May 21 14:36:04 volumio volumio[5018]: info: VolumeController::SetAlsaVolume20 May 21 14:36:04 volumio volumio[5018]: info: CoreStateMachine::pushState May 21 14:36:04 volumio volumio[5018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:36:04 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:36:05 volumio volumio[1884]: [158B blob data] May 21 14:36:05 volumio volumio[1884]: {"time":1747838164979,"response":"volume Success"}20 May 21 14:36:05 volumio volumio[5018]: info: CoreCommandRouter::volumioGetQueue May 21 14:36:05 volumio volumio[5018]: info: CoreStateMachine::getQueue May 21 14:36:05 volumio volumio[5018]: info: CorePlayQueue::getQueue May 21 14:36:06 volumio volumio[1884]: 25 May 21 14:36:06 volumio volumio[1884]: % Total % Received % Xferd Average Speed Time Time Time Current May 21 14:36:06 volumio volumio[1884]: Dload Upload Total Spent Left Speed May 21 14:36:06 volumio volumio[5018]: info: May 21 14:36:06 volumio volumio[5018]: ---------------------------- Client requests Volume 25 May 21 14:36:06 volumio volumio[5018]: info: VolumeController::SetAlsaVolume25 May 21 14:36:06 volumio volumio[5018]: info: CoreStateMachine::pushState May 21 14:36:06 volumio volumio[5018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:36:06 volumio volumio[5018]: info: CoreCommandRouter::volumioPushState May 21 14:36:06 volumio volumio[1884]: [237B blob data] May 21 14:36:06 volumio volumio[1884]: {"time":1747838166408,"response":"volume Success"}25 May 21 14:36:06 volumio volumio[5018]: info: CoreCommandRouter::volumioGetQueue May 21 14:36:06 volumio volumio[5018]: info: CoreStateMachine::getQueue May 21 14:36:06 volumio volumio[5018]: info: CorePlayQueue::getQueue May 21 14:36:06 volumio volumio[5018]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 21 14:36:06 volumio volumio[5018]: info: Retrying Login Due to Network Error May 21 14:36:16 volumio volumio[5018]: info: MyVolumio login type: Token May 21 14:36:46 volumio volumio[5018]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 21 14:36:46 volumio volumio[5018]: info: Retrying Login Due to Network Error May 21 14:36:55 volumio volumio[5018]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 14:36:55 volumio volumio[5018]: Error: connect ETIMEDOUT 157.240.12.36:443 May 21 14:36:55 volumio volumio[5018]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 21 14:36:55 volumio volumio[5018]: errno: -110, May 21 14:36:55 volumio volumio[5018]: code: 'ETIMEDOUT', May 21 14:36:55 volumio volumio[5018]: syscall: 'connect', May 21 14:36:55 volumio volumio[5018]: address: '157.240.12.36', May 21 14:36:55 volumio volumio[5018]: port: 443 May 21 14:36:55 volumio volumio[5018]: } May 21 14:36:55 volumio volumio[5018]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 14:36:55 volumio sudo[6283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 14:35 May 21 14:36:55 volumio sudo[6283]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"