-- Logs begin at Wed 2025-07-02 22:36:24 CST, end at Wed 2025-07-02 23:27:55 CST. -- Jul 02 23:26:30 volumio volumio[1026]: info: Jul 02 23:26:30 volumio volumio[1026]: ---------------------------- MPD announces state update: player Jul 02 23:26:30 volumio volumio[1026]: info: ControllerMpd::getState Jul 02 23:26:30 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:26:30 volumio volumio[1026]: info: sendMpdCommand status took 0 milliseconds Jul 02 23:26:30 volumio volumio[1026]: verbose: ControllerMpd::parseState Jul 02 23:26:30 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:26:30 volumio volumio[1026]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 02 23:26:30 volumio volumio[1026]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:26:30 volumio volumio[1026]: info: ControllerMpd::pushState Jul 02 23:26:30 volumio volumio[1026]: info: CoreCommandRouter::servicePushState Jul 02 23:26:30 volumio volumio[1026]: info: CoreStateMachine::pushState Jul 02 23:26:30 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState Jul 02 23:26:30 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Jul 02 23:26:30 volumio volumio[1026]: 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":null,"album":"真爱(精选全记录)","uri":"http://fsandroid.kugou.com/202507022325/2f0f0f0ec2f1c3450e8a8ae691967dee/v3/0acd9c63e6fb4afffce53457d0e414b3/yp/full/ap1005_us1111385499_df33pkce0fvogu3p3qp1032eh9_pi2_mx32130948_quviper_tape_ct445200_s1470672440.mp3","trackType":"mp3"} Jul 02 23:26:30 volumio volumio[1026]: verbose: CURRENT POSITION 0 Jul 02 23:26:30 volumio volumio[1026]: info: CoreStateMachine::syncState stateService stop Jul 02 23:26:30 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus play Jul 02 23:26:30 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState Jul 02 23:26:30 volumio volumio[1026]: info: CoreStateMachine::stPlaybackTimer Jul 02 23:26:30 volumio volumio[1026]: info: ------------------------------ 7ms Jul 02 23:26:31 volumio volumio[1026]: info: Clearing queue after UPNP request Jul 02 23:26:31 volumio volumio[1026]: info: CoreStateMachine::ClearQueue Jul 02 23:26:31 volumio volumio[1026]: info: CoreStateMachine::stop Jul 02 23:26:31 volumio volumio[1026]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 23:26:31 volumio volumio[1026]: info: CorePlayQueue::clearPlayQueue Jul 02 23:26:31 volumio volumio[1026]: info: CorePlayQueue::saveQueue Jul 02 23:26:31 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState Jul 02 23:26:31 volumio volumio[1026]: info: CoreCommandRouter::volumioPushQueue Jul 02 23:26:31 volumio volumio[1026]: info: Jul 02 23:26:31 volumio volumio[1026]: ---------------------------- MPD announces system playlist update Jul 02 23:26:31 volumio volumio[1026]: info: Ignoring MPD Status Update Jul 02 23:26:31 volumio volumio[1026]: error: updateQueue error: null Jul 02 23:26:31 volumio volumio[1026]: info: ------------------------------ 0ms Jul 02 23:26:31 volumio volumio[1026]: info: Starting UPNP Playback Jul 02 23:26:31 volumio volumio[1026]: info: Preparing playback through UPNP Jul 02 23:26:31 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Jul 02 23:26:31 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Jul 02 23:26:31 volumio volumio[1026]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 23:26:31 volumio volumio[1026]: info: Jul 02 23:26:31 volumio volumio[1026]: ---------------------------- MPD announces system playlist update Jul 02 23:26:31 volumio volumio[1026]: info: Ignoring MPD Status Update Jul 02 23:26:31 volumio volumio[1026]: info: Jul 02 23:26:31 volumio volumio[1026]: ---------------------------- MPD announces system playlist update Jul 02 23:26:31 volumio volumio[1026]: info: Ignoring MPD Status Update Jul 02 23:26:31 volumio volumio[1026]: info: ------------------------------ 5ms Jul 02 23:26:31 volumio volumio[1026]: info: Jul 02 23:26:31 volumio volumio[1026]: ---------------------------- MPD announces system playlist update Jul 02 23:26:31 volumio volumio[1026]: info: Ignoring MPD Status Update Jul 02 23:26:31 volumio volumio[1026]: info: ------------------------------ 1ms Jul 02 23:26:31 volumio volumio[1026]: info: ------------------------------ 0ms Jul 02 23:26:31 volumio volumio[1026]: info: Jul 02 23:26:31 volumio volumio[1026]: ---------------------------- MPD announces system playlist update Jul 02 23:26:31 volumio volumio[1026]: info: Ignoring MPD Status Update Jul 02 23:26:31 volumio volumio[1026]: info: ------------------------------ 1ms Jul 02 23:26:31 volumio volumio[1026]: info: Jul 02 23:26:31 volumio volumio[1026]: ---------------------------- MPD announces system playlist update Jul 02 23:26:31 volumio volumio[1026]: info: Ignoring MPD Status Update Jul 02 23:26:31 volumio volumio[1026]: info: ------------------------------ 1ms Jul 02 23:26:31 volumio volumio[1026]: info: Jul 02 23:26:31 volumio volumio[1026]: ---------------------------- MPD announces system playlist update Jul 02 23:26:31 volumio volumio[1026]: info: Ignoring MPD Status Update Jul 02 23:26:31 volumio volumio[1026]: info: ------------------------------ 0ms Jul 02 23:26:32 volumio volumio[1026]: info: Jul 02 23:26:32 volumio volumio[1026]: ---------------------------- MPD announces state update: player Jul 02 23:26:32 volumio volumio[1026]: info: ControllerMpd::getState Jul 02 23:26:32 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:26:32 volumio volumio[1026]: info: sendMpdCommand status took 1 milliseconds Jul 02 23:26:32 volumio volumio[1026]: verbose: ControllerMpd::parseState Jul 02 23:26:32 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:26:32 volumio volumio[1026]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 02 23:26:32 volumio volumio[1026]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:26:32 volumio volumio[1026]: info: ControllerMpd::pushState Jul 02 23:26:32 volumio volumio[1026]: info: CoreCommandRouter::servicePushState Jul 02 23:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState Jul 02 23:26:32 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState Jul 02 23:26:32 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Jul 02 23:26:32 volumio volumio[1026]: 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":null,"uri":"http://fsandroid.kugou.com/202507022326/fd4a543867e205db16b37131cfe94916/v3/bbb2e46e8b88202f3e57ccbbfd96ec24/yp/full/ap1005_us1111385499_df33pkce0fvogu3p3qp1032eh9_pi2_mx32130948_quacappella2_ct445200_s174012740.mkv","trackType":"mkv"} Jul 02 23:26:32 volumio volumio[1026]: verbose: CURRENT POSITION 0 Jul 02 23:26:32 volumio volumio[1026]: info: CoreStateMachine::syncState stateService stop Jul 02 23:26:32 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus stop Jul 02 23:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState Jul 02 23:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState Jul 02 23:26:32 volumio volumio[1026]: info: No code Jul 02 23:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState Jul 02 23:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState Jul 02 23:26:32 volumio volumio[1026]: info: ------------------------------ 15ms Jul 02 23:26:32 volumio volumio[1026]: info: Clearing queue after UPNP request Jul 02 23:26:32 volumio volumio[1026]: info: CoreStateMachine::ClearQueue Jul 02 23:26:32 volumio volumio[1026]: info: CoreStateMachine::stop Jul 02 23:26:32 volumio volumio[1026]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 23:26:32 volumio volumio[1026]: info: CorePlayQueue::clearPlayQueue Jul 02 23:26:32 volumio volumio[1026]: info: CorePlayQueue::saveQueue Jul 02 23:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState Jul 02 23:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushQueue Jul 02 23:26:32 volumio volumio[1026]: info: Jul 02 23:26:32 volumio volumio[1026]: ---------------------------- MPD announces system playlist update Jul 02 23:26:32 volumio volumio[1026]: info: Ignoring MPD Status Update Jul 02 23:26:32 volumio volumio[1026]: error: updateQueue error: null Jul 02 23:26:32 volumio volumio[1026]: info: ------------------------------ 0ms Jul 02 23:26:32 volumio volumio[1026]: info: Starting UPNP Playback Jul 02 23:26:32 volumio volumio[1026]: info: Preparing playback through UPNP Jul 02 23:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState Jul 02 23:26:32 volumio volumio[1026]: info: CorePlayQueue::getTrack 0 Jul 02 23:26:32 volumio volumio[1026]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 23:26:32 volumio volumio[1026]: info: Jul 02 23:26:32 volumio volumio[1026]: ---------------------------- MPD announces system playlist update Jul 02 23:26:32 volumio volumio[1026]: info: Ignoring MPD Status Update Jul 02 23:26:32 volumio volumio[1026]: info: Jul 02 23:26:32 volumio volumio[1026]: ---------------------------- MPD announces system playlist update Jul 02 23:26:32 volumio volumio[1026]: info: Ignoring MPD Status Update Jul 02 23:26:32 volumio volumio[1026]: info: ------------------------------ 1ms Jul 02 23:26:32 volumio volumio[1026]: info: ------------------------------ 1ms Jul 02 23:26:32 volumio volumio[1026]: info: Jul 02 23:26:32 volumio volumio[1026]: ---------------------------- MPD announces system playlist update Jul 02 23:26:32 volumio volumio[1026]: info: Ignoring MPD Status Update Jul 02 23:26:32 volumio volumio[1026]: info: ------------------------------ 0ms Jul 02 23:26:32 volumio volumio[1026]: info: Jul 02 23:26:32 volumio volumio[1026]: ---------------------------- MPD announces system playlist update Jul 02 23:26:32 volumio volumio[1026]: info: Ignoring MPD Status Update Jul 02 23:26:32 volumio volumio[1026]: info: ------------------------------ 0ms Jul 02 23:26:32 volumio volumio[1026]: info: Jul 02 23:26:32 volumio volumio[1026]: ---------------------------- MPD announces system playlist update Jul 02 23:26:32 volumio volumio[1026]: info: Ignoring MPD Status Update Jul 02 23:26:32 volumio volumio[1026]: info: ------------------------------ 1ms Jul 02 23:26:32 volumio volumio[1026]: info: Jul 02 23:26:32 volumio volumio[1026]: ---------------------------- MPD announces system playlist update Jul 02 23:26:32 volumio volumio[1026]: info: Ignoring MPD Status Update Jul 02 23:26:32 volumio volumio[1026]: info: ------------------------------ 0ms Jul 02 23:26:33 volumio volumio[1026]: info: Jul 02 23:26:33 volumio volumio[1026]: ---------------------------- MPD announces state update: player Jul 02 23:26:33 volumio volumio[1026]: info: ControllerMpd::getState Jul 02 23:26:33 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:26:33 volumio volumio[1026]: info: Jul 02 23:26:33 volumio volumio[1026]: ---------------------------- MPD announces state update: player Jul 02 23:26:33 volumio volumio[1026]: info: sendMpdCommand status took 2 milliseconds Jul 02 23:26:33 volumio volumio[1026]: info: ControllerMpd::getState Jul 02 23:26:33 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:26:33 volumio volumio[1026]: verbose: ControllerMpd::parseState Jul 02 23:26:33 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:26:33 volumio volumio[1026]: info: sendMpdCommand status took 1 milliseconds Jul 02 23:26:33 volumio volumio[1026]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 02 23:26:33 volumio volumio[1026]: verbose: ControllerMpd::parseState Jul 02 23:26:33 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:26:33 volumio volumio[1026]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:26:33 volumio volumio[1026]: info: ControllerMpd::pushState Jul 02 23:26:33 volumio volumio[1026]: info: CoreCommandRouter::servicePushState Jul 02 23:26:33 volumio volumio[1026]: verbose: In UPNP mode Jul 02 23:26:33 volumio volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":217,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张真 - 醒世诗","artist":"张真","album":null,"uri":"http://fsandroid.kugou.com/202507022326/fd4a543867e205db16b37131cfe94916/v3/bbb2e46e8b88202f3e57ccbbfd96ec24/yp/full/ap1005_us1111385499_df33pkce0fvogu3p3qp1032eh9_pi2_mx32130948_quacappella2_ct445200_s174012740.mkv","trackType":"mkv"} Jul 02 23:26:33 volumio volumio[1026]: verbose: CURRENT POSITION 0 Jul 02 23:26:33 volumio volumio[1026]: info: CoreStateMachine::syncState stateService play Jul 02 23:26:33 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus stop Jul 02 23:26:33 volumio volumio[1026]: info: ------------------------------ 4ms Jul 02 23:26:33 volumio volumio[1026]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 02 23:26:33 volumio volumio[1026]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:26:33 volumio volumio[1026]: info: ControllerMpd::pushState Jul 02 23:26:33 volumio volumio[1026]: info: CoreCommandRouter::servicePushState Jul 02 23:26:33 volumio volumio[1026]: verbose: In UPNP mode Jul 02 23:26:33 volumio volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":217,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张真 - 醒世诗","artist":"张真","album":null,"uri":"http://fsandroid.kugou.com/202507022326/fd4a543867e205db16b37131cfe94916/v3/bbb2e46e8b88202f3e57ccbbfd96ec24/yp/full/ap1005_us1111385499_df33pkce0fvogu3p3qp1032eh9_pi2_mx32130948_quacappella2_ct445200_s174012740.mkv","trackType":"mkv"} Jul 02 23:26:33 volumio volumio[1026]: verbose: CURRENT POSITION 0 Jul 02 23:26:33 volumio volumio[1026]: info: CoreStateMachine::syncState stateService play Jul 02 23:26:33 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus play Jul 02 23:26:33 volumio volumio[1026]: info: Received an update from plugin. extracting info from payload Jul 02 23:26:33 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 23:26:33 volumio volumio[1026]: info: CoreStateMachine::pushState Jul 02 23:26:33 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:26:33 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState Jul 02 23:26:33 volumio volumio[1026]: info: CoreStateMachine::pushState Jul 02 23:26:33 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState Jul 02 23:26:33 volumio volumio[1026]: info: ------------------------------ 10ms Jul 02 23:27:04 volumio volumio[1026]: info: Jul 02 23:27:04 volumio volumio[1026]: ---------------------------- MPD announces state update: player Jul 02 23:27:04 volumio volumio[1026]: info: ControllerMpd::getState Jul 02 23:27:04 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:27:04 volumio volumio[1026]: info: sendMpdCommand status took 0 milliseconds Jul 02 23:27:04 volumio volumio[1026]: verbose: ControllerMpd::parseState Jul 02 23:27:04 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:27:04 volumio volumio[1026]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 02 23:27:04 volumio volumio[1026]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:27:04 volumio volumio[1026]: info: ControllerMpd::pushState Jul 02 23:27:04 volumio volumio[1026]: info: CoreCommandRouter::servicePushState Jul 02 23:27:04 volumio volumio[1026]: verbose: In UPNP mode Jul 02 23:27:04 volumio volumio[1026]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":32270,"duration":217,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"张真 - 醒世诗","artist":"张真","album":null,"uri":"http://fsandroid.kugou.com/202507022326/fd4a543867e205db16b37131cfe94916/v3/bbb2e46e8b88202f3e57ccbbfd96ec24/yp/full/ap1005_us1111385499_df33pkce0fvogu3p3qp1032eh9_pi2_mx32130948_quacappella2_ct445200_s174012740.mkv","trackType":"mkv"} Jul 02 23:27:04 volumio volumio[1026]: verbose: CURRENT POSITION 0 Jul 02 23:27:04 volumio volumio[1026]: info: CoreStateMachine::syncState stateService pause Jul 02 23:27:04 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus play Jul 02 23:27:04 volumio volumio[1026]: info: CoreStateMachine::pushState Jul 02 23:27:04 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:27:04 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState Jul 02 23:27:04 volumio volumio[1026]: info: CoreStateMachine::stPlaybackTimer Jul 02 23:27:04 volumio volumio[1026]: info: ------------------------------ 7ms Jul 02 23:27:55 volumio volumio[1026]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 23:27:55 volumio volumio[1026]: Error: connect ETIMEDOUT 31.13.68.169:80 Jul 02 23:27:55 volumio volumio[1026]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jul 02 23:27:55 volumio volumio[1026]: errno: -110, Jul 02 23:27:55 volumio volumio[1026]: code: 'ETIMEDOUT', Jul 02 23:27:55 volumio volumio[1026]: syscall: 'connect', Jul 02 23:27:55 volumio volumio[1026]: address: '31.13.68.169', Jul 02 23:27:55 volumio volumio[1026]: port: 80 Jul 02 23:27:55 volumio volumio[1026]: } Jul 02 23:27:55 volumio volumio[1026]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 23:27:55 volumio sudo[15679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-02 23:26 Jul 02 23:27:55 volumio sudo[15679]: 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"