-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Fri 2025-07-18 21:43:56 CST. -- Jul 18 21:43:32 volumio volumio[1272]: 95 Jul 18 21:43:32 volumio volumio[1272]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 18 21:43:32 volumio volumio[1272]: Dload Upload Total Spent Left Speed Jul 18 21:43:32 volumio volumio[991]: info: Jul 18 21:43:32 volumio volumio[991]: ---------------------------- Client requests Volume 95 Jul 18 21:43:32 volumio volumio[991]: info: VolumeController::SetAlsaVolume95 Jul 18 21:43:32 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:32 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 21:43:32 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:32 volumio volumio[1272]: [158B blob data] Jul 18 21:43:32 volumio volumio[991]: info: CoreCommandRouter::volumioGetQueue Jul 18 21:43:32 volumio volumio[1272]: {"time":1752846212965,"response":"volume Success"}95 Jul 18 21:43:32 volumio volumio[991]: info: CoreStateMachine::getQueue Jul 18 21:43:32 volumio volumio[991]: info: CorePlayQueue::getQueue Jul 18 21:43:34 volumio volumio[1272]: 90 Jul 18 21:43:34 volumio volumio[1272]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 18 21:43:34 volumio volumio[1272]: Dload Upload Total Spent Left Speed Jul 18 21:43:34 volumio volumio[991]: info: Jul 18 21:43:34 volumio volumio[991]: ---------------------------- Client requests Volume 90 Jul 18 21:43:34 volumio volumio[991]: info: VolumeController::SetAlsaVolume90 Jul 18 21:43:34 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:34 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 21:43:34 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:34 volumio volumio[1272]: [158B blob data] Jul 18 21:43:34 volumio volumio[1272]: {"time":1752846214370,"response":"volume Success"}90 Jul 18 21:43:34 volumio volumio[991]: info: CoreCommandRouter::volumioGetQueue Jul 18 21:43:34 volumio volumio[991]: info: CoreStateMachine::getQueue Jul 18 21:43:34 volumio volumio[991]: info: CorePlayQueue::getQueue Jul 18 21:43:35 volumio volumio[1272]: 85 Jul 18 21:43:35 volumio volumio[1272]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 18 21:43:35 volumio volumio[1272]: Dload Upload Total Spent Left Speed Jul 18 21:43:35 volumio volumio[991]: info: Jul 18 21:43:35 volumio volumio[991]: ---------------------------- Client requests Volume 85 Jul 18 21:43:35 volumio volumio[991]: info: VolumeController::SetAlsaVolume85 Jul 18 21:43:35 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:35 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 21:43:35 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:35 volumio volumio[1272]: [158B blob data] Jul 18 21:43:35 volumio volumio[991]: info: CoreCommandRouter::volumioGetQueue Jul 18 21:43:35 volumio volumio[991]: info: CoreStateMachine::getQueue Jul 18 21:43:35 volumio volumio[991]: info: CorePlayQueue::getQueue Jul 18 21:43:35 volumio volumio[1272]: {"time":1752846215114,"response":"volume Success"}85 Jul 18 21:43:37 volumio volumio[1272]: 80 Jul 18 21:43:37 volumio volumio[1272]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 18 21:43:37 volumio volumio[1272]: Dload Upload Total Spent Left Speed Jul 18 21:43:37 volumio volumio[991]: info: Jul 18 21:43:37 volumio volumio[991]: ---------------------------- Client requests Volume 80 Jul 18 21:43:37 volumio volumio[991]: info: VolumeController::SetAlsaVolume80 Jul 18 21:43:37 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:37 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 21:43:37 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:37 volumio volumio[1272]: [158B blob data] Jul 18 21:43:37 volumio volumio[1272]: {"time":1752846217173,"response":"volume Success"}80 Jul 18 21:43:37 volumio volumio[991]: info: CoreCommandRouter::volumioGetQueue Jul 18 21:43:37 volumio volumio[991]: info: CoreStateMachine::getQueue Jul 18 21:43:37 volumio volumio[991]: info: CorePlayQueue::getQueue Jul 18 21:43:40 volumio volumio[991]: info: Jul 18 21:43:40 volumio volumio[991]: ---------------------------- MPD announces state update: player Jul 18 21:43:40 volumio volumio[991]: info: ControllerMpd::getState Jul 18 21:43:40 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Jul 18 21:43:40 volumio volumio[991]: info: Jul 18 21:43:40 volumio volumio[991]: ---------------------------- MPD announces state update: player Jul 18 21:43:40 volumio volumio[991]: info: ControllerMpd::getState Jul 18 21:43:40 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Jul 18 21:43:40 volumio volumio[991]: info: Jul 18 21:43:40 volumio volumio[991]: ---------------------------- MPD announces state update: player Jul 18 21:43:40 volumio volumio[991]: info: ControllerMpd::getState Jul 18 21:43:40 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Jul 18 21:43:40 volumio volumio[991]: info: sendMpdCommand status took 2 milliseconds Jul 18 21:43:40 volumio volumio[991]: info: sendMpdCommand status took 2 milliseconds Jul 18 21:43:40 volumio volumio[991]: info: sendMpdCommand status took 1 milliseconds Jul 18 21:43:40 volumio volumio[991]: verbose: ControllerMpd::parseState Jul 18 21:43:40 volumio volumio[991]: verbose: ControllerMpd::parseState Jul 18 21:43:40 volumio volumio[991]: verbose: ControllerMpd::parseState Jul 18 21:43:40 volumio volumio[991]: info: ControllerMpd::pushState Jul 18 21:43:40 volumio volumio[991]: info: CoreCommandRouter::servicePushState Jul 18 21:43:40 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:40 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:40 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Jul 18 21:43:40 volumio volumio[991]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 21:43:40 volumio volumio[991]: verbose: CURRENT POSITION 0 Jul 18 21:43:40 volumio volumio[991]: info: CoreStateMachine::syncState stateService stop Jul 18 21:43:40 volumio volumio[991]: info: CoreStateMachine::syncState currentStatus play Jul 18 21:43:40 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:40 volumio volumio[991]: info: CoreStateMachine::stPlaybackTimer Jul 18 21:43:40 volumio volumio[991]: info: ControllerMpd::pushState Jul 18 21:43:40 volumio volumio[991]: info: CoreCommandRouter::servicePushState Jul 18 21:43:40 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:40 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 21:43:40 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:40 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Jul 18 21:43:40 volumio volumio[991]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 21:43:40 volumio volumio[991]: verbose: CURRENT POSITION 0 Jul 18 21:43:40 volumio volumio[991]: info: CoreStateMachine::syncState stateService stop Jul 18 21:43:40 volumio volumio[991]: info: CoreStateMachine::syncState currentStatus stop Jul 18 21:43:40 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:40 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:40 volumio volumio[991]: info: No code Jul 18 21:43:40 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:40 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:40 volumio volumio[991]: info: ControllerMpd::pushState Jul 18 21:43:40 volumio volumio[991]: info: CoreCommandRouter::servicePushState Jul 18 21:43:40 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:40 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:40 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Jul 18 21:43:40 volumio volumio[991]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 21:43:40 volumio volumio[991]: verbose: CURRENT POSITION 0 Jul 18 21:43:40 volumio volumio[991]: info: CoreStateMachine::syncState stateService stop Jul 18 21:43:40 volumio volumio[991]: info: CoreStateMachine::syncState currentStatus stop Jul 18 21:43:40 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:40 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:40 volumio volumio[991]: info: No code Jul 18 21:43:40 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:40 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:40 volumio volumio[991]: info: ------------------------------ 31ms Jul 18 21:43:40 volumio volumio[991]: info: ------------------------------ 30ms Jul 18 21:43:40 volumio volumio[991]: info: ------------------------------ 30ms Jul 18 21:43:40 volumio volumio[991]: info: CoreCommandRouter::volumioGetQueue Jul 18 21:43:40 volumio volumio[991]: info: CoreStateMachine::getQueue Jul 18 21:43:40 volumio volumio[991]: info: CorePlayQueue::getQueue Jul 18 21:43:43 volumio volumio[991]: info: Clearing queue after UPNP request Jul 18 21:43:43 volumio volumio[991]: info: CoreStateMachine::ClearQueue Jul 18 21:43:43 volumio volumio[991]: info: CoreStateMachine::stop Jul 18 21:43:43 volumio volumio[991]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 21:43:43 volumio volumio[991]: info: CorePlayQueue::clearPlayQueue Jul 18 21:43:43 volumio volumio[991]: info: CorePlayQueue::saveQueue Jul 18 21:43:43 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:43 volumio volumio[991]: info: CoreCommandRouter::volumioPushQueue Jul 18 21:43:43 volumio volumio[991]: info: Jul 18 21:43:43 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:43 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:43 volumio volumio[991]: info: Jul 18 21:43:43 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:43 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:43 volumio volumio[991]: info: Jul 18 21:43:43 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:43 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:43 volumio volumio[991]: error: updateQueue error: null Jul 18 21:43:43 volumio volumio[991]: error: updateQueue error: null Jul 18 21:43:43 volumio volumio[991]: error: updateQueue error: null Jul 18 21:43:43 volumio volumio[991]: info: ------------------------------ 3ms Jul 18 21:43:43 volumio volumio[991]: info: ------------------------------ 2ms Jul 18 21:43:43 volumio volumio[991]: info: ------------------------------ 1ms Jul 18 21:43:43 volumio volumio[991]: info: Starting UPNP Playback Jul 18 21:43:43 volumio volumio[991]: info: Preparing playback through UPNP Jul 18 21:43:43 volumio volumio[991]: info: CoreCommandRouter::volumioGetState Jul 18 21:43:43 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Jul 18 21:43:43 volumio volumio[991]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 21:43:43 volumio volumio[991]: info: Jul 18 21:43:43 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:43 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:43 volumio volumio[991]: info: Jul 18 21:43:43 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:43 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:43 volumio volumio[991]: info: Jul 18 21:43:43 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:43 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:43 volumio volumio[991]: info: ------------------------------ 2ms Jul 18 21:43:43 volumio volumio[991]: info: ------------------------------ 2ms Jul 18 21:43:43 volumio volumio[991]: info: ------------------------------ 2ms Jul 18 21:43:43 volumio volumio[991]: info: Jul 18 21:43:43 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:43 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:43 volumio volumio[991]: info: Jul 18 21:43:43 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:43 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:43 volumio volumio[991]: info: Jul 18 21:43:43 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:43 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:43 volumio volumio[991]: info: Jul 18 21:43:43 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:43 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:43 volumio volumio[991]: info: ------------------------------ 3ms Jul 18 21:43:43 volumio volumio[991]: info: ------------------------------ 2ms Jul 18 21:43:43 volumio volumio[991]: info: ------------------------------ 1ms Jul 18 21:43:43 volumio volumio[991]: info: Jul 18 21:43:43 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:43 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:43 volumio volumio[991]: info: Jul 18 21:43:43 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:43 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:43 volumio volumio[991]: info: Jul 18 21:43:43 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:43 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:43 volumio volumio[991]: info: ------------------------------ 2ms Jul 18 21:43:43 volumio volumio[991]: info: ------------------------------ 2ms Jul 18 21:43:43 volumio volumio[991]: info: Jul 18 21:43:43 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:43 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:43 volumio volumio[991]: info: Jul 18 21:43:43 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:43 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:43 volumio volumio[991]: info: ------------------------------ 3ms Jul 18 21:43:43 volumio volumio[991]: info: ------------------------------ 2ms Jul 18 21:43:43 volumio volumio[991]: info: ------------------------------ 1ms Jul 18 21:43:44 volumio volumio[991]: info: Jul 18 21:43:44 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:44 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:44 volumio volumio[991]: info: Jul 18 21:43:44 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:44 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:44 volumio volumio[991]: info: Jul 18 21:43:44 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:44 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:44 volumio volumio[991]: info: Jul 18 21:43:44 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:44 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:44 volumio volumio[991]: info: ------------------------------ 3ms Jul 18 21:43:44 volumio volumio[991]: info: ------------------------------ 2ms Jul 18 21:43:44 volumio volumio[991]: info: Jul 18 21:43:44 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:44 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:44 volumio volumio[991]: info: Jul 18 21:43:44 volumio volumio[991]: ---------------------------- MPD announces system playlist update Jul 18 21:43:44 volumio volumio[991]: info: Ignoring MPD Status Update Jul 18 21:43:44 volumio volumio[991]: info: ------------------------------ 3ms Jul 18 21:43:44 volumio volumio[991]: info: ------------------------------ 2ms Jul 18 21:43:44 volumio volumio[991]: info: ------------------------------ 1ms Jul 18 21:43:44 volumio volumio[991]: info: Jul 18 21:43:44 volumio volumio[991]: ---------------------------- MPD announces state update: player Jul 18 21:43:44 volumio volumio[991]: info: ControllerMpd::getState Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Jul 18 21:43:44 volumio volumio[991]: info: Jul 18 21:43:44 volumio volumio[991]: ---------------------------- MPD announces state update: player Jul 18 21:43:44 volumio volumio[991]: info: ControllerMpd::getState Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Jul 18 21:43:44 volumio volumio[991]: info: Jul 18 21:43:44 volumio volumio[991]: ---------------------------- MPD announces state update: player Jul 18 21:43:44 volumio volumio[991]: info: ControllerMpd::getState Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Jul 18 21:43:44 volumio volumio[991]: info: sendMpdCommand status took 3 milliseconds Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::parseState Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 21:43:44 volumio volumio[991]: info: Jul 18 21:43:44 volumio volumio[991]: ---------------------------- MPD announces state update: player Jul 18 21:43:44 volumio volumio[991]: info: ControllerMpd::getState Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Jul 18 21:43:44 volumio volumio[991]: info: sendMpdCommand status took 4 milliseconds Jul 18 21:43:44 volumio volumio[991]: info: sendMpdCommand status took 3 milliseconds Jul 18 21:43:44 volumio volumio[991]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::parseState Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::parseState Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::parseTrackInfo Jul 18 21:43:44 volumio volumio[991]: info: ControllerMpd::pushState Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::servicePushState Jul 18 21:43:44 volumio volumio[991]: verbose: In UPNP mode Jul 18 21:43:44 volumio volumio[991]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ラピスのお人形","artist":"ヰ世界情緒","album":"ラピスのお人形","uri":"http://m801.music.126.net/20250718220843/772d2a683490cfe108116eb72f8a7b6b/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/30417329634/3355/30bd/d3ce/4e656455bf8a184c138e0e03fa85f4fb.mp3?vuutv=2SP6hYlZ6uHYvJz5HR/f44A+uoHFXPhXYBRGPUZwzUzKw1/Zb8TrEsVLDdXaO0Q/oTyfa65z7xqul1SJ4qJN1S3B8HUe+BIrW9edeGDPkr0=","trackType":""} Jul 18 21:43:44 volumio volumio[991]: verbose: CURRENT POSITION 0 Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::syncState stateService play Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::syncState currentStatus stop Jul 18 21:43:44 volumio volumio[991]: info: ------------------------------ 9ms Jul 18 21:43:44 volumio volumio[991]: info: Jul 18 21:43:44 volumio volumio[991]: ---------------------------- MPD announces state update: player Jul 18 21:43:44 volumio volumio[991]: info: ControllerMpd::getState Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Jul 18 21:43:44 volumio volumio[991]: info: Jul 18 21:43:44 volumio volumio[991]: ---------------------------- MPD announces state update: player Jul 18 21:43:44 volumio volumio[991]: info: ControllerMpd::getState Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Jul 18 21:43:44 volumio volumio[991]: info: sendMpdCommand status took 6 milliseconds Jul 18 21:43:44 volumio volumio[991]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 18 21:43:44 volumio volumio[991]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 18 21:43:44 volumio volumio[991]: info: sendMpdCommand status took 4 milliseconds Jul 18 21:43:44 volumio volumio[991]: info: sendMpdCommand status took 3 milliseconds Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::parseState Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::parseTrackInfo Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::parseTrackInfo Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::parseState Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::parseState Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 21:43:44 volumio volumio[991]: info: ControllerMpd::pushState Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::servicePushState Jul 18 21:43:44 volumio volumio[991]: verbose: In UPNP mode Jul 18 21:43:44 volumio volumio[991]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ラピスのお人形","artist":"ヰ世界情緒","album":"ラピスのお人形","uri":"http://m801.music.126.net/20250718220843/772d2a683490cfe108116eb72f8a7b6b/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/30417329634/3355/30bd/d3ce/4e656455bf8a184c138e0e03fa85f4fb.mp3?vuutv=2SP6hYlZ6uHYvJz5HR/f44A+uoHFXPhXYBRGPUZwzUzKw1/Zb8TrEsVLDdXaO0Q/oTyfa65z7xqul1SJ4qJN1S3B8HUe+BIrW9edeGDPkr0=","trackType":""} Jul 18 21:43:44 volumio volumio[991]: verbose: CURRENT POSITION 0 Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::syncState stateService play Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::syncState currentStatus play Jul 18 21:43:44 volumio volumio[991]: info: Received an update from plugin. extracting info from payload Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:44 volumio volumio[991]: info: ControllerMpd::pushState Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::servicePushState Jul 18 21:43:44 volumio volumio[991]: verbose: In UPNP mode Jul 18 21:43:44 volumio volumio[991]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ラピスのお人形","artist":"ヰ世界情緒","album":"ラピスのお人形","uri":"http://m801.music.126.net/20250718220843/772d2a683490cfe108116eb72f8a7b6b/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/30417329634/3355/30bd/d3ce/4e656455bf8a184c138e0e03fa85f4fb.mp3?vuutv=2SP6hYlZ6uHYvJz5HR/f44A+uoHFXPhXYBRGPUZwzUzKw1/Zb8TrEsVLDdXaO0Q/oTyfa65z7xqul1SJ4qJN1S3B8HUe+BIrW9edeGDPkr0=","trackType":""} Jul 18 21:43:44 volumio volumio[991]: verbose: CURRENT POSITION 0 Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::syncState stateService play Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::syncState currentStatus play Jul 18 21:43:44 volumio volumio[991]: info: Received an update from plugin. extracting info from payload Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:44 volumio volumio[991]: info: ------------------------------ 33ms Jul 18 21:43:44 volumio volumio[991]: info: ------------------------------ 33ms Jul 18 21:43:44 volumio volumio[991]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 18 21:43:44 volumio volumio[991]: info: sendMpdCommand playlistinfo took 21 milliseconds Jul 18 21:43:44 volumio volumio[991]: info: sendMpdCommand playlistinfo took 21 milliseconds Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::parseTrackInfo Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::parseTrackInfo Jul 18 21:43:44 volumio volumio[991]: verbose: ControllerMpd::parseTrackInfo Jul 18 21:43:44 volumio volumio[991]: info: ControllerMpd::pushState Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::servicePushState Jul 18 21:43:44 volumio volumio[991]: verbose: In UPNP mode Jul 18 21:43:44 volumio volumio[991]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ラピスのお人形","artist":"ヰ世界情緒","album":"ラピスのお人形","uri":"http://m801.music.126.net/20250718220843/772d2a683490cfe108116eb72f8a7b6b/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/30417329634/3355/30bd/d3ce/4e656455bf8a184c138e0e03fa85f4fb.mp3?vuutv=2SP6hYlZ6uHYvJz5HR/f44A+uoHFXPhXYBRGPUZwzUzKw1/Zb8TrEsVLDdXaO0Q/oTyfa65z7xqul1SJ4qJN1S3B8HUe+BIrW9edeGDPkr0=","trackType":""} Jul 18 21:43:44 volumio volumio[991]: verbose: CURRENT POSITION 0 Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::syncState stateService play Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::syncState currentStatus play Jul 18 21:43:44 volumio volumio[991]: info: Received an update from plugin. extracting info from payload Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:44 volumio volumio[991]: info: ControllerMpd::pushState Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::servicePushState Jul 18 21:43:44 volumio volumio[991]: verbose: In UPNP mode Jul 18 21:43:44 volumio volumio[991]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ラピスのお人形","artist":"ヰ世界情緒","album":"ラピスのお人形","uri":"http://m801.music.126.net/20250718220843/772d2a683490cfe108116eb72f8a7b6b/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/30417329634/3355/30bd/d3ce/4e656455bf8a184c138e0e03fa85f4fb.mp3?vuutv=2SP6hYlZ6uHYvJz5HR/f44A+uoHFXPhXYBRGPUZwzUzKw1/Zb8TrEsVLDdXaO0Q/oTyfa65z7xqul1SJ4qJN1S3B8HUe+BIrW9edeGDPkr0=","trackType":""} Jul 18 21:43:44 volumio volumio[991]: verbose: CURRENT POSITION 0 Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::syncState stateService play Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::syncState currentStatus play Jul 18 21:43:44 volumio volumio[991]: info: Received an update from plugin. extracting info from payload Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:44 volumio volumio[991]: info: ControllerMpd::pushState Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::servicePushState Jul 18 21:43:44 volumio volumio[991]: verbose: In UPNP mode Jul 18 21:43:44 volumio volumio[991]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ラピスのお人形","artist":"ヰ世界情緒","album":"ラピスのお人形","uri":"http://m801.music.126.net/20250718220843/772d2a683490cfe108116eb72f8a7b6b/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/30417329634/3355/30bd/d3ce/4e656455bf8a184c138e0e03fa85f4fb.mp3?vuutv=2SP6hYlZ6uHYvJz5HR/f44A+uoHFXPhXYBRGPUZwzUzKw1/Zb8TrEsVLDdXaO0Q/oTyfa65z7xqul1SJ4qJN1S3B8HUe+BIrW9edeGDPkr0=","trackType":""} Jul 18 21:43:44 volumio volumio[991]: verbose: CURRENT POSITION 0 Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::syncState stateService play Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::syncState currentStatus play Jul 18 21:43:44 volumio volumio[991]: info: Received an update from plugin. extracting info from payload Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::pushState Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Jul 18 21:43:44 volumio volumio[991]: info: ------------------------------ 60ms Jul 18 21:43:44 volumio volumio[991]: info: ------------------------------ 55ms Jul 18 21:43:44 volumio volumio[991]: info: ------------------------------ 55ms Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioGetQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CorePlayQueue::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioGetQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CorePlayQueue::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioGetQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CorePlayQueue::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioGetQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CorePlayQueue::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioGetQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CorePlayQueue::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioGetQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CorePlayQueue::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioGetQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CorePlayQueue::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioGetQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CorePlayQueue::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioGetQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CorePlayQueue::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreCommandRouter::volumioGetQueue Jul 18 21:43:44 volumio volumio[991]: info: CoreStateMachine::getQueue Jul 18 21:43:44 volumio volumio[991]: info: CorePlayQueue::getQueue Jul 18 21:43:55 volumio volumio[991]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 21:43:55 volumio volumio[991]: Error: connect ETIMEDOUT 128.121.243.77:443 Jul 18 21:43:55 volumio volumio[991]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 18 21:43:55 volumio volumio[991]: errno: -110, Jul 18 21:43:55 volumio volumio[991]: code: 'ETIMEDOUT', Jul 18 21:43:55 volumio volumio[991]: syscall: 'connect', Jul 18 21:43:55 volumio volumio[991]: address: '128.121.243.77', Jul 18 21:43:55 volumio volumio[991]: port: 443 Jul 18 21:43:55 volumio volumio[991]: } Jul 18 21:43:55 volumio volumio[991]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 21:43:56 volumio sudo[9114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-18 21:42 Jul 18 21:43:56 volumio sudo[9114]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"