-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Thu 2025-09-11 17:17:11 JST. -- Sep 11 17:16:03 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 11 17:16:03 zeus-volumio2 volumio[954]: info: Preload queue cleared Sep 11 17:16:09 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 11 17:16:09 zeus-volumio2 volumio[954]: info: Preload queue cleared Sep 11 17:16:16 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 11 17:16:16 zeus-volumio2 volumio[954]: info: Preload queue cleared Sep 11 17:16:18 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 11 17:16:18 zeus-volumio2 volumio[954]: info: Preload queue cleared Sep 11 17:16:18 zeus-volumio2 volumio[954]: info: Preloading song: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$35070$35071$35073R6753281 Sep 11 17:16:18 zeus-volumio2 volumio[954]: info: Preloading song: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$35070$35071$35073R6753537 Sep 11 17:16:18 zeus-volumio2 volumio[954]: info: Exploding uri upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$35070$35071$35073R6753281 in service upnp_browser Sep 11 17:16:18 zeus-volumio2 volumio[954]: info: Exploding uri upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$35070$35071$35073R6753537 in service upnp_browser Sep 11 17:16:18 zeus-volumio2 volumio[954]: info: No valid Plugin REST Endpoint Sep 11 17:16:18 zeus-volumio2 volumio[954]: info: No valid Plugin REST Endpoint Sep 11 17:16:19 zeus-volumio2 thd[676]: {"time":1757578541664,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:19 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:19 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:19 zeus-volumio2 volumio[954]: info: Sep 11 17:16:19 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:19 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:19 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:19 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:19 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:19 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:19 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:19 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:19 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:20 zeus-volumio2 thd[676]: {"time":1757578579880,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:20 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:20 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: Sep 11 17:16:20 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:20 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:20 zeus-volumio2 thd[676]: {"time":1757578580112,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:20 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:20 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: Sep 11 17:16:20 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:20 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:20 zeus-volumio2 thd[676]: {"time":1757578580443,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:20 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:20 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: Sep 11 17:16:20 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:20 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:20 zeus-volumio2 thd[676]: {"time":1757578580527,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:20 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:20 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: Sep 11 17:16:20 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:20 zeus-volumio2 thd[676]: [140B blob data] Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:20 zeus-volumio2 thd[676]: [79B blob data] Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:20 zeus-volumio2 thd[676]: {"time":1757578580819,"response":"volume Success"} % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:20 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: Sep 11 17:16:20 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:20 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:20 zeus-volumio2 thd[676]: {"time":1757578580866,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:20 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:20 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: Sep 11 17:16:20 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:20 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:20 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:21 zeus-volumio2 thd[676]: {"time":1757578580924,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:21 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:21 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: Sep 11 17:16:21 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:21 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:21 zeus-volumio2 thd[676]: {"time":1757578581194,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:21 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:21 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: Sep 11 17:16:21 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:21 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:21 zeus-volumio2 thd[676]: {"time":1757578581256,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:21 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:21 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: Sep 11 17:16:21 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:21 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:21 zeus-volumio2 thd[676]: {"time":1757578581558,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:21 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:21 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: Sep 11 17:16:21 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:21 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:21 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Preload queue cleared Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::ClearQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::stop Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::stPlaybackTimer Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::updateTrackBlock Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrackBlock Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 2 Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::serviceStop Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 2 Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::serviceStop Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: [1757578582006] ControllerUPNPBrowser::stop Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::clearPlayQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::saveQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::addQueueItems Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::addQueueItems Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Preload queue cleared Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Adding Item to queue: upnp/folder/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$35070$35071$35073 Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Exploding uri upnp/folder/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$35070$35071$35073 in service upnp_browser Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Sep 11 17:16:22 zeus-volumio2 volumio[954]: ---------------------------- MPD announces state update: player Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: sendMpdCommand stop took 11 milliseconds Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: ControllerMpd::getState Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: sendMpdCommand status took 1 milliseconds Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::parseState Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::parseTrackInfo Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: ControllerMpd::pushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::servicePushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 2 Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 2 Sep 11 17:16:22 zeus-volumio2 volumio[954]: 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":"A Small Flower Song","artist":"Lapis Lazuli","album":"Celtic Letters","uri":"http://192.168.1.116:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5984001.flac","trackType":"flac"} Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: CURRENT POSITION 2 Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::syncState stateService stop Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::syncState currentStatus stop Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 2 Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: No code Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 2 Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: ------------------------------ 8ms Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::saveQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::updateTrackBlock Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrackBlock Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPlay Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::play index 0 Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::stop Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::play index undefined Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 0 Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::startPlaybackTimer Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 0 Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: [1757578582075] ControllerUPNPBrowser::clearAddPlayTrack Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: sendMpdCommand stop took 1 milliseconds Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand clear Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Sep 11 17:16:22 zeus-volumio2 volumio[954]: ---------------------------- MPD announces system playlist update Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Ignoring MPD Status Update Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: sendMpdCommand clear took 1 milliseconds Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.116:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I6753281.flac" Sep 11 17:16:22 zeus-volumio2 volumio[954]: error: updateQueue error: null Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: ------------------------------ 139ms Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.116:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I6753281.flac" Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Sep 11 17:16:22 zeus-volumio2 volumio[954]: ---------------------------- MPD announces system playlist update Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Ignoring MPD Status Update Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: sendMpdCommand add "http://192.168.1.116:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I6753281.flac" took 1 milliseconds Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand play Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: ------------------------------ 1ms Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: sendMpdCommand play took 1 milliseconds Sep 11 17:16:22 zeus-volumio2 thd[676]: {"time":1757578581626,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Sep 11 17:16:22 zeus-volumio2 volumio[954]: ---------------------------- MPD announces state update: player Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: ControllerMpd::getState Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:16:22 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:22 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Sep 11 17:16:22 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:22 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Sep 11 17:16:22 zeus-volumio2 volumio[954]: ---------------------------- MPD announces state update: player Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: sendMpdCommand status took 34 milliseconds Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: ControllerMpd::getState Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::parseState Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: sendMpdCommand status took 3 milliseconds Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::parseState Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::parseTrackInfo Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: ControllerMpd::pushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::servicePushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 0 Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1440,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I6753281.flac","artist":null,"album":null,"uri":"http://192.168.1.116:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I6753281.flac","trackType":"flac"} Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: CURRENT POSITION 0 Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::syncState stateService play Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::syncState currentStatus stop Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: ------------------------------ 41ms Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::parseTrackInfo Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: ControllerMpd::pushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::servicePushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 0 Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1440,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I6753281.flac","artist":null,"album":null,"uri":"http://192.168.1.116:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I6753281.flac","trackType":"flac"} Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: CURRENT POSITION 0 Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::syncState stateService play Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::syncState currentStatus play Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Received an update from plugin. extracting info from payload Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: ------------------------------ 19ms Sep 11 17:16:22 zeus-volumio2 thd[676]: {"time":1757578582269,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:22 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:22 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Sep 11 17:16:22 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:22 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Sep 11 17:16:22 zeus-volumio2 volumio[954]: ---------------------------- MPD announces system playlist update Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Ignoring MPD Status Update Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Sep 11 17:16:22 zeus-volumio2 volumio[954]: ---------------------------- MPD announces state update: player Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: ControllerMpd::getState Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: ------------------------------ 2ms Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: sendMpdCommand status took 5 milliseconds Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::parseState Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: ControllerMpd::parseTrackInfo Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: ControllerMpd::pushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::servicePushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 0 Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: STATE SERVICE {"status":"play","position":0,"seek":328,"duration":1440,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6319 Kbps","isStreaming":false,"title":"文化祭＃1","artist":"山崎恵理子","album":"文化祭","uri":"http://192.168.1.116:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I6753281.flac","trackType":"flac"} Sep 11 17:16:22 zeus-volumio2 volumio[954]: verbose: CURRENT POSITION 0 Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::syncState stateService play Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::syncState currentStatus play Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Received an update from plugin. extracting info from payload Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: ------------------------------ 14ms Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:22 zeus-volumio2 thd[676]: {"time":1757578582349,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:22 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:22 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: Sep 11 17:16:22 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:22 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:22 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:23 zeus-volumio2 thd[676]: {"time":1757578582895,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:23 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:23 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:23 zeus-volumio2 volumio[954]: info: Sep 11 17:16:23 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:23 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:23 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:23 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:23 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:23 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:23 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:23 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:23 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:23 zeus-volumio2 thd[676]: {"time":1757578583077,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:23 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:23 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:23 zeus-volumio2 volumio[954]: info: Sep 11 17:16:23 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:23 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:23 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:23 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:23 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:23 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:23 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:23 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:23 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:23 zeus-volumio2 thd[676]: {"time":1757578583899,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:24 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:24 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:24 zeus-volumio2 volumio[954]: info: Sep 11 17:16:24 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:24 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:24 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:24 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:24 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:24 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:24 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:24 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:24 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:28 zeus-volumio2 thd[676]: {"time":1757578584022,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:28 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:28 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:28 zeus-volumio2 volumio[954]: info: Sep 11 17:16:28 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:28 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:28 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:28 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:28 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:28 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:28 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:28 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:28 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:29 zeus-volumio2 thd[676]: {"time":1757578588807,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:29 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:29 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: Sep 11 17:16:29 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:29 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: Discovery: Getting this device information Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetState Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 17:16:29 zeus-volumio2 thd[676]: {"time":1757578589077,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:29 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:29 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: Sep 11 17:16:29 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:29 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:29 zeus-volumio2 thd[676]: {"time":1757578589645,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:29 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:29 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: Sep 11 17:16:29 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:29 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:29 zeus-volumio2 thd[676]: {"time":1757578589837,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:29 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:29 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: Sep 11 17:16:29 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:29 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:29 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:29 zeus-volumio2 thd[676]: {"time":1757578589932,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:30 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:30 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:30 zeus-volumio2 volumio[954]: info: Sep 11 17:16:30 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:30 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:30 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:30 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:30 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:30 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:30 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:30 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:30 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:30 zeus-volumio2 thd[676]: {"time":1757578590012,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:30 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:30 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:30 zeus-volumio2 volumio[954]: info: Sep 11 17:16:30 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:30 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:30 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:30 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:30 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:30 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:30 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:30 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:30 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:35 zeus-volumio2 thd[676]: {"time":1757578590251,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:35 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:35 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: Sep 11 17:16:35 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:35 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:35 zeus-volumio2 thd[676]: {"time":1757578595089,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:35 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:35 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: Sep 11 17:16:35 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:35 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:35 zeus-volumio2 thd[676]: {"time":1757578595219,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:35 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:35 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: Sep 11 17:16:35 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:35 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:35 zeus-volumio2 thd[676]: {"time":1757578595627,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:35 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:35 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: Sep 11 17:16:35 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:35 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:36 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:36 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:36 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:36 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:36 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:36 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:36 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:37 zeus-volumio2 thd[676]: {"time":1757578595998,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:37 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:37 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: Sep 11 17:16:37 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:37 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:37 zeus-volumio2 thd[676]: {"time":1757578597190,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:37 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:37 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: Sep 11 17:16:37 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:37 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:37 zeus-volumio2 thd[676]: {"time":1757578597365,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:37 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:37 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: Sep 11 17:16:37 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:37 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:37 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:44 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 11 17:16:44 zeus-volumio2 volumio[954]: info: Preload queue cleared Sep 11 17:16:47 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 11 17:16:47 zeus-volumio2 volumio[954]: info: Preload queue cleared Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Preload queue cleared Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Preloading song: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6682113 Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Preloading song: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6682369 Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Preloading song: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6682625 Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Preloading song: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6682881 Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Preloading song: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6683137 Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Preloading song: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6683393 Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Preloading song: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6683649 Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Preloading song: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6683905 Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Preloading song: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6684161 Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Preloading song: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6684417 Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Exploding uri upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6682113 in service upnp_browser Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Exploding uri upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6682369 in service upnp_browser Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Exploding uri upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6682625 in service upnp_browser Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Exploding uri upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6682881 in service upnp_browser Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Exploding uri upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6683137 in service upnp_browser Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Exploding uri upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6683393 in service upnp_browser Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: No valid Plugin REST Endpoint Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: No valid Plugin REST Endpoint Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Exploding uri upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6683649 in service upnp_browser Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Exploding uri upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6683905 in service upnp_browser Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Exploding uri upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6684161 in service upnp_browser Sep 11 17:16:51 zeus-volumio2 volumio[954]: info: Exploding uri upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6684417 in service upnp_browser Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Preload queue cleared Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::ClearQueue Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::stop Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::stPlaybackTimer Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::updateTrackBlock Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrackBlock Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 0 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::serviceStop Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 0 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreCommandRouter::serviceStop Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: [1757578613965] ControllerUPNPBrowser::stop Sep 11 17:16:53 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CorePlayQueue::clearPlayQueue Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CorePlayQueue::saveQueue Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushQueue Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::addQueueItems Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CorePlayQueue::addQueueItems Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Preload queue cleared Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Adding Item to queue: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6682113 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Using cached record of: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6682113 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushQueue Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CorePlayQueue::saveQueue Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::updateTrackBlock Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrackBlock Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPlay Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::play index 0 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::addQueueItems Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CorePlayQueue::addQueueItems Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Preload queue cleared Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Adding Item to queue: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6682369 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Using cached record of: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6682369 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Adding Item to queue: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6682625 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Using cached record of: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6682625 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Adding Item to queue: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6682881 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Using cached record of: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6682881 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Adding Item to queue: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6683137 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Using cached record of: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6683137 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Adding Item to queue: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6683393 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Using cached record of: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6683393 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Adding Item to queue: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6683649 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Using cached record of: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6683649 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Adding Item to queue: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6683905 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Using cached record of: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6683905 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Adding Item to queue: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6684161 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Using cached record of: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6684161 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Adding Item to queue: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6684417 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Using cached record of: upnp/http://192.168.1.116:9000/dev0/srv1/control@0$1$18$7305$48280$48282R6684417 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::stop Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushQueue Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CorePlayQueue::saveQueue Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::play index undefined Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::updateTrackBlock Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrackBlock Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 0 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CoreStateMachine::startPlaybackTimer Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 0 Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: [1757578613976] ControllerUPNPBrowser::clearAddPlayTrack Sep 11 17:16:53 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Sep 11 17:16:53 zeus-volumio2 volumio[954]: ---------------------------- MPD announces state update: player Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: sendMpdCommand stop took 12 milliseconds Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: ControllerMpd::getState Sep 11 17:16:53 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: sendMpdCommand stop took 3 milliseconds Sep 11 17:16:53 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand clear Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Sep 11 17:16:53 zeus-volumio2 volumio[954]: ---------------------------- MPD announces system playlist update Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: Ignoring MPD Status Update Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: sendMpdCommand status took 2 milliseconds Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: sendMpdCommand clear took 1 milliseconds Sep 11 17:16:53 zeus-volumio2 volumio[954]: verbose: ControllerMpd::parseState Sep 11 17:16:53 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 17:16:53 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.116:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I6682113.flac" Sep 11 17:16:53 zeus-volumio2 volumio[954]: error: updateQueue error: null Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: ------------------------------ 2ms Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 11 17:16:53 zeus-volumio2 volumio[954]: verbose: ControllerMpd::parseTrackInfo Sep 11 17:16:53 zeus-volumio2 volumio[954]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 11 17:16:53 zeus-volumio2 volumio[954]: info: ------------------------------ 4ms Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.116:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I6682113.flac" Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: Sep 11 17:16:54 zeus-volumio2 volumio[954]: ---------------------------- MPD announces system playlist update Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: Ignoring MPD Status Update Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: sendMpdCommand add "http://192.168.1.116:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I6682113.flac" took 0 milliseconds Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand play Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: ------------------------------ 1ms Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: sendMpdCommand play took 0 milliseconds Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: Sep 11 17:16:54 zeus-volumio2 volumio[954]: ---------------------------- MPD announces state update: player Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: ControllerMpd::getState Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: Sep 11 17:16:54 zeus-volumio2 volumio[954]: ---------------------------- MPD announces state update: player Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: ControllerMpd::getState Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: sendMpdCommand status took 2 milliseconds Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: ControllerMpd::parseState Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: sendMpdCommand status took 5 milliseconds Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: ControllerMpd::parseState Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: ControllerMpd::parseTrackInfo Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: ControllerMpd::pushState Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreCommandRouter::servicePushState Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 0 Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I6682113.flac","artist":null,"album":null,"uri":"http://192.168.1.116:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I6682113.flac","trackType":"flac"} Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: CURRENT POSITION 0 Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreStateMachine::syncState stateService play Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreStateMachine::syncState currentStatus stop Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: ------------------------------ 8ms Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: ControllerMpd::parseTrackInfo Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: ControllerMpd::pushState Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreCommandRouter::servicePushState Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 0 Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I6682113.flac","artist":null,"album":null,"uri":"http://192.168.1.116:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I6682113.flac","trackType":"flac"} Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: CURRENT POSITION 0 Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreStateMachine::syncState stateService play Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreStateMachine::syncState currentStatus play Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: Received an update from plugin. extracting info from payload Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: ------------------------------ 16ms Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: Sep 11 17:16:54 zeus-volumio2 volumio[954]: ---------------------------- MPD announces system playlist update Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: Ignoring MPD Status Update Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: Sep 11 17:16:54 zeus-volumio2 volumio[954]: ---------------------------- MPD announces state update: player Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: ControllerMpd::getState Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: ------------------------------ 1ms Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: sendMpdCommand status took 1 milliseconds Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: ControllerMpd::parseState Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: ControllerMpd::parseTrackInfo Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: ControllerMpd::pushState Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreCommandRouter::servicePushState Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CorePlayQueue::getTrack 0 Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":231,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Your World and My World","artist":"Albert Hammond","album":"Your World & My World","uri":"http://192.168.1.116:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I6682113.flac","trackType":"flac"} Sep 11 17:16:54 zeus-volumio2 volumio[954]: verbose: CURRENT POSITION 0 Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreStateMachine::syncState stateService play Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreStateMachine::syncState currentStatus play Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: Received an update from plugin. extracting info from payload Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: ------------------------------ 8ms Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:54 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:55 zeus-volumio2 thd[676]: {"time":1757578597509,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:55 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:55 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:55 zeus-volumio2 volumio[954]: info: Sep 11 17:16:55 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:55 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:55 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:55 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:55 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:55 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:55 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:55 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:55 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:55 zeus-volumio2 thd[676]: {"time":1757578615750,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:55 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:55 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:55 zeus-volumio2 volumio[954]: info: Sep 11 17:16:55 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:55 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:55 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:55 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:55 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:55 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:55 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:55 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:55 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:56 zeus-volumio2 thd[676]: {"time":1757578615948,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:56 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:56 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: Sep 11 17:16:56 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:56 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:56 zeus-volumio2 thd[676]: {"time":1757578616155,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:56 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:56 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: Sep 11 17:16:56 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:56 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:56 zeus-volumio2 thd[676]: {"time":1757578616715,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:56 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:56 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: Sep 11 17:16:56 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:56 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:56 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:57 zeus-volumio2 thd[676]: {"time":1757578616874,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:57 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:57 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:57 zeus-volumio2 volumio[954]: info: Sep 11 17:16:57 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:57 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:57 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:57 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:57 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:57 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:57 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:57 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:57 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:57 zeus-volumio2 thd[676]: {"time":1757578617056,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:57 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:57 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:57 zeus-volumio2 volumio[954]: info: Sep 11 17:16:57 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:57 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:57 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:57 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:57 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:57 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:57 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:57 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:57 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:58 zeus-volumio2 thd[676]: {"time":1757578617936,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:16:58 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:58 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: Sep 11 17:16:58 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:58 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:58 zeus-volumio2 thd[676]: {"time":1757578618034,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:58 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:58 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: Sep 11 17:16:58 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:58 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:58 zeus-volumio2 thd[676]: {"time":1757578618473,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:58 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:58 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: Sep 11 17:16:58 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:58 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:58 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:16:59 zeus-volumio2 thd[676]: {"time":1757578618755,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:16:59 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:16:59 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:16:59 zeus-volumio2 volumio[954]: info: Sep 11 17:16:59 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:16:59 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:16:59 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:16:59 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:16:59 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:16:59 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:16:59 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:16:59 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:16:59 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:00 zeus-volumio2 thd[676]: {"time":1757578619838,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:17:00 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:00 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:00 zeus-volumio2 volumio[954]: info: Sep 11 17:17:00 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:17:00 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:17:00 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:00 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:00 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:00 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:00 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:00 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:00 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:00 zeus-volumio2 thd[676]: {"time":1757578620162,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:17:00 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:00 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:00 zeus-volumio2 volumio[954]: info: Sep 11 17:17:00 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:17:00 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:17:00 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:00 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:00 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:00 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:00 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:00 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:00 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:01 zeus-volumio2 thd[676]: {"time":1757578620647,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:17:01 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:01 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: Sep 11 17:17:01 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:01 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:01 zeus-volumio2 thd[676]: {"time":1757578621317,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:17:01 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:01 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: Sep 11 17:17:01 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:01 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:01 zeus-volumio2 thd[676]: {"time":1757578621725,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:17:01 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:01 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: Sep 11 17:17:01 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:01 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:01 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:02 zeus-volumio2 thd[676]: {"time":1757578621895,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:17:02 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:02 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:02 zeus-volumio2 volumio[954]: info: Sep 11 17:17:02 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:17:02 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:17:02 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:02 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:02 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:02 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:02 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:02 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:02 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:02 zeus-volumio2 thd[676]: {"time":1757578622086,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:17:02 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:02 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:02 zeus-volumio2 volumio[954]: info: Sep 11 17:17:02 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:17:02 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:17:02 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:02 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:02 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:02 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:02 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:02 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:02 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:03 zeus-volumio2 thd[676]: {"time":1757578622283,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:17:03 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:03 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:03 zeus-volumio2 volumio[954]: info: Sep 11 17:17:03 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:17:03 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:17:03 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:03 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:03 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:03 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:03 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:03 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:03 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:03 zeus-volumio2 thd[676]: {"time":1757578623239,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:17:03 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:03 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:03 zeus-volumio2 volumio[954]: info: Sep 11 17:17:03 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:17:03 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:17:03 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:03 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:03 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:03 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:03 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:03 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:03 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:04 zeus-volumio2 thd[676]: {"time":1757578623367,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:17:04 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:04 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: Sep 11 17:17:04 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:04 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:04 zeus-volumio2 thd[676]: {"time":1757578624172,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:17:04 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:04 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: Sep 11 17:17:04 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:04 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:04 zeus-volumio2 thd[676]: {"time":1757578624622,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:17:04 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:04 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: Sep 11 17:17:04 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:04 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:04 zeus-volumio2 thd[676]: {"time":1757578624716,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:17:04 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:04 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: Sep 11 17:17:04 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:04 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:04 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:05 zeus-volumio2 thd[676]: {"time":1757578624852,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:17:05 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:05 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:05 zeus-volumio2 volumio[954]: info: Sep 11 17:17:05 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:17:05 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:17:05 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:05 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:05 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:05 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:05 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:05 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:05 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:05 zeus-volumio2 thd[676]: {"time":1757578625371,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:17:06 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:06 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: Sep 11 17:17:06 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:06 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:06 zeus-volumio2 thd[676]: {"time":1757578626023,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:17:06 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:06 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: Sep 11 17:17:06 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:06 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:06 zeus-volumio2 thd[676]: {"time":1757578626264,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume plus Sep 11 17:17:06 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:06 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: Sep 11 17:17:06 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume + Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume+ Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:06 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:06 zeus-volumio2 thd[676]: {"time":1757578626522,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:17:06 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:06 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: Sep 11 17:17:06 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:06 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:06 zeus-volumio2 thd[676]: {"time":1757578626791,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Sep 11 17:17:06 zeus-volumio2 thd[676]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 17:17:06 zeus-volumio2 thd[676]: Dload Upload Total Spent Left Speed Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: Sep 11 17:17:06 zeus-volumio2 volumio[954]: ---------------------------- Client requests Volume - Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: VolumeController::SetAlsaVolume- Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreStateMachine::pushState Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioPushState Sep 11 17:17:06 zeus-volumio2 thd[676]: [158B blob data] Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CoreStateMachine::getQueue Sep 11 17:17:06 zeus-volumio2 volumio[954]: info: CorePlayQueue::getQueue Sep 11 17:17:10 zeus-volumio2 volumio[954]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 17:17:10 zeus-volumio2 volumio[954]: Error: read ECONNRESET Sep 11 17:17:10 zeus-volumio2 volumio[954]: at TCP.onStreamRead (internal/stream_base_commons.js:209:20) { Sep 11 17:17:10 zeus-volumio2 volumio[954]: errno: -104, Sep 11 17:17:10 zeus-volumio2 volumio[954]: code: 'ECONNRESET', Sep 11 17:17:10 zeus-volumio2 volumio[954]: syscall: 'read' Sep 11 17:17:10 zeus-volumio2 volumio[954]: } Sep 11 17:17:10 zeus-volumio2 volumio[954]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 17:17:11 zeus-volumio2 sudo[8657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-11 17:16 Sep 11 17:17:11 zeus-volumio2 sudo[8657]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"