-- Logs begin at Sun 2024-12-01 05:59:26 UTC, end at Sun 2024-12-01 06:25:56 UTC. -- Dec 01 06:24:21 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: appearance , setBackgrounds Dec 01 06:24:21 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Dec 01 06:24:21 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 01 06:25:41 volumio volumio[765]: info: Dec 01 06:25:41 volumio volumio[765]: ---------------------------- MPD announces state update: player Dec 01 06:25:41 volumio volumio[765]: info: ControllerMpd::getState Dec 01 06:25:41 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand status Dec 01 06:25:41 volumio volumio[765]: info: sendMpdCommand status took 1 milliseconds Dec 01 06:25:41 volumio volumio[765]: verbose: ControllerMpd::parseState Dec 01 06:25:41 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 06:25:41 volumio volumio[765]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 01 06:25:41 volumio volumio[765]: verbose: ControllerMpd::parseTrackInfo Dec 01 06:25:41 volumio volumio[765]: info: ControllerMpd::pushState Dec 01 06:25:41 volumio volumio[765]: info: CoreCommandRouter::servicePushState Dec 01 06:25:41 volumio volumio[765]: info: CoreStateMachine::pushState Dec 01 06:25:41 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Dec 01 06:25:41 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 06:25:41 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Dec 01 06:25:41 volumio volumio[765]: 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://fsandroid.kugou.com/202412011423/f89cb9595960785c8f5eeed8bf55edc5/v3/208d789bd23b987fbafcd99e24a75308/yp/full/ap1005_us1512660708_df0ioeh305smr82hmpht0bipij_pi2_mx64323384_quviper_tape_s2990788838.mp3","trackType":"mp3"} Dec 01 06:25:41 volumio volumio[765]: verbose: CURRENT POSITION 0 Dec 01 06:25:41 volumio volumio[765]: info: CoreStateMachine::syncState stateService stop Dec 01 06:25:41 volumio volumio[765]: info: CoreStateMachine::syncState currentStatus play Dec 01 06:25:41 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Dec 01 06:25:41 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 06:25:41 volumio volumio[765]: info: CoreStateMachine::stPlaybackTimer Dec 01 06:25:41 volumio volumio[765]: info: ------------------------------ 13ms Dec 01 06:25:41 volumio volumio[765]: info: CoreCommandRouter::volumioGetQueue Dec 01 06:25:41 volumio volumio[765]: info: CoreStateMachine::getQueue Dec 01 06:25:41 volumio volumio[765]: info: CorePlayQueue::getQueue Dec 01 06:25:41 volumio volumio[765]: info: Clearing queue after UPNP request Dec 01 06:25:41 volumio volumio[765]: info: CoreStateMachine::ClearQueue Dec 01 06:25:41 volumio volumio[765]: info: CoreStateMachine::stop Dec 01 06:25:41 volumio volumio[765]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 06:25:41 volumio volumio[765]: verbose: UNSET VOLATILE Dec 01 06:25:41 volumio volumio[765]: info: CorePlayQueue::clearPlayQueue Dec 01 06:25:41 volumio volumio[765]: info: CorePlayQueue::saveQueue Dec 01 06:25:41 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Dec 01 06:25:41 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 06:25:41 volumio volumio[765]: info: CoreCommandRouter::volumioPushQueue Dec 01 06:25:41 volumio volumio[765]: info: Dec 01 06:25:41 volumio volumio[765]: ---------------------------- MPD announces system playlist update Dec 01 06:25:41 volumio volumio[765]: info: Ignoring MPD Status Update Dec 01 06:25:41 volumio volumio[765]: error: updateQueue error: null Dec 01 06:25:41 volumio volumio[765]: info: ------------------------------ 1ms Dec 01 06:25:41 volumio volumio[765]: info: Starting UPNP Playback Dec 01 06:25:41 volumio volumio[765]: info: Preparing playback through UPNP Dec 01 06:25:41 volumio volumio[765]: info: CoreCommandRouter::volumioGetState Dec 01 06:25:41 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Dec 01 06:25:41 volumio volumio[765]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 01 06:25:42 volumio volumio[765]: info: Dec 01 06:25:42 volumio volumio[765]: ---------------------------- MPD announces system playlist update Dec 01 06:25:42 volumio volumio[765]: info: Ignoring MPD Status Update Dec 01 06:25:42 volumio volumio[765]: info: Dec 01 06:25:42 volumio volumio[765]: ---------------------------- MPD announces system playlist update Dec 01 06:25:42 volumio volumio[765]: info: Ignoring MPD Status Update Dec 01 06:25:42 volumio volumio[765]: info: ------------------------------ 2ms Dec 01 06:25:42 volumio volumio[765]: info: ------------------------------ 1ms Dec 01 06:25:42 volumio volumio[765]: info: Dec 01 06:25:42 volumio volumio[765]: ---------------------------- MPD announces system playlist update Dec 01 06:25:42 volumio volumio[765]: info: Ignoring MPD Status Update Dec 01 06:25:42 volumio volumio[765]: info: ------------------------------ 1ms Dec 01 06:25:42 volumio volumio[765]: info: Dec 01 06:25:42 volumio volumio[765]: ---------------------------- MPD announces system playlist update Dec 01 06:25:42 volumio volumio[765]: info: Ignoring MPD Status Update Dec 01 06:25:42 volumio volumio[765]: info: ------------------------------ 1ms Dec 01 06:25:42 volumio volumio[765]: info: Dec 01 06:25:42 volumio volumio[765]: ---------------------------- MPD announces system playlist update Dec 01 06:25:42 volumio volumio[765]: info: Ignoring MPD Status Update Dec 01 06:25:42 volumio volumio[765]: info: ------------------------------ 10ms Dec 01 06:25:42 volumio volumio[765]: info: Dec 01 06:25:42 volumio volumio[765]: ---------------------------- MPD announces system playlist update Dec 01 06:25:42 volumio volumio[765]: info: Ignoring MPD Status Update Dec 01 06:25:42 volumio volumio[765]: info: ------------------------------ 1ms Dec 01 06:25:42 volumio volumio[765]: info: Dec 01 06:25:42 volumio volumio[765]: ---------------------------- MPD announces state update: player Dec 01 06:25:42 volumio volumio[765]: info: ControllerMpd::getState Dec 01 06:25:42 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand status Dec 01 06:25:42 volumio volumio[765]: info: sendMpdCommand status took 38 milliseconds Dec 01 06:25:42 volumio volumio[765]: verbose: ControllerMpd::parseState Dec 01 06:25:42 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 06:25:42 volumio volumio[765]: info: Dec 01 06:25:42 volumio volumio[765]: ---------------------------- MPD announces state update: player Dec 01 06:25:42 volumio volumio[765]: info: ControllerMpd::getState Dec 01 06:25:42 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand status Dec 01 06:25:42 volumio volumio[765]: info: Dec 01 06:25:42 volumio volumio[765]: ---------------------------- MPD announces system playlist update Dec 01 06:25:42 volumio volumio[765]: info: Ignoring MPD Status Update Dec 01 06:25:42 volumio volumio[765]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 01 06:25:42 volumio volumio[765]: info: sendMpdCommand status took 3 milliseconds Dec 01 06:25:42 volumio volumio[765]: verbose: ControllerMpd::parseTrackInfo Dec 01 06:25:42 volumio volumio[765]: verbose: ControllerMpd::parseState Dec 01 06:25:42 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 06:25:42 volumio volumio[765]: info: ControllerMpd::pushState Dec 01 06:25:42 volumio volumio[765]: info: CoreCommandRouter::servicePushState Dec 01 06:25:42 volumio volumio[765]: verbose: In UPNP mode Dec 01 06:25:42 volumio volumio[765]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"天使在夜里哭","artist":"周子寒","album":"天使在夜里哭","uri":"http://fsandroid.kugou.com/202412011425/28b1b9c62ef438fec9d4adf45142b298/v3/737ea307e05274a7ea8561a96217536e/yp/full/ap1005_us1512660708_df0ioeh305smr82hmpht0bipij_pi2_mx38244162_quviper_tape_s916803390.mp3","trackType":"mp3"} Dec 01 06:25:42 volumio volumio[765]: verbose: CURRENT POSITION 0 Dec 01 06:25:42 volumio volumio[765]: info: CoreStateMachine::syncState stateService play Dec 01 06:25:42 volumio volumio[765]: info: CoreStateMachine::syncState currentStatus stop Dec 01 06:25:42 volumio volumio[765]: info: CoreStateMachine::pushState Dec 01 06:25:42 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Dec 01 06:25:42 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 06:25:42 volumio volumio[765]: info: ------------------------------ 53ms Dec 01 06:25:42 volumio volumio[765]: info: ------------------------------ 10ms Dec 01 06:25:42 volumio volumio[765]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 01 06:25:42 volumio volumio[765]: verbose: ControllerMpd::parseTrackInfo Dec 01 06:25:42 volumio volumio[765]: info: ControllerMpd::pushState Dec 01 06:25:42 volumio volumio[765]: info: CoreCommandRouter::servicePushState Dec 01 06:25:42 volumio volumio[765]: verbose: In UPNP mode Dec 01 06:25:42 volumio volumio[765]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":312,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"天使在夜里哭","artist":"周子寒","album":"天使在夜里哭","uri":"http://fsandroid.kugou.com/202412011425/28b1b9c62ef438fec9d4adf45142b298/v3/737ea307e05274a7ea8561a96217536e/yp/full/ap1005_us1512660708_df0ioeh305smr82hmpht0bipij_pi2_mx38244162_quviper_tape_s916803390.mp3","trackType":"mp3"} Dec 01 06:25:42 volumio volumio[765]: verbose: CURRENT POSITION 0 Dec 01 06:25:42 volumio volumio[765]: info: CoreStateMachine::syncState stateService play Dec 01 06:25:42 volumio volumio[765]: info: CoreStateMachine::syncState currentStatus play Dec 01 06:25:42 volumio volumio[765]: info: Received an update from plugin. extracting info from payload Dec 01 06:25:42 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 01 06:25:42 volumio volumio[765]: info: CoreStateMachine::pushState Dec 01 06:25:42 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Dec 01 06:25:42 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 06:25:42 volumio volumio[765]: info: CoreStateMachine::pushState Dec 01 06:25:42 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Dec 01 06:25:42 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 06:25:42 volumio volumio[765]: info: ------------------------------ 34ms Dec 01 06:25:42 volumio volumio[765]: info: CoreCommandRouter::volumioGetQueue Dec 01 06:25:42 volumio volumio[765]: info: CoreStateMachine::getQueue Dec 01 06:25:42 volumio volumio[765]: info: CorePlayQueue::getQueue Dec 01 06:25:42 volumio volumio[765]: info: CoreCommandRouter::volumioGetQueue Dec 01 06:25:42 volumio volumio[765]: info: CoreStateMachine::getQueue Dec 01 06:25:42 volumio volumio[765]: info: CorePlayQueue::getQueue Dec 01 06:25:42 volumio volumio[765]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BA%91%E6%9C%B5/%E5%80%94%E5%BC%BA/cfe2275e-281a-4d0e-8f5e-d7e754e2fc79.jpg' Dec 01 06:25:55 volumio volumio[765]: info: Dec 01 06:25:55 volumio volumio[765]: ---------------------------- MPD announces state update: player Dec 01 06:25:55 volumio volumio[765]: info: ControllerMpd::getState Dec 01 06:25:55 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand status Dec 01 06:25:55 volumio volumio[765]: info: sendMpdCommand status took 3 milliseconds Dec 01 06:25:55 volumio volumio[765]: verbose: ControllerMpd::parseState Dec 01 06:25:55 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 06:25:55 volumio volumio[765]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 01 06:25:55 volumio volumio[765]: verbose: ControllerMpd::parseTrackInfo Dec 01 06:25:55 volumio volumio[765]: info: ControllerMpd::pushState Dec 01 06:25:55 volumio volumio[765]: info: CoreCommandRouter::servicePushState Dec 01 06:25:55 volumio volumio[765]: info: CoreStateMachine::pushState Dec 01 06:25:55 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Dec 01 06:25:55 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 06:25:55 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Dec 01 06:25:55 volumio volumio[765]: 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://fsandroid.kugou.com/202412011425/28b1b9c62ef438fec9d4adf45142b298/v3/737ea307e05274a7ea8561a96217536e/yp/full/ap1005_us1512660708_df0ioeh305smr82hmpht0bipij_pi2_mx38244162_quviper_tape_s916803390.mp3","trackType":"mp3"} Dec 01 06:25:55 volumio volumio[765]: verbose: CURRENT POSITION 0 Dec 01 06:25:55 volumio volumio[765]: info: CoreStateMachine::syncState stateService stop Dec 01 06:25:55 volumio volumio[765]: info: CoreStateMachine::syncState currentStatus play Dec 01 06:25:55 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Dec 01 06:25:55 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 06:25:55 volumio volumio[765]: info: CoreStateMachine::stPlaybackTimer Dec 01 06:25:55 volumio volumio[765]: info: ------------------------------ 10ms Dec 01 06:25:55 volumio volumio[765]: info: CoreCommandRouter::volumioGetQueue Dec 01 06:25:55 volumio volumio[765]: info: CoreStateMachine::getQueue Dec 01 06:25:55 volumio volumio[765]: info: CorePlayQueue::getQueue Dec 01 06:25:55 volumio volumio[765]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 06:25:55 volumio volumio[765]: { Error: connect ETIMEDOUT 31.13.94.41:443 Dec 01 06:25:55 volumio volumio[765]: at Object._errnoException (util.js:1022:11) Dec 01 06:25:55 volumio volumio[765]: at _exceptionWithHostPort (util.js:1044:20) Dec 01 06:25:55 volumio volumio[765]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Dec 01 06:25:55 volumio volumio[765]: code: 'ETIMEDOUT', Dec 01 06:25:55 volumio volumio[765]: errno: 'ETIMEDOUT', Dec 01 06:25:55 volumio volumio[765]: syscall: 'connect', Dec 01 06:25:55 volumio volumio[765]: address: '31.13.94.41', Dec 01 06:25:55 volumio volumio[765]: port: 443 } Dec 01 06:25:55 volumio volumio[765]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 06:25:56 volumio sudo[9948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 06:24 Dec 01 06:25:56 volumio sudo[9948]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"