-- Logs begin at Mon 2022-11-21 19:46:11 UTC, end at Mon 2022-11-21 20:01:02 UTC. -- Nov 21 20:00:00 volumio volumio[1174]: 20 Nov 21 20:00:00 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:00 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:00 volumio volumio[850]: info: Nov 21 20:00:00 volumio volumio[850]: ---------------------------- Client requests Volume 20 Nov 21 20:00:00 volumio volumio[850]: info: VolumeController::SetAlsaVolume20 Nov 21 20:00:00 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:00 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:00 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:00 volumio volumio[1174]: [155B blob data] Nov 21 20:00:00 volumio volumio[1174]: {"time":1669060800465,"response":"volume Success"}20 Nov 21 20:00:00 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:00 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:00 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:01 volumio volumio[1174]: 25 Nov 21 20:00:01 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:01 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:01 volumio volumio[850]: info: Nov 21 20:00:01 volumio volumio[850]: ---------------------------- Client requests Volume 25 Nov 21 20:00:01 volumio volumio[850]: info: VolumeController::SetAlsaVolume25 Nov 21 20:00:01 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:01 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:01 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:01 volumio volumio[1174]: [155B blob data] Nov 21 20:00:01 volumio volumio[1174]: {"time":1669060801701,"response":"volume Success"}25 Nov 21 20:00:01 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:01 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:01 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:02 volumio volumio[1174]: 29 Nov 21 20:00:02 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:02 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:02 volumio volumio[850]: info: Nov 21 20:00:02 volumio volumio[850]: ---------------------------- Client requests Volume 29 Nov 21 20:00:02 volumio volumio[850]: info: VolumeController::SetAlsaVolume29 Nov 21 20:00:02 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:02 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:02 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:02 volumio volumio[1174]: [155B blob data] Nov 21 20:00:02 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:02 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:02 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:02 volumio volumio[1174]: {"time":1669060802805,"response":"volume Success"}29 Nov 21 20:00:03 volumio volumio[1174]: 34 Nov 21 20:00:03 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:03 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:03 volumio volumio[850]: info: Nov 21 20:00:03 volumio volumio[850]: ---------------------------- Client requests Volume 34 Nov 21 20:00:04 volumio volumio[850]: info: VolumeController::SetAlsaVolume34 Nov 21 20:00:04 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:04 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:04 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:04 volumio volumio[1174]: [155B blob data] Nov 21 20:00:04 volumio volumio[1174]: {"time":1669060803995,"response":"volume Success"}34 Nov 21 20:00:04 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:04 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:04 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:05 volumio volumio[1174]: 37 Nov 21 20:00:05 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:05 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:05 volumio volumio[850]: info: Nov 21 20:00:05 volumio volumio[850]: ---------------------------- Client requests Volume 37 Nov 21 20:00:05 volumio volumio[850]: info: VolumeController::SetAlsaVolume37 Nov 21 20:00:05 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:05 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:05 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:05 volumio volumio[1174]: [155B blob data] Nov 21 20:00:05 volumio volumio[1174]: {"time":1669060805055,"response":"volume Success"}37 Nov 21 20:00:05 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:05 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:05 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:06 volumio volumio[1174]: 42 Nov 21 20:00:06 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:06 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:06 volumio volumio[850]: info: Nov 21 20:00:06 volumio volumio[850]: ---------------------------- Client requests Volume 42 Nov 21 20:00:06 volumio volumio[850]: info: VolumeController::SetAlsaVolume42 Nov 21 20:00:06 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:06 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:06 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:06 volumio volumio[1174]: [155B blob data] Nov 21 20:00:06 volumio volumio[1174]: {"time":1669060806306,"response":"volume Success"}42 Nov 21 20:00:06 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:06 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:06 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:07 volumio volumio[1174]: 46 Nov 21 20:00:07 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:07 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:07 volumio volumio[850]: info: Nov 21 20:00:07 volumio volumio[850]: ---------------------------- Client requests Volume 46 Nov 21 20:00:07 volumio volumio[850]: info: VolumeController::SetAlsaVolume46 Nov 21 20:00:07 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:07 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:07 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:08 volumio volumio[1174]: [155B blob data] Nov 21 20:00:08 volumio volumio[1174]: {"time":1669060807986,"response":"volume Success"}46 Nov 21 20:00:08 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:08 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:08 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:09 volumio volumio[1174]: 47 Nov 21 20:00:09 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:09 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:09 volumio volumio[850]: info: Nov 21 20:00:09 volumio volumio[850]: ---------------------------- Client requests Volume 47 Nov 21 20:00:09 volumio volumio[850]: info: VolumeController::SetAlsaVolume47 Nov 21 20:00:09 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:09 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:09 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:09 volumio volumio[1174]: [155B blob data] Nov 21 20:00:09 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:09 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:09 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:09 volumio volumio[1174]: {"time":1669060809286,"response":"volume Success"}47 Nov 21 20:00:09 volumio volumio[1174]: 48 Nov 21 20:00:09 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:09 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:09 volumio volumio[850]: info: Nov 21 20:00:09 volumio volumio[850]: ---------------------------- Client requests Volume 48 Nov 21 20:00:09 volumio volumio[850]: info: VolumeController::SetAlsaVolume48 Nov 21 20:00:09 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:09 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:09 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:09 volumio volumio[1174]: [155B blob data] Nov 21 20:00:09 volumio volumio[1174]: {"time":1669060809372,"response":"volume Success"}48 Nov 21 20:00:09 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:09 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:09 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:10 volumio volumio[1174]: 53 Nov 21 20:00:10 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:10 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:10 volumio volumio[850]: info: Nov 21 20:00:10 volumio volumio[850]: ---------------------------- Client requests Volume 53 Nov 21 20:00:10 volumio volumio[850]: info: VolumeController::SetAlsaVolume53 Nov 21 20:00:10 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:10 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:10 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:10 volumio volumio[1174]: [155B blob data] Nov 21 20:00:10 volumio volumio[1174]: {"time":1669060810665,"response":"volume Success"}53 Nov 21 20:00:10 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:10 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:10 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:12 volumio volumio[1174]: 52 Nov 21 20:00:12 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:12 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:12 volumio volumio[850]: info: Nov 21 20:00:12 volumio volumio[850]: ---------------------------- Client requests Volume 52 Nov 21 20:00:12 volumio volumio[850]: info: VolumeController::SetAlsaVolume52 Nov 21 20:00:12 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:12 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:12 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:12 volumio volumio[1174]: [155B blob data] Nov 21 20:00:12 volumio volumio[1174]: {"time":1669060812122,"response":"volume Success"}52 Nov 21 20:00:12 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:12 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:12 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:13 volumio volumio[1174]: 51 Nov 21 20:00:13 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:13 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:13 volumio volumio[850]: info: Nov 21 20:00:13 volumio volumio[850]: ---------------------------- Client requests Volume 51 Nov 21 20:00:13 volumio volumio[850]: info: VolumeController::SetAlsaVolume51 Nov 21 20:00:13 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:13 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:13 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:13 volumio volumio[1174]: [155B blob data] Nov 21 20:00:13 volumio volumio[1174]: {"time":1669060813562,"response":"volume Success"}51 Nov 21 20:00:13 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:13 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:13 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:20 volumio volumio[1174]: 25 Nov 21 20:00:20 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:20 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:20 volumio volumio[850]: info: Nov 21 20:00:20 volumio volumio[850]: ---------------------------- Client requests Volume 25 Nov 21 20:00:20 volumio volumio[850]: info: VolumeController::SetAlsaVolume25 Nov 21 20:00:20 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:20 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:20 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:20 volumio volumio[1174]: [155B blob data] Nov 21 20:00:20 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:20 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:20 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:20 volumio volumio[1174]: {"time":1669060820544,"response":"volume Success"}25 Nov 21 20:00:21 volumio volumio[1174]: 24 Nov 21 20:00:22 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:22 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:22 volumio volumio[850]: info: Nov 21 20:00:22 volumio volumio[850]: ---------------------------- Client requests Volume 24 Nov 21 20:00:22 volumio volumio[850]: info: VolumeController::SetAlsaVolume24 Nov 21 20:00:22 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:22 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:22 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:22 volumio volumio[1174]: [155B blob data] Nov 21 20:00:22 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:22 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:22 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:22 volumio volumio[1174]: {"time":1669060822004,"response":"volume Success"}24 Nov 21 20:00:23 volumio volumio[1174]: 20 Nov 21 20:00:23 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:23 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:23 volumio volumio[850]: info: Nov 21 20:00:23 volumio volumio[850]: ---------------------------- Client requests Volume 20 Nov 21 20:00:23 volumio volumio[850]: info: VolumeController::SetAlsaVolume20 Nov 21 20:00:23 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:23 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:23 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:23 volumio volumio[1174]: [155B blob data] Nov 21 20:00:23 volumio volumio[1174]: {"time":1669060823162,"response":"volume Success"}20 Nov 21 20:00:23 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:23 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:23 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:24 volumio volumio[1174]: 17 Nov 21 20:00:24 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:24 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:24 volumio volumio[850]: info: Nov 21 20:00:24 volumio volumio[850]: ---------------------------- Client requests Volume 17 Nov 21 20:00:24 volumio volumio[850]: info: VolumeController::SetAlsaVolume17 Nov 21 20:00:24 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:24 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:24 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:24 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:24 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:24 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:24 volumio volumio[1174]: [155B blob data] Nov 21 20:00:24 volumio volumio[1174]: {"time":1669060824761,"response":"volume Success"}17 Nov 21 20:00:25 volumio volumio[1174]: 15 Nov 21 20:00:25 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:25 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:25 volumio volumio[850]: info: Nov 21 20:00:25 volumio volumio[850]: ---------------------------- Client requests Volume 15 Nov 21 20:00:25 volumio volumio[850]: info: VolumeController::SetAlsaVolume15 Nov 21 20:00:25 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:25 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:25 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:25 volumio volumio[1174]: [155B blob data] Nov 21 20:00:25 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:25 volumio volumio[1174]: {"time":1669060825691,"response":"volume Success"}15 Nov 21 20:00:25 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:25 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:27 volumio volumio[1174]: 13 Nov 21 20:00:27 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:27 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:27 volumio volumio[850]: info: Nov 21 20:00:27 volumio volumio[850]: ---------------------------- Client requests Volume 13 Nov 21 20:00:27 volumio volumio[850]: info: VolumeController::SetAlsaVolume13 Nov 21 20:00:27 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:27 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:27 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:27 volumio volumio[1174]: [155B blob data] Nov 21 20:00:27 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:27 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:27 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:27 volumio volumio[1174]: {"time":1669060827337,"response":"volume Success"}13 Nov 21 20:00:27 volumio volumio[1174]: 11 Nov 21 20:00:27 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:27 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:27 volumio volumio[850]: info: Nov 21 20:00:27 volumio volumio[850]: ---------------------------- Client requests Volume 11 Nov 21 20:00:27 volumio volumio[850]: info: VolumeController::SetAlsaVolume11 Nov 21 20:00:27 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:27 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:27 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:27 volumio volumio[1174]: [155B blob data] Nov 21 20:00:27 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:27 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:27 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:27 volumio volumio[1174]: {"time":1669060827542,"response":"volume Success"}11 Nov 21 20:00:28 volumio volumio[1174]: 1 Nov 21 20:00:28 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:28 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:28 volumio volumio[850]: info: Nov 21 20:00:28 volumio volumio[850]: ---------------------------- Client requests Volume 1 Nov 21 20:00:28 volumio volumio[850]: info: VolumeController::SetAlsaVolume1 Nov 21 20:00:28 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:28 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:28 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:28 volumio volumio[1174]: [155B blob data] Nov 21 20:00:29 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:29 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:29 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:29 volumio volumio[1174]: {"time":1669060828982,"response":"volume Success"}1 Nov 21 20:00:30 volumio volumio[1174]: 0 Nov 21 20:00:30 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:30 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:30 volumio volumio[850]: info: Nov 21 20:00:30 volumio volumio[850]: ---------------------------- Client requests Volume 1 Nov 21 20:00:30 volumio volumio[850]: info: VolumeController::SetAlsaVolume1 Nov 21 20:00:30 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:30 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:30 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:30 volumio volumio[1174]: [155B blob data] Nov 21 20:00:30 volumio volumio[1174]: {"time":1669060830854,"response":"volume Success"}1 Nov 21 20:00:30 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:30 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:30 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:32 volumio volumio[1174]: 0 Nov 21 20:00:32 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:32 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:32 volumio volumio[850]: info: Nov 21 20:00:32 volumio volumio[850]: ---------------------------- Client requests Volume 1 Nov 21 20:00:32 volumio volumio[850]: info: VolumeController::SetAlsaVolume1 Nov 21 20:00:32 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:32 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:32 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:32 volumio volumio[1174]: [155B blob data] Nov 21 20:00:32 volumio volumio[1174]: {"time":1669060832282,"response":"volume Success"}1 Nov 21 20:00:32 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:32 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:32 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:34 volumio volumio[1174]: 0 Nov 21 20:00:34 volumio volumio[1174]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 20:00:34 volumio volumio[1174]: Dload Upload Total Spent Left Speed Nov 21 20:00:34 volumio volumio[850]: info: Nov 21 20:00:34 volumio volumio[850]: ---------------------------- Client requests Volume 1 Nov 21 20:00:34 volumio volumio[850]: info: VolumeController::SetAlsaVolume1 Nov 21 20:00:34 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:34 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:34 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:34 volumio volumio[1174]: [155B blob data] Nov 21 20:00:34 volumio volumio[1174]: {"time":1669060834858,"response":"volume Success"}1 Nov 21 20:00:34 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:00:34 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:00:34 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:00:57 volumio volumio[850]: info: Clearing queue after UPNP request Nov 21 20:00:57 volumio volumio[850]: info: CoreStateMachine::ClearQueue Nov 21 20:00:57 volumio volumio[850]: info: CoreStateMachine::stop Nov 21 20:00:57 volumio volumio[850]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 20:00:57 volumio volumio[850]: verbose: UNSET VOLATILE Nov 21 20:00:57 volumio volumio[850]: info: CoreStateMachine::stPlaybackTimer Nov 21 20:00:57 volumio volumio[850]: info: CoreStateMachine::updateTrackBlock Nov 21 20:00:57 volumio volumio[850]: info: CorePlayQueue::getTrackBlock Nov 21 20:00:57 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:57 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Nov 21 20:00:57 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:57 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:57 volumio volumio[850]: info: CoreStateMachine::serviceStop Nov 21 20:00:57 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Nov 21 20:00:57 volumio volumio[850]: info: ControllerMpd::stop Nov 21 20:00:57 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 20:00:57 volumio volumio[850]: info: CorePlayQueue::clearPlayQueue Nov 21 20:00:57 volumio volumio[850]: info: CorePlayQueue::saveQueue Nov 21 20:00:57 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:57 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:57 volumio volumio[850]: info: CoreCommandRouter::volumioPushQueue Nov 21 20:00:57 volumio volumio[850]: info: Nov 21 20:00:57 volumio volumio[850]: ---------------------------- MPD announces system playlist update Nov 21 20:00:57 volumio volumio[850]: info: Ignoring MPD Status Update Nov 21 20:00:57 volumio volumio[850]: info: Nov 21 20:00:57 volumio volumio[850]: ---------------------------- MPD announces state update: player Nov 21 20:00:57 volumio volumio[850]: info: ControllerMpd::getState Nov 21 20:00:57 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Nov 21 20:00:57 volumio volumio[850]: info: sendMpdCommand stop took 75 milliseconds Nov 21 20:00:57 volumio volumio[850]: error: updateQueue error: null Nov 21 20:00:57 volumio volumio[850]: info: ------------------------------ 2ms Nov 21 20:00:57 volumio volumio[850]: info: sendMpdCommand status took 1 milliseconds Nov 21 20:00:57 volumio volumio[850]: verbose: ControllerMpd::parseState Nov 21 20:00:57 volumio volumio[850]: info: ControllerMpd::pushState Nov 21 20:00:57 volumio volumio[850]: info: CoreCommandRouter::servicePushState Nov 21 20:00:57 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:57 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Nov 21 20:00:57 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:57 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:57 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Nov 21 20:00:57 volumio volumio[850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 20:00:57 volumio volumio[850]: verbose: CURRENT POSITION 0 Nov 21 20:00:57 volumio volumio[850]: info: CoreStateMachine::syncState stateService stop Nov 21 20:00:57 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus stop Nov 21 20:00:57 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:57 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Nov 21 20:00:57 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:57 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:57 volumio volumio[850]: info: No code Nov 21 20:00:57 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:00:57 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Nov 21 20:00:57 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:00:57 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:00:57 volumio volumio[850]: info: ------------------------------ 13ms Nov 21 20:00:58 volumio volumio[850]: info: Starting UPNP Playback Nov 21 20:00:58 volumio volumio[850]: info: Preparing playback through UPNP Nov 21 20:00:58 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Nov 21 20:00:58 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Nov 21 20:00:58 volumio volumio[850]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 20:00:58 volumio volumio[850]: info: Nov 21 20:00:58 volumio volumio[850]: ---------------------------- MPD announces system playlist update Nov 21 20:00:58 volumio volumio[850]: info: Ignoring MPD Status Update Nov 21 20:00:58 volumio volumio[850]: info: Nov 21 20:00:58 volumio volumio[850]: ---------------------------- MPD announces system playlist update Nov 21 20:00:58 volumio volumio[850]: info: Ignoring MPD Status Update Nov 21 20:00:58 volumio volumio[850]: info: ------------------------------ 2ms Nov 21 20:00:58 volumio volumio[850]: info: ------------------------------ 1ms Nov 21 20:00:58 volumio volumio[850]: info: Nov 21 20:00:58 volumio volumio[850]: ---------------------------- MPD announces system playlist update Nov 21 20:00:58 volumio volumio[850]: info: Ignoring MPD Status Update Nov 21 20:00:58 volumio volumio[850]: info: Nov 21 20:00:58 volumio volumio[850]: ---------------------------- MPD announces system playlist update Nov 21 20:00:58 volumio volumio[850]: info: Ignoring MPD Status Update Nov 21 20:00:58 volumio volumio[850]: info: ------------------------------ 1ms Nov 21 20:00:58 volumio volumio[850]: info: ------------------------------ 1ms Nov 21 20:00:58 volumio volumio[850]: info: Nov 21 20:00:58 volumio volumio[850]: ---------------------------- MPD announces system playlist update Nov 21 20:00:58 volumio volumio[850]: info: Ignoring MPD Status Update Nov 21 20:00:58 volumio volumio[850]: info: ------------------------------ 1ms Nov 21 20:00:58 volumio volumio[850]: info: Nov 21 20:00:58 volumio volumio[850]: ---------------------------- MPD announces system playlist update Nov 21 20:00:58 volumio volumio[850]: info: Ignoring MPD Status Update Nov 21 20:00:58 volumio volumio[850]: info: ------------------------------ 1ms Nov 21 20:01:01 volumio volumio[850]: info: Nov 21 20:01:01 volumio volumio[850]: ---------------------------- MPD announces state update: player Nov 21 20:01:01 volumio volumio[850]: info: ControllerMpd::getState Nov 21 20:01:01 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Nov 21 20:01:01 volumio volumio[850]: info: Nov 21 20:01:01 volumio volumio[850]: ---------------------------- MPD announces state update: player Nov 21 20:01:01 volumio volumio[850]: info: sendMpdCommand status took 12 milliseconds Nov 21 20:01:01 volumio volumio[850]: info: ControllerMpd::getState Nov 21 20:01:01 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Nov 21 20:01:01 volumio volumio[850]: verbose: ControllerMpd::parseState Nov 21 20:01:01 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 20:01:01 volumio volumio[850]: info: sendMpdCommand status took 3 milliseconds Nov 21 20:01:01 volumio volumio[850]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 21 20:01:01 volumio volumio[850]: verbose: ControllerMpd::parseState Nov 21 20:01:01 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 20:01:01 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Nov 21 20:01:01 volumio volumio[850]: info: ControllerMpd::pushState Nov 21 20:01:01 volumio volumio[850]: info: CoreCommandRouter::servicePushState Nov 21 20:01:01 volumio volumio[850]: verbose: In UPNP mode Nov 21 20:01:01 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"604 Kbps","isStreaming":false,"title":"Paid For Love","artist":"ilan Bluestone","album":"Paid For Love","uri":"http://192.168.1.166:57645/proxy/tidal/53D39F45FD2758E1E4F2B915C52D5991.flac","trackType":"flac"} Nov 21 20:01:01 volumio volumio[850]: verbose: CURRENT POSITION 0 Nov 21 20:01:01 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Nov 21 20:01:01 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus stop Nov 21 20:01:01 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:01:01 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:01:01 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:01:01 volumio volumio[850]: info: ------------------------------ 23ms Nov 21 20:01:01 volumio volumio[850]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 21 20:01:01 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Nov 21 20:01:01 volumio volumio[850]: info: ControllerMpd::pushState Nov 21 20:01:01 volumio volumio[850]: info: CoreCommandRouter::servicePushState Nov 21 20:01:01 volumio volumio[850]: verbose: In UPNP mode Nov 21 20:01:01 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"611 Kbps","isStreaming":false,"title":"Paid For Love","artist":"ilan Bluestone","album":"Paid For Love","uri":"http://192.168.1.166:57645/proxy/tidal/53D39F45FD2758E1E4F2B915C52D5991.flac","trackType":"flac"} Nov 21 20:01:01 volumio volumio[850]: verbose: CURRENT POSITION 0 Nov 21 20:01:01 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Nov 21 20:01:01 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus play Nov 21 20:01:01 volumio volumio[850]: info: Received an update from plugin. extracting info from payload Nov 21 20:01:01 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 21 20:01:01 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:01:01 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:01:01 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:01:01 volumio volumio[850]: info: CoreStateMachine::pushState Nov 21 20:01:01 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Nov 21 20:01:01 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:01:01 volumio volumio[850]: info: ------------------------------ 30ms Nov 21 20:01:01 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:01:01 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:01:01 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:01:01 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:01:01 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:01:01 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:01:01 volumio volumio[850]: info: CoreCommandRouter::volumioGetQueue Nov 21 20:01:01 volumio volumio[850]: info: CoreStateMachine::getQueue Nov 21 20:01:01 volumio volumio[850]: info: CorePlayQueue::getQueue Nov 21 20:01:01 volumio systemd[1]: Starting Cleanup of Temporary Directories... Nov 21 20:01:01 volumio systemd[1]: Started Cleanup of Temporary Directories. Nov 21 20:01:02 volumio volumio[850]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 20:01:02 volumio volumio[850]: { Error: certificate is not yet valid Nov 21 20:01:02 volumio volumio[850]: at TLSSocket. (_tls_wrap.js:1105:38) Nov 21 20:01:02 volumio volumio[850]: at emitNone (events.js:106:13) Nov 21 20:01:02 volumio volumio[850]: at TLSSocket.emit (events.js:208:7) Nov 21 20:01:02 volumio volumio[850]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Nov 21 20:01:02 volumio volumio[850]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Nov 21 20:01:02 volumio volumio[850]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 20:01:02 volumio sudo[4418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-11-21 20:00 Nov 21 20:01:02 volumio sudo[4418]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 30 22:42:09 CEST 2021" VOLUMIO_VERSION="2.915" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="02ee61bb422c79b6e5c0b6d1847a78d9"