-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Wed 2025-11-05 11:53:41 CST. -- Nov 05 11:52:00 volumio ntpd[975]: Soliciting pool server 162.159.200.1 Nov 05 11:52:04 volumio systemd[1]: Starting Daily apt download activities... Nov 05 11:52:05 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Nov 05 11:52:05 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:05 volumio systemd[1]: apt-daily.service: Succeeded. Nov 05 11:52:05 volumio systemd[1]: Started Daily apt download activities. Nov 05 11:52:10 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Nov 05 11:52:10 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:15 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Nov 05 11:52:15 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:20 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Nov 05 11:52:20 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:25 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Nov 05 11:52:25 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:30 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Nov 05 11:52:30 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:33 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 05 11:52:33 volumio volumio[1144]: info: CURURI: music-library/INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE] Nov 05 11:52:33 volumio volumio[1144]: info: Preload queue cleared Nov 05 11:52:33 volumio volumio[1144]: info: Preloading song: music-library/INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE]/蒋小雨 - 夜空的风HQⅡ.wav Nov 05 11:52:33 volumio volumio[1144]: info: Exploding uri music-library/INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE]/蒋小雨 - 夜空的风HQⅡ.wav in service mpd Nov 05 11:52:34 volumio volumio[1144]: info: ALBUMART /albumart?cacheid=870&path=%2Fmnt%2FINTERNAL%2F%E8%92%8B%E5%B0%8F%E9%9B%A8%E3%80%8A%E5%A4%9C%E7%A9%BA%E7%9A%84%E9%A3%8EHQII%E3%80%8B%5B%E6%AD%A3%E7%89%88CD%E4%BD%8E%E9%80%9F%E5%8E%9F%E6%8A%93WAV%2BCUE%5D%2F%E8%92%8B%E5%B0%8F%E9%9B%A8%20-%20%E5%A4%9C%E7%A9%BA%E7%9A%84%E9%A3%8EHQ%E2%85%A1.wav&metadata=false Nov 05 11:52:34 volumio volumio[1144]: info: URI /mnt/INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE]/蒋小雨 - 夜空的风HQⅡ.wav Nov 05 11:52:35 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Nov 05 11:52:35 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:37 volumio volumio[1144]: info: Preload queue cleared Nov 05 11:52:37 volumio volumio[1144]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 05 11:52:37 volumio volumio[1144]: info: CoreStateMachine::ClearQueue Nov 05 11:52:37 volumio volumio[1144]: info: CoreStateMachine::stop Nov 05 11:52:37 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 11:52:37 volumio volumio[1144]: info: CorePlayQueue::clearPlayQueue Nov 05 11:52:37 volumio volumio[1144]: info: CorePlayQueue::saveQueue Nov 05 11:52:37 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Nov 05 11:52:37 volumio volumio[1144]: info: CoreStateMachine::addQueueItems Nov 05 11:52:37 volumio volumio[1144]: info: CorePlayQueue::addQueueItems Nov 05 11:52:37 volumio volumio[1144]: info: Preload queue cleared Nov 05 11:52:37 volumio volumio[1144]: info: Adding Item to queue: music-library/INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE]/蒋小雨 - 夜空的风HQⅡ.wav Nov 05 11:52:37 volumio volumio[1144]: info: Using cached record of: music-library/INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE]/蒋小雨 - 夜空的风HQⅡ.wav Nov 05 11:52:37 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Nov 05 11:52:37 volumio volumio[1144]: info: CorePlayQueue::saveQueue Nov 05 11:52:37 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Nov 05 11:52:37 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Nov 05 11:52:37 volumio volumio[1144]: info: CoreCommandRouter::volumioPlay Nov 05 11:52:37 volumio volumio[1144]: info: CoreStateMachine::play index 0 Nov 05 11:52:37 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 11:52:37 volumio volumio[1144]: info: CoreStateMachine::stop Nov 05 11:52:37 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 11:52:37 volumio volumio[1144]: info: CoreStateMachine::play index undefined Nov 05 11:52:37 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 11:52:37 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:37 volumio volumio[1144]: info: CoreStateMachine::startPlaybackTimer Nov 05 11:52:37 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:37 volumio volumio[1144]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE]/蒋小雨 - 夜空的风HQⅡ.wav Nov 05 11:52:37 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Nov 05 11:52:37 volumio volumio[1144]: info: sendMpdCommand stop took 8 milliseconds Nov 05 11:52:37 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clear Nov 05 11:52:37 volumio volumio[1144]: info: Nov 05 11:52:37 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Nov 05 11:52:37 volumio volumio[1144]: info: Ignoring MPD Status Update Nov 05 11:52:37 volumio volumio[1144]: info: sendMpdCommand clear took 3 milliseconds Nov 05 11:52:37 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE]/蒋小雨 - 夜空的风HQⅡ.wav" Nov 05 11:52:37 volumio volumio[1144]: info: Nov 05 11:52:37 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Nov 05 11:52:37 volumio volumio[1144]: info: Ignoring MPD Status Update Nov 05 11:52:37 volumio volumio[1144]: info: Nov 05 11:52:37 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Nov 05 11:52:37 volumio volumio[1144]: info: Ignoring MPD Status Update Nov 05 11:52:37 volumio volumio[1144]: info: Nov 05 11:52:37 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Nov 05 11:52:37 volumio volumio[1144]: info: Ignoring MPD Status Update Nov 05 11:52:37 volumio volumio[1144]: error: updateQueue error: null Nov 05 11:52:37 volumio volumio[1144]: info: Nov 05 11:52:37 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Nov 05 11:52:37 volumio volumio[1144]: info: Ignoring MPD Status Update Nov 05 11:52:37 volumio volumio[1144]: info: ------------------------------ 10ms Nov 05 11:52:37 volumio volumio[1144]: info: sendMpdCommand add "INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE]/蒋小雨 - 夜空的风HQⅡ.wav" took 9 milliseconds Nov 05 11:52:37 volumio volumio[1144]: info: ------------------------------ 7ms Nov 05 11:52:37 volumio volumio[1144]: info: ------------------------------ 6ms Nov 05 11:52:37 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand play Nov 05 11:52:37 volumio volumio[1144]: info: Nov 05 11:52:37 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Nov 05 11:52:37 volumio volumio[1144]: info: Ignoring MPD Status Update Nov 05 11:52:37 volumio volumio[1144]: info: Nov 05 11:52:37 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Nov 05 11:52:37 volumio volumio[1144]: info: Ignoring MPD Status Update Nov 05 11:52:37 volumio volumio[1144]: info: Nov 05 11:52:37 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Nov 05 11:52:37 volumio volumio[1144]: info: Ignoring MPD Status Update Nov 05 11:52:37 volumio volumio[1144]: error: Upnp client error: Error: This socket has been ended by the other party Nov 05 11:52:37 volumio volumio[1144]: info: ------------------------------ 12ms Nov 05 11:52:37 volumio volumio[1144]: info: ------------------------------ 11ms Nov 05 11:52:37 volumio volumio[1144]: info: sendMpdCommand play took 8 milliseconds Nov 05 11:52:37 volumio volumio[1144]: info: ------------------------------ 7ms Nov 05 11:52:37 volumio volumio[1144]: info: ------------------------------ 7ms Nov 05 11:52:37 volumio volumio[1144]: info: ------------------------------ 6ms Nov 05 11:52:38 volumio volumio[1144]: info: Nov 05 11:52:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Nov 05 11:52:38 volumio volumio[1144]: info: ControllerMpd::getState Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Nov 05 11:52:38 volumio volumio[1144]: info: Nov 05 11:52:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Nov 05 11:52:38 volumio volumio[1144]: info: ControllerMpd::getState Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Nov 05 11:52:38 volumio volumio[1144]: info: Nov 05 11:52:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Nov 05 11:52:38 volumio volumio[1144]: info: ControllerMpd::getState Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Nov 05 11:52:38 volumio volumio[1144]: info: Nov 05 11:52:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Nov 05 11:52:38 volumio volumio[1144]: info: ControllerMpd::getState Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Nov 05 11:52:38 volumio volumio[1144]: info: sendMpdCommand status took 169 milliseconds Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 11:52:38 volumio volumio[1144]: info: Nov 05 11:52:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Nov 05 11:52:38 volumio volumio[1144]: info: ControllerMpd::getState Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Nov 05 11:52:38 volumio volumio[1144]: info: sendMpdCommand status took 171 milliseconds Nov 05 11:52:38 volumio volumio[1144]: info: sendMpdCommand status took 169 milliseconds Nov 05 11:52:38 volumio volumio[1144]: info: sendMpdCommand status took 167 milliseconds Nov 05 11:52:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Nov 05 11:52:38 volumio volumio[1144]: info: ControllerMpd::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2807,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"蒋小雨 - 夜空的风HQⅡ.wav","artist":null,"album":null,"uri":"INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE]/蒋小雨 - 夜空的风HQⅡ.wav","trackType":"wav"} Nov 05 11:52:38 volumio volumio[1144]: verbose: CURRENT POSITION 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Nov 05 11:52:38 volumio volumio[1144]: info: ------------------------------ 187ms Nov 05 11:52:38 volumio volumio[1144]: info: Nov 05 11:52:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Nov 05 11:52:38 volumio volumio[1144]: info: ControllerMpd::getState Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Nov 05 11:52:38 volumio volumio[1144]: info: Nov 05 11:52:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Nov 05 11:52:38 volumio volumio[1144]: info: ControllerMpd::getState Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Nov 05 11:52:38 volumio volumio[1144]: info: Nov 05 11:52:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Nov 05 11:52:38 volumio volumio[1144]: info: ControllerMpd::getState Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Nov 05 11:52:38 volumio volumio[1144]: info: sendMpdCommand status took 19 milliseconds Nov 05 11:52:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 16 milliseconds Nov 05 11:52:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 16 milliseconds Nov 05 11:52:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 16 milliseconds Nov 05 11:52:38 volumio volumio[1144]: info: sendMpdCommand status took 8 milliseconds Nov 05 11:52:38 volumio volumio[1144]: info: sendMpdCommand status took 6 milliseconds Nov 05 11:52:38 volumio volumio[1144]: info: sendMpdCommand status took 3 milliseconds Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 11:52:38 volumio volumio[1144]: info: ControllerMpd::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341,"duration":2807,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"蒋小雨 - 夜空的风HQⅡ.wav","artist":null,"album":null,"uri":"INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE]/蒋小雨 - 夜空的风HQⅡ.wav","trackType":"wav"} Nov 05 11:52:38 volumio volumio[1144]: verbose: CURRENT POSITION 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Nov 05 11:52:38 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:52:38 volumio volumio[1144]: info: ControllerMpd::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":523,"duration":2807,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"蒋小雨 - 夜空的风HQⅡ.wav","artist":null,"album":null,"uri":"INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE]/蒋小雨 - 夜空的风HQⅡ.wav","trackType":"wav"} Nov 05 11:52:38 volumio volumio[1144]: verbose: CURRENT POSITION 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Nov 05 11:52:38 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:52:38 volumio volumio[1144]: info: ControllerMpd::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":2807,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"蒋小雨 - 夜空的风HQⅡ.wav","artist":null,"album":null,"uri":"INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE]/蒋小雨 - 夜空的风HQⅡ.wav","trackType":"wav"} Nov 05 11:52:38 volumio volumio[1144]: verbose: CURRENT POSITION 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Nov 05 11:52:38 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:52:38 volumio volumio[1144]: info: ------------------------------ 249ms Nov 05 11:52:38 volumio volumio[1144]: info: ------------------------------ 247ms Nov 05 11:52:38 volumio volumio[1144]: info: ------------------------------ 245ms Nov 05 11:52:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 59 milliseconds Nov 05 11:52:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 58 milliseconds Nov 05 11:52:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 57 milliseconds Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Nov 05 11:52:38 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Nov 05 11:52:38 volumio volumio[1144]: info: ControllerMpd::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":2807,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"蒋小雨 - 夜空的风HQⅡ.wav","artist":null,"album":null,"uri":"INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE]/蒋小雨 - 夜空的风HQⅡ.wav","trackType":"wav"} Nov 05 11:52:38 volumio volumio[1144]: verbose: CURRENT POSITION 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Nov 05 11:52:38 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:52:38 volumio volumio[1144]: info: ControllerMpd::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":2807,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"蒋小雨 - 夜空的风HQⅡ.wav","artist":null,"album":null,"uri":"INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE]/蒋小雨 - 夜空的风HQⅡ.wav","trackType":"wav"} Nov 05 11:52:38 volumio volumio[1144]: verbose: CURRENT POSITION 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Nov 05 11:52:38 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:52:38 volumio volumio[1144]: info: ControllerMpd::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":2807,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"蒋小雨 - 夜空的风HQⅡ.wav","artist":null,"album":null,"uri":"INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE]/蒋小雨 - 夜空的风HQⅡ.wav","trackType":"wav"} Nov 05 11:52:38 volumio volumio[1144]: verbose: CURRENT POSITION 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Nov 05 11:52:38 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:52:38 volumio volumio[1144]: info: ControllerMpd::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":2807,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"蒋小雨 - 夜空的风HQⅡ.wav","artist":null,"album":null,"uri":"INTERNAL/蒋小雨《夜空的风HQII》[正版CD低速原抓WAV+CUE]/蒋小雨 - 夜空的风HQⅡ.wav","trackType":"wav"} Nov 05 11:52:38 volumio volumio[1144]: verbose: CURRENT POSITION 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Nov 05 11:52:38 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:52:38 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:52:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:52:38 volumio volumio[1144]: info: ------------------------------ 157ms Nov 05 11:52:38 volumio volumio[1144]: info: ------------------------------ 146ms Nov 05 11:52:38 volumio volumio[1144]: info: ------------------------------ 146ms Nov 05 11:52:40 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Nov 05 11:52:40 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:52:40 volumio sudo[2309]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/bin/bash run_peppymeter.sh Nov 05 11:52:40 volumio sudo[2309]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 05 11:52:45 volumio volumio[1144]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 6 Nov 05 11:52:45 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Nov 05 11:52:45 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:53:02 volumio sudo[2309]: pam_unix(sudo:session): session closed for user root Nov 05 11:53:05 volumio volumio[1144]: info: VolumeController::SetAlsaVolume42 Nov 05 11:53:05 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:53:05 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:53:05 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 11:53:05 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:53:07 volumio volumio[1144]: info: VolumeController::SetAlsaVolume59 Nov 05 11:53:07 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:53:07 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:53:07 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 11:53:07 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:53:07 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Nov 05 11:53:07 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:53:07 volumio sudo[2493]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/bin/bash run_peppymeter.sh Nov 05 11:53:07 volumio sudo[2493]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 05 11:53:11 volumio volumio[1144]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 6 Nov 05 11:53:11 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Nov 05 11:53:11 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:53:36 volumio kernel: usb 1-3: USB disconnect, device number 3 Nov 05 11:53:36 volumio kernel: usb 1-3: Unable to submit urb #7: -19 at snd_usb_queue_pending_output_urbs Nov 05 11:53:36 volumio volumio[1144]: info: Nov 05 11:53:36 volumio volumio[1144]: ---------------------------- USB Audio Device Detached Nov 05 11:53:36 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Nov 05 11:53:36 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 05 11:53:36 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Nov 05 11:53:37 volumio kernel: usb 1-3: new full-speed USB device number 4 using xhci_hcd Nov 05 11:53:37 volumio kernel: usb 1-3: new full-speed USB device number 5 using xhci_hcd Nov 05 11:53:37 volumio kernel: usb usb1-port3: attempt power cycle Nov 05 11:53:38 volumio kernel: usb 1-3: new full-speed USB device number 6 using xhci_hcd Nov 05 11:53:38 volumio kernel: usb 1-3: device descriptor read/8, error -71 Nov 05 11:53:38 volumio kernel: usb 1-3: device descriptor read/8, error -71 Nov 05 11:53:38 volumio kernel: usb 1-3: new high-speed USB device number 7 using xhci_hcd Nov 05 11:53:38 volumio kernel: usb 1-3: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= 6.c5 Nov 05 11:53:38 volumio kernel: usb 1-3: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Nov 05 11:53:38 volumio kernel: usb 1-3: Product: xCORE USB Audio 2.0 Nov 05 11:53:38 volumio kernel: usb 1-3: Manufacturer: XMOS Nov 05 11:53:39 volumio kernel: usb 1-3: 1:3 : unsupported format bits 0x100000000 Nov 05 11:53:40 volumio volumio[1144]: info: Nov 05 11:53:40 volumio volumio[1144]: ---------------------------- USB Audio Device Attached Nov 05 11:53:40 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Nov 05 11:53:40 volumio volumio[1144]: info: CoreCommandRouter::Close All Modals sent Nov 05 11:53:40 volumio volumio[1144]: info: Preparing to save Alsa Options, stopping services first Nov 05 11:53:40 volumio volumio[1144]: info: CoreCommandRouter::volumioGetState Nov 05 11:53:40 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:53:40 volumio volumio[1144]: info: CoreCommandRouter::volumioPause Nov 05 11:53:40 volumio volumio[1144]: info: CoreStateMachine::pause Nov 05 11:53:40 volumio volumio[1144]: info: CoreStateMachine::stPlaybackTimer Nov 05 11:53:40 volumio volumio[1144]: info: CoreStateMachine::servicePause Nov 05 11:53:40 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:53:40 volumio volumio[1144]: info: CoreCommandRouter::servicePause Nov 05 11:53:40 volumio volumio[1144]: info: ControllerMpd::pause Nov 05 11:53:40 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand pause Nov 05 11:53:40 volumio volumio[1144]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"xCORE USB Audio 2.0","alsacard":"x20"},"i2s":false} Nov 05 11:53:40 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 05 11:53:40 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 05 11:53:40 volumio volumio[1144]: info: Device 5 does not have any Mixer Control Available Nov 05 11:53:40 volumio volumio[1144]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 05 11:53:40 volumio volumio[1144]: info: Updating Volume Controller Parameters: Device: 5 Name: xCORE USB Audio 2.0 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 05 11:53:40 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 05 11:53:40 volumio volumio[1144]: info: Disabling external Volume Control Nov 05 11:53:40 volumio volumio[1144]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 05 11:53:40 volumio volumio[1144]: info: CoreStateMachine::pushState Nov 05 11:53:40 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Nov 05 11:53:40 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 11:53:40 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Nov 05 11:53:40 volumio volumio[1144]: info: Preparing to generate the ALSA configuration file Nov 05 11:53:40 volumio volumio[1144]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Nov 05 11:53:40 volumio volumio[1144]: info: Reading ALSA contributions from plugins. Nov 05 11:53:40 volumio volumio[1144]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 05 11:53:40 volumio volumio[1144]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Nov 05 11:53:40 volumio volumio[1144]: at Object.readdirSync (fs.js:1048:3) Nov 05 11:53:40 volumio volumio[1144]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Nov 05 11:53:40 volumio volumio[1144]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Nov 05 11:53:40 volumio volumio[1144]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Nov 05 11:53:40 volumio volumio[1144]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Nov 05 11:53:40 volumio volumio[1144]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Nov 05 11:53:40 volumio volumio[1144]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Nov 05 11:53:40 volumio volumio[1144]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Nov 05 11:53:40 volumio volumio[1144]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Nov 05 11:53:40 volumio volumio[1144]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Nov 05 11:53:40 volumio volumio[1144]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Nov 05 11:53:40 volumio volumio[1144]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Nov 05 11:53:40 volumio volumio[1144]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Nov 05 11:53:40 volumio volumio[1144]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Nov 05 11:53:40 volumio volumio[1144]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Nov 05 11:53:40 volumio volumio[1144]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Nov 05 11:53:40 volumio volumio[1144]: errno: -20, Nov 05 11:53:40 volumio volumio[1144]: syscall: 'scandir', Nov 05 11:53:40 volumio volumio[1144]: code: 'ENOTDIR', Nov 05 11:53:40 volumio volumio[1144]: path: '/tmp/peppyrunning' Nov 05 11:53:40 volumio volumio[1144]: } Nov 05 11:53:40 volumio volumio[1144]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 05 11:53:41 volumio sudo[2686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-05 11:52 Nov 05 11:53:41 volumio sudo[2686]: 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="5d50cd80bebce60dddccd7f5e813d656e28ca4cd" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="7840f283c310c38bfe939a4c485727a6ff6a0ce1" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 21 Oct 2025 04:19:36 PM CEST" VOLUMIO_VERSION="3.866" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a531aa6bc68498be155aace11c795690"