-- Logs begin at Fri 2024-10-18 22:41:32 UTC, end at Fri 2024-10-18 23:26:22 UTC. -- Oct 18 23:25:06 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Oct 18 23:25:06 volumio volumio[879]: info: Listing playlists Oct 18 23:25:06 volumio volumio[879]: info: Listing playlists Oct 18 23:25:16 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Oct 18 23:25:26 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Oct 18 23:25:26 volumio volumio[879]: info: Listing playlists Oct 18 23:25:26 volumio volumio[879]: info: Listing playlists Oct 18 23:25:36 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Oct 18 23:25:46 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Oct 18 23:25:46 volumio volumio[879]: info: Listing playlists Oct 18 23:25:46 volumio volumio[879]: info: Listing playlists Oct 18 23:25:49 volumio volumio[879]: info: Oct 18 23:25:49 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 18 23:25:49 volumio volumio[879]: info: ControllerMpd::getState Oct 18 23:25:49 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 18 23:25:49 volumio volumio[879]: info: sendMpdCommand status took 2 milliseconds Oct 18 23:25:49 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 18 23:25:49 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 23:25:49 volumio volumio[879]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 18 23:25:49 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 18 23:25:49 volumio volumio[879]: info: ControllerMpd::pushState Oct 18 23:25:49 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 18 23:25:49 volumio volumio[879]: info: CoreStateMachine::pushState Oct 18 23:25:49 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 18 23:25:49 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 18 23:25:49 volumio volumio[879]: 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":"ap1005_us715451503_df3es5fq3lumqm1xldyt3kelzi_pi2_mx31311291_quhigh_s3828231907.flac","artist":null,"album":null,"uri":"http://fsandroid.tx.kugou.com/202410190724/4e9464d13b1266a7a4263b4478b23ce0/v3/b1d4e7ec2bb754838339a9e3fc0cad49/yp/full/ap1005_us715451503_df3es5fq3lumqm1xldyt3kelzi_pi2_mx31311291_quhigh_s3828231907.flac","trackType":"flac"} Oct 18 23:25:49 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 18 23:25:49 volumio volumio[879]: info: CoreStateMachine::syncState stateService stop Oct 18 23:25:49 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Oct 18 23:25:49 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 18 23:25:49 volumio volumio[879]: info: CoreStateMachine::stPlaybackTimer Oct 18 23:25:49 volumio volumio[879]: info: ------------------------------ 24ms Oct 18 23:25:50 volumio volumio[879]: info: Clearing queue after UPNP request Oct 18 23:25:50 volumio volumio[879]: info: CoreStateMachine::ClearQueue Oct 18 23:25:50 volumio volumio[879]: info: CoreStateMachine::stop Oct 18 23:25:50 volumio volumio[879]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 18 23:25:50 volumio volumio[879]: info: CorePlayQueue::clearPlayQueue Oct 18 23:25:50 volumio volumio[879]: info: CorePlayQueue::saveQueue Oct 18 23:25:50 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 18 23:25:50 volumio volumio[879]: info: CoreCommandRouter::volumioPushQueue Oct 18 23:25:50 volumio volumio[879]: info: Oct 18 23:25:50 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 18 23:25:50 volumio volumio[879]: info: Ignoring MPD Status Update Oct 18 23:25:50 volumio volumio[879]: error: updateQueue error: null Oct 18 23:25:50 volumio volumio[879]: info: ------------------------------ 2ms Oct 18 23:25:50 volumio volumio[879]: info: Starting UPNP Playback Oct 18 23:25:50 volumio volumio[879]: info: Preparing playback through UPNP Oct 18 23:25:50 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Oct 18 23:25:50 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 18 23:25:50 volumio volumio[879]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 18 23:25:51 volumio volumio[879]: info: Oct 18 23:25:51 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 18 23:25:51 volumio volumio[879]: info: Ignoring MPD Status Update Oct 18 23:25:51 volumio volumio[879]: info: Oct 18 23:25:51 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 18 23:25:51 volumio volumio[879]: info: Ignoring MPD Status Update Oct 18 23:25:51 volumio volumio[879]: info: ------------------------------ 3ms Oct 18 23:25:51 volumio volumio[879]: info: ------------------------------ 2ms Oct 18 23:25:51 volumio volumio[879]: info: Oct 18 23:25:51 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 18 23:25:51 volumio volumio[879]: info: Ignoring MPD Status Update Oct 18 23:25:51 volumio volumio[879]: info: ------------------------------ 1ms Oct 18 23:25:51 volumio volumio[879]: info: Oct 18 23:25:51 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 18 23:25:51 volumio volumio[879]: info: Ignoring MPD Status Update Oct 18 23:25:51 volumio volumio[879]: info: ------------------------------ 1ms Oct 18 23:25:51 volumio volumio[879]: info: Oct 18 23:25:51 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 18 23:25:51 volumio volumio[879]: info: Ignoring MPD Status Update Oct 18 23:25:51 volumio volumio[879]: info: ------------------------------ 1ms Oct 18 23:25:51 volumio volumio[879]: info: Oct 18 23:25:51 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 18 23:25:51 volumio volumio[879]: info: Ignoring MPD Status Update Oct 18 23:25:51 volumio volumio[879]: info: ------------------------------ 1ms Oct 18 23:25:51 volumio volumio[879]: info: Oct 18 23:25:51 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 18 23:25:51 volumio volumio[879]: info: ControllerMpd::getState Oct 18 23:25:51 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 18 23:25:51 volumio volumio[879]: info: Oct 18 23:25:51 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 18 23:25:51 volumio volumio[879]: info: sendMpdCommand status took 22 milliseconds Oct 18 23:25:51 volumio volumio[879]: info: ControllerMpd::getState Oct 18 23:25:51 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 18 23:25:51 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 18 23:25:51 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 23:25:51 volumio volumio[879]: info: sendMpdCommand status took 2 milliseconds Oct 18 23:25:51 volumio volumio[879]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 18 23:25:51 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 18 23:25:51 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 23:25:51 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 18 23:25:51 volumio volumio[879]: info: ControllerMpd::pushState Oct 18 23:25:51 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 18 23:25:51 volumio volumio[879]: verbose: In UPNP mode Oct 18 23:25:51 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"降央卓玛 - 草原迎宾曲","artist":"降央卓玛","album":null,"uri":"http://fsandroid.tx.kugou.com/202410190725/c7ee8770ff0a0c65d0dca5cf68f6a6e2/v3/2ea6b2ea24ff38bcbcd7b2b739d1c9bf/yp/full/ap1005_us715451503_df3es5fq3lumqm1xldyt3kelzi_pi2_mx32088536_quhigh_s4035328516.flac","trackType":"flac"} Oct 18 23:25:51 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 18 23:25:51 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 18 23:25:51 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus stop Oct 18 23:25:51 volumio volumio[879]: info: ------------------------------ 28ms Oct 18 23:25:51 volumio volumio[879]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 18 23:25:51 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 18 23:25:51 volumio volumio[879]: info: ControllerMpd::pushState Oct 18 23:25:51 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 18 23:25:51 volumio volumio[879]: verbose: In UPNP mode Oct 18 23:25:51 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"降央卓玛 - 草原迎宾曲","artist":"降央卓玛","album":null,"uri":"http://fsandroid.tx.kugou.com/202410190725/c7ee8770ff0a0c65d0dca5cf68f6a6e2/v3/2ea6b2ea24ff38bcbcd7b2b739d1c9bf/yp/full/ap1005_us715451503_df3es5fq3lumqm1xldyt3kelzi_pi2_mx32088536_quhigh_s4035328516.flac","trackType":"flac"} Oct 18 23:25:51 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 18 23:25:51 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 18 23:25:51 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Oct 18 23:25:51 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Oct 18 23:25:51 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 18 23:25:51 volumio volumio[879]: info: CoreStateMachine::pushState Oct 18 23:25:51 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 23:25:51 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 18 23:25:51 volumio volumio[879]: info: CoreStateMachine::pushState Oct 18 23:25:51 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 18 23:25:51 volumio volumio[879]: info: ------------------------------ 46ms Oct 18 23:25:51 volumio volumio[879]: info: Oct 18 23:25:51 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 18 23:25:51 volumio volumio[879]: info: Ignoring MPD Status Update Oct 18 23:25:51 volumio volumio[879]: info: Oct 18 23:25:51 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 18 23:25:51 volumio volumio[879]: info: ControllerMpd::getState Oct 18 23:25:51 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 18 23:25:51 volumio volumio[879]: info: ------------------------------ 23ms Oct 18 23:25:51 volumio volumio[879]: info: sendMpdCommand status took 23 milliseconds Oct 18 23:25:51 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 18 23:25:51 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 23:25:51 volumio volumio[879]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 18 23:25:51 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 18 23:25:51 volumio volumio[879]: info: ControllerMpd::pushState Oct 18 23:25:51 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 18 23:25:51 volumio volumio[879]: verbose: In UPNP mode Oct 18 23:25:51 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1137 Kbps","isStreaming":false,"title":"草原迎宾曲","artist":"降央卓玛","album":"金色的诱惑","uri":"http://fsandroid.tx.kugou.com/202410190725/c7ee8770ff0a0c65d0dca5cf68f6a6e2/v3/2ea6b2ea24ff38bcbcd7b2b739d1c9bf/yp/full/ap1005_us715451503_df3es5fq3lumqm1xldyt3kelzi_pi2_mx32088536_quhigh_s4035328516.flac","trackType":"flac"} Oct 18 23:25:51 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 18 23:25:51 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 18 23:25:51 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Oct 18 23:25:51 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Oct 18 23:25:51 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 18 23:25:51 volumio volumio[879]: info: CoreStateMachine::pushState Oct 18 23:25:51 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 23:25:51 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 18 23:25:51 volumio volumio[879]: info: CoreStateMachine::pushState Oct 18 23:25:51 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 18 23:25:51 volumio volumio[879]: info: ------------------------------ 89ms Oct 18 23:25:51 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 18 23:25:51 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 18 23:25:51 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 18 23:25:51 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 18 23:25:51 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 18 23:25:51 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 18 23:25:51 volumio volumio[879]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%8D%E5%A4%AE%E5%8D%93%E7%8E%9B/1a03f30e-09fc-4ef8-85ab-85abd6e74f39.jpg' Oct 18 23:25:57 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Oct 18 23:26:07 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Oct 18 23:26:08 volumio volumio[879]: info: Listing playlists Oct 18 23:26:16 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Oct 18 23:26:21 volumio volumio[879]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 23:26:21 volumio volumio[879]: Error: connect ETIMEDOUT 69.63.176.15:80 Oct 18 23:26:21 volumio volumio[879]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 18 23:26:21 volumio volumio[879]: errno: -110, Oct 18 23:26:21 volumio volumio[879]: code: 'ETIMEDOUT', Oct 18 23:26:21 volumio volumio[879]: syscall: 'connect', Oct 18 23:26:21 volumio volumio[879]: address: '69.63.176.15', Oct 18 23:26:21 volumio volumio[879]: port: 80 Oct 18 23:26:21 volumio volumio[879]: } Oct 18 23:26:21 volumio volumio[879]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 23:26:22 volumio sudo[13878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-18 23:25 Oct 18 23:26:22 volumio sudo[13878]: 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="1d63a568b7d5e4a317f16be7d0961cbb2d82e282" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="4f8978e0148ab5e1610548b80abf1b4b5e03ea0a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 07 Feb 2023 05:04:19 PM CET" VOLUMIO_VERSION="3.429" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4c8dcdad83349056095d42fbad08221f"