-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Sat 2024-07-13 20:43:07 CST. -- Jul 13 20:42:12 volumio volumio[675]: info: Clearing queue after UPNP request Jul 13 20:42:12 volumio volumio[675]: info: CoreStateMachine::ClearQueue Jul 13 20:42:12 volumio volumio[675]: info: CoreStateMachine::stop Jul 13 20:42:12 volumio volumio[675]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 20:42:12 volumio volumio[675]: info: CorePlayQueue::clearPlayQueue Jul 13 20:42:12 volumio volumio[675]: info: CorePlayQueue::saveQueue Jul 13 20:42:12 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:42:13 volumio volumio[675]: info: CoreCommandRouter::volumioPushQueue Jul 13 20:42:13 volumio volumio[675]: info: Jul 13 20:42:13 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:13 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:13 volumio volumio[675]: error: updateQueue error: null Jul 13 20:42:13 volumio volumio[675]: info: ------------------------------ 5ms Jul 13 20:42:13 volumio volumio[675]: info: Starting UPNP Playback Jul 13 20:42:13 volumio volumio[675]: info: Preparing playback through UPNP Jul 13 20:42:13 volumio volumio[675]: info: CoreCommandRouter::volumioGetState Jul 13 20:42:13 volumio volumio[675]: info: CorePlayQueue::getTrack 0 Jul 13 20:42:13 volumio volumio[675]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 20:42:13 volumio volumio[675]: info: Jul 13 20:42:13 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:13 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:13 volumio volumio[675]: info: ------------------------------ 4ms Jul 13 20:42:13 volumio volumio[675]: info: Jul 13 20:42:13 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:13 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:13 volumio volumio[675]: info: ------------------------------ 5ms Jul 13 20:42:13 volumio volumio[675]: info: Jul 13 20:42:13 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:13 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:13 volumio volumio[675]: info: ------------------------------ 5ms Jul 13 20:42:13 volumio volumio[675]: info: Jul 13 20:42:13 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:13 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:13 volumio volumio[675]: info: ------------------------------ 5ms Jul 13 20:42:13 volumio volumio[675]: info: Jul 13 20:42:13 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:13 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:13 volumio volumio[675]: info: ------------------------------ 5ms Jul 13 20:42:13 volumio volumio[675]: info: Jul 13 20:42:13 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:13 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:13 volumio volumio[675]: info: ------------------------------ 5ms Jul 13 20:42:18 volumio volumio[675]: info: Jul 13 20:42:18 volumio volumio[675]: ---------------------------- MPD announces state update: player Jul 13 20:42:18 volumio volumio[675]: info: ControllerMpd::getState Jul 13 20:42:18 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand status Jul 13 20:42:18 volumio volumio[675]: info: CoreCommandRouter::volumioPlay Jul 13 20:42:18 volumio volumio[675]: info: CoreStateMachine::play index undefined Jul 13 20:42:18 volumio volumio[675]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 20:42:18 volumio volumio[675]: info: CorePlayQueue::getTrack 0 Jul 13 20:42:18 volumio volumio[675]: info: Jul 13 20:42:18 volumio volumio[675]: ---------------------------- MPD announces state update: player Jul 13 20:42:18 volumio volumio[675]: info: sendMpdCommand status took 137 milliseconds Jul 13 20:42:18 volumio volumio[675]: info: ControllerMpd::getState Jul 13 20:42:18 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand status Jul 13 20:42:18 volumio volumio[675]: verbose: ControllerMpd::parseState Jul 13 20:42:18 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 20:42:18 volumio volumio[675]: info: sendMpdCommand status took 9 milliseconds Jul 13 20:42:18 volumio volumio[675]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 13 20:42:18 volumio volumio[675]: verbose: ControllerMpd::parseState Jul 13 20:42:18 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 20:42:18 volumio volumio[675]: verbose: ControllerMpd::parseTrackInfo Jul 13 20:42:18 volumio volumio[675]: info: ControllerMpd::pushState Jul 13 20:42:18 volumio volumio[675]: info: CoreCommandRouter::servicePushState Jul 13 20:42:18 volumio volumio[675]: info: CorePlayQueue::getTrack 0 Jul 13 20:42:18 volumio volumio[675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":168,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"邓典 - 神魂颠倒","artist":"邓典","album":null,"uri":"http://192.168.124.170:49590/kgcache/%E9%82%93%E5%85%B8%20-%20%E7%A5%9E%E9%AD%82%E9%A2%A0%E5%80%92_SQ.kge","trackType":"kge"} Jul 13 20:42:18 volumio volumio[675]: verbose: CURRENT POSITION 0 Jul 13 20:42:18 volumio volumio[675]: info: CoreStateMachine::syncState stateService play Jul 13 20:42:18 volumio volumio[675]: info: CoreStateMachine::syncState currentStatus stop Jul 13 20:42:18 volumio volumio[675]: info: ------------------------------ 183ms Jul 13 20:42:18 volumio volumio[675]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 13 20:42:18 volumio volumio[675]: verbose: ControllerMpd::parseTrackInfo Jul 13 20:42:18 volumio volumio[675]: info: ControllerMpd::pushState Jul 13 20:42:18 volumio volumio[675]: info: CoreCommandRouter::servicePushState Jul 13 20:42:18 volumio volumio[675]: info: CorePlayQueue::getTrack 0 Jul 13 20:42:18 volumio volumio[675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":168,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"邓典 - 神魂颠倒","artist":"邓典","album":null,"uri":"http://192.168.124.170:49590/kgcache/%E9%82%93%E5%85%B8%20-%20%E7%A5%9E%E9%AD%82%E9%A2%A0%E5%80%92_SQ.kge","trackType":"kge"} Jul 13 20:42:18 volumio volumio[675]: verbose: CURRENT POSITION 0 Jul 13 20:42:18 volumio volumio[675]: info: CoreStateMachine::syncState stateService play Jul 13 20:42:18 volumio volumio[675]: info: CoreStateMachine::syncState currentStatus play Jul 13 20:42:18 volumio volumio[675]: info: Received an update from plugin. extracting info from payload Jul 13 20:42:18 volumio volumio[675]: info: CoreStateMachine::pushState Jul 13 20:42:18 volumio volumio[675]: info: CorePlayQueue::getTrack 0 Jul 13 20:42:18 volumio volumio[675]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 20:42:18 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:42:18 volumio volumio[675]: info: CoreStateMachine::pushState Jul 13 20:42:18 volumio volumio[675]: info: CorePlayQueue::getTrack 0 Jul 13 20:42:18 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:42:18 volumio volumio[675]: info: ------------------------------ 88ms Jul 13 20:42:29 volumio volumio[999]: 88 Jul 13 20:42:29 volumio volumio[999]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 13 20:42:29 volumio volumio[999]: Dload Upload Total Spent Left Speed Jul 13 20:42:29 volumio volumio[675]: info: Jul 13 20:42:29 volumio volumio[675]: ---------------------------- Client requests Volume 88 Jul 13 20:42:29 volumio volumio[675]: info: VolumeController::SetAlsaVolume88 Jul 13 20:42:29 volumio volumio[675]: info: CoreStateMachine::pushState Jul 13 20:42:29 volumio volumio[675]: info: CorePlayQueue::getTrack 0 Jul 13 20:42:29 volumio volumio[675]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 20:42:29 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:42:29 volumio volumio[999]: [158B blob data] Jul 13 20:42:29 volumio volumio[999]: {"time":1720874549867,"response":"volume Success"}88 Jul 13 20:42:29 volumio volumio[999]: 96 Jul 13 20:42:30 volumio volumio[999]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 13 20:42:30 volumio volumio[999]: Dload Upload Total Spent Left Speed Jul 13 20:42:30 volumio volumio[675]: info: Jul 13 20:42:30 volumio volumio[675]: ---------------------------- Client requests Volume 96 Jul 13 20:42:30 volumio volumio[675]: info: VolumeController::SetAlsaVolume96 Jul 13 20:42:30 volumio volumio[675]: info: CoreStateMachine::pushState Jul 13 20:42:30 volumio volumio[675]: info: CorePlayQueue::getTrack 0 Jul 13 20:42:30 volumio volumio[675]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 20:42:30 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:42:30 volumio volumio[999]: [158B blob data] Jul 13 20:42:30 volumio volumio[999]: {"time":1720874550091,"response":"volume Success"}96 Jul 13 20:42:30 volumio volumio[999]: 97 Jul 13 20:42:30 volumio volumio[999]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 13 20:42:30 volumio volumio[999]: Dload Upload Total Spent Left Speed Jul 13 20:42:30 volumio volumio[675]: info: Jul 13 20:42:30 volumio volumio[675]: ---------------------------- Client requests Volume 97 Jul 13 20:42:30 volumio volumio[675]: info: VolumeController::SetAlsaVolume97 Jul 13 20:42:30 volumio volumio[675]: info: CoreStateMachine::pushState Jul 13 20:42:30 volumio volumio[675]: info: CorePlayQueue::getTrack 0 Jul 13 20:42:30 volumio volumio[675]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 20:42:30 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:42:30 volumio volumio[999]: [158B blob data] Jul 13 20:42:30 volumio volumio[999]: {"time":1720874550310,"response":"volume Success"}97 Jul 13 20:42:32 volumio volumio[675]: info: Jul 13 20:42:32 volumio volumio[675]: ---------------------------- MPD announces state update: player Jul 13 20:42:32 volumio volumio[675]: info: ControllerMpd::getState Jul 13 20:42:32 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand status Jul 13 20:42:32 volumio volumio[675]: info: sendMpdCommand status took 3 milliseconds Jul 13 20:42:32 volumio volumio[675]: verbose: ControllerMpd::parseState Jul 13 20:42:32 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 20:42:32 volumio volumio[675]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 13 20:42:32 volumio volumio[675]: verbose: ControllerMpd::parseTrackInfo Jul 13 20:42:32 volumio volumio[675]: info: ControllerMpd::pushState Jul 13 20:42:32 volumio volumio[675]: info: CoreCommandRouter::servicePushState Jul 13 20:42:32 volumio volumio[675]: info: CoreStateMachine::pushState Jul 13 20:42:32 volumio volumio[675]: info: CorePlayQueue::getTrack 0 Jul 13 20:42:32 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:42:32 volumio volumio[675]: info: CorePlayQueue::getTrack 0 Jul 13 20:42:32 volumio volumio[675]: 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":null,"uri":"http://192.168.124.170:49590/kgcache/%E9%82%93%E5%85%B8%20-%20%E7%A5%9E%E9%AD%82%E9%A2%A0%E5%80%92_SQ.kge","trackType":"kge"} Jul 13 20:42:32 volumio volumio[675]: verbose: CURRENT POSITION 0 Jul 13 20:42:32 volumio volumio[675]: info: CoreStateMachine::syncState stateService stop Jul 13 20:42:32 volumio volumio[675]: info: CoreStateMachine::syncState currentStatus play Jul 13 20:42:32 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:42:32 volumio volumio[675]: info: CoreStateMachine::stPlaybackTimer Jul 13 20:42:32 volumio volumio[675]: info: ------------------------------ 57ms Jul 13 20:42:32 volumio volumio[675]: info: Clearing queue after UPNP request Jul 13 20:42:33 volumio volumio[675]: info: CoreStateMachine::ClearQueue Jul 13 20:42:33 volumio volumio[675]: info: CoreStateMachine::stop Jul 13 20:42:33 volumio volumio[675]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 20:42:33 volumio volumio[675]: info: CorePlayQueue::clearPlayQueue Jul 13 20:42:33 volumio volumio[675]: info: CorePlayQueue::saveQueue Jul 13 20:42:33 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:42:33 volumio volumio[675]: info: CoreCommandRouter::volumioPushQueue Jul 13 20:42:33 volumio volumio[675]: info: Jul 13 20:42:33 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:33 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:33 volumio volumio[675]: error: updateQueue error: null Jul 13 20:42:33 volumio volumio[675]: info: ------------------------------ 4ms Jul 13 20:42:33 volumio volumio[675]: info: Starting UPNP Playback Jul 13 20:42:33 volumio volumio[675]: info: Preparing playback through UPNP Jul 13 20:42:33 volumio volumio[675]: info: CoreCommandRouter::volumioGetState Jul 13 20:42:33 volumio volumio[675]: info: CorePlayQueue::getTrack 0 Jul 13 20:42:33 volumio volumio[675]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 20:42:33 volumio volumio[675]: info: Jul 13 20:42:33 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:33 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:33 volumio volumio[675]: info: ------------------------------ 4ms Jul 13 20:42:33 volumio volumio[675]: info: Jul 13 20:42:33 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:33 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:33 volumio volumio[675]: info: ------------------------------ 5ms Jul 13 20:42:33 volumio volumio[675]: info: Jul 13 20:42:33 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:33 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:33 volumio volumio[675]: info: ------------------------------ 6ms Jul 13 20:42:33 volumio volumio[675]: info: Jul 13 20:42:33 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:33 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:33 volumio volumio[675]: info: ------------------------------ 6ms Jul 13 20:42:33 volumio volumio[675]: info: Jul 13 20:42:33 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:33 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:33 volumio volumio[675]: info: ------------------------------ 6ms Jul 13 20:42:33 volumio volumio[675]: info: Jul 13 20:42:33 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:33 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:33 volumio volumio[675]: info: ------------------------------ 6ms Jul 13 20:42:33 volumio volumio[675]: info: Jul 13 20:42:33 volumio volumio[675]: ---------------------------- MPD announces state update: player Jul 13 20:42:33 volumio volumio[675]: info: ControllerMpd::getState Jul 13 20:42:33 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand status Jul 13 20:42:34 volumio volumio[675]: info: sendMpdCommand status took 309 milliseconds Jul 13 20:42:34 volumio volumio[675]: verbose: ControllerMpd::parseState Jul 13 20:42:34 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 20:42:34 volumio volumio[675]: info: Jul 13 20:42:34 volumio volumio[675]: ---------------------------- MPD announces state update: player Jul 13 20:42:34 volumio volumio[675]: info: ControllerMpd::getState Jul 13 20:42:34 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand status Jul 13 20:42:34 volumio volumio[675]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 13 20:42:34 volumio volumio[675]: verbose: ControllerMpd::parseTrackInfo Jul 13 20:42:34 volumio volumio[675]: info: ControllerMpd::pushState Jul 13 20:42:34 volumio volumio[675]: info: CoreCommandRouter::servicePushState Jul 13 20:42:34 volumio volumio[675]: verbose: In UPNP mode Jul 13 20:42:34 volumio volumio[675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2042,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"许嵩、黄龄 - 惊鸿一面","artist":"许嵩","album":null,"uri":"http://192.168.124.170:49590/kgcache/%E8%AE%B8%E5%B5%A9%E3%80%81%E9%BB%84%E9%BE%84%20-%20%E6%83%8A%E9%B8%BF%E4%B8%80%E9%9D%A2_SQ.kge","trackType":"kge"} Jul 13 20:42:34 volumio volumio[675]: verbose: CURRENT POSITION 0 Jul 13 20:42:34 volumio volumio[675]: info: CoreStateMachine::syncState stateService play Jul 13 20:42:34 volumio volumio[675]: info: CoreStateMachine::syncState currentStatus stop Jul 13 20:42:34 volumio volumio[675]: info: ------------------------------ 343ms Jul 13 20:42:34 volumio volumio[675]: info: sendMpdCommand status took 10 milliseconds Jul 13 20:42:34 volumio volumio[675]: verbose: ControllerMpd::parseState Jul 13 20:42:34 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 20:42:34 volumio volumio[675]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 13 20:42:34 volumio volumio[675]: verbose: ControllerMpd::parseTrackInfo Jul 13 20:42:34 volumio volumio[675]: info: ControllerMpd::pushState Jul 13 20:42:34 volumio volumio[675]: info: CoreCommandRouter::servicePushState Jul 13 20:42:34 volumio volumio[675]: verbose: In UPNP mode Jul 13 20:42:34 volumio volumio[675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":2042,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"144 Kbps","isStreaming":false,"title":"许嵩、黄龄 - 惊鸿一面","artist":"许嵩","album":null,"uri":"http://192.168.124.170:49590/kgcache/%E8%AE%B8%E5%B5%A9%E3%80%81%E9%BB%84%E9%BE%84%20-%20%E6%83%8A%E9%B8%BF%E4%B8%80%E9%9D%A2_SQ.kge","trackType":"kge"} Jul 13 20:42:34 volumio volumio[675]: verbose: CURRENT POSITION 0 Jul 13 20:42:34 volumio volumio[675]: info: CoreStateMachine::syncState stateService play Jul 13 20:42:34 volumio volumio[675]: info: CoreStateMachine::syncState currentStatus play Jul 13 20:42:34 volumio volumio[675]: info: Received an update from plugin. extracting info from payload Jul 13 20:42:34 volumio volumio[675]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 13 20:42:34 volumio volumio[675]: info: CoreStateMachine::pushState Jul 13 20:42:34 volumio volumio[675]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 20:42:34 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:42:34 volumio volumio[675]: info: CoreStateMachine::pushState Jul 13 20:42:34 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:42:34 volumio volumio[675]: info: ------------------------------ 74ms Jul 13 20:42:53 volumio volumio[675]: info: Jul 13 20:42:53 volumio volumio[675]: ---------------------------- MPD announces state update: player Jul 13 20:42:53 volumio volumio[675]: info: ControllerMpd::getState Jul 13 20:42:53 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand status Jul 13 20:42:53 volumio volumio[675]: info: sendMpdCommand status took 2 milliseconds Jul 13 20:42:53 volumio volumio[675]: verbose: ControllerMpd::parseState Jul 13 20:42:53 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 20:42:53 volumio volumio[675]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 13 20:42:53 volumio volumio[675]: verbose: ControllerMpd::parseTrackInfo Jul 13 20:42:53 volumio volumio[675]: info: ControllerMpd::pushState Jul 13 20:42:53 volumio volumio[675]: info: CoreCommandRouter::servicePushState Jul 13 20:42:53 volumio volumio[675]: info: CoreStateMachine::pushState Jul 13 20:42:53 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:42:53 volumio volumio[675]: info: CorePlayQueue::getTrack 0 Jul 13 20:42:53 volumio volumio[675]: 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":null,"uri":"http://192.168.124.170:49590/kgcache/%E8%AE%B8%E5%B5%A9%E3%80%81%E9%BB%84%E9%BE%84%20-%20%E6%83%8A%E9%B8%BF%E4%B8%80%E9%9D%A2_SQ.kge","trackType":"kge"} Jul 13 20:42:53 volumio volumio[675]: verbose: CURRENT POSITION 0 Jul 13 20:42:53 volumio volumio[675]: info: CoreStateMachine::syncState stateService stop Jul 13 20:42:53 volumio volumio[675]: info: CoreStateMachine::syncState currentStatus play Jul 13 20:42:53 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:42:53 volumio volumio[675]: info: CoreStateMachine::stPlaybackTimer Jul 13 20:42:53 volumio volumio[675]: info: ------------------------------ 56ms Jul 13 20:42:53 volumio volumio[675]: info: Clearing queue after UPNP request Jul 13 20:42:54 volumio volumio[675]: info: CoreStateMachine::ClearQueue Jul 13 20:42:54 volumio volumio[675]: info: CoreStateMachine::stop Jul 13 20:42:54 volumio volumio[675]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 20:42:54 volumio volumio[675]: info: CorePlayQueue::clearPlayQueue Jul 13 20:42:54 volumio volumio[675]: info: CorePlayQueue::saveQueue Jul 13 20:42:54 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:42:54 volumio volumio[675]: info: CoreCommandRouter::volumioPushQueue Jul 13 20:42:54 volumio volumio[675]: info: Jul 13 20:42:54 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:54 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:54 volumio volumio[675]: error: updateQueue error: null Jul 13 20:42:54 volumio volumio[675]: info: ------------------------------ 4ms Jul 13 20:42:54 volumio volumio[675]: info: Starting UPNP Playback Jul 13 20:42:54 volumio volumio[675]: info: Preparing playback through UPNP Jul 13 20:42:54 volumio volumio[675]: info: CoreCommandRouter::volumioGetState Jul 13 20:42:54 volumio volumio[675]: info: CorePlayQueue::getTrack 0 Jul 13 20:42:54 volumio volumio[675]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 20:42:54 volumio volumio[675]: info: Jul 13 20:42:54 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:54 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:54 volumio volumio[675]: info: Jul 13 20:42:54 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:54 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:54 volumio volumio[675]: info: ------------------------------ 5ms Jul 13 20:42:54 volumio volumio[675]: info: ------------------------------ 4ms Jul 13 20:42:54 volumio volumio[675]: info: Jul 13 20:42:54 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:54 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:54 volumio volumio[675]: info: ------------------------------ 4ms Jul 13 20:42:54 volumio volumio[675]: info: Jul 13 20:42:54 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:54 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:54 volumio volumio[675]: info: ------------------------------ 4ms Jul 13 20:42:54 volumio volumio[675]: info: Jul 13 20:42:54 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:54 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:54 volumio volumio[675]: info: ------------------------------ 9ms Jul 13 20:42:54 volumio volumio[675]: info: Jul 13 20:42:54 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:42:54 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:42:54 volumio volumio[675]: info: ------------------------------ 3ms Jul 13 20:42:54 volumio volumio[675]: info: Jul 13 20:42:54 volumio volumio[675]: ---------------------------- MPD announces state update: player Jul 13 20:42:54 volumio volumio[675]: info: ControllerMpd::getState Jul 13 20:42:54 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand status Jul 13 20:42:55 volumio volumio[675]: info: Jul 13 20:42:55 volumio volumio[675]: ---------------------------- MPD announces state update: player Jul 13 20:42:55 volumio volumio[675]: info: sendMpdCommand status took 288 milliseconds Jul 13 20:42:55 volumio volumio[675]: info: ControllerMpd::getState Jul 13 20:42:55 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand status Jul 13 20:42:55 volumio volumio[675]: verbose: ControllerMpd::parseState Jul 13 20:42:55 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 20:42:55 volumio volumio[675]: info: sendMpdCommand status took 4 milliseconds Jul 13 20:42:55 volumio volumio[675]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 13 20:42:55 volumio volumio[675]: verbose: ControllerMpd::parseState Jul 13 20:42:55 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 20:42:55 volumio volumio[675]: verbose: ControllerMpd::parseTrackInfo Jul 13 20:42:55 volumio volumio[675]: info: ControllerMpd::pushState Jul 13 20:42:55 volumio volumio[675]: info: CoreCommandRouter::servicePushState Jul 13 20:42:55 volumio volumio[675]: verbose: In UPNP mode Jul 13 20:42:55 volumio volumio[675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1972,"samplerate":"8 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"李常超（Lao乾妈） - 盗墓笔记·十年人间","artist":"李常超（Lao乾妈）","album":null,"uri":"http://192.168.124.170:49590/kgcache/%E6%9D%8E%E5%B8%B8%E8%B6%85%EF%BC%88Lao%E4%B9%BE%E5%A6%88%EF%BC%89%20-%20%E7%9B%97%E5%A2%93%E7%AC%94%E8%AE%B0%C2%B7%E5%8D%81%E5%B9%B4%E4%BA%BA%E9%97%B4_DSD.kge","trackType":"kge"} Jul 13 20:42:55 volumio volumio[675]: verbose: CURRENT POSITION 0 Jul 13 20:42:55 volumio volumio[675]: info: CoreStateMachine::syncState stateService play Jul 13 20:42:55 volumio volumio[675]: info: CoreStateMachine::syncState currentStatus stop Jul 13 20:42:55 volumio volumio[675]: info: ------------------------------ 305ms Jul 13 20:42:55 volumio volumio[675]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 13 20:42:55 volumio volumio[675]: verbose: ControllerMpd::parseTrackInfo Jul 13 20:42:55 volumio volumio[675]: info: ControllerMpd::pushState Jul 13 20:42:55 volumio volumio[675]: info: CoreCommandRouter::servicePushState Jul 13 20:42:55 volumio volumio[675]: verbose: In UPNP mode Jul 13 20:42:55 volumio volumio[675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1972,"samplerate":"8 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"李常超（Lao乾妈） - 盗墓笔记·十年人间","artist":"李常超（Lao乾妈）","album":null,"uri":"http://192.168.124.170:49590/kgcache/%E6%9D%8E%E5%B8%B8%E8%B6%85%EF%BC%88Lao%E4%B9%BE%E5%A6%88%EF%BC%89%20-%20%E7%9B%97%E5%A2%93%E7%AC%94%E8%AE%B0%C2%B7%E5%8D%81%E5%B9%B4%E4%BA%BA%E9%97%B4_DSD.kge","trackType":"kge"} Jul 13 20:42:55 volumio volumio[675]: verbose: CURRENT POSITION 0 Jul 13 20:42:55 volumio volumio[675]: info: CoreStateMachine::syncState stateService play Jul 13 20:42:55 volumio volumio[675]: info: CoreStateMachine::syncState currentStatus play Jul 13 20:42:55 volumio volumio[675]: info: Received an update from plugin. extracting info from payload Jul 13 20:42:55 volumio volumio[675]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 13 20:42:55 volumio volumio[675]: info: CoreStateMachine::pushState Jul 13 20:42:55 volumio volumio[675]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 20:42:55 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:42:55 volumio volumio[675]: info: CoreStateMachine::pushState Jul 13 20:42:55 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:42:55 volumio volumio[675]: info: ------------------------------ 66ms Jul 13 20:43:00 volumio volumio[675]: info: Jul 13 20:43:00 volumio volumio[675]: ---------------------------- MPD announces state update: player Jul 13 20:43:00 volumio volumio[675]: info: ControllerMpd::getState Jul 13 20:43:00 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand status Jul 13 20:43:00 volumio volumio[675]: info: sendMpdCommand status took 2 milliseconds Jul 13 20:43:00 volumio volumio[675]: verbose: ControllerMpd::parseState Jul 13 20:43:00 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 20:43:00 volumio volumio[675]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 13 20:43:00 volumio volumio[675]: verbose: ControllerMpd::parseTrackInfo Jul 13 20:43:00 volumio volumio[675]: info: ControllerMpd::pushState Jul 13 20:43:00 volumio volumio[675]: info: CoreCommandRouter::servicePushState Jul 13 20:43:00 volumio volumio[675]: info: CoreStateMachine::pushState Jul 13 20:43:00 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:43:00 volumio volumio[675]: info: CorePlayQueue::getTrack 0 Jul 13 20:43:00 volumio volumio[675]: 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":"李常超（Lao乾妈） - 盗墓笔记·十年人间","artist":"李常超（Lao乾妈）","album":null,"uri":"http://192.168.124.170:49590/kgcache/%E6%9D%8E%E5%B8%B8%E8%B6%85%EF%BC%88Lao%E4%B9%BE%E5%A6%88%EF%BC%89%20-%20%E7%9B%97%E5%A2%93%E7%AC%94%E8%AE%B0%C2%B7%E5%8D%81%E5%B9%B4%E4%BA%BA%E9%97%B4_DSD.kge","trackType":"kge"} Jul 13 20:43:00 volumio volumio[675]: verbose: CURRENT POSITION 0 Jul 13 20:43:00 volumio volumio[675]: info: CoreStateMachine::syncState stateService stop Jul 13 20:43:00 volumio volumio[675]: info: CoreStateMachine::syncState currentStatus play Jul 13 20:43:00 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:43:00 volumio volumio[675]: info: CoreStateMachine::stPlaybackTimer Jul 13 20:43:00 volumio volumio[675]: info: ------------------------------ 50ms Jul 13 20:43:00 volumio volumio[675]: info: Clearing queue after UPNP request Jul 13 20:43:00 volumio volumio[675]: info: CoreStateMachine::ClearQueue Jul 13 20:43:00 volumio volumio[675]: info: CoreStateMachine::stop Jul 13 20:43:00 volumio volumio[675]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 20:43:00 volumio volumio[675]: info: CorePlayQueue::clearPlayQueue Jul 13 20:43:00 volumio volumio[675]: info: CorePlayQueue::saveQueue Jul 13 20:43:00 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:43:00 volumio volumio[675]: info: CoreCommandRouter::volumioPushQueue Jul 13 20:43:00 volumio volumio[675]: info: Jul 13 20:43:00 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:43:00 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:43:00 volumio volumio[675]: error: updateQueue error: null Jul 13 20:43:00 volumio volumio[675]: info: ------------------------------ 4ms Jul 13 20:43:00 volumio volumio[675]: info: Starting UPNP Playback Jul 13 20:43:00 volumio volumio[675]: info: Preparing playback through UPNP Jul 13 20:43:00 volumio volumio[675]: info: CoreCommandRouter::volumioGetState Jul 13 20:43:00 volumio volumio[675]: info: CorePlayQueue::getTrack 0 Jul 13 20:43:00 volumio volumio[675]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 20:43:01 volumio volumio[675]: info: Jul 13 20:43:01 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:43:01 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:43:01 volumio volumio[675]: info: Jul 13 20:43:01 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:43:01 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:43:01 volumio volumio[675]: info: ------------------------------ 5ms Jul 13 20:43:01 volumio volumio[675]: info: ------------------------------ 4ms Jul 13 20:43:01 volumio volumio[675]: info: Jul 13 20:43:01 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:43:01 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:43:01 volumio volumio[675]: info: ------------------------------ 4ms Jul 13 20:43:01 volumio volumio[675]: info: Jul 13 20:43:01 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:43:01 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:43:01 volumio volumio[675]: info: ------------------------------ 4ms Jul 13 20:43:01 volumio volumio[675]: info: Jul 13 20:43:01 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:43:01 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:43:01 volumio volumio[675]: info: ------------------------------ 4ms Jul 13 20:43:01 volumio volumio[675]: info: Jul 13 20:43:01 volumio volumio[675]: ---------------------------- MPD announces system playlist update Jul 13 20:43:01 volumio volumio[675]: info: Ignoring MPD Status Update Jul 13 20:43:01 volumio volumio[675]: info: ------------------------------ 4ms Jul 13 20:43:01 volumio volumio[675]: info: Jul 13 20:43:01 volumio volumio[675]: ---------------------------- MPD announces state update: player Jul 13 20:43:01 volumio volumio[675]: info: ControllerMpd::getState Jul 13 20:43:01 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand status Jul 13 20:43:01 volumio volumio[675]: info: Jul 13 20:43:01 volumio volumio[675]: ---------------------------- MPD announces state update: player Jul 13 20:43:01 volumio volumio[675]: info: sendMpdCommand status took 148 milliseconds Jul 13 20:43:01 volumio volumio[675]: info: ControllerMpd::getState Jul 13 20:43:01 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand status Jul 13 20:43:01 volumio volumio[675]: verbose: ControllerMpd::parseState Jul 13 20:43:01 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 20:43:01 volumio volumio[675]: info: sendMpdCommand status took 5 milliseconds Jul 13 20:43:01 volumio volumio[675]: verbose: ControllerMpd::parseState Jul 13 20:43:01 volumio volumio[675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 20:43:01 volumio volumio[675]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 13 20:43:01 volumio volumio[675]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 13 20:43:01 volumio volumio[675]: verbose: ControllerMpd::parseTrackInfo Jul 13 20:43:01 volumio volumio[675]: info: ControllerMpd::pushState Jul 13 20:43:01 volumio volumio[675]: info: CoreCommandRouter::servicePushState Jul 13 20:43:01 volumio volumio[675]: verbose: In UPNP mode Jul 13 20:43:01 volumio volumio[675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1225,"samplerate":"32 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"银临、Aki阿杰 - 牵丝戏","artist":"银临","album":null,"uri":"http://192.168.124.170:49590/kgcache/%E9%93%B6%E4%B8%B4%E3%80%81Aki%E9%98%BF%E6%9D%B0%20-%20%E7%89%B5%E4%B8%9D%E6%88%8F_DSD.kge","trackType":"kge"} Jul 13 20:43:01 volumio volumio[675]: verbose: CURRENT POSITION 0 Jul 13 20:43:01 volumio volumio[675]: info: CoreStateMachine::syncState stateService play Jul 13 20:43:01 volumio volumio[675]: info: CoreStateMachine::syncState currentStatus stop Jul 13 20:43:01 volumio volumio[675]: info: ControllerMpd::pushState Jul 13 20:43:01 volumio volumio[675]: info: CoreCommandRouter::servicePushState Jul 13 20:43:01 volumio volumio[675]: verbose: In UPNP mode Jul 13 20:43:01 volumio volumio[675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1225,"samplerate":"32 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"银临、Aki阿杰 - 牵丝戏","artist":"银临","album":null,"uri":"http://192.168.124.170:49590/kgcache/%E9%93%B6%E4%B8%B4%E3%80%81Aki%E9%98%BF%E6%9D%B0%20-%20%E7%89%B5%E4%B8%9D%E6%88%8F_DSD.kge","trackType":"kge"} Jul 13 20:43:01 volumio volumio[675]: verbose: CURRENT POSITION 0 Jul 13 20:43:01 volumio volumio[675]: info: CoreStateMachine::syncState stateService play Jul 13 20:43:01 volumio volumio[675]: info: CoreStateMachine::syncState currentStatus play Jul 13 20:43:01 volumio volumio[675]: info: Received an update from plugin. extracting info from payload Jul 13 20:43:01 volumio volumio[675]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 13 20:43:01 volumio volumio[675]: info: CoreStateMachine::pushState Jul 13 20:43:01 volumio volumio[675]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 20:43:01 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:43:01 volumio volumio[675]: info: CoreStateMachine::pushState Jul 13 20:43:01 volumio volumio[675]: info: CoreCommandRouter::volumioPushState Jul 13 20:43:01 volumio volumio[675]: info: ------------------------------ 219ms Jul 13 20:43:01 volumio volumio[675]: info: ------------------------------ 97ms Jul 13 20:43:06 volumio volumio[675]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 20:43:06 volumio volumio[675]: Error: connect ETIMEDOUT 199.59.148.209:80 Jul 13 20:43:06 volumio volumio[675]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 13 20:43:06 volumio volumio[675]: errno: -110, Jul 13 20:43:06 volumio volumio[675]: code: 'ETIMEDOUT', Jul 13 20:43:06 volumio volumio[675]: syscall: 'connect', Jul 13 20:43:06 volumio volumio[675]: address: '199.59.148.209', Jul 13 20:43:06 volumio volumio[675]: port: 80 Jul 13 20:43:06 volumio volumio[675]: } Jul 13 20:43:06 volumio volumio[675]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 20:43:07 volumio sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-13 20:42 Jul 13 20:43:07 volumio sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"