-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Thu 2025-04-24 07:10:56 UTC. -- Apr 24 07:09:14 volumio volumio[814]: info: Apr 24 07:09:14 volumio volumio[814]: ---------------------------- MPD announces state update: player Apr 24 07:09:14 volumio volumio[814]: info: ControllerMpd::getState Apr 24 07:09:14 volumio volumio[814]: verbose: ControllerMpd::sendMpdCommand status Apr 24 07:09:14 volumio volumio[814]: info: sendMpdCommand status took 1 milliseconds Apr 24 07:09:14 volumio volumio[814]: verbose: ControllerMpd::parseState Apr 24 07:09:14 volumio volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 07:09:14 volumio volumio[814]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 24 07:09:14 volumio volumio[814]: verbose: ControllerMpd::parseTrackInfo Apr 24 07:09:14 volumio volumio[814]: info: ControllerMpd::pushState Apr 24 07:09:14 volumio volumio[814]: info: CoreCommandRouter::servicePushState Apr 24 07:09:14 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:09:14 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:09:14 volumio volumio[814]: info: CorePlayQueue::getTrack 0 Apr 24 07:09:14 volumio volumio[814]: 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://fsandroid.kugou.com/202504241508/c0ae8e177125600c64d905ce8bb3a689/v3/9b3aebaadc90321696bf81074cf33fad/yp/full/ap1005_us1174815328_df4wyl5a0oaa8a0tvwwp03rlul_pi2_mx303903215_quviper_clear_s3121040842.flac","trackType":"flac"} Apr 24 07:09:14 volumio volumio[814]: verbose: CURRENT POSITION 0 Apr 24 07:09:14 volumio volumio[814]: info: CoreStateMachine::syncState stateService stop Apr 24 07:09:14 volumio volumio[814]: info: CoreStateMachine::syncState currentStatus play Apr 24 07:09:14 volumio volumio[814]: info: CoreStateMachine::play index undefined Apr 24 07:09:14 volumio volumio[814]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 07:09:14 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:09:14 volumio volumio[814]: info: CorePlayQueue::getTrack 0 Apr 24 07:09:14 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:09:14 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:09:14 volumio volumio[814]: info: CorePlayQueue::getTrack 0 Apr 24 07:09:14 volumio volumio[814]: info: ------------------------------ 19ms Apr 24 07:09:15 volumio volumio[814]: info: Clearing queue after UPNP request Apr 24 07:09:15 volumio volumio[814]: info: CoreStateMachine::ClearQueue Apr 24 07:09:15 volumio volumio[814]: info: CoreStateMachine::stop Apr 24 07:09:15 volumio volumio[814]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 07:09:15 volumio volumio[814]: info: CorePlayQueue::clearPlayQueue Apr 24 07:09:15 volumio volumio[814]: info: CorePlayQueue::saveQueue Apr 24 07:09:15 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:09:15 volumio volumio[814]: info: CoreCommandRouter::volumioPushQueue Apr 24 07:09:15 volumio volumio[814]: info: Apr 24 07:09:15 volumio volumio[814]: ---------------------------- MPD announces system playlist update Apr 24 07:09:15 volumio volumio[814]: info: Ignoring MPD Status Update Apr 24 07:09:15 volumio volumio[814]: error: updateQueue error: null Apr 24 07:09:15 volumio volumio[814]: info: ------------------------------ 1ms Apr 24 07:09:15 volumio volumio[814]: info: Starting UPNP Playback Apr 24 07:09:15 volumio volumio[814]: info: Preparing playback through UPNP Apr 24 07:09:15 volumio volumio[814]: info: CoreCommandRouter::volumioGetState Apr 24 07:09:15 volumio volumio[814]: info: CorePlayQueue::getTrack 0 Apr 24 07:09:15 volumio volumio[814]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 24 07:09:16 volumio volumio[814]: info: Apr 24 07:09:16 volumio volumio[814]: ---------------------------- MPD announces system playlist update Apr 24 07:09:16 volumio volumio[814]: info: Ignoring MPD Status Update Apr 24 07:09:16 volumio volumio[814]: info: ------------------------------ 2ms Apr 24 07:09:16 volumio volumio[814]: info: Apr 24 07:09:16 volumio volumio[814]: ---------------------------- MPD announces system playlist update Apr 24 07:09:16 volumio volumio[814]: info: Ignoring MPD Status Update Apr 24 07:09:16 volumio volumio[814]: info: ------------------------------ 1ms Apr 24 07:09:16 volumio volumio[814]: info: Apr 24 07:09:16 volumio volumio[814]: ---------------------------- MPD announces system playlist update Apr 24 07:09:16 volumio volumio[814]: info: Ignoring MPD Status Update Apr 24 07:09:16 volumio volumio[814]: info: ------------------------------ 1ms Apr 24 07:09:16 volumio volumio[814]: info: Apr 24 07:09:16 volumio volumio[814]: ---------------------------- MPD announces system playlist update Apr 24 07:09:16 volumio volumio[814]: info: Ignoring MPD Status Update Apr 24 07:09:16 volumio volumio[814]: info: ------------------------------ 2ms Apr 24 07:09:16 volumio volumio[814]: info: Apr 24 07:09:16 volumio volumio[814]: ---------------------------- MPD announces system playlist update Apr 24 07:09:16 volumio volumio[814]: info: Ignoring MPD Status Update Apr 24 07:09:16 volumio volumio[814]: info: ------------------------------ 2ms Apr 24 07:09:16 volumio volumio[814]: info: Apr 24 07:09:16 volumio volumio[814]: ---------------------------- MPD announces system playlist update Apr 24 07:09:16 volumio volumio[814]: info: Ignoring MPD Status Update Apr 24 07:09:16 volumio volumio[814]: info: ------------------------------ 2ms Apr 24 07:09:16 volumio volumio[814]: info: Apr 24 07:09:16 volumio volumio[814]: ---------------------------- MPD announces state update: player Apr 24 07:09:16 volumio volumio[814]: info: ControllerMpd::getState Apr 24 07:09:16 volumio volumio[814]: verbose: ControllerMpd::sendMpdCommand status Apr 24 07:09:16 volumio volumio[814]: info: Apr 24 07:09:16 volumio volumio[814]: ---------------------------- MPD announces state update: player Apr 24 07:09:16 volumio volumio[814]: info: sendMpdCommand status took 3 milliseconds Apr 24 07:09:16 volumio volumio[814]: info: ControllerMpd::getState Apr 24 07:09:16 volumio volumio[814]: verbose: ControllerMpd::sendMpdCommand status Apr 24 07:09:16 volumio volumio[814]: verbose: ControllerMpd::parseState Apr 24 07:09:16 volumio volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 07:09:16 volumio volumio[814]: info: sendMpdCommand status took 3 milliseconds Apr 24 07:09:16 volumio volumio[814]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 24 07:09:16 volumio volumio[814]: verbose: ControllerMpd::parseState Apr 24 07:09:16 volumio volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 07:09:16 volumio volumio[814]: verbose: ControllerMpd::parseTrackInfo Apr 24 07:09:16 volumio volumio[814]: info: ControllerMpd::pushState Apr 24 07:09:16 volumio volumio[814]: info: CoreCommandRouter::servicePushState Apr 24 07:09:16 volumio volumio[814]: verbose: In UPNP mode Apr 24 07:09:16 volumio volumio[814]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":201,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈宁 - 往事只能回味","artist":"陈宁","album":null,"uri":"http://fsandroid.kugou.com/202504241509/1ba7bd62bb64edce72c5cc657061f61c/v3/ff8ef5c20d646313f2a28d269454b7fa/yp/full/ap1005_us1174815328_df4wyl5a0oaa8a0tvwwp03rlul_pi2_mx512807428_quviper_clear_s773626859.flac","trackType":"flac"} Apr 24 07:09:16 volumio volumio[814]: verbose: CURRENT POSITION 0 Apr 24 07:09:16 volumio volumio[814]: info: CoreStateMachine::syncState stateService play Apr 24 07:09:16 volumio volumio[814]: info: CoreStateMachine::syncState currentStatus stop Apr 24 07:09:16 volumio volumio[814]: info: ------------------------------ 10ms Apr 24 07:09:16 volumio volumio[814]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 24 07:09:16 volumio volumio[814]: verbose: ControllerMpd::parseTrackInfo Apr 24 07:09:16 volumio volumio[814]: info: ControllerMpd::pushState Apr 24 07:09:16 volumio volumio[814]: info: CoreCommandRouter::servicePushState Apr 24 07:09:16 volumio volumio[814]: verbose: In UPNP mode Apr 24 07:09:16 volumio volumio[814]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":201,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈宁 - 往事只能回味","artist":"陈宁","album":null,"uri":"http://fsandroid.kugou.com/202504241509/1ba7bd62bb64edce72c5cc657061f61c/v3/ff8ef5c20d646313f2a28d269454b7fa/yp/full/ap1005_us1174815328_df4wyl5a0oaa8a0tvwwp03rlul_pi2_mx512807428_quviper_clear_s773626859.flac","trackType":"flac"} Apr 24 07:09:16 volumio volumio[814]: verbose: CURRENT POSITION 0 Apr 24 07:09:16 volumio volumio[814]: info: CoreStateMachine::syncState stateService play Apr 24 07:09:16 volumio volumio[814]: info: CoreStateMachine::syncState currentStatus play Apr 24 07:09:16 volumio volumio[814]: info: Received an update from plugin. extracting info from payload Apr 24 07:09:16 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 24 07:09:16 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:09:16 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:09:16 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:09:16 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:09:16 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:09:16 volumio volumio[814]: info: ------------------------------ 24ms Apr 24 07:09:53 volumio volumio[1010]: 88 Apr 24 07:09:53 volumio volumio[1010]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 24 07:09:53 volumio volumio[1010]: Dload Upload Total Spent Left Speed Apr 24 07:09:53 volumio volumio[814]: info: Apr 24 07:09:53 volumio volumio[814]: ---------------------------- Client requests Volume 88 Apr 24 07:09:53 volumio volumio[814]: info: VolumeController::SetAlsaVolume88 Apr 24 07:09:53 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:09:53 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:09:53 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:09:53 volumio volumio[1010]: [158B blob data] Apr 24 07:09:53 volumio volumio[1010]: {"time":1745478593321,"response":"volume Success"}88 Apr 24 07:09:54 volumio volumio[1010]: 98 Apr 24 07:09:54 volumio volumio[1010]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 24 07:09:54 volumio volumio[1010]: Dload Upload Total Spent Left Speed Apr 24 07:09:54 volumio volumio[814]: info: Apr 24 07:09:54 volumio volumio[814]: ---------------------------- Client requests Volume 98 Apr 24 07:09:54 volumio volumio[814]: info: VolumeController::SetAlsaVolume98 Apr 24 07:09:54 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:09:54 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:09:54 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:09:54 volumio volumio[1010]: [158B blob data] Apr 24 07:09:54 volumio volumio[1010]: {"time":1745478594438,"response":"volume Success"}98 Apr 24 07:09:55 volumio volumio[1010]: 100 Apr 24 07:09:55 volumio volumio[1010]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 24 07:09:55 volumio volumio[1010]: Dload Upload Total Spent Left Speed Apr 24 07:09:55 volumio volumio[814]: info: Apr 24 07:09:55 volumio volumio[814]: ---------------------------- Client requests Volume 100 Apr 24 07:09:55 volumio volumio[814]: info: VolumeController::SetAlsaVolume100 Apr 24 07:09:55 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:09:55 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:09:55 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:09:55 volumio volumio[1010]: [158B blob data] Apr 24 07:09:55 volumio volumio[1010]: {"time":1745478595468,"response":"volume Success"}100 Apr 24 07:09:59 volumio volumio[1010]: 90 Apr 24 07:09:59 volumio volumio[1010]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 24 07:09:59 volumio volumio[1010]: Dload Upload Total Spent Left Speed Apr 24 07:09:59 volumio volumio[814]: info: Apr 24 07:09:59 volumio volumio[814]: ---------------------------- Client requests Volume 90 Apr 24 07:09:59 volumio volumio[814]: info: VolumeController::SetAlsaVolume90 Apr 24 07:09:59 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:09:59 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:09:59 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:09:59 volumio volumio[1010]: [158B blob data] Apr 24 07:09:59 volumio volumio[1010]: {"time":1745478599712,"response":"volume Success"}90 Apr 24 07:10:00 volumio volumio[1010]: 80 Apr 24 07:10:00 volumio volumio[1010]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 24 07:10:00 volumio volumio[1010]: Dload Upload Total Spent Left Speed Apr 24 07:10:00 volumio volumio[814]: info: Apr 24 07:10:00 volumio volumio[814]: ---------------------------- Client requests Volume 80 Apr 24 07:10:00 volumio volumio[814]: info: VolumeController::SetAlsaVolume80 Apr 24 07:10:00 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:10:00 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:10:00 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:10:00 volumio volumio[1010]: [158B blob data] Apr 24 07:10:00 volumio volumio[1010]: {"time":1745478600844,"response":"volume Success"}80 Apr 24 07:10:01 volumio volumio[1010]: 70 Apr 24 07:10:01 volumio volumio[1010]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 24 07:10:01 volumio volumio[1010]: Dload Upload Total Spent Left Speed Apr 24 07:10:01 volumio volumio[814]: info: Apr 24 07:10:01 volumio volumio[814]: ---------------------------- Client requests Volume 70 Apr 24 07:10:01 volumio volumio[814]: info: VolumeController::SetAlsaVolume70 Apr 24 07:10:01 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:10:01 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:10:01 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:10:01 volumio volumio[1010]: [158B blob data] Apr 24 07:10:01 volumio volumio[1010]: {"time":1745478601754,"response":"volume Success"}70 Apr 24 07:10:03 volumio volumio[814]: info: Apr 24 07:10:03 volumio volumio[814]: ---------------------------- MPD announces state update: player Apr 24 07:10:03 volumio volumio[814]: info: ControllerMpd::getState Apr 24 07:10:03 volumio volumio[814]: verbose: ControllerMpd::sendMpdCommand status Apr 24 07:10:03 volumio volumio[814]: info: sendMpdCommand status took 1 milliseconds Apr 24 07:10:03 volumio volumio[814]: verbose: ControllerMpd::parseState Apr 24 07:10:03 volumio volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 07:10:03 volumio volumio[814]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 24 07:10:03 volumio volumio[814]: verbose: ControllerMpd::parseTrackInfo Apr 24 07:10:03 volumio volumio[814]: info: ControllerMpd::pushState Apr 24 07:10:03 volumio volumio[814]: info: CoreCommandRouter::servicePushState Apr 24 07:10:03 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:10:03 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:10:03 volumio volumio[814]: info: CorePlayQueue::getTrack 0 Apr 24 07:10:03 volumio volumio[814]: 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://fsandroid.kugou.com/202504241509/1ba7bd62bb64edce72c5cc657061f61c/v3/ff8ef5c20d646313f2a28d269454b7fa/yp/full/ap1005_us1174815328_df4wyl5a0oaa8a0tvwwp03rlul_pi2_mx512807428_quviper_clear_s773626859.flac","trackType":"flac"} Apr 24 07:10:03 volumio volumio[814]: verbose: CURRENT POSITION 0 Apr 24 07:10:03 volumio volumio[814]: info: CoreStateMachine::syncState stateService stop Apr 24 07:10:03 volumio volumio[814]: info: CoreStateMachine::syncState currentStatus play Apr 24 07:10:03 volumio volumio[814]: info: CoreStateMachine::play index undefined Apr 24 07:10:03 volumio volumio[814]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 07:10:03 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:10:03 volumio volumio[814]: info: CorePlayQueue::getTrack 0 Apr 24 07:10:03 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:10:03 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:10:03 volumio volumio[814]: info: CorePlayQueue::getTrack 0 Apr 24 07:10:03 volumio volumio[814]: info: ------------------------------ 18ms Apr 24 07:10:04 volumio volumio[814]: info: Clearing queue after UPNP request Apr 24 07:10:04 volumio volumio[814]: info: CoreStateMachine::ClearQueue Apr 24 07:10:04 volumio volumio[814]: info: CoreStateMachine::stop Apr 24 07:10:04 volumio volumio[814]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 07:10:04 volumio volumio[814]: info: CorePlayQueue::clearPlayQueue Apr 24 07:10:04 volumio volumio[814]: info: CorePlayQueue::saveQueue Apr 24 07:10:04 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:10:04 volumio volumio[814]: info: CoreCommandRouter::volumioPushQueue Apr 24 07:10:04 volumio volumio[814]: info: Apr 24 07:10:04 volumio volumio[814]: ---------------------------- MPD announces system playlist update Apr 24 07:10:04 volumio volumio[814]: info: Ignoring MPD Status Update Apr 24 07:10:04 volumio volumio[814]: error: updateQueue error: null Apr 24 07:10:04 volumio volumio[814]: info: ------------------------------ 1ms Apr 24 07:10:04 volumio volumio[814]: info: Starting UPNP Playback Apr 24 07:10:04 volumio volumio[814]: info: Preparing playback through UPNP Apr 24 07:10:04 volumio volumio[814]: info: CoreCommandRouter::volumioGetState Apr 24 07:10:04 volumio volumio[814]: info: CorePlayQueue::getTrack 0 Apr 24 07:10:04 volumio volumio[814]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 24 07:10:05 volumio volumio[814]: info: Apr 24 07:10:05 volumio volumio[814]: ---------------------------- MPD announces system playlist update Apr 24 07:10:05 volumio volumio[814]: info: Ignoring MPD Status Update Apr 24 07:10:05 volumio volumio[814]: info: ------------------------------ 1ms Apr 24 07:10:05 volumio volumio[814]: info: Apr 24 07:10:05 volumio volumio[814]: ---------------------------- MPD announces system playlist update Apr 24 07:10:05 volumio volumio[814]: info: Ignoring MPD Status Update Apr 24 07:10:05 volumio volumio[814]: info: Apr 24 07:10:05 volumio volumio[814]: ---------------------------- MPD announces system playlist update Apr 24 07:10:05 volumio volumio[814]: info: Ignoring MPD Status Update Apr 24 07:10:05 volumio volumio[814]: info: ------------------------------ 2ms Apr 24 07:10:05 volumio volumio[814]: info: ------------------------------ 1ms Apr 24 07:10:05 volumio volumio[814]: info: Apr 24 07:10:05 volumio volumio[814]: ---------------------------- MPD announces system playlist update Apr 24 07:10:05 volumio volumio[814]: info: Ignoring MPD Status Update Apr 24 07:10:05 volumio volumio[814]: info: Apr 24 07:10:05 volumio volumio[814]: ---------------------------- MPD announces system playlist update Apr 24 07:10:05 volumio volumio[814]: info: Ignoring MPD Status Update Apr 24 07:10:05 volumio volumio[814]: info: ------------------------------ 2ms Apr 24 07:10:05 volumio volumio[814]: info: ------------------------------ 2ms Apr 24 07:10:05 volumio volumio[814]: info: Apr 24 07:10:05 volumio volumio[814]: ---------------------------- MPD announces system playlist update Apr 24 07:10:05 volumio volumio[814]: info: Ignoring MPD Status Update Apr 24 07:10:05 volumio volumio[814]: info: ------------------------------ 2ms Apr 24 07:10:05 volumio volumio[814]: info: Apr 24 07:10:05 volumio volumio[814]: ---------------------------- MPD announces state update: player Apr 24 07:10:05 volumio volumio[814]: info: ControllerMpd::getState Apr 24 07:10:05 volumio volumio[814]: verbose: ControllerMpd::sendMpdCommand status Apr 24 07:10:05 volumio volumio[814]: info: Apr 24 07:10:05 volumio volumio[814]: ---------------------------- MPD announces state update: player Apr 24 07:10:05 volumio volumio[814]: info: sendMpdCommand status took 4 milliseconds Apr 24 07:10:05 volumio volumio[814]: info: ControllerMpd::getState Apr 24 07:10:05 volumio volumio[814]: verbose: ControllerMpd::sendMpdCommand status Apr 24 07:10:05 volumio volumio[814]: verbose: ControllerMpd::parseState Apr 24 07:10:05 volumio volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 07:10:05 volumio volumio[814]: info: sendMpdCommand status took 2 milliseconds Apr 24 07:10:05 volumio volumio[814]: verbose: ControllerMpd::parseState Apr 24 07:10:05 volumio volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 07:10:05 volumio volumio[814]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 24 07:10:05 volumio volumio[814]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 24 07:10:05 volumio volumio[814]: verbose: ControllerMpd::parseTrackInfo Apr 24 07:10:05 volumio volumio[814]: verbose: ControllerMpd::parseTrackInfo Apr 24 07:10:05 volumio volumio[814]: info: ControllerMpd::pushState Apr 24 07:10:05 volumio volumio[814]: info: CoreCommandRouter::servicePushState Apr 24 07:10:05 volumio volumio[814]: verbose: In UPNP mode Apr 24 07:10:05 volumio volumio[814]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈宁 - 父亲写的散文诗","artist":"陈宁","album":null,"uri":"http://fsandroid.kugou.com/202504241510/1e3fbeee41a9fff35e38733ca40ce339/v3/c2bff9a290dd3c7cd3faa2c89db85724/yp/full/ap1005_us1174815328_df4wyl5a0oaa8a0tvwwp03rlul_pi2_mx211669628_quviper_clear_s2741480562.flac","trackType":"flac"} Apr 24 07:10:05 volumio volumio[814]: verbose: CURRENT POSITION 0 Apr 24 07:10:05 volumio volumio[814]: info: CoreStateMachine::syncState stateService play Apr 24 07:10:05 volumio volumio[814]: info: CoreStateMachine::syncState currentStatus stop Apr 24 07:10:05 volumio volumio[814]: info: ControllerMpd::pushState Apr 24 07:10:05 volumio volumio[814]: info: CoreCommandRouter::servicePushState Apr 24 07:10:05 volumio volumio[814]: verbose: In UPNP mode Apr 24 07:10:05 volumio volumio[814]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈宁 - 父亲写的散文诗","artist":"陈宁","album":null,"uri":"http://fsandroid.kugou.com/202504241510/1e3fbeee41a9fff35e38733ca40ce339/v3/c2bff9a290dd3c7cd3faa2c89db85724/yp/full/ap1005_us1174815328_df4wyl5a0oaa8a0tvwwp03rlul_pi2_mx211669628_quviper_clear_s2741480562.flac","trackType":"flac"} Apr 24 07:10:05 volumio volumio[814]: verbose: CURRENT POSITION 0 Apr 24 07:10:05 volumio volumio[814]: info: CoreStateMachine::syncState stateService play Apr 24 07:10:05 volumio volumio[814]: info: CoreStateMachine::syncState currentStatus play Apr 24 07:10:05 volumio volumio[814]: info: Received an update from plugin. extracting info from payload Apr 24 07:10:05 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 24 07:10:05 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:10:05 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:10:05 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:10:05 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:10:05 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:10:05 volumio volumio[814]: info: ------------------------------ 21ms Apr 24 07:10:05 volumio volumio[814]: info: ------------------------------ 23ms Apr 24 07:10:14 volumio volumio[1010]: 60 Apr 24 07:10:14 volumio volumio[1010]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 24 07:10:14 volumio volumio[1010]: Dload Upload Total Spent Left Speed Apr 24 07:10:14 volumio volumio[814]: info: Apr 24 07:10:14 volumio volumio[814]: ---------------------------- Client requests Volume 60 Apr 24 07:10:14 volumio volumio[814]: info: VolumeController::SetAlsaVolume60 Apr 24 07:10:14 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:10:14 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:10:14 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:10:14 volumio volumio[1010]: [158B blob data] Apr 24 07:10:14 volumio volumio[1010]: {"time":1745478614318,"response":"volume Success"}60 Apr 24 07:10:15 volumio volumio[1010]: 50 Apr 24 07:10:15 volumio volumio[1010]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 24 07:10:15 volumio volumio[1010]: Dload Upload Total Spent Left Speed Apr 24 07:10:15 volumio volumio[814]: info: Apr 24 07:10:15 volumio volumio[814]: ---------------------------- Client requests Volume 50 Apr 24 07:10:15 volumio volumio[814]: info: VolumeController::SetAlsaVolume50 Apr 24 07:10:15 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:10:15 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:10:15 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:10:15 volumio volumio[1010]: [158B blob data] Apr 24 07:10:15 volumio volumio[1010]: {"time":1745478615635,"response":"volume Success"}50 Apr 24 07:10:16 volumio volumio[1010]: 60 Apr 24 07:10:16 volumio volumio[1010]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 24 07:10:16 volumio volumio[1010]: Dload Upload Total Spent Left Speed Apr 24 07:10:16 volumio volumio[814]: info: Apr 24 07:10:16 volumio volumio[814]: ---------------------------- Client requests Volume 60 Apr 24 07:10:16 volumio volumio[814]: info: VolumeController::SetAlsaVolume60 Apr 24 07:10:16 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:10:16 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:10:16 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:10:16 volumio volumio[1010]: [158B blob data] Apr 24 07:10:16 volumio volumio[1010]: {"time":1745478616826,"response":"volume Success"}60 Apr 24 07:10:17 volumio volumio[1010]: 70 Apr 24 07:10:17 volumio volumio[1010]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 24 07:10:17 volumio volumio[1010]: Dload Upload Total Spent Left Speed Apr 24 07:10:17 volumio volumio[814]: info: Apr 24 07:10:17 volumio volumio[814]: ---------------------------- Client requests Volume 70 Apr 24 07:10:17 volumio volumio[814]: info: VolumeController::SetAlsaVolume70 Apr 24 07:10:17 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:10:17 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:10:17 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:10:17 volumio volumio[1010]: [158B blob data] Apr 24 07:10:17 volumio volumio[1010]: {"time":1745478617232,"response":"volume Success"}70 Apr 24 07:10:17 volumio volumio[1010]: 80 Apr 24 07:10:17 volumio volumio[1010]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 24 07:10:17 volumio volumio[1010]: Dload Upload Total Spent Left Speed Apr 24 07:10:17 volumio volumio[814]: info: Apr 24 07:10:17 volumio volumio[814]: ---------------------------- Client requests Volume 80 Apr 24 07:10:17 volumio volumio[814]: info: VolumeController::SetAlsaVolume80 Apr 24 07:10:17 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:10:17 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:10:17 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:10:17 volumio volumio[1010]: [158B blob data] Apr 24 07:10:17 volumio volumio[1010]: {"time":1745478617668,"response":"volume Success"}80 Apr 24 07:10:18 volumio volumio[1010]: 90 Apr 24 07:10:18 volumio volumio[1010]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 24 07:10:18 volumio volumio[1010]: Dload Upload Total Spent Left Speed Apr 24 07:10:18 volumio volumio[814]: info: Apr 24 07:10:18 volumio volumio[814]: ---------------------------- Client requests Volume 90 Apr 24 07:10:18 volumio volumio[814]: info: VolumeController::SetAlsaVolume90 Apr 24 07:10:18 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:10:18 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:10:18 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:10:18 volumio volumio[1010]: [158B blob data] Apr 24 07:10:18 volumio volumio[1010]: {"time":1745478618154,"response":"volume Success"}90 Apr 24 07:10:19 volumio volumio[1010]: 100 Apr 24 07:10:19 volumio volumio[1010]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 24 07:10:19 volumio volumio[1010]: Dload Upload Total Spent Left Speed Apr 24 07:10:19 volumio volumio[814]: info: Apr 24 07:10:19 volumio volumio[814]: ---------------------------- Client requests Volume 100 Apr 24 07:10:19 volumio volumio[814]: info: VolumeController::SetAlsaVolume100 Apr 24 07:10:19 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:10:19 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:10:19 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:10:19 volumio volumio[1010]: [158B blob data] Apr 24 07:10:19 volumio volumio[1010]: {"time":1745478619602,"response":"volume Success"}100 Apr 24 07:10:21 volumio volumio[1010]: 90 Apr 24 07:10:21 volumio volumio[1010]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 24 07:10:21 volumio volumio[1010]: Dload Upload Total Spent Left Speed Apr 24 07:10:21 volumio volumio[814]: info: Apr 24 07:10:21 volumio volumio[814]: ---------------------------- Client requests Volume 90 Apr 24 07:10:21 volumio volumio[814]: info: VolumeController::SetAlsaVolume90 Apr 24 07:10:21 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:10:21 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:10:21 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:10:21 volumio volumio[1010]: [158B blob data] Apr 24 07:10:21 volumio volumio[1010]: {"time":1745478621353,"response":"volume Success"}90 Apr 24 07:10:31 volumio volumio[814]: info: Apr 24 07:10:31 volumio volumio[814]: ---------------------------- MPD announces state update: player Apr 24 07:10:31 volumio volumio[814]: info: ControllerMpd::getState Apr 24 07:10:31 volumio volumio[814]: verbose: ControllerMpd::sendMpdCommand status Apr 24 07:10:31 volumio volumio[814]: info: sendMpdCommand status took 1 milliseconds Apr 24 07:10:31 volumio volumio[814]: verbose: ControllerMpd::parseState Apr 24 07:10:31 volumio volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 07:10:31 volumio volumio[814]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 24 07:10:31 volumio volumio[814]: verbose: ControllerMpd::parseTrackInfo Apr 24 07:10:31 volumio volumio[814]: info: ControllerMpd::pushState Apr 24 07:10:31 volumio volumio[814]: info: CoreCommandRouter::servicePushState Apr 24 07:10:31 volumio volumio[814]: verbose: In UPNP mode Apr 24 07:10:31 volumio volumio[814]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26743,"duration":300,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3951 Kbps","isStreaming":false,"title":"陈宁 - 父亲写的散文诗","artist":"陈宁","album":null,"uri":"http://fsandroid.kugou.com/202504241510/1e3fbeee41a9fff35e38733ca40ce339/v3/c2bff9a290dd3c7cd3faa2c89db85724/yp/full/ap1005_us1174815328_df4wyl5a0oaa8a0tvwwp03rlul_pi2_mx211669628_quviper_clear_s2741480562.flac","trackType":"flac"} Apr 24 07:10:31 volumio volumio[814]: verbose: CURRENT POSITION 0 Apr 24 07:10:31 volumio volumio[814]: info: CoreStateMachine::syncState stateService pause Apr 24 07:10:31 volumio volumio[814]: info: CoreStateMachine::syncState currentStatus play Apr 24 07:10:31 volumio volumio[814]: info: CoreStateMachine::pushState Apr 24 07:10:31 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:10:31 volumio volumio[814]: info: CoreCommandRouter::volumioPushState Apr 24 07:10:31 volumio volumio[814]: info: CoreStateMachine::stPlaybackTimer Apr 24 07:10:31 volumio volumio[814]: info: ------------------------------ 12ms Apr 24 07:10:55 volumio volumio[814]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 07:10:55 volumio volumio[814]: Error: connect ETIMEDOUT 69.171.224.40:80 Apr 24 07:10:55 volumio volumio[814]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 24 07:10:55 volumio volumio[814]: errno: -110, Apr 24 07:10:55 volumio volumio[814]: code: 'ETIMEDOUT', Apr 24 07:10:55 volumio volumio[814]: syscall: 'connect', Apr 24 07:10:55 volumio volumio[814]: address: '69.171.224.40', Apr 24 07:10:55 volumio volumio[814]: port: 80 Apr 24 07:10:55 volumio volumio[814]: } Apr 24 07:10:55 volumio volumio[814]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 07:10:56 volumio sudo[2267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-24 07:09 Apr 24 07:10:56 volumio sudo[2267]: 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="68644ecc3d95554759c26a672c9f9845659ebf49" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="d1eed292e757ec0ddcc4830aa293d402cdfd9c92" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 06 Mar 2023 10:42:18 AM CET" VOLUMIO_VERSION="3.435" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cb521a82390d29f21453532486094e99"