-- Logs begin at Tue 2024-07-16 00:57:56 UTC, end at Tue 2024-07-16 05:58:18 UTC. -- Jul 16 05:57:01 volumio volumio[617]: info: CorePlayQueue::getTrack 15 Jul 16 05:57:01 volumio volumio[617]: info: CorePlayQueue::getTrack 16 Jul 16 05:57:01 volumio volumio[617]: info: Prefetching next song Jul 16 05:57:01 volumio volumio[617]: info: DOING PREFETCH IN MPD Jul 16 05:57:01 volumio volumio[617]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/古典名盘合集SACD套装/！RCA“LIVING STEREO”系列/[RCA82876-61394]穆索尔斯基《图画展览会》& 俄罗斯管弦乐名曲集 莱纳/17 - 穆索尔斯基《荒山之夜》 - A Night on Bald Mountain.dsf" Jul 16 05:57:01 volumio volumio[617]: info: sendMpdCommand add "NAS/NAS/古典名盘合集SACD套装/！RCA“LIVING STEREO”系列/[RCA82876-61394]穆索尔斯基《图画展览会》& 俄罗斯管弦乐名曲集 莱纳/17 - 穆索尔斯基《荒山之夜》 - A Night on Bald Mountain.dsf" took 0 milliseconds Jul 16 05:57:01 volumio volumio[617]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 16 05:57:01 volumio volumio[617]: info: Jul 16 05:57:01 volumio volumio[617]: ---------------------------- MPD announces system playlist update Jul 16 05:57:01 volumio volumio[617]: info: Ignoring MPD Status Update Jul 16 05:57:01 volumio volumio[617]: info: sendMpdCommand consume 1 took 1 milliseconds Jul 16 05:57:01 volumio volumio[617]: info: ------------------------------ 0ms Jul 16 05:57:06 volumio volumio[617]: info: CoreStateMachine::startPlaybackTimer Jul 16 05:57:06 volumio volumio[617]: info: CorePlayQueue::getTrack 16 Jul 16 05:57:06 volumio volumio[617]: info: Jul 16 05:57:06 volumio volumio[617]: ---------------------------- MPD announces system playlist update Jul 16 05:57:06 volumio volumio[617]: info: Ignoring MPD Status Update Jul 16 05:57:06 volumio volumio[617]: info: Jul 16 05:57:06 volumio volumio[617]: ---------------------------- MPD announces state update: player Jul 16 05:57:06 volumio volumio[617]: info: ControllerMpd::getState Jul 16 05:57:06 volumio volumio[617]: verbose: ControllerMpd::sendMpdCommand status Jul 16 05:57:06 volumio volumio[617]: info: ------------------------------ 1ms Jul 16 05:57:06 volumio volumio[617]: info: sendMpdCommand status took 1 milliseconds Jul 16 05:57:06 volumio volumio[617]: verbose: ControllerMpd::parseState Jul 16 05:57:06 volumio volumio[617]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 05:57:06 volumio volumio[617]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 16 05:57:06 volumio volumio[617]: verbose: ControllerMpd::parseTrackInfo Jul 16 05:57:06 volumio volumio[617]: info: ControllerMpd::pushState Jul 16 05:57:06 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:57:06 volumio volumio[617]: info: CorePlayQueue::getTrack 16 Jul 16 05:57:06 volumio volumio[617]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":614,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"A Night on Bald Mountain","artist":"Fritz Reiner, conductor; Chicago Symphony Orchestra","album":"穆索尔斯基《图画展览会》-弗里茨·莱纳","uri":"NAS/NAS/古典名盘合集SACD套装/！RCA“LIVING STEREO”系列/[RCA82876-61394]穆索尔斯基《图画展览会》& 俄罗斯管弦乐名曲集 莱纳/17 - 穆索尔斯基《荒山之夜》 - A Night on Bald Mountain.dsf","trackType":"dsf"} Jul 16 05:57:06 volumio volumio[617]: verbose: CURRENT POSITION 16 Jul 16 05:57:06 volumio volumio[617]: info: CoreStateMachine::syncState stateService play Jul 16 05:57:06 volumio volumio[617]: info: CoreStateMachine::syncState currentStatus play Jul 16 05:57:06 volumio volumio[617]: info: Received an update from plugin. extracting info from payload Jul 16 05:57:06 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:57:06 volumio volumio[617]: info: CorePlayQueue::getTrack 16 Jul 16 05:57:06 volumio volumio[617]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 05:57:06 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:57:06 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:57:06 volumio volumio[617]: info: CorePlayQueue::getTrack 16 Jul 16 05:57:06 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:57:06 volumio volumio[617]: info: ------------------------------ 4ms Jul 16 05:58:13 volumio volumio[617]: info: Airplay started streaming, receiving metadatas Jul 16 05:58:13 volumio volumio[617]: info: CorePlayQueue::getTrack 16 Jul 16 05:58:13 volumio volumio[617]: info: CoreCommandRouter::volumioStop Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::stop Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::stPlaybackTimer Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::updateTrackBlock Jul 16 05:58:13 volumio volumio[617]: info: CorePlayQueue::getTrackBlock Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:13 volumio volumio[617]: info: CorePlayQueue::getTrack 16 Jul 16 05:58:13 volumio volumio[617]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 05:58:13 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::serviceStop Jul 16 05:58:13 volumio volumio[617]: info: CorePlayQueue::getTrack 16 Jul 16 05:58:13 volumio volumio[617]: info: CoreCommandRouter::serviceStop Jul 16 05:58:13 volumio volumio[617]: info: ControllerMpd::stop Jul 16 05:58:13 volumio volumio[617]: verbose: ControllerMpd::sendMpdCommand stop Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 16 05:58:13 volumio volumio[617]: info: Airplay started streaming, receiving metadatas Jul 16 05:58:13 volumio volumio[617]: info: CorePlayQueue::getTrack 16 Jul 16 05:58:13 volumio volumio[617]: info: CoreCommandRouter::volumioStop Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::stop Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 16 05:58:13 volumio volumio[617]: info: Airplay started streaming, receiving metadatas Jul 16 05:58:13 volumio volumio[617]: info: CorePlayQueue::getTrack 16 Jul 16 05:58:13 volumio volumio[617]: info: CoreCommandRouter::volumioStop Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::stop Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 16 05:58:13 volumio shairport-sync[1201]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 16 05:58:13 volumio shairport-sync[1201]: Dload Upload Total Spent Left Speed Jul 16 05:58:13 volumio volumio[617]: info: Jul 16 05:58:13 volumio volumio[617]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jul 16 05:58:13 volumio volumio[617]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jul 16 05:58:13 volumio volumio[617]: info: Airplay playback start Jul 16 05:58:13 volumio volumio[617]: info: CorePlayQueue::getTrack 16 Jul 16 05:58:13 volumio volumio[617]: info: CoreCommandRouter::volumioStop Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::stop Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 16 05:58:13 volumio shairport-sync[1201]: [158B blob data] Jul 16 05:58:13 volumio volumio[617]: info: sendMpdCommand stop took 192 milliseconds Jul 16 05:58:13 volumio volumio[617]: info: Jul 16 05:58:13 volumio volumio[617]: ---------------------------- MPD announces state update: player Jul 16 05:58:13 volumio volumio[617]: info: ControllerMpd::getState Jul 16 05:58:13 volumio volumio[617]: verbose: ControllerMpd::sendMpdCommand status Jul 16 05:58:13 volumio volumio[617]: info: sendMpdCommand status took 0 milliseconds Jul 16 05:58:13 volumio volumio[617]: verbose: ControllerMpd::parseState Jul 16 05:58:13 volumio volumio[617]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 05:58:13 volumio volumio[617]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 16 05:58:13 volumio volumio[617]: verbose: ControllerMpd::parseTrackInfo Jul 16 05:58:13 volumio volumio[617]: info: ControllerMpd::pushState Jul 16 05:58:13 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:13 volumio volumio[617]: info: CorePlayQueue::getTrack 16 Jul 16 05:58:13 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:13 volumio volumio[617]: info: CorePlayQueue::getTrack 16 Jul 16 05:58:13 volumio volumio[617]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Night on Bald Mountain","artist":"Fritz Reiner, conductor; Chicago Symphony Orchestra","album":"穆索尔斯基《图画展览会》-弗里茨·莱纳","uri":"NAS/NAS/古典名盘合集SACD套装/！RCA“LIVING STEREO”系列/[RCA82876-61394]穆索尔斯基《图画展览会》& 俄罗斯管弦乐名曲集 莱纳/17 - 穆索尔斯基《荒山之夜》 - A Night on Bald Mountain.dsf","trackType":"dsf"} Jul 16 05:58:13 volumio volumio[617]: verbose: CURRENT POSITION 16 Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::syncState stateService stop Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::syncState currentStatus stop Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:13 volumio volumio[617]: info: CorePlayQueue::getTrack 16 Jul 16 05:58:13 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:13 volumio volumio[617]: info: No code Jul 16 05:58:13 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:13 volumio volumio[617]: info: CorePlayQueue::getTrack 16 Jul 16 05:58:13 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:13 volumio volumio[617]: info: ------------------------------ 7ms Jul 16 05:58:14 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:14 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:14 volumio volumio[617]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 05:58:14 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:14 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:14 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:14 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:14 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:14 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:14 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:14 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:14 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:14 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:15 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:15 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:15 volumio volumio[617]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 05:58:15 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:15 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:15 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:15 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:15 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:15 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:15 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:15 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:15 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:15 volumio volumio[617]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 05:58:15 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:15 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:15 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:15 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:15 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:15 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:15 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:17 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:17 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:17 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:17 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:17 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:17 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:17 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:17 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::servicePushState Jul 16 05:58:17 volumio volumio[617]: info: CoreStateMachine::pushState Jul 16 05:58:17 volumio volumio[617]: info: CoreCommandRouter::volumioPushState Jul 16 05:58:18 volumio volumio[617]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 16 05:58:18 volumio volumio[617]: Error: connect ECONNREFUSED 54.89.135.129:443 Jul 16 05:58:18 volumio volumio[617]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jul 16 05:58:18 volumio volumio[617]: errno: -111, Jul 16 05:58:18 volumio volumio[617]: code: 'ECONNREFUSED', Jul 16 05:58:18 volumio volumio[617]: syscall: 'connect', Jul 16 05:58:18 volumio volumio[617]: address: '54.89.135.129', Jul 16 05:58:18 volumio volumio[617]: port: 443 Jul 16 05:58:18 volumio volumio[617]: } Jul 16 05:58:18 volumio volumio[617]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 16 05:58:18 volumio sudo[12831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-16 05:57 Jul 16 05:58:18 volumio sudo[12831]: 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="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 06:13:59 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6a6571e2ab129da64c127254a7a779b9"