-- 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"