-- Logs begin at Thu 2024-10-17 10:45:27 MSK, end at Thu 2024-10-17 11:16:40 MSK. -- Oct 17 11:15:00 volumio volumio[1476]: 21 Oct 17 11:15:00 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:00 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:00 volumio volumio[1189]: info: Oct 17 11:15:00 volumio volumio[1189]: ---------------------------- Client requests Volume 21 Oct 17 11:15:00 volumio volumio[1189]: info: VolumeController::SetAlsaVolume21 Oct 17 11:15:00 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:00 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:00 volumio volumio[1476]: [158B blob data] Oct 17 11:15:00 volumio volumio[1476]: {"time":1729152900200,"response":"volume Success"}21 Oct 17 11:15:00 volumio volumio[1476]: 22 Oct 17 11:15:00 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:00 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:00 volumio volumio[1189]: info: Oct 17 11:15:00 volumio volumio[1189]: ---------------------------- Client requests Volume 22 Oct 17 11:15:00 volumio volumio[1189]: info: VolumeController::SetAlsaVolume22 Oct 17 11:15:00 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:00 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:00 volumio volumio[1476]: [237B blob data] Oct 17 11:15:00 volumio volumio[1476]: {"time":1729152900476,"response":"volume Success"}22 Oct 17 11:15:00 volumio volumio[1476]: 23 Oct 17 11:15:00 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:00 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:00 volumio volumio[1189]: info: Oct 17 11:15:00 volumio volumio[1189]: ---------------------------- Client requests Volume 23 Oct 17 11:15:00 volumio volumio[1189]: info: VolumeController::SetAlsaVolume23 Oct 17 11:15:00 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:00 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:00 volumio volumio[1476]: [158B blob data] Oct 17 11:15:00 volumio volumio[1476]: {"time":1729152900664,"response":"volume Success"}23 Oct 17 11:15:00 volumio volumio[1476]: 24 Oct 17 11:15:00 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:00 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:00 volumio volumio[1189]: info: Oct 17 11:15:00 volumio volumio[1189]: ---------------------------- Client requests Volume 24 Oct 17 11:15:00 volumio volumio[1189]: info: VolumeController::SetAlsaVolume24 Oct 17 11:15:00 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:00 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:00 volumio volumio[1476]: [158B blob data] Oct 17 11:15:00 volumio volumio[1476]: {"time":1729152900878,"response":"volume Success"}24 Oct 17 11:15:01 volumio volumio[1476]: 25 Oct 17 11:15:01 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:01 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:01 volumio volumio[1189]: info: Oct 17 11:15:01 volumio volumio[1189]: ---------------------------- Client requests Volume 25 Oct 17 11:15:01 volumio volumio[1189]: info: VolumeController::SetAlsaVolume25 Oct 17 11:15:01 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:01 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:01 volumio volumio[1476]: [158B blob data] Oct 17 11:15:01 volumio volumio[1476]: {"time":1729152901081,"response":"volume Success"}25 Oct 17 11:15:01 volumio volumio[1476]: 26 Oct 17 11:15:01 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:01 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:01 volumio volumio[1189]: info: Oct 17 11:15:01 volumio volumio[1189]: ---------------------------- Client requests Volume 26 Oct 17 11:15:01 volumio volumio[1189]: info: VolumeController::SetAlsaVolume26 Oct 17 11:15:01 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:01 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:01 volumio volumio[1476]: [158B blob data] Oct 17 11:15:01 volumio volumio[1476]: {"time":1729152901278,"response":"volume Success"}26 Oct 17 11:15:01 volumio volumio[1476]: 27 Oct 17 11:15:01 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:01 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:01 volumio volumio[1189]: info: Oct 17 11:15:01 volumio volumio[1189]: ---------------------------- Client requests Volume 27 Oct 17 11:15:01 volumio volumio[1189]: info: VolumeController::SetAlsaVolume27 Oct 17 11:15:01 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:01 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:01 volumio volumio[1476]: [237B blob data] Oct 17 11:15:01 volumio volumio[1476]: {"time":1729152901491,"response":"volume Success"}27 Oct 17 11:15:01 volumio volumio[1476]: 28 Oct 17 11:15:01 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:01 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:01 volumio volumio[1189]: info: Oct 17 11:15:01 volumio volumio[1189]: ---------------------------- Client requests Volume 28 Oct 17 11:15:01 volumio volumio[1189]: info: VolumeController::SetAlsaVolume28 Oct 17 11:15:01 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:01 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:01 volumio volumio[1476]: [158B blob data] Oct 17 11:15:01 volumio volumio[1476]: {"time":1729152901692,"response":"volume Success"}28 Oct 17 11:15:01 volumio volumio[1476]: 29 Oct 17 11:15:01 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:01 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:01 volumio volumio[1189]: info: Oct 17 11:15:01 volumio volumio[1189]: ---------------------------- Client requests Volume 29 Oct 17 11:15:01 volumio volumio[1189]: info: VolumeController::SetAlsaVolume29 Oct 17 11:15:01 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:01 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:01 volumio volumio[1476]: [158B blob data] Oct 17 11:15:01 volumio volumio[1476]: {"time":1729152901914,"response":"volume Success"}29 Oct 17 11:15:02 volumio volumio[1476]: 30 Oct 17 11:15:02 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:02 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:02 volumio volumio[1189]: info: Oct 17 11:15:02 volumio volumio[1189]: ---------------------------- Client requests Volume 30 Oct 17 11:15:02 volumio volumio[1189]: info: VolumeController::SetAlsaVolume30 Oct 17 11:15:02 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:02 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:02 volumio volumio[1476]: [158B blob data] Oct 17 11:15:02 volumio volumio[1476]: {"time":1729152902079,"response":"volume Success"}30 Oct 17 11:15:02 volumio volumio[1476]: 31 Oct 17 11:15:02 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:02 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:02 volumio volumio[1189]: info: Oct 17 11:15:02 volumio volumio[1189]: ---------------------------- Client requests Volume 31 Oct 17 11:15:02 volumio volumio[1189]: info: VolumeController::SetAlsaVolume31 Oct 17 11:15:02 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:02 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:02 volumio volumio[1476]: [158B blob data] Oct 17 11:15:02 volumio volumio[1476]: {"time":1729152902308,"response":"volume Success"}31 Oct 17 11:15:02 volumio volumio[1476]: 32 Oct 17 11:15:02 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:02 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:02 volumio volumio[1189]: info: Oct 17 11:15:02 volumio volumio[1189]: ---------------------------- Client requests Volume 32 Oct 17 11:15:02 volumio volumio[1189]: info: VolumeController::SetAlsaVolume32 Oct 17 11:15:02 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:02 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:02 volumio volumio[1476]: [158B blob data] Oct 17 11:15:02 volumio volumio[1476]: {"time":1729152902519,"response":"volume Success"}32 Oct 17 11:15:02 volumio volumio[1476]: 33 Oct 17 11:15:02 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:02 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:02 volumio volumio[1189]: info: Oct 17 11:15:02 volumio volumio[1189]: ---------------------------- Client requests Volume 33 Oct 17 11:15:02 volumio volumio[1189]: info: VolumeController::SetAlsaVolume33 Oct 17 11:15:02 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:02 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:02 volumio volumio[1476]: [158B blob data] Oct 17 11:15:02 volumio volumio[1476]: {"time":1729152902713,"response":"volume Success"}33 Oct 17 11:15:02 volumio volumio[1476]: 34 Oct 17 11:15:02 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:02 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:02 volumio volumio[1189]: info: Oct 17 11:15:02 volumio volumio[1189]: ---------------------------- Client requests Volume 34 Oct 17 11:15:02 volumio volumio[1189]: info: VolumeController::SetAlsaVolume34 Oct 17 11:15:02 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:02 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:02 volumio volumio[1476]: [158B blob data] Oct 17 11:15:02 volumio volumio[1476]: {"time":1729152902931,"response":"volume Success"}34 Oct 17 11:15:03 volumio volumio[1476]: 35 Oct 17 11:15:03 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:03 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:03 volumio volumio[1189]: info: Oct 17 11:15:03 volumio volumio[1189]: ---------------------------- Client requests Volume 35 Oct 17 11:15:03 volumio volumio[1189]: info: VolumeController::SetAlsaVolume35 Oct 17 11:15:03 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:03 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:03 volumio volumio[1476]: [158B blob data] Oct 17 11:15:03 volumio volumio[1476]: {"time":1729152903147,"response":"volume Success"}35 Oct 17 11:15:03 volumio volumio[1476]: 36 Oct 17 11:15:03 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:03 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:03 volumio volumio[1189]: info: Oct 17 11:15:03 volumio volumio[1189]: ---------------------------- Client requests Volume 36 Oct 17 11:15:03 volumio volumio[1189]: info: VolumeController::SetAlsaVolume36 Oct 17 11:15:03 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:03 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:03 volumio volumio[1476]: [158B blob data] Oct 17 11:15:03 volumio volumio[1476]: {"time":1729152903371,"response":"volume Success"}36 Oct 17 11:15:03 volumio volumio[1476]: 37 Oct 17 11:15:03 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:03 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:03 volumio volumio[1189]: info: Oct 17 11:15:03 volumio volumio[1189]: ---------------------------- Client requests Volume 37 Oct 17 11:15:03 volumio volumio[1189]: info: VolumeController::SetAlsaVolume37 Oct 17 11:15:03 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:03 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:03 volumio volumio[1476]: [158B blob data] Oct 17 11:15:03 volumio volumio[1476]: {"time":1729152903582,"response":"volume Success"}37 Oct 17 11:15:03 volumio volumio[1476]: 38 Oct 17 11:15:03 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:03 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:03 volumio volumio[1189]: info: Oct 17 11:15:03 volumio volumio[1189]: ---------------------------- Client requests Volume 38 Oct 17 11:15:03 volumio volumio[1189]: info: VolumeController::SetAlsaVolume38 Oct 17 11:15:03 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:03 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:03 volumio volumio[1476]: [158B blob data] Oct 17 11:15:03 volumio volumio[1476]: {"time":1729152903775,"response":"volume Success"}38 Oct 17 11:15:03 volumio volumio[1476]: 39 Oct 17 11:15:04 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:04 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:04 volumio volumio[1189]: info: Oct 17 11:15:04 volumio volumio[1189]: ---------------------------- Client requests Volume 39 Oct 17 11:15:04 volumio volumio[1189]: info: VolumeController::SetAlsaVolume39 Oct 17 11:15:04 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:04 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:04 volumio volumio[1476]: [158B blob data] Oct 17 11:15:04 volumio volumio[1476]: {"time":1729152904012,"response":"volume Success"}39 Oct 17 11:15:04 volumio volumio[1476]: 40 Oct 17 11:15:04 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:04 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:04 volumio volumio[1189]: info: Oct 17 11:15:04 volumio volumio[1189]: ---------------------------- Client requests Volume 40 Oct 17 11:15:04 volumio volumio[1189]: info: VolumeController::SetAlsaVolume40 Oct 17 11:15:04 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:04 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:04 volumio volumio[1476]: [158B blob data] Oct 17 11:15:04 volumio volumio[1476]: {"time":1729152904237,"response":"volume Success"}40 Oct 17 11:15:04 volumio volumio[1476]: 41 Oct 17 11:15:04 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:04 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:04 volumio volumio[1189]: info: Oct 17 11:15:04 volumio volumio[1189]: ---------------------------- Client requests Volume 41 Oct 17 11:15:04 volumio volumio[1189]: info: VolumeController::SetAlsaVolume41 Oct 17 11:15:04 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:04 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:04 volumio volumio[1476]: [237B blob data] Oct 17 11:15:04 volumio volumio[1476]: {"time":1729152904455,"response":"volume Success"}41 Oct 17 11:15:04 volumio volumio[1476]: 42 Oct 17 11:15:04 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:04 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:04 volumio volumio[1189]: info: Oct 17 11:15:04 volumio volumio[1189]: ---------------------------- Client requests Volume 42 Oct 17 11:15:04 volumio volumio[1189]: info: VolumeController::SetAlsaVolume42 Oct 17 11:15:04 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:04 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:04 volumio volumio[1476]: [158B blob data] Oct 17 11:15:04 volumio volumio[1476]: {"time":1729152904666,"response":"volume Success"}42 Oct 17 11:15:04 volumio volumio[1476]: 43 Oct 17 11:15:04 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:04 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:04 volumio volumio[1189]: info: Oct 17 11:15:04 volumio volumio[1189]: ---------------------------- Client requests Volume 43 Oct 17 11:15:04 volumio volumio[1189]: info: VolumeController::SetAlsaVolume43 Oct 17 11:15:04 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:04 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:04 volumio volumio[1476]: [158B blob data] Oct 17 11:15:04 volumio volumio[1476]: {"time":1729152904883,"response":"volume Success"}43 Oct 17 11:15:05 volumio volumio[1476]: 44 Oct 17 11:15:05 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:05 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:05 volumio volumio[1189]: info: Oct 17 11:15:05 volumio volumio[1189]: ---------------------------- Client requests Volume 44 Oct 17 11:15:05 volumio volumio[1189]: info: VolumeController::SetAlsaVolume44 Oct 17 11:15:05 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:05 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:05 volumio volumio[1476]: [158B blob data] Oct 17 11:15:05 volumio volumio[1476]: {"time":1729152905101,"response":"volume Success"}44 Oct 17 11:15:05 volumio volumio[1476]: 45 Oct 17 11:15:05 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:05 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:05 volumio volumio[1189]: info: Oct 17 11:15:05 volumio volumio[1189]: ---------------------------- Client requests Volume 45 Oct 17 11:15:05 volumio volumio[1189]: info: VolumeController::SetAlsaVolume45 Oct 17 11:15:05 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:05 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:05 volumio volumio[1476]: [158B blob data] Oct 17 11:15:05 volumio volumio[1476]: {"time":1729152905313,"response":"volume Success"}45 Oct 17 11:15:05 volumio volumio[1476]: 46 Oct 17 11:15:05 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:05 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:05 volumio volumio[1189]: info: Oct 17 11:15:05 volumio volumio[1189]: ---------------------------- Client requests Volume 46 Oct 17 11:15:05 volumio volumio[1189]: info: VolumeController::SetAlsaVolume46 Oct 17 11:15:05 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:05 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:05 volumio volumio[1476]: [158B blob data] Oct 17 11:15:05 volumio volumio[1476]: {"time":1729152905507,"response":"volume Success"}46 Oct 17 11:15:05 volumio volumio[1476]: 47 Oct 17 11:15:05 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:05 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:05 volumio volumio[1189]: info: Oct 17 11:15:05 volumio volumio[1189]: ---------------------------- Client requests Volume 47 Oct 17 11:15:05 volumio volumio[1189]: info: VolumeController::SetAlsaVolume47 Oct 17 11:15:05 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:05 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:05 volumio volumio[1476]: [158B blob data] Oct 17 11:15:05 volumio volumio[1476]: {"time":1729152905709,"response":"volume Success"}47 Oct 17 11:15:05 volumio volumio[1476]: 48 Oct 17 11:15:05 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:05 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:05 volumio volumio[1189]: info: Oct 17 11:15:05 volumio volumio[1189]: ---------------------------- Client requests Volume 48 Oct 17 11:15:05 volumio volumio[1189]: info: VolumeController::SetAlsaVolume48 Oct 17 11:15:05 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:05 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:05 volumio volumio[1476]: [158B blob data] Oct 17 11:15:05 volumio volumio[1476]: {"time":1729152905929,"response":"volume Success"}48 Oct 17 11:15:06 volumio volumio[1476]: 49 Oct 17 11:15:06 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:06 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:06 volumio volumio[1189]: info: Oct 17 11:15:06 volumio volumio[1189]: ---------------------------- Client requests Volume 49 Oct 17 11:15:06 volumio volumio[1189]: info: VolumeController::SetAlsaVolume49 Oct 17 11:15:06 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:06 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:06 volumio volumio[1476]: [158B blob data] Oct 17 11:15:06 volumio volumio[1476]: {"time":1729152906139,"response":"volume Success"}49 Oct 17 11:15:06 volumio volumio[1476]: 50 Oct 17 11:15:06 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:06 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:06 volumio volumio[1189]: info: Oct 17 11:15:06 volumio volumio[1189]: ---------------------------- Client requests Volume 50 Oct 17 11:15:06 volumio volumio[1189]: info: VolumeController::SetAlsaVolume50 Oct 17 11:15:06 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:06 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:06 volumio volumio[1476]: [158B blob data] Oct 17 11:15:06 volumio volumio[1476]: {"time":1729152906377,"response":"volume Success"}50 Oct 17 11:15:06 volumio volumio[1476]: 51 Oct 17 11:15:06 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:06 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:06 volumio volumio[1189]: info: Oct 17 11:15:06 volumio volumio[1189]: ---------------------------- Client requests Volume 51 Oct 17 11:15:06 volumio volumio[1189]: info: VolumeController::SetAlsaVolume51 Oct 17 11:15:06 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:06 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:06 volumio volumio[1476]: [158B blob data] Oct 17 11:15:06 volumio volumio[1476]: {"time":1729152906609,"response":"volume Success"}51 Oct 17 11:15:06 volumio volumio[1476]: 52 Oct 17 11:15:06 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:06 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:06 volumio volumio[1189]: info: Oct 17 11:15:06 volumio volumio[1189]: ---------------------------- Client requests Volume 52 Oct 17 11:15:06 volumio volumio[1189]: info: VolumeController::SetAlsaVolume52 Oct 17 11:15:06 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:06 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:06 volumio volumio[1476]: [158B blob data] Oct 17 11:15:06 volumio volumio[1476]: {"time":1729152906815,"response":"volume Success"}52 Oct 17 11:15:06 volumio volumio[1476]: 53 Oct 17 11:15:07 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:07 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:07 volumio volumio[1189]: info: Oct 17 11:15:07 volumio volumio[1189]: ---------------------------- Client requests Volume 53 Oct 17 11:15:07 volumio volumio[1189]: info: VolumeController::SetAlsaVolume53 Oct 17 11:15:07 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:07 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:07 volumio volumio[1476]: [158B blob data] Oct 17 11:15:07 volumio volumio[1476]: {"time":1729152907014,"response":"volume Success"}53 Oct 17 11:15:07 volumio volumio[1476]: 54 Oct 17 11:15:07 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:07 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:07 volumio volumio[1189]: info: Oct 17 11:15:07 volumio volumio[1189]: ---------------------------- Client requests Volume 54 Oct 17 11:15:07 volumio volumio[1189]: info: VolumeController::SetAlsaVolume54 Oct 17 11:15:07 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:07 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:07 volumio volumio[1476]: [158B blob data] Oct 17 11:15:07 volumio volumio[1476]: {"time":1729152907222,"response":"volume Success"}54 Oct 17 11:15:07 volumio volumio[1476]: 55 Oct 17 11:15:07 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:07 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:07 volumio volumio[1189]: info: Oct 17 11:15:07 volumio volumio[1189]: ---------------------------- Client requests Volume 55 Oct 17 11:15:07 volumio volumio[1189]: info: VolumeController::SetAlsaVolume55 Oct 17 11:15:07 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:07 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:07 volumio volumio[1476]: [158B blob data] Oct 17 11:15:07 volumio volumio[1476]: {"time":1729152907447,"response":"volume Success"}55 Oct 17 11:15:07 volumio volumio[1476]: 56 Oct 17 11:15:07 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:07 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:07 volumio volumio[1189]: info: Oct 17 11:15:07 volumio volumio[1189]: ---------------------------- Client requests Volume 56 Oct 17 11:15:07 volumio volumio[1189]: info: VolumeController::SetAlsaVolume56 Oct 17 11:15:07 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:07 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:07 volumio volumio[1476]: [158B blob data] Oct 17 11:15:07 volumio volumio[1476]: {"time":1729152907668,"response":"volume Success"}56 Oct 17 11:15:07 volumio volumio[1476]: 57 Oct 17 11:15:07 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:07 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:07 volumio volumio[1189]: info: Oct 17 11:15:07 volumio volumio[1189]: ---------------------------- Client requests Volume 57 Oct 17 11:15:07 volumio volumio[1189]: info: VolumeController::SetAlsaVolume57 Oct 17 11:15:07 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:07 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:07 volumio volumio[1476]: [158B blob data] Oct 17 11:15:07 volumio volumio[1476]: {"time":1729152907874,"response":"volume Success"}57 Oct 17 11:15:08 volumio volumio[1476]: 58 Oct 17 11:15:08 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:08 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:08 volumio volumio[1189]: info: Oct 17 11:15:08 volumio volumio[1189]: ---------------------------- Client requests Volume 58 Oct 17 11:15:08 volumio volumio[1189]: info: VolumeController::SetAlsaVolume58 Oct 17 11:15:08 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:08 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:08 volumio volumio[1476]: [158B blob data] Oct 17 11:15:08 volumio volumio[1476]: {"time":1729152908090,"response":"volume Success"}58 Oct 17 11:15:08 volumio volumio[1476]: 59 Oct 17 11:15:08 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:08 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:08 volumio volumio[1189]: info: Oct 17 11:15:08 volumio volumio[1189]: ---------------------------- Client requests Volume 59 Oct 17 11:15:08 volumio volumio[1189]: info: VolumeController::SetAlsaVolume59 Oct 17 11:15:08 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:08 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:08 volumio volumio[1476]: [158B blob data] Oct 17 11:15:08 volumio volumio[1476]: {"time":1729152908300,"response":"volume Success"}59 Oct 17 11:15:08 volumio volumio[1476]: 60 Oct 17 11:15:08 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:08 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:08 volumio volumio[1189]: info: Oct 17 11:15:08 volumio volumio[1189]: ---------------------------- Client requests Volume 60 Oct 17 11:15:08 volumio volumio[1189]: info: VolumeController::SetAlsaVolume60 Oct 17 11:15:08 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:08 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:08 volumio volumio[1476]: [158B blob data] Oct 17 11:15:08 volumio volumio[1476]: {"time":1729152908608,"response":"volume Success"}60 Oct 17 11:15:08 volumio wpa_supplicant[1104]: wlan0: WPA: Group rekeying completed with 24:43:e2:63:3a:17 [GTK=CCMP] Oct 17 11:15:08 volumio volumio[1476]: 61 Oct 17 11:15:08 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:08 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:08 volumio volumio[1189]: info: Oct 17 11:15:08 volumio volumio[1189]: ---------------------------- Client requests Volume 61 Oct 17 11:15:08 volumio volumio[1189]: info: VolumeController::SetAlsaVolume61 Oct 17 11:15:08 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:08 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:08 volumio volumio[1476]: [158B blob data] Oct 17 11:15:08 volumio volumio[1476]: {"time":1729152908790,"response":"volume Success"}61 Oct 17 11:15:08 volumio volumio[1476]: 62 Oct 17 11:15:09 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:09 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:09 volumio volumio[1189]: info: Oct 17 11:15:09 volumio volumio[1189]: ---------------------------- Client requests Volume 62 Oct 17 11:15:09 volumio volumio[1189]: info: VolumeController::SetAlsaVolume62 Oct 17 11:15:09 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:09 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:09 volumio volumio[1476]: [158B blob data] Oct 17 11:15:09 volumio volumio[1476]: {"time":1729152909009,"response":"volume Success"}62 Oct 17 11:15:09 volumio volumio[1476]: 63 Oct 17 11:15:09 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:09 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:09 volumio volumio[1189]: info: Oct 17 11:15:09 volumio volumio[1189]: ---------------------------- Client requests Volume 63 Oct 17 11:15:09 volumio volumio[1189]: info: VolumeController::SetAlsaVolume63 Oct 17 11:15:09 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:09 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:09 volumio volumio[1476]: [158B blob data] Oct 17 11:15:09 volumio volumio[1476]: {"time":1729152909248,"response":"volume Success"}63 Oct 17 11:15:09 volumio volumio[1476]: 64 Oct 17 11:15:09 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:09 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:09 volumio volumio[1189]: info: Oct 17 11:15:09 volumio volumio[1189]: ---------------------------- Client requests Volume 64 Oct 17 11:15:09 volumio volumio[1189]: info: VolumeController::SetAlsaVolume64 Oct 17 11:15:09 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:09 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:09 volumio volumio[1476]: [237B blob data] Oct 17 11:15:09 volumio volumio[1476]: {"time":1729152909460,"response":"volume Success"}64 Oct 17 11:15:09 volumio volumio[1476]: 65 Oct 17 11:15:09 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:09 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:09 volumio volumio[1189]: info: Oct 17 11:15:09 volumio volumio[1189]: ---------------------------- Client requests Volume 65 Oct 17 11:15:09 volumio volumio[1189]: info: VolumeController::SetAlsaVolume65 Oct 17 11:15:09 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:09 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:09 volumio volumio[1476]: [158B blob data] Oct 17 11:15:09 volumio volumio[1476]: {"time":1729152909673,"response":"volume Success"}65 Oct 17 11:15:10 volumio volumio[1476]: 66 Oct 17 11:15:10 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:10 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:10 volumio volumio[1189]: info: Oct 17 11:15:10 volumio volumio[1189]: ---------------------------- Client requests Volume 66 Oct 17 11:15:10 volumio volumio[1189]: info: VolumeController::SetAlsaVolume66 Oct 17 11:15:10 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:10 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:10 volumio volumio[1476]: [158B blob data] Oct 17 11:15:10 volumio volumio[1476]: {"time":1729152910342,"response":"volume Success"}66 Oct 17 11:15:10 volumio volumio[1476]: 67 Oct 17 11:15:10 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:10 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:10 volumio volumio[1189]: info: Oct 17 11:15:10 volumio volumio[1189]: ---------------------------- Client requests Volume 67 Oct 17 11:15:10 volumio volumio[1189]: info: VolumeController::SetAlsaVolume67 Oct 17 11:15:10 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:10 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:10 volumio volumio[1476]: [158B blob data] Oct 17 11:15:10 volumio volumio[1476]: {"time":1729152910597,"response":"volume Success"}67 Oct 17 11:15:11 volumio volumio[1476]: 68 Oct 17 11:15:11 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:11 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:11 volumio volumio[1189]: info: Oct 17 11:15:11 volumio volumio[1189]: ---------------------------- Client requests Volume 68 Oct 17 11:15:11 volumio volumio[1189]: info: VolumeController::SetAlsaVolume68 Oct 17 11:15:11 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:11 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:11 volumio volumio[1476]: [158B blob data] Oct 17 11:15:11 volumio volumio[1476]: {"time":1729152911245,"response":"volume Success"}68 Oct 17 11:15:11 volumio volumio[1476]: 69 Oct 17 11:15:11 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:11 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:11 volumio volumio[1189]: info: Oct 17 11:15:11 volumio volumio[1189]: ---------------------------- Client requests Volume 69 Oct 17 11:15:11 volumio volumio[1189]: info: VolumeController::SetAlsaVolume69 Oct 17 11:15:11 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:11 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:11 volumio volumio[1476]: [237B blob data] Oct 17 11:15:11 volumio volumio[1476]: {"time":1729152911470,"response":"volume Success"}69 Oct 17 11:15:11 volumio volumio[1476]: 70 Oct 17 11:15:11 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:11 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:11 volumio volumio[1189]: info: Oct 17 11:15:11 volumio volumio[1189]: ---------------------------- Client requests Volume 70 Oct 17 11:15:11 volumio volumio[1189]: info: VolumeController::SetAlsaVolume70 Oct 17 11:15:11 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:11 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:11 volumio volumio[1476]: [158B blob data] Oct 17 11:15:11 volumio volumio[1476]: {"time":1729152911696,"response":"volume Success"}70 Oct 17 11:15:11 volumio volumio[1476]: 71 Oct 17 11:15:11 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:11 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:11 volumio volumio[1189]: info: Oct 17 11:15:11 volumio volumio[1189]: ---------------------------- Client requests Volume 71 Oct 17 11:15:11 volumio volumio[1189]: info: VolumeController::SetAlsaVolume71 Oct 17 11:15:11 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:11 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:11 volumio volumio[1476]: [158B blob data] Oct 17 11:15:11 volumio volumio[1476]: {"time":1729152911944,"response":"volume Success"}71 Oct 17 11:15:12 volumio volumio[1476]: 72 Oct 17 11:15:12 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:12 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:12 volumio volumio[1189]: info: Oct 17 11:15:12 volumio volumio[1189]: ---------------------------- Client requests Volume 72 Oct 17 11:15:12 volumio volumio[1189]: info: VolumeController::SetAlsaVolume72 Oct 17 11:15:12 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:12 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:12 volumio volumio[1476]: [158B blob data] Oct 17 11:15:12 volumio volumio[1476]: {"time":1729152912170,"response":"volume Success"}72 Oct 17 11:15:12 volumio volumio[1476]: 73 Oct 17 11:15:12 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:12 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:12 volumio volumio[1189]: info: Oct 17 11:15:12 volumio volumio[1189]: ---------------------------- Client requests Volume 73 Oct 17 11:15:12 volumio volumio[1189]: info: VolumeController::SetAlsaVolume73 Oct 17 11:15:12 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:12 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:12 volumio volumio[1476]: [158B blob data] Oct 17 11:15:12 volumio volumio[1476]: {"time":1729152912395,"response":"volume Success"}73 Oct 17 11:15:12 volumio volumio[1476]: 74 Oct 17 11:15:12 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:12 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:12 volumio volumio[1189]: info: Oct 17 11:15:12 volumio volumio[1189]: ---------------------------- Client requests Volume 74 Oct 17 11:15:12 volumio volumio[1189]: info: VolumeController::SetAlsaVolume74 Oct 17 11:15:12 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:12 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:12 volumio volumio[1476]: [158B blob data] Oct 17 11:15:12 volumio volumio[1476]: {"time":1729152912603,"response":"volume Success"}74 Oct 17 11:15:12 volumio volumio[1476]: 75 Oct 17 11:15:12 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:12 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:12 volumio volumio[1189]: info: Oct 17 11:15:12 volumio volumio[1189]: ---------------------------- Client requests Volume 75 Oct 17 11:15:12 volumio volumio[1189]: info: VolumeController::SetAlsaVolume75 Oct 17 11:15:12 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:12 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:12 volumio volumio[1476]: [158B blob data] Oct 17 11:15:12 volumio volumio[1476]: {"time":1729152912908,"response":"volume Success"}75 Oct 17 11:15:13 volumio volumio[1476]: 76 Oct 17 11:15:13 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:13 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:13 volumio volumio[1189]: info: Oct 17 11:15:13 volumio volumio[1189]: ---------------------------- Client requests Volume 76 Oct 17 11:15:13 volumio volumio[1189]: info: VolumeController::SetAlsaVolume76 Oct 17 11:15:13 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:13 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:13 volumio volumio[1476]: [158B blob data] Oct 17 11:15:13 volumio volumio[1476]: {"time":1729152913084,"response":"volume Success"}76 Oct 17 11:15:13 volumio volumio[1476]: 77 Oct 17 11:15:13 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:13 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:13 volumio volumio[1189]: info: Oct 17 11:15:13 volumio volumio[1189]: ---------------------------- Client requests Volume 77 Oct 17 11:15:13 volumio volumio[1189]: info: VolumeController::SetAlsaVolume77 Oct 17 11:15:13 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:13 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:13 volumio volumio[1476]: [158B blob data] Oct 17 11:15:13 volumio volumio[1476]: {"time":1729152913319,"response":"volume Success"}77 Oct 17 11:15:13 volumio volumio[1476]: 78 Oct 17 11:15:13 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:13 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:13 volumio volumio[1189]: info: Oct 17 11:15:13 volumio volumio[1189]: ---------------------------- Client requests Volume 78 Oct 17 11:15:13 volumio volumio[1189]: info: VolumeController::SetAlsaVolume78 Oct 17 11:15:13 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:13 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:13 volumio volumio[1476]: [158B blob data] Oct 17 11:15:13 volumio volumio[1476]: {"time":1729152913547,"response":"volume Success"}78 Oct 17 11:15:13 volumio volumio[1476]: 79 Oct 17 11:15:13 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:13 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:13 volumio volumio[1189]: info: Oct 17 11:15:13 volumio volumio[1189]: ---------------------------- Client requests Volume 79 Oct 17 11:15:13 volumio volumio[1189]: info: VolumeController::SetAlsaVolume79 Oct 17 11:15:13 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:13 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:13 volumio volumio[1476]: [158B blob data] Oct 17 11:15:13 volumio volumio[1476]: {"time":1729152913780,"response":"volume Success"}79 Oct 17 11:15:13 volumio volumio[1476]: 80 Oct 17 11:15:14 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:14 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:14 volumio volumio[1189]: info: Oct 17 11:15:14 volumio volumio[1189]: ---------------------------- Client requests Volume 80 Oct 17 11:15:14 volumio volumio[1189]: info: VolumeController::SetAlsaVolume80 Oct 17 11:15:14 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:14 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:14 volumio volumio[1476]: [158B blob data] Oct 17 11:15:14 volumio volumio[1476]: {"time":1729152914009,"response":"volume Success"}80 Oct 17 11:15:14 volumio volumio[1476]: 81 Oct 17 11:15:14 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:14 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:14 volumio volumio[1189]: info: Oct 17 11:15:14 volumio volumio[1189]: ---------------------------- Client requests Volume 81 Oct 17 11:15:14 volumio volumio[1189]: info: VolumeController::SetAlsaVolume81 Oct 17 11:15:14 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:14 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:14 volumio volumio[1476]: [158B blob data] Oct 17 11:15:14 volumio volumio[1476]: {"time":1729152914284,"response":"volume Success"}81 Oct 17 11:15:14 volumio volumio[1476]: 82 Oct 17 11:15:14 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:14 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:14 volumio volumio[1189]: info: Oct 17 11:15:14 volumio volumio[1189]: ---------------------------- Client requests Volume 82 Oct 17 11:15:14 volumio volumio[1189]: info: VolumeController::SetAlsaVolume82 Oct 17 11:15:14 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:14 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:14 volumio volumio[1476]: [158B blob data] Oct 17 11:15:14 volumio volumio[1476]: {"time":1729152914620,"response":"volume Success"}82 Oct 17 11:15:15 volumio volumio[1476]: 83 Oct 17 11:15:15 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:15 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:15 volumio volumio[1189]: info: Oct 17 11:15:15 volumio volumio[1189]: ---------------------------- Client requests Volume 83 Oct 17 11:15:15 volumio volumio[1189]: info: VolumeController::SetAlsaVolume83 Oct 17 11:15:15 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:15 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:15 volumio volumio[1476]: [158B blob data] Oct 17 11:15:15 volumio volumio[1476]: {"time":1729152915236,"response":"volume Success"}83 Oct 17 11:15:15 volumio volumio[1476]: 84 Oct 17 11:15:15 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:15 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:15 volumio volumio[1189]: info: Oct 17 11:15:15 volumio volumio[1189]: ---------------------------- Client requests Volume 84 Oct 17 11:15:15 volumio volumio[1189]: info: VolumeController::SetAlsaVolume84 Oct 17 11:15:15 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:15 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:15 volumio volumio[1476]: [237B blob data] Oct 17 11:15:15 volumio volumio[1476]: {"time":1729152915484,"response":"volume Success"}84 Oct 17 11:15:15 volumio volumio[1476]: 85 Oct 17 11:15:15 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:15 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:15 volumio volumio[1189]: info: Oct 17 11:15:15 volumio volumio[1189]: ---------------------------- Client requests Volume 85 Oct 17 11:15:15 volumio volumio[1189]: info: VolumeController::SetAlsaVolume85 Oct 17 11:15:15 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:15 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:15 volumio volumio[1476]: [158B blob data] Oct 17 11:15:15 volumio volumio[1476]: {"time":1729152915753,"response":"volume Success"}85 Oct 17 11:15:15 volumio volumio[1476]: 86 Oct 17 11:15:15 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:15 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:15 volumio volumio[1189]: info: Oct 17 11:15:15 volumio volumio[1189]: ---------------------------- Client requests Volume 86 Oct 17 11:15:15 volumio volumio[1189]: info: VolumeController::SetAlsaVolume86 Oct 17 11:15:16 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:16 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:16 volumio volumio[1476]: [158B blob data] Oct 17 11:15:16 volumio volumio[1476]: {"time":1729152915983,"response":"volume Success"}86 Oct 17 11:15:16 volumio volumio[1476]: 87 Oct 17 11:15:16 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:16 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:16 volumio volumio[1189]: info: Oct 17 11:15:16 volumio volumio[1189]: ---------------------------- Client requests Volume 87 Oct 17 11:15:16 volumio volumio[1189]: info: VolumeController::SetAlsaVolume87 Oct 17 11:15:16 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:16 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:16 volumio volumio[1476]: [158B blob data] Oct 17 11:15:16 volumio volumio[1476]: {"time":1729152916220,"response":"volume Success"}87 Oct 17 11:15:16 volumio volumio[1476]: 88 Oct 17 11:15:16 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:16 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:16 volumio volumio[1189]: info: Oct 17 11:15:16 volumio volumio[1189]: ---------------------------- Client requests Volume 88 Oct 17 11:15:16 volumio volumio[1189]: info: VolumeController::SetAlsaVolume88 Oct 17 11:15:16 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:16 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:16 volumio volumio[1476]: [158B blob data] Oct 17 11:15:16 volumio volumio[1476]: {"time":1729152916442,"response":"volume Success"}88 Oct 17 11:15:16 volumio volumio[1476]: 89 Oct 17 11:15:16 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:16 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:16 volumio volumio[1189]: info: Oct 17 11:15:16 volumio volumio[1189]: ---------------------------- Client requests Volume 89 Oct 17 11:15:16 volumio volumio[1189]: info: VolumeController::SetAlsaVolume89 Oct 17 11:15:16 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:16 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:16 volumio volumio[1476]: [158B blob data] Oct 17 11:15:16 volumio volumio[1476]: {"time":1729152916684,"response":"volume Success"}89 Oct 17 11:15:16 volumio volumio[1476]: 90 Oct 17 11:15:16 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:16 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:16 volumio volumio[1189]: info: Oct 17 11:15:16 volumio volumio[1189]: ---------------------------- Client requests Volume 90 Oct 17 11:15:16 volumio volumio[1189]: info: VolumeController::SetAlsaVolume90 Oct 17 11:15:16 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:16 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:16 volumio volumio[1476]: [158B blob data] Oct 17 11:15:16 volumio volumio[1476]: {"time":1729152916905,"response":"volume Success"}90 Oct 17 11:15:17 volumio volumio[1476]: 91 Oct 17 11:15:17 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:17 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:17 volumio volumio[1189]: info: Oct 17 11:15:17 volumio volumio[1189]: ---------------------------- Client requests Volume 91 Oct 17 11:15:17 volumio volumio[1189]: info: VolumeController::SetAlsaVolume91 Oct 17 11:15:17 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:17 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:17 volumio volumio[1476]: [158B blob data] Oct 17 11:15:17 volumio volumio[1476]: {"time":1729152917210,"response":"volume Success"}91 Oct 17 11:15:17 volumio volumio[1476]: 92 Oct 17 11:15:17 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:17 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:17 volumio volumio[1189]: info: Oct 17 11:15:17 volumio volumio[1189]: ---------------------------- Client requests Volume 92 Oct 17 11:15:17 volumio volumio[1189]: info: VolumeController::SetAlsaVolume92 Oct 17 11:15:17 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:17 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:17 volumio volumio[1476]: [158B blob data] Oct 17 11:15:17 volumio volumio[1476]: {"time":1729152917385,"response":"volume Success"}92 Oct 17 11:15:17 volumio volumio[1476]: 93 Oct 17 11:15:17 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:17 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:17 volumio volumio[1189]: info: Oct 17 11:15:17 volumio volumio[1189]: ---------------------------- Client requests Volume 93 Oct 17 11:15:17 volumio volumio[1189]: info: VolumeController::SetAlsaVolume93 Oct 17 11:15:17 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:17 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:17 volumio volumio[1476]: [158B blob data] Oct 17 11:15:17 volumio volumio[1476]: {"time":1729152917636,"response":"volume Success"}93 Oct 17 11:15:17 volumio volumio[1476]: 94 Oct 17 11:15:17 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:17 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:17 volumio volumio[1189]: info: Oct 17 11:15:17 volumio volumio[1189]: ---------------------------- Client requests Volume 94 Oct 17 11:15:17 volumio volumio[1189]: info: VolumeController::SetAlsaVolume94 Oct 17 11:15:17 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:17 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:17 volumio volumio[1476]: [158B blob data] Oct 17 11:15:17 volumio volumio[1476]: {"time":1729152917899,"response":"volume Success"}94 Oct 17 11:15:18 volumio volumio[1476]: 95 Oct 17 11:15:18 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:18 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:18 volumio volumio[1189]: info: Oct 17 11:15:18 volumio volumio[1189]: ---------------------------- Client requests Volume 95 Oct 17 11:15:18 volumio volumio[1189]: info: VolumeController::SetAlsaVolume95 Oct 17 11:15:18 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:18 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:18 volumio volumio[1476]: [158B blob data] Oct 17 11:15:18 volumio volumio[1476]: {"time":1729152918124,"response":"volume Success"}95 Oct 17 11:15:18 volumio volumio[1476]: 96 Oct 17 11:15:18 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:18 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:18 volumio volumio[1189]: info: Oct 17 11:15:18 volumio volumio[1189]: ---------------------------- Client requests Volume 96 Oct 17 11:15:18 volumio volumio[1189]: info: VolumeController::SetAlsaVolume96 Oct 17 11:15:18 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:18 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:18 volumio volumio[1476]: [158B blob data] Oct 17 11:15:18 volumio volumio[1476]: {"time":1729152918341,"response":"volume Success"}96 Oct 17 11:15:18 volumio volumio[1476]: 97 Oct 17 11:15:18 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:18 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:18 volumio volumio[1189]: info: Oct 17 11:15:18 volumio volumio[1189]: ---------------------------- Client requests Volume 97 Oct 17 11:15:18 volumio volumio[1189]: info: VolumeController::SetAlsaVolume97 Oct 17 11:15:18 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:18 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:18 volumio volumio[1476]: [158B blob data] Oct 17 11:15:18 volumio volumio[1476]: {"time":1729152918582,"response":"volume Success"}97 Oct 17 11:15:18 volumio volumio[1476]: 98 Oct 17 11:15:18 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:18 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:18 volumio volumio[1189]: info: Oct 17 11:15:18 volumio volumio[1189]: ---------------------------- Client requests Volume 98 Oct 17 11:15:18 volumio volumio[1189]: info: VolumeController::SetAlsaVolume98 Oct 17 11:15:18 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:18 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:18 volumio volumio[1476]: [158B blob data] Oct 17 11:15:18 volumio volumio[1476]: {"time":1729152918825,"response":"volume Success"}98 Oct 17 11:15:19 volumio volumio[1476]: 99 Oct 17 11:15:19 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:19 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:19 volumio volumio[1189]: info: Oct 17 11:15:19 volumio volumio[1189]: ---------------------------- Client requests Volume 99 Oct 17 11:15:19 volumio volumio[1189]: info: VolumeController::SetAlsaVolume99 Oct 17 11:15:19 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:19 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:19 volumio volumio[1476]: [158B blob data] Oct 17 11:15:19 volumio volumio[1476]: {"time":1729152919094,"response":"volume Success"}99 Oct 17 11:15:19 volumio volumio[1476]: 100 Oct 17 11:15:19 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:19 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:19 volumio volumio[1189]: info: Oct 17 11:15:19 volumio volumio[1189]: ---------------------------- Client requests Volume 100 Oct 17 11:15:19 volumio volumio[1189]: info: VolumeController::SetAlsaVolume100 Oct 17 11:15:19 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:19 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:19 volumio volumio[1476]: [158B blob data] Oct 17 11:15:19 volumio volumio[1476]: {"time":1729152919335,"response":"volume Success"}100 Oct 17 11:15:20 volumio wpa_supplicant[1104]: wlan0: WPA: Group rekeying completed with 24:43:e2:63:3a:17 [GTK=CCMP] Oct 17 11:15:21 volumio volumio[1189]: info: Oct 17 11:15:21 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:21 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:21 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:21 volumio volumio[1189]: info: Oct 17 11:15:21 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:21 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:21 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:21 volumio volumio[1189]: info: Oct 17 11:15:21 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:21 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:21 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:21 volumio volumio[1189]: info: Oct 17 11:15:21 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:21 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:21 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:21 volumio volumio[1189]: info: Oct 17 11:15:21 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:21 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:21 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:21 volumio volumio[1189]: info: sendMpdCommand status took 14 milliseconds Oct 17 11:15:21 volumio volumio[1189]: info: sendMpdCommand status took 6 milliseconds Oct 17 11:15:21 volumio volumio[1189]: info: sendMpdCommand status took 4 milliseconds Oct 17 11:15:21 volumio volumio[1189]: info: sendMpdCommand status took 3 milliseconds Oct 17 11:15:21 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:21 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:21 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:21 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:21 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:21 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:21 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:21 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:21 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:21 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:21 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 17 11:15:21 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 17 11:15:21 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 17 11:15:21 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:21 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:21 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:21 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:21 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:21 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:21 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:21 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:21 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:21 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:21 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:21 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:21 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:21 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:21 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:21 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:21 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:21 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:21 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:21 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:21 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:21 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:21 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:21 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:21 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:21 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:21 volumio volumio[1189]: info: ------------------------------ 39ms Oct 17 11:15:21 volumio volumio[1189]: info: ------------------------------ 30ms Oct 17 11:15:21 volumio volumio[1189]: info: ------------------------------ 29ms Oct 17 11:15:21 volumio volumio[1189]: info: ------------------------------ 27ms Oct 17 11:15:21 volumio volumio[1189]: info: ------------------------------ 26ms Oct 17 11:15:22 volumio volumio[1189]: info: Clearing queue after UPNP request Oct 17 11:15:22 volumio volumio[1189]: info: CoreStateMachine::ClearQueue Oct 17 11:15:22 volumio volumio[1189]: info: CoreStateMachine::stop Oct 17 11:15:22 volumio volumio[1189]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:15:22 volumio volumio[1189]: info: CoreStateMachine::stPlaybackTimer Oct 17 11:15:22 volumio volumio[1189]: info: CoreStateMachine::updateTrackBlock Oct 17 11:15:22 volumio volumio[1189]: info: CorePlayQueue::getTrackBlock Oct 17 11:15:22 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:22 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:22 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 11:15:22 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Oct 17 11:15:22 volumio volumio[1189]: info: CoreStateMachine::serviceStop Oct 17 11:15:22 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:22 volumio volumio[1189]: info: ControllerMpd::stop Oct 17 11:15:22 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 11:15:22 volumio volumio[1189]: info: CorePlayQueue::clearPlayQueue Oct 17 11:15:22 volumio volumio[1189]: info: CorePlayQueue::saveQueue Oct 17 11:15:22 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Oct 17 11:15:22 volumio volumio[1189]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: sendMpdCommand stop took 24 milliseconds Oct 17 11:15:22 volumio volumio[1189]: error: updateQueue error: null Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: error: updateQueue error: null Oct 17 11:15:22 volumio volumio[1189]: error: updateQueue error: null Oct 17 11:15:22 volumio volumio[1189]: error: updateQueue error: null Oct 17 11:15:22 volumio volumio[1189]: error: updateQueue error: null Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:22 volumio volumio[1189]: info: Starting UPNP Playback Oct 17 11:15:22 volumio volumio[1189]: info: Preparing playback through UPNP Oct 17 11:15:22 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 17 11:15:22 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:22 volumio volumio[1189]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 7ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 2ms Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 2ms Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: Oct 17 11:15:22 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:22 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:22 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:23 volumio volumio[1189]: info: Oct 17 11:15:23 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:23 volumio volumio[1189]: info: Oct 17 11:15:23 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:23 volumio volumio[1189]: info: Oct 17 11:15:23 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:23 volumio volumio[1189]: info: Oct 17 11:15:23 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:23 volumio volumio[1189]: info: Oct 17 11:15:23 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:23 volumio volumio[1189]: info: Oct 17 11:15:23 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand status took 14 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand status took 13 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand status took 13 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand status took 13 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand status took 10 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand status took 3 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:23 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:23 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"zed-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:23 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus stop Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:23 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:23 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"zed-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:23 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:23 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:23 volumio volumio[1189]: info: ------------------------------ 27ms Oct 17 11:15:23 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:23 volumio volumio[1189]: info: ------------------------------ 26ms Oct 17 11:15:23 volumio volumio[1189]: info: Oct 17 11:15:23 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:23 volumio volumio[1189]: info: Oct 17 11:15:23 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:23 volumio volumio[1189]: info: Oct 17 11:15:23 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:23 volumio volumio[1189]: info: Oct 17 11:15:23 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand status took 7 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand status took 6 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand status took 4 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand status took 4 milliseconds Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:23 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:23 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"zed-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:23 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:23 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:23 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:23 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"zed-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:23 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:23 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:23 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:23 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"zed-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:23 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:23 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:23 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:23 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"zed-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:23 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:23 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:23 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:23 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:23 volumio volumio[1189]: info: ------------------------------ 53ms Oct 17 11:15:23 volumio volumio[1189]: info: ------------------------------ 50ms Oct 17 11:15:23 volumio volumio[1189]: info: ------------------------------ 49ms Oct 17 11:15:23 volumio volumio[1189]: info: ------------------------------ 41ms Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 17 11:15:23 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:23 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:23 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:23 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"zed-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:23 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:23 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:23 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:23 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"zed-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:23 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:23 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:23 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:23 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"zed-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:23 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:23 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:23 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:23 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:23 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"zed-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:23 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:23 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:23 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:23 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:23 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:23 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:23 volumio volumio[1189]: info: ------------------------------ 45ms Oct 17 11:15:23 volumio volumio[1189]: info: ------------------------------ 44ms Oct 17 11:15:23 volumio volumio[1189]: info: ------------------------------ 43ms Oct 17 11:15:23 volumio volumio[1189]: info: ------------------------------ 42ms Oct 17 11:15:24 volumio volumio[1189]: info: Oct 17 11:15:24 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:24 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:24 volumio volumio[1189]: info: Oct 17 11:15:24 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:24 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:24 volumio volumio[1189]: info: Oct 17 11:15:24 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:24 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:24 volumio volumio[1189]: info: Oct 17 11:15:24 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:24 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:24 volumio volumio[1189]: info: Oct 17 11:15:24 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:24 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:24 volumio volumio[1189]: info: Oct 17 11:15:24 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:24 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:24 volumio volumio[1189]: info: Oct 17 11:15:24 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:24 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:24 volumio volumio[1189]: info: Oct 17 11:15:24 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:24 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:24 volumio volumio[1189]: info: Oct 17 11:15:24 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:24 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:24 volumio volumio[1189]: info: Oct 17 11:15:24 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:24 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:24 volumio volumio[1189]: info: ------------------------------ 14ms Oct 17 11:15:24 volumio volumio[1189]: info: sendMpdCommand status took 12 milliseconds Oct 17 11:15:24 volumio volumio[1189]: info: ------------------------------ 12ms Oct 17 11:15:24 volumio volumio[1189]: info: sendMpdCommand status took 12 milliseconds Oct 17 11:15:24 volumio volumio[1189]: info: ------------------------------ 11ms Oct 17 11:15:24 volumio volumio[1189]: info: sendMpdCommand status took 10 milliseconds Oct 17 11:15:24 volumio volumio[1189]: info: ------------------------------ 8ms Oct 17 11:15:24 volumio volumio[1189]: info: sendMpdCommand status took 7 milliseconds Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:24 volumio volumio[1189]: info: ------------------------------ 9ms Oct 17 11:15:24 volumio volumio[1189]: info: sendMpdCommand status took 8 milliseconds Oct 17 11:15:24 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 11:15:24 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 17 11:15:24 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 11:15:24 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:24 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:24 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:24 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:24 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":546,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","artist":"SECTOR | Zed channel","album":null,"uri":"http://89.223.45.5:8000/zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:24 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:24 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:24 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:24 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 17 11:15:24 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:24 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:24 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:24 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:24 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","artist":"SECTOR | Zed channel","album":null,"uri":"http://89.223.45.5:8000/zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:24 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:24 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:24 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:24 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 17 11:15:24 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:24 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:24 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:24 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:24 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":899,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","artist":"SECTOR | Zed channel","album":null,"uri":"http://89.223.45.5:8000/zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:24 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:24 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:24 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:24 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 17 11:15:24 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:24 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:24 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:24 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:24 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","artist":"SECTOR | Zed channel","album":null,"uri":"http://89.223.45.5:8000/zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:24 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:24 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:24 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:24 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 17 11:15:24 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:24 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:24 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:24 volumio volumio[1189]: info: ------------------------------ 32ms Oct 17 11:15:24 volumio volumio[1189]: info: ------------------------------ 30ms Oct 17 11:15:24 volumio volumio[1189]: info: ------------------------------ 29ms Oct 17 11:15:24 volumio volumio[1189]: info: ------------------------------ 26ms Oct 17 11:15:24 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 17 11:15:24 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:24 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:24 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:24 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:24 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","artist":"SECTOR | Zed channel","album":null,"uri":"http://89.223.45.5:8000/zed-flac?_mcpm_iid=1729152278728&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:24 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:24 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:24 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:24 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 17 11:15:24 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:24 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:24 volumio volumio[1189]: info: ------------------------------ 28ms Oct 17 11:15:25 volumio volumio[1189]: info: Oct 17 11:15:25 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:25 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:25 volumio volumio[1189]: info: Oct 17 11:15:25 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:25 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:25 volumio volumio[1189]: info: Oct 17 11:15:25 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:25 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:25 volumio volumio[1189]: info: Oct 17 11:15:25 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:25 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:25 volumio volumio[1189]: info: Oct 17 11:15:25 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:25 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:25 volumio volumio[1189]: info: sendMpdCommand status took 8 milliseconds Oct 17 11:15:25 volumio volumio[1189]: info: sendMpdCommand status took 7 milliseconds Oct 17 11:15:25 volumio volumio[1189]: info: sendMpdCommand status took 6 milliseconds Oct 17 11:15:25 volumio volumio[1189]: info: sendMpdCommand status took 5 milliseconds Oct 17 11:15:25 volumio volumio[1189]: info: sendMpdCommand status took 2 milliseconds Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:25 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 11:15:25 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 11:15:25 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 11:15:25 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 11:15:25 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:25 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:25 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:25 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:25 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:25 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:25 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:25 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:25 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:25 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:25 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:25 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:25 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:25 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:25 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:25 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:25 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:25 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:25 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:25 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:25 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:25 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:25 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:25 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:25 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:25 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:25 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:25 volumio volumio[1189]: info: ------------------------------ 25ms Oct 17 11:15:25 volumio volumio[1189]: info: ------------------------------ 23ms Oct 17 11:15:25 volumio volumio[1189]: info: ------------------------------ 22ms Oct 17 11:15:25 volumio volumio[1189]: info: ------------------------------ 21ms Oct 17 11:15:25 volumio volumio[1189]: info: ------------------------------ 19ms Oct 17 11:15:25 volumio volumio[1189]: info: Clearing queue after UPNP request Oct 17 11:15:26 volumio volumio[1189]: info: CoreStateMachine::ClearQueue Oct 17 11:15:26 volumio volumio[1189]: info: CoreStateMachine::stop Oct 17 11:15:26 volumio volumio[1189]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:15:26 volumio volumio[1189]: info: CoreStateMachine::stPlaybackTimer Oct 17 11:15:26 volumio volumio[1189]: info: CoreStateMachine::updateTrackBlock Oct 17 11:15:26 volumio volumio[1189]: info: CorePlayQueue::getTrackBlock Oct 17 11:15:26 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:26 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:26 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Oct 17 11:15:26 volumio volumio[1189]: info: CoreStateMachine::serviceStop Oct 17 11:15:26 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:26 volumio volumio[1189]: info: ControllerMpd::stop Oct 17 11:15:26 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 11:15:26 volumio volumio[1189]: info: CorePlayQueue::clearPlayQueue Oct 17 11:15:26 volumio volumio[1189]: info: CorePlayQueue::saveQueue Oct 17 11:15:26 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Oct 17 11:15:26 volumio volumio[1189]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: sendMpdCommand stop took 29 milliseconds Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: error: updateQueue error: null Oct 17 11:15:26 volumio volumio[1189]: error: updateQueue error: null Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 8ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:26 volumio volumio[1189]: info: Starting UPNP Playback Oct 17 11:15:26 volumio volumio[1189]: info: Preparing playback through UPNP Oct 17 11:15:26 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 17 11:15:26 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:26 volumio volumio[1189]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 9ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 7ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: Oct 17 11:15:26 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:26 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 7ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:26 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:27 volumio volumio[1189]: info: Oct 17 11:15:27 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:27 volumio volumio[1189]: info: Oct 17 11:15:27 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:27 volumio volumio[1189]: info: Oct 17 11:15:27 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:27 volumio volumio[1189]: info: Oct 17 11:15:27 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:27 volumio volumio[1189]: info: Oct 17 11:15:27 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:27 volumio volumio[1189]: info: Oct 17 11:15:27 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand status took 27 milliseconds Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand status took 27 milliseconds Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand status took 27 milliseconds Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand status took 30 milliseconds Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand status took 6 milliseconds Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:27 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:27 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:27 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus stop Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:27 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:27 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:27 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:27 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:27 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:27 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:27 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:27 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:27 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:27 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:27 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:27 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:27 volumio volumio[1189]: info: ------------------------------ 52ms Oct 17 11:15:27 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:27 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:27 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:27 volumio volumio[1189]: info: ------------------------------ 50ms Oct 17 11:15:27 volumio volumio[1189]: info: ------------------------------ 49ms Oct 17 11:15:27 volumio volumio[1189]: info: ------------------------------ 48ms Oct 17 11:15:27 volumio volumio[1189]: info: Oct 17 11:15:27 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:27 volumio volumio[1189]: info: Oct 17 11:15:27 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:27 volumio volumio[1189]: info: Oct 17 11:15:27 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:27 volumio volumio[1189]: info: Oct 17 11:15:27 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 17 milliseconds Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand status took 6 milliseconds Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand status took 4 milliseconds Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand status took 3 milliseconds Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand status took 2 milliseconds Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:27 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:27 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:27 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:27 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:27 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:27 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:27 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:27 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:27 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:27 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:27 volumio volumio[1189]: info: ------------------------------ 62ms Oct 17 11:15:27 volumio volumio[1189]: info: ------------------------------ 39ms Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 17 11:15:27 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:27 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:27 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:27 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:27 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:27 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:27 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:27 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:27 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:27 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:27 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:27 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:27 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:27 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:27 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:27 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:27 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:27 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:27 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:27 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:27 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:27 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:27 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:27 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:27 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:27 volumio volumio[1189]: info: ------------------------------ 25ms Oct 17 11:15:27 volumio volumio[1189]: info: ------------------------------ 25ms Oct 17 11:15:27 volumio volumio[1189]: info: ------------------------------ 24ms Oct 17 11:15:27 volumio volumio[1189]: info: ------------------------------ 23ms Oct 17 11:15:28 volumio volumio[1189]: info: Oct 17 11:15:28 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:28 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:28 volumio volumio[1189]: info: Oct 17 11:15:28 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:28 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:28 volumio volumio[1189]: info: Oct 17 11:15:28 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:28 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:28 volumio volumio[1189]: info: Oct 17 11:15:28 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:28 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:28 volumio volumio[1189]: info: Oct 17 11:15:28 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:28 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:28 volumio volumio[1189]: info: Oct 17 11:15:28 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:28 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:28 volumio volumio[1189]: info: Oct 17 11:15:28 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:28 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:28 volumio volumio[1189]: info: Oct 17 11:15:28 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:28 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:28 volumio volumio[1189]: info: ------------------------------ 8ms Oct 17 11:15:28 volumio volumio[1189]: info: sendMpdCommand status took 7 milliseconds Oct 17 11:15:28 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:28 volumio volumio[1189]: info: sendMpdCommand status took 7 milliseconds Oct 17 11:15:28 volumio volumio[1189]: info: ------------------------------ 8ms Oct 17 11:15:28 volumio volumio[1189]: info: sendMpdCommand status took 8 milliseconds Oct 17 11:15:28 volumio volumio[1189]: info: ------------------------------ 7ms Oct 17 11:15:28 volumio volumio[1189]: info: sendMpdCommand status took 7 milliseconds Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:28 volumio volumio[1189]: info: Oct 17 11:15:28 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:28 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:28 volumio volumio[1189]: info: Oct 17 11:15:28 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:28 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:28 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 17 11:15:28 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 17 11:15:28 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 17 11:15:28 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 17 11:15:28 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:28 volumio volumio[1189]: info: sendMpdCommand status took 2 milliseconds Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:28 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:28 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:28 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:28 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","artist":"SECTOR | Progressive channel","album":null,"uri":"http://89.223.45.5:8000/progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:28 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:28 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:28 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:28 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 17 11:15:28 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:28 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:28 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:28 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:28 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:28 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","artist":"SECTOR | Progressive channel","album":null,"uri":"http://89.223.45.5:8000/progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:28 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:28 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:28 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:28 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 17 11:15:28 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:28 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:28 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:28 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:28 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:28 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","artist":"SECTOR | Progressive channel","album":null,"uri":"http://89.223.45.5:8000/progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:28 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:28 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:28 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:28 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 17 11:15:28 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:28 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:28 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:28 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:28 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:28 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","artist":"SECTOR | Progressive channel","album":null,"uri":"http://89.223.45.5:8000/progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:28 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:28 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:28 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:28 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 17 11:15:28 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:28 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:28 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:28 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:28 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:28 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:28 volumio volumio[1189]: info: ------------------------------ 33ms Oct 17 11:15:28 volumio volumio[1189]: info: ------------------------------ 32ms Oct 17 11:15:28 volumio volumio[1189]: info: ------------------------------ 30ms Oct 17 11:15:28 volumio volumio[1189]: info: ------------------------------ 29ms Oct 17 11:15:28 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 17 11:15:28 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:28 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:28 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:28 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:28 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","artist":"SECTOR | Progressive channel","album":null,"uri":"http://89.223.45.5:8000/progressive-flac?_mcpm_iid=1729152278730&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:28 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:28 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:28 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:28 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 17 11:15:28 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:28 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:28 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:28 volumio volumio[1189]: info: ------------------------------ 22ms Oct 17 11:15:30 volumio volumio[1189]: info: Oct 17 11:15:30 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:30 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:30 volumio volumio[1189]: info: Oct 17 11:15:30 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:30 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:30 volumio volumio[1189]: info: Oct 17 11:15:30 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:30 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:30 volumio volumio[1189]: info: Oct 17 11:15:30 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:30 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:30 volumio volumio[1189]: info: Oct 17 11:15:30 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:30 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:30 volumio volumio[1189]: info: sendMpdCommand status took 6 milliseconds Oct 17 11:15:30 volumio volumio[1189]: info: sendMpdCommand status took 5 milliseconds Oct 17 11:15:30 volumio volumio[1189]: info: sendMpdCommand status took 4 milliseconds Oct 17 11:15:30 volumio volumio[1189]: info: sendMpdCommand status took 3 milliseconds Oct 17 11:15:30 volumio volumio[1189]: info: sendMpdCommand status took 2 milliseconds Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:30 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 17 11:15:30 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 17 11:15:30 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 17 11:15:30 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:30 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:30 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:30 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:30 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:30 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:30 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:30 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:30 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:30 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:30 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:30 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:30 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:30 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:30 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:30 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:30 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:30 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:30 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:30 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:30 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:30 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:30 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:30 volumio volumio[1189]: info: ------------------------------ 48ms Oct 17 11:15:30 volumio volumio[1189]: info: ------------------------------ 47ms Oct 17 11:15:30 volumio volumio[1189]: info: ------------------------------ 46ms Oct 17 11:15:30 volumio volumio[1189]: info: ------------------------------ 45ms Oct 17 11:15:30 volumio volumio[1189]: info: ------------------------------ 44ms Oct 17 11:15:30 volumio volumio[1189]: info: Clearing queue after UPNP request Oct 17 11:15:30 volumio volumio[1189]: info: CoreStateMachine::ClearQueue Oct 17 11:15:30 volumio volumio[1189]: info: CoreStateMachine::stop Oct 17 11:15:30 volumio volumio[1189]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:15:30 volumio volumio[1189]: info: CoreStateMachine::stPlaybackTimer Oct 17 11:15:30 volumio volumio[1189]: info: CoreStateMachine::updateTrackBlock Oct 17 11:15:30 volumio volumio[1189]: info: CorePlayQueue::getTrackBlock Oct 17 11:15:30 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:30 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:30 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Oct 17 11:15:30 volumio volumio[1189]: info: CoreStateMachine::serviceStop Oct 17 11:15:30 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:30 volumio volumio[1189]: info: ControllerMpd::stop Oct 17 11:15:30 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 11:15:30 volumio volumio[1189]: info: CorePlayQueue::clearPlayQueue Oct 17 11:15:30 volumio volumio[1189]: info: CorePlayQueue::saveQueue Oct 17 11:15:30 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Oct 17 11:15:30 volumio volumio[1189]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:15:30 volumio volumio[1189]: info: Oct 17 11:15:30 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:30 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:30 volumio volumio[1189]: info: sendMpdCommand stop took 23 milliseconds Oct 17 11:15:30 volumio volumio[1189]: error: updateQueue error: null Oct 17 11:15:30 volumio volumio[1189]: info: ------------------------------ 2ms Oct 17 11:15:30 volumio volumio[1189]: info: Oct 17 11:15:30 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:30 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:30 volumio volumio[1189]: info: Oct 17 11:15:30 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:30 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:30 volumio volumio[1189]: info: Oct 17 11:15:30 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:30 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:30 volumio volumio[1189]: info: Oct 17 11:15:30 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:30 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:30 volumio volumio[1189]: error: updateQueue error: null Oct 17 11:15:30 volumio volumio[1189]: error: updateQueue error: null Oct 17 11:15:30 volumio volumio[1189]: error: updateQueue error: null Oct 17 11:15:30 volumio volumio[1189]: error: updateQueue error: null Oct 17 11:15:30 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:30 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:30 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:30 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:30 volumio volumio[1189]: info: Starting UPNP Playback Oct 17 11:15:30 volumio volumio[1189]: info: Preparing playback through UPNP Oct 17 11:15:30 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 17 11:15:30 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:30 volumio volumio[1189]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 2ms Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 7ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: Oct 17 11:15:31 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:31 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 6ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 5ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 4ms Oct 17 11:15:31 volumio volumio[1189]: info: ------------------------------ 3ms Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand status took 26 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand status took 28 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand status took 27 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand status took 26 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand status took 25 milliseconds Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand status took 11 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand status took 6 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand status took 5 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand status took 5 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand status took 4 milliseconds Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:32 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:32 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"space-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:32 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus stop Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:32 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:32 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"space-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:32 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:32 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:32 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:32 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"space-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:32 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:32 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:32 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:32 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"space-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:32 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:32 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:32 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:32 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"space-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:32 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:32 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:32 volumio volumio[1189]: info: ------------------------------ 58ms Oct 17 11:15:32 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:32 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:32 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:32 volumio volumio[1189]: info: ------------------------------ 58ms Oct 17 11:15:32 volumio volumio[1189]: info: ------------------------------ 56ms Oct 17 11:15:32 volumio volumio[1189]: info: ------------------------------ 56ms Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:32 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:32 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"space-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:32 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:32 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:32 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:32 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"space-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:32 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:32 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:32 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:32 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"space-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:32 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:32 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:32 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:32 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"space-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:32 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:32 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:32 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:32 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"space-flac","artist":null,"album":null,"uri":"http://89.223.45.5:8000/space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:32 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:32 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:32 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:32 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:32 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:32 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:32 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:32 volumio volumio[1189]: info: ------------------------------ 49ms Oct 17 11:15:32 volumio volumio[1189]: info: ------------------------------ 43ms Oct 17 11:15:32 volumio volumio[1189]: info: ------------------------------ 42ms Oct 17 11:15:32 volumio volumio[1189]: info: ------------------------------ 40ms Oct 17 11:15:32 volumio volumio[1189]: info: ------------------------------ 39ms Oct 17 11:15:32 volumio wpa_supplicant[1104]: wlan0: WPA: Group rekeying completed with 24:43:e2:63:3a:17 [GTK=CCMP] Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:32 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:32 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:32 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:32 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 17 11:15:32 volumio volumio[1189]: info: Ignoring MPD Status Update Oct 17 11:15:32 volumio volumio[1189]: info: Oct 17 11:15:32 volumio volumio[1189]: ---------------------------- MPD announces state update: player Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::getState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:15:32 volumio volumio[1189]: info: ------------------------------ 13ms Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand status took 13 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: ------------------------------ 13ms Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand status took 12 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: ------------------------------ 10ms Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand status took 9 milliseconds Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:32 volumio volumio[1189]: info: ------------------------------ 11ms Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand status took 10 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: ------------------------------ 10ms Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand status took 9 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 17 11:15:32 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseState Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:15:32 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:32 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:32 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:32 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:32 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","artist":"SECTOR | Space channel","album":null,"uri":"http://89.223.45.5:8000/space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:32 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:32 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:32 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 17 11:15:32 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:33 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:33 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:33 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:33 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:33 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","artist":"SECTOR | Space channel","album":null,"uri":"http://89.223.45.5:8000/space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:33 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:33 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:33 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:33 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:33 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 17 11:15:33 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:33 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:33 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:33 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:33 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:33 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","artist":"SECTOR | Space channel","album":null,"uri":"http://89.223.45.5:8000/space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:33 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:33 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:33 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:33 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:33 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 17 11:15:33 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:33 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:33 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:33 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:33 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:33 volumio volumio[1189]: info: ------------------------------ 46ms Oct 17 11:15:33 volumio volumio[1189]: info: ------------------------------ 52ms Oct 17 11:15:33 volumio volumio[1189]: info: ------------------------------ 50ms Oct 17 11:15:33 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 17 11:15:33 volumio volumio[1189]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 17 11:15:33 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:33 volumio volumio[1189]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:15:33 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:33 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:33 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:33 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","artist":"SECTOR | Space channel","album":null,"uri":"http://89.223.45.5:8000/space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:33 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:33 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:33 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:33 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:33 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 17 11:15:33 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:33 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:33 volumio volumio[1189]: info: ControllerMpd::pushState Oct 17 11:15:33 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 17 11:15:33 volumio volumio[1189]: verbose: In UPNP mode Oct 17 11:15:33 volumio volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","artist":"SECTOR | Space channel","album":null,"uri":"http://89.223.45.5:8000/space-flac?_mcpm_iid=1729152278739&_mcpm_cpname=Hi-Fi%20Cast%3A%20SM-A325F&_mcpm_cpid=31fbcca0-aed8-44ca-9d97-d82014e1b978","trackType":""} Oct 17 11:15:33 volumio volumio[1189]: verbose: CURRENT POSITION 0 Oct 17 11:15:33 volumio volumio[1189]: info: CoreStateMachine::syncState stateService play Oct 17 11:15:33 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus play Oct 17 11:15:33 volumio volumio[1189]: info: Received an update from plugin. extracting info from payload Oct 17 11:15:33 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 17 11:15:33 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:33 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:33 volumio volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 17 11:15:33 volumio volumio[1189]: info: ------------------------------ 67ms Oct 17 11:15:33 volumio volumio[1189]: info: ------------------------------ 65ms Oct 17 11:15:34 volumio volumio[1476]: 99 Oct 17 11:15:34 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:34 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:34 volumio volumio[1189]: info: Oct 17 11:15:34 volumio volumio[1189]: ---------------------------- Client requests Volume 99 Oct 17 11:15:34 volumio volumio[1189]: info: VolumeController::SetAlsaVolume99 Oct 17 11:15:34 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:34 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:34 volumio volumio[1476]: [158B blob data] Oct 17 11:15:34 volumio volumio[1476]: {"time":1729152934929,"response":"volume Success"}99 Oct 17 11:15:36 volumio volumio[1476]: 98 Oct 17 11:15:36 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:36 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:36 volumio volumio[1189]: info: Oct 17 11:15:36 volumio volumio[1189]: ---------------------------- Client requests Volume 98 Oct 17 11:15:36 volumio volumio[1189]: info: VolumeController::SetAlsaVolume98 Oct 17 11:15:36 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:36 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:36 volumio volumio[1476]: [158B blob data] Oct 17 11:15:36 volumio volumio[1476]: {"time":1729152936063,"response":"volume Success"}98 Oct 17 11:15:36 volumio volumio[1476]: 97 Oct 17 11:15:36 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:36 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:36 volumio volumio[1189]: info: Oct 17 11:15:36 volumio volumio[1189]: ---------------------------- Client requests Volume 97 Oct 17 11:15:36 volumio volumio[1189]: info: VolumeController::SetAlsaVolume97 Oct 17 11:15:36 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:36 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:36 volumio volumio[1476]: [158B blob data] Oct 17 11:15:36 volumio volumio[1476]: {"time":1729152936243,"response":"volume Success"}97 Oct 17 11:15:37 volumio volumio[1476]: 96 Oct 17 11:15:37 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:37 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:37 volumio volumio[1189]: info: Oct 17 11:15:37 volumio volumio[1189]: ---------------------------- Client requests Volume 96 Oct 17 11:15:37 volumio volumio[1189]: info: VolumeController::SetAlsaVolume96 Oct 17 11:15:37 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:37 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:37 volumio volumio[1476]: [158B blob data] Oct 17 11:15:37 volumio volumio[1476]: {"time":1729152937741,"response":"volume Success"}96 Oct 17 11:15:37 volumio volumio[1476]: 95 Oct 17 11:15:37 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:37 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:37 volumio volumio[1189]: info: Oct 17 11:15:37 volumio volumio[1189]: ---------------------------- Client requests Volume 95 Oct 17 11:15:37 volumio volumio[1189]: info: VolumeController::SetAlsaVolume95 Oct 17 11:15:38 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:38 volumio volumio[1476]: [158B blob data] Oct 17 11:15:38 volumio volumio[1476]: {"time":1729152937993,"response":"volume Success"}95 Oct 17 11:15:38 volumio volumio[1476]: 94 Oct 17 11:15:38 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:38 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:38 volumio volumio[1189]: info: Oct 17 11:15:38 volumio volumio[1189]: ---------------------------- Client requests Volume 94 Oct 17 11:15:38 volumio volumio[1189]: info: VolumeController::SetAlsaVolume94 Oct 17 11:15:38 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:38 volumio volumio[1476]: [158B blob data] Oct 17 11:15:38 volumio volumio[1476]: {"time":1729152938205,"response":"volume Success"}94 Oct 17 11:15:38 volumio volumio[1476]: 93 Oct 17 11:15:38 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:38 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:38 volumio volumio[1189]: info: Oct 17 11:15:38 volumio volumio[1189]: ---------------------------- Client requests Volume 93 Oct 17 11:15:38 volumio volumio[1189]: info: VolumeController::SetAlsaVolume93 Oct 17 11:15:38 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:38 volumio volumio[1476]: [158B blob data] Oct 17 11:15:38 volumio volumio[1476]: {"time":1729152938428,"response":"volume Success"}93 Oct 17 11:15:38 volumio volumio[1476]: 92 Oct 17 11:15:38 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:38 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:38 volumio volumio[1189]: info: Oct 17 11:15:38 volumio volumio[1189]: ---------------------------- Client requests Volume 92 Oct 17 11:15:38 volumio volumio[1189]: info: VolumeController::SetAlsaVolume92 Oct 17 11:15:38 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:38 volumio volumio[1476]: [158B blob data] Oct 17 11:15:38 volumio volumio[1476]: {"time":1729152938649,"response":"volume Success"}92 Oct 17 11:15:38 volumio volumio[1476]: 91 Oct 17 11:15:38 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:38 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:38 volumio volumio[1189]: info: Oct 17 11:15:38 volumio volumio[1189]: ---------------------------- Client requests Volume 91 Oct 17 11:15:38 volumio volumio[1189]: info: VolumeController::SetAlsaVolume91 Oct 17 11:15:38 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:38 volumio volumio[1476]: [158B blob data] Oct 17 11:15:38 volumio volumio[1476]: {"time":1729152938883,"response":"volume Success"}91 Oct 17 11:15:39 volumio volumio[1476]: 90 Oct 17 11:15:39 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:39 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:39 volumio volumio[1189]: info: Oct 17 11:15:39 volumio volumio[1189]: ---------------------------- Client requests Volume 90 Oct 17 11:15:39 volumio volumio[1189]: info: VolumeController::SetAlsaVolume90 Oct 17 11:15:39 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:39 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:39 volumio volumio[1476]: [158B blob data] Oct 17 11:15:39 volumio volumio[1476]: {"time":1729152939192,"response":"volume Success"}90 Oct 17 11:15:39 volumio volumio[1476]: 89 Oct 17 11:15:39 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:39 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:39 volumio volumio[1189]: info: Oct 17 11:15:39 volumio volumio[1189]: ---------------------------- Client requests Volume 89 Oct 17 11:15:39 volumio volumio[1189]: info: VolumeController::SetAlsaVolume89 Oct 17 11:15:39 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:39 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:39 volumio volumio[1476]: [158B blob data] Oct 17 11:15:39 volumio volumio[1476]: {"time":1729152939433,"response":"volume Success"}89 Oct 17 11:15:39 volumio volumio[1476]: 88 Oct 17 11:15:39 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:39 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:39 volumio volumio[1189]: info: Oct 17 11:15:39 volumio volumio[1189]: ---------------------------- Client requests Volume 88 Oct 17 11:15:39 volumio volumio[1189]: info: VolumeController::SetAlsaVolume88 Oct 17 11:15:39 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:39 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:39 volumio volumio[1476]: [158B blob data] Oct 17 11:15:39 volumio volumio[1476]: {"time":1729152939649,"response":"volume Success"}88 Oct 17 11:15:39 volumio ntpd[1177]: ntpd exiting on signal 15 (Terminated) Oct 17 11:15:39 volumio ntpd[1177]: 195.3.218.2 local addr 192.168.55.104 -> Oct 17 11:15:39 volumio ntpd[1177]: 91.206.16.3 local addr 192.168.55.104 -> Oct 17 11:15:39 volumio ntpd[1177]: 91.209.94.10 local addr 192.168.55.104 -> Oct 17 11:15:39 volumio ntpd[1177]: 162.159.200.1 local addr 192.168.55.104 -> Oct 17 11:15:39 volumio ntpd[1177]: 95.163.183.223 local addr 192.168.55.104 -> Oct 17 11:15:39 volumio ntpd[1177]: 86.110.169.234 local addr 192.168.55.104 -> Oct 17 11:15:39 volumio systemd[1]: Stopping Network Time Service... Oct 17 11:15:39 volumio systemd[1]: ntp.service: Succeeded. Oct 17 11:15:39 volumio systemd[1]: Stopped Network Time Service. Oct 17 11:15:39 volumio systemd[1]: Starting Network Time Service... Oct 17 11:15:39 volumio ntpd[4771]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 17 11:15:39 volumio ntpd[4771]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 17 11:15:39 volumio systemd[1]: Started Network Time Service. Oct 17 11:15:39 volumio ntpd[4777]: proto: precision = 0.292 usec (-22) Oct 17 11:15:39 volumio ntpd[4777]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 17 11:15:39 volumio ntpd[4777]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 17 11:15:39 volumio ntpd[4777]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 660 days ago Oct 17 11:15:39 volumio ntpd[4777]: Listen and drop on 0 v6wildcard [::]:123 Oct 17 11:15:39 volumio ntpd[4777]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 17 11:15:39 volumio ntpd[4777]: Listen normally on 2 lo 127.0.0.1:123 Oct 17 11:15:39 volumio ntpd[4777]: Listen normally on 3 wlan0 192.168.55.104:123 Oct 17 11:15:39 volumio ntpd[4777]: Listening on routing socket on fd #20 for interface updates Oct 17 11:15:39 volumio ntpd[4777]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Oct 17 11:15:39 volumio ntpd[4777]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Oct 17 11:15:39 volumio volumio[1476]: 87 Oct 17 11:15:39 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:39 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:39 volumio volumio[1189]: info: Oct 17 11:15:39 volumio volumio[1189]: ---------------------------- Client requests Volume 87 Oct 17 11:15:39 volumio volumio[1189]: info: VolumeController::SetAlsaVolume87 Oct 17 11:15:39 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:39 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:39 volumio volumio[1476]: [158B blob data] Oct 17 11:15:39 volumio volumio[1476]: {"time":1729152939868,"response":"volume Success"}87 Oct 17 11:15:40 volumio volumio[1476]: 86 Oct 17 11:15:40 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:40 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:40 volumio volumio[1189]: info: Oct 17 11:15:40 volumio volumio[1189]: ---------------------------- Client requests Volume 86 Oct 17 11:15:40 volumio volumio[1189]: info: VolumeController::SetAlsaVolume86 Oct 17 11:15:40 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:40 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:40 volumio volumio[1476]: [158B blob data] Oct 17 11:15:40 volumio volumio[1476]: {"time":1729152940089,"response":"volume Success"}86 Oct 17 11:15:40 volumio volumio[1476]: 85 Oct 17 11:15:40 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:40 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:40 volumio volumio[1189]: info: Oct 17 11:15:40 volumio volumio[1189]: ---------------------------- Client requests Volume 85 Oct 17 11:15:40 volumio volumio[1189]: info: VolumeController::SetAlsaVolume85 Oct 17 11:15:40 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:40 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:40 volumio volumio[1476]: [158B blob data] Oct 17 11:15:40 volumio volumio[1476]: {"time":1729152940324,"response":"volume Success"}85 Oct 17 11:15:40 volumio volumio[1476]: 84 Oct 17 11:15:40 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:40 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:40 volumio volumio[1189]: info: Oct 17 11:15:40 volumio volumio[1189]: ---------------------------- Client requests Volume 84 Oct 17 11:15:40 volumio volumio[1189]: info: VolumeController::SetAlsaVolume84 Oct 17 11:15:40 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:40 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:40 volumio volumio[1476]: [158B blob data] Oct 17 11:15:40 volumio volumio[1476]: {"time":1729152940567,"response":"volume Success"}84 Oct 17 11:15:40 volumio volumio[1476]: 83 Oct 17 11:15:40 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:40 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:40 volumio volumio[1189]: info: Oct 17 11:15:40 volumio volumio[1189]: ---------------------------- Client requests Volume 83 Oct 17 11:15:40 volumio volumio[1189]: info: VolumeController::SetAlsaVolume83 Oct 17 11:15:40 volumio ntpd[4777]: Soliciting pool server 95.163.183.223 Oct 17 11:15:40 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:40 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:40 volumio volumio[1476]: [158B blob data] Oct 17 11:15:40 volumio volumio[1476]: {"time":1729152940818,"response":"volume Success"}83 Oct 17 11:15:41 volumio volumio[1476]: 82 Oct 17 11:15:41 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:41 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:41 volumio volumio[1189]: info: Oct 17 11:15:41 volumio volumio[1189]: ---------------------------- Client requests Volume 82 Oct 17 11:15:41 volumio volumio[1189]: info: VolumeController::SetAlsaVolume82 Oct 17 11:15:41 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:41 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:41 volumio volumio[1476]: [158B blob data] Oct 17 11:15:41 volumio volumio[1476]: {"time":1729152941075,"response":"volume Success"}82 Oct 17 11:15:41 volumio volumio[1476]: 81 Oct 17 11:15:41 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:41 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:41 volumio volumio[1189]: info: Oct 17 11:15:41 volumio volumio[1189]: ---------------------------- Client requests Volume 81 Oct 17 11:15:41 volumio volumio[1189]: info: VolumeController::SetAlsaVolume81 Oct 17 11:15:41 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:41 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:41 volumio volumio[1476]: [158B blob data] Oct 17 11:15:41 volumio volumio[1476]: {"time":1729152941319,"response":"volume Success"}81 Oct 17 11:15:41 volumio volumio[1476]: 80 Oct 17 11:15:41 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:41 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:41 volumio volumio[1189]: info: Oct 17 11:15:41 volumio volumio[1189]: ---------------------------- Client requests Volume 80 Oct 17 11:15:41 volumio volumio[1189]: info: VolumeController::SetAlsaVolume80 Oct 17 11:15:41 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:41 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:41 volumio volumio[1476]: [158B blob data] Oct 17 11:15:41 volumio volumio[1476]: {"time":1729152941581,"response":"volume Success"}80 Oct 17 11:15:41 volumio ntpd[4777]: Soliciting pool server 195.161.115.4 Oct 17 11:15:41 volumio ntpd[4777]: Soliciting pool server 195.3.218.2 Oct 17 11:15:42 volumio volumio[1476]: 79 Oct 17 11:15:42 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:42 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:42 volumio volumio[1189]: info: Oct 17 11:15:42 volumio volumio[1189]: ---------------------------- Client requests Volume 79 Oct 17 11:15:42 volumio volumio[1189]: info: VolumeController::SetAlsaVolume79 Oct 17 11:15:42 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:42 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:42 volumio volumio[1476]: [158B blob data] Oct 17 11:15:42 volumio volumio[1476]: {"time":1729152942171,"response":"volume Success"}79 Oct 17 11:15:42 volumio volumio[1476]: 78 Oct 17 11:15:42 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:42 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:42 volumio volumio[1189]: info: Oct 17 11:15:42 volumio volumio[1189]: ---------------------------- Client requests Volume 78 Oct 17 11:15:42 volumio volumio[1189]: info: VolumeController::SetAlsaVolume78 Oct 17 11:15:42 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:42 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:42 volumio volumio[1476]: [158B blob data] Oct 17 11:15:42 volumio volumio[1476]: {"time":1729152942394,"response":"volume Success"}78 Oct 17 11:15:42 volumio ntpd[4777]: Soliciting pool server 162.159.200.123 Oct 17 11:15:42 volumio ntpd[4777]: Soliciting pool server 82.142.168.18 Oct 17 11:15:42 volumio ntpd[4777]: Soliciting pool server 37.193.156.169 Oct 17 11:15:42 volumio volumio[1476]: 77 Oct 17 11:15:43 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:43 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:43 volumio volumio[1189]: info: Oct 17 11:15:43 volumio volumio[1189]: ---------------------------- Client requests Volume 77 Oct 17 11:15:43 volumio volumio[1189]: info: VolumeController::SetAlsaVolume77 Oct 17 11:15:43 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:43 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:43 volumio volumio[1476]: [158B blob data] Oct 17 11:15:43 volumio volumio[1476]: {"time":1729152943005,"response":"volume Success"}77 Oct 17 11:15:43 volumio volumio[1476]: 76 Oct 17 11:15:43 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:43 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:43 volumio volumio[1189]: info: Oct 17 11:15:43 volumio volumio[1189]: ---------------------------- Client requests Volume 76 Oct 17 11:15:43 volumio volumio[1189]: info: VolumeController::SetAlsaVolume76 Oct 17 11:15:43 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:43 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:43 volumio volumio[1476]: [158B blob data] Oct 17 11:15:43 volumio volumio[1476]: {"time":1729152943247,"response":"volume Success"}76 Oct 17 11:15:43 volumio ntpd[4777]: Soliciting pool server 176.112.203.33 Oct 17 11:15:43 volumio ntpd[4777]: Soliciting pool server 176.215.178.239 Oct 17 11:15:43 volumio ntpd[4777]: Soliciting pool server 185.68.101.2 Oct 17 11:15:43 volumio ntpd[4777]: Soliciting pool server 192.36.143.130 Oct 17 11:15:43 volumio volumio[1476]: 77 Oct 17 11:15:43 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:43 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:43 volumio volumio[1189]: info: Oct 17 11:15:43 volumio volumio[1189]: ---------------------------- Client requests Volume 77 Oct 17 11:15:43 volumio volumio[1189]: info: VolumeController::SetAlsaVolume77 Oct 17 11:15:44 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:44 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:44 volumio volumio[1476]: [158B blob data] Oct 17 11:15:44 volumio volumio[1476]: {"time":1729152943982,"response":"volume Success"}77 Oct 17 11:15:44 volumio volumio[1476]: 78 Oct 17 11:15:44 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:44 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:44 volumio volumio[1189]: info: Oct 17 11:15:44 volumio volumio[1189]: ---------------------------- Client requests Volume 78 Oct 17 11:15:44 volumio volumio[1189]: info: VolumeController::SetAlsaVolume78 Oct 17 11:15:44 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:44 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:44 volumio volumio[1476]: [158B blob data] Oct 17 11:15:44 volumio volumio[1476]: {"time":1729152944233,"response":"volume Success"}78 Oct 17 11:15:44 volumio volumio[1476]: 79 Oct 17 11:15:44 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:44 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:44 volumio volumio[1189]: info: Oct 17 11:15:44 volumio volumio[1189]: ---------------------------- Client requests Volume 79 Oct 17 11:15:44 volumio volumio[1189]: info: VolumeController::SetAlsaVolume79 Oct 17 11:15:44 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:44 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:44 volumio volumio[1476]: [158B blob data] Oct 17 11:15:44 volumio volumio[1476]: {"time":1729152944433,"response":"volume Success"}79 Oct 17 11:15:44 volumio volumio[1476]: 80 Oct 17 11:15:44 volumio wpa_supplicant[1104]: wlan0: WPA: Group rekeying completed with 24:43:e2:63:3a:17 [GTK=CCMP] Oct 17 11:15:44 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:44 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:44 volumio volumio[1189]: info: Oct 17 11:15:44 volumio volumio[1189]: ---------------------------- Client requests Volume 80 Oct 17 11:15:44 volumio volumio[1189]: info: VolumeController::SetAlsaVolume80 Oct 17 11:15:44 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:44 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:44 volumio volumio[1476]: [158B blob data] Oct 17 11:15:44 volumio volumio[1476]: {"time":1729152944679,"response":"volume Success"}80 Oct 17 11:15:44 volumio ntpd[4777]: Soliciting pool server 78.132.143.57 Oct 17 11:15:44 volumio ntpd[4777]: Soliciting pool server 91.206.16.3 Oct 17 11:15:44 volumio volumio[1476]: 81 Oct 17 11:15:44 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:44 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:44 volumio volumio[1189]: info: Oct 17 11:15:44 volumio volumio[1189]: ---------------------------- Client requests Volume 81 Oct 17 11:15:44 volumio volumio[1189]: info: VolumeController::SetAlsaVolume81 Oct 17 11:15:44 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:44 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:44 volumio volumio[1476]: [158B blob data] Oct 17 11:15:44 volumio volumio[1476]: {"time":1729152944886,"response":"volume Success"}81 Oct 17 11:15:45 volumio volumio[1476]: 82 Oct 17 11:15:45 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:45 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:45 volumio volumio[1189]: info: Oct 17 11:15:45 volumio volumio[1189]: ---------------------------- Client requests Volume 82 Oct 17 11:15:45 volumio volumio[1189]: info: VolumeController::SetAlsaVolume82 Oct 17 11:15:45 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:45 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:45 volumio volumio[1476]: [158B blob data] Oct 17 11:15:45 volumio volumio[1476]: {"time":1729152945129,"response":"volume Success"}82 Oct 17 11:15:45 volumio volumio[1476]: 83 Oct 17 11:15:45 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:45 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:45 volumio volumio[1189]: info: Oct 17 11:15:45 volumio volumio[1189]: ---------------------------- Client requests Volume 83 Oct 17 11:15:45 volumio volumio[1189]: info: VolumeController::SetAlsaVolume83 Oct 17 11:15:45 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:45 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:45 volumio volumio[1476]: [158B blob data] Oct 17 11:15:45 volumio volumio[1476]: {"time":1729152945360,"response":"volume Success"}83 Oct 17 11:15:45 volumio volumio[1476]: 84 Oct 17 11:15:45 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:45 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:45 volumio volumio[1189]: info: Oct 17 11:15:45 volumio volumio[1189]: ---------------------------- Client requests Volume 84 Oct 17 11:15:45 volumio volumio[1189]: info: VolumeController::SetAlsaVolume84 Oct 17 11:15:45 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:45 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:45 volumio volumio[1476]: [158B blob data] Oct 17 11:15:45 volumio volumio[1476]: {"time":1729152945578,"response":"volume Success"}84 Oct 17 11:15:45 volumio volumio[1476]: 85 Oct 17 11:15:45 volumio ntpd[4777]: Soliciting pool server 94.247.111.10 Oct 17 11:15:45 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:45 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:45 volumio volumio[1189]: info: Oct 17 11:15:45 volumio volumio[1189]: ---------------------------- Client requests Volume 85 Oct 17 11:15:45 volumio volumio[1189]: info: VolumeController::SetAlsaVolume85 Oct 17 11:15:45 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:45 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:45 volumio volumio[1476]: [158B blob data] Oct 17 11:15:45 volumio volumio[1476]: {"time":1729152945794,"response":"volume Success"}85 Oct 17 11:15:45 volumio volumio[1476]: 86 Oct 17 11:15:46 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:46 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:46 volumio volumio[1189]: info: Oct 17 11:15:46 volumio volumio[1189]: ---------------------------- Client requests Volume 86 Oct 17 11:15:46 volumio volumio[1189]: info: VolumeController::SetAlsaVolume86 Oct 17 11:15:46 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:46 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:46 volumio volumio[1476]: [158B blob data] Oct 17 11:15:46 volumio volumio[1476]: {"time":1729152946022,"response":"volume Success"}86 Oct 17 11:15:46 volumio volumio[1476]: 87 Oct 17 11:15:46 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:46 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:46 volumio volumio[1189]: info: Oct 17 11:15:46 volumio volumio[1189]: ---------------------------- Client requests Volume 87 Oct 17 11:15:46 volumio volumio[1189]: info: VolumeController::SetAlsaVolume87 Oct 17 11:15:46 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:46 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:46 volumio volumio[1476]: [158B blob data] Oct 17 11:15:46 volumio volumio[1476]: {"time":1729152946238,"response":"volume Success"}87 Oct 17 11:15:46 volumio volumio[1476]: 88 Oct 17 11:15:46 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:46 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:46 volumio volumio[1189]: info: Oct 17 11:15:46 volumio volumio[1189]: ---------------------------- Client requests Volume 88 Oct 17 11:15:46 volumio volumio[1189]: info: VolumeController::SetAlsaVolume88 Oct 17 11:15:46 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:46 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:46 volumio volumio[1476]: [237B blob data] Oct 17 11:15:46 volumio volumio[1476]: {"time":1729152946456,"response":"volume Success"}88 Oct 17 11:15:46 volumio volumio[1476]: 89 Oct 17 11:15:46 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:46 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:46 volumio volumio[1189]: info: Oct 17 11:15:46 volumio volumio[1189]: ---------------------------- Client requests Volume 89 Oct 17 11:15:46 volumio volumio[1189]: info: VolumeController::SetAlsaVolume89 Oct 17 11:15:46 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:46 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:46 volumio volumio[1476]: [158B blob data] Oct 17 11:15:46 volumio volumio[1476]: {"time":1729152946712,"response":"volume Success"}89 Oct 17 11:15:46 volumio ntpd[4777]: Soliciting pool server 162.159.200.1 Oct 17 11:15:46 volumio volumio[1476]: 90 Oct 17 11:15:46 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:46 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:46 volumio volumio[1189]: info: Oct 17 11:15:46 volumio volumio[1189]: ---------------------------- Client requests Volume 90 Oct 17 11:15:46 volumio volumio[1189]: info: VolumeController::SetAlsaVolume90 Oct 17 11:15:47 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:47 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:47 volumio volumio[1476]: [158B blob data] Oct 17 11:15:47 volumio volumio[1476]: {"time":1729152946980,"response":"volume Success"}90 Oct 17 11:15:47 volumio volumio[1476]: 91 Oct 17 11:15:47 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:47 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:47 volumio volumio[1189]: info: Oct 17 11:15:47 volumio volumio[1189]: ---------------------------- Client requests Volume 91 Oct 17 11:15:47 volumio volumio[1189]: info: VolumeController::SetAlsaVolume91 Oct 17 11:15:47 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:47 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:47 volumio volumio[1476]: [158B blob data] Oct 17 11:15:47 volumio volumio[1476]: {"time":1729152947205,"response":"volume Success"}91 Oct 17 11:15:47 volumio volumio[1476]: 92 Oct 17 11:15:47 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:47 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:47 volumio volumio[1189]: info: Oct 17 11:15:47 volumio volumio[1189]: ---------------------------- Client requests Volume 92 Oct 17 11:15:47 volumio volumio[1189]: info: VolumeController::SetAlsaVolume92 Oct 17 11:15:47 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:47 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:47 volumio volumio[1476]: [158B blob data] Oct 17 11:15:47 volumio volumio[1476]: {"time":1729152947438,"response":"volume Success"}92 Oct 17 11:15:47 volumio volumio[1476]: 93 Oct 17 11:15:47 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:47 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:47 volumio volumio[1189]: info: Oct 17 11:15:47 volumio volumio[1189]: ---------------------------- Client requests Volume 93 Oct 17 11:15:47 volumio volumio[1189]: info: VolumeController::SetAlsaVolume93 Oct 17 11:15:47 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:47 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:47 volumio volumio[1476]: [158B blob data] Oct 17 11:15:47 volumio volumio[1476]: {"time":1729152947659,"response":"volume Success"}93 Oct 17 11:15:47 volumio volumio[1476]: 94 Oct 17 11:15:47 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:47 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:47 volumio volumio[1189]: info: Oct 17 11:15:47 volumio volumio[1189]: ---------------------------- Client requests Volume 94 Oct 17 11:15:47 volumio volumio[1189]: info: VolumeController::SetAlsaVolume94 Oct 17 11:15:47 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:47 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:47 volumio volumio[1476]: [158B blob data] Oct 17 11:15:47 volumio volumio[1476]: {"time":1729152947888,"response":"volume Success"}94 Oct 17 11:15:48 volumio volumio[1476]: 95 Oct 17 11:15:48 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:48 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:48 volumio volumio[1189]: info: Oct 17 11:15:48 volumio volumio[1189]: ---------------------------- Client requests Volume 95 Oct 17 11:15:48 volumio volumio[1189]: info: VolumeController::SetAlsaVolume95 Oct 17 11:15:48 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:48 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:48 volumio volumio[1476]: [158B blob data] Oct 17 11:15:48 volumio volumio[1476]: {"time":1729152948130,"response":"volume Success"}95 Oct 17 11:15:48 volumio volumio[1476]: 96 Oct 17 11:15:48 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:48 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:48 volumio volumio[1189]: info: Oct 17 11:15:48 volumio volumio[1189]: ---------------------------- Client requests Volume 96 Oct 17 11:15:48 volumio volumio[1189]: info: VolumeController::SetAlsaVolume96 Oct 17 11:15:48 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:48 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:48 volumio volumio[1476]: [158B blob data] Oct 17 11:15:48 volumio volumio[1476]: {"time":1729152948362,"response":"volume Success"}96 Oct 17 11:15:48 volumio volumio[1476]: 97 Oct 17 11:15:48 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:48 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:48 volumio volumio[1189]: info: Oct 17 11:15:48 volumio volumio[1189]: ---------------------------- Client requests Volume 97 Oct 17 11:15:48 volumio volumio[1189]: info: VolumeController::SetAlsaVolume97 Oct 17 11:15:48 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:48 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:48 volumio volumio[1476]: [158B blob data] Oct 17 11:15:48 volumio volumio[1476]: {"time":1729152948577,"response":"volume Success"}97 Oct 17 11:15:48 volumio volumio[1476]: 98 Oct 17 11:15:48 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:48 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:48 volumio volumio[1189]: info: Oct 17 11:15:48 volumio volumio[1189]: ---------------------------- Client requests Volume 98 Oct 17 11:15:48 volumio volumio[1189]: info: VolumeController::SetAlsaVolume98 Oct 17 11:15:48 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:48 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:48 volumio volumio[1476]: [158B blob data] Oct 17 11:15:48 volumio volumio[1476]: {"time":1729152948802,"response":"volume Success"}98 Oct 17 11:15:48 volumio volumio[1476]: 99 Oct 17 11:15:49 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:49 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:49 volumio volumio[1189]: info: Oct 17 11:15:49 volumio volumio[1189]: ---------------------------- Client requests Volume 99 Oct 17 11:15:49 volumio volumio[1189]: info: VolumeController::SetAlsaVolume99 Oct 17 11:15:49 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:49 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:49 volumio volumio[1476]: [158B blob data] Oct 17 11:15:49 volumio volumio[1476]: {"time":1729152949048,"response":"volume Success"}99 Oct 17 11:15:49 volumio volumio[1476]: 100 Oct 17 11:15:49 volumio volumio[1476]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 11:15:49 volumio volumio[1476]: Dload Upload Total Spent Left Speed Oct 17 11:15:49 volumio volumio[1189]: info: Oct 17 11:15:49 volumio volumio[1189]: ---------------------------- Client requests Volume 100 Oct 17 11:15:49 volumio volumio[1189]: info: VolumeController::SetAlsaVolume100 Oct 17 11:15:49 volumio volumio[1189]: info: CoreStateMachine::pushState Oct 17 11:15:49 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:15:49 volumio volumio[1476]: [158B blob data] Oct 17 11:15:49 volumio volumio[1476]: {"time":1729152949277,"response":"volume Success"}100 Oct 17 11:15:56 volumio wpa_supplicant[1104]: wlan0: WPA: Group rekeying completed with 24:43:e2:63:3a:17 [GTK=CCMP] Oct 17 11:16:08 volumio wpa_supplicant[1104]: wlan0: WPA: Group rekeying completed with 24:43:e2:63:3a:17 [GTK=CCMP] Oct 17 11:16:09 volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 11:16:09 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 17 11:16:09 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 17 11:16:09 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 17 11:16:09 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Oct 17 11:16:09 volumio volumio[1189]: info: Received Get System Version Oct 17 11:16:09 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 11:16:17 volumio volumio[1189]: info: CALLMETHOD: system_controller system saveHDMISettings [object Object] Oct 17 11:16:17 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , saveHDMISettings Oct 17 11:16:17 volumio sudo[4938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Oct 17 11:16:17 volumio sudo[4938]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 11:16:17 volumio systemd[1]: Started Start Volumio Kiosk. Oct 17 11:16:17 volumio sudo[4938]: pam_unix(sudo:session): session closed for user root Oct 17 11:16:17 volumio sudo[4941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable volumio-kiosk.service Oct 17 11:16:17 volumio sudo[4941]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 11:16:17 volumio systemd[1]: Reloading. Oct 17 11:16:17 volumio startx[4940]: X.Org X Server 1.20.4 Oct 17 11:16:17 volumio startx[4940]: X Protocol Version 11, Revision 0 Oct 17 11:16:17 volumio startx[4940]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Oct 17 11:16:17 volumio startx[4940]: Current Operating System: Linux volumio 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Oct 17 11:16:17 volumio startx[4940]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=d0822069-d48a-490f-b8bc-9970c0b6e384 bootpart=UUID=1496-1547 datapart=UUID=a0bf3376-7b72-4c09-9fc2-d0970ff7651c hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no Oct 17 11:16:17 volumio startx[4940]: Build Date: 18 December 2021 09:05:36AM Oct 17 11:16:17 volumio startx[4940]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Oct 17 11:16:17 volumio startx[4940]: Current version of pixman: 0.36.0 Oct 17 11:16:18 volumio startx[4940]: Before reporting problems, check http://wiki.x.org Oct 17 11:16:18 volumio startx[4940]: to make sure that you have the latest version. Oct 17 11:16:18 volumio startx[4940]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 17 11:16:18 volumio startx[4940]: (++) from command line, (!!) notice, (II) informational, Oct 17 11:16:18 volumio startx[4940]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 17 11:16:18 volumio startx[4940]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Oct 17 11:16:17 2024 Oct 17 11:16:18 volumio startx[4940]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 17 11:16:18 volumio startx[4940]: (II) [KMS] Kernel modesetting enabled. Oct 17 11:16:18 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 17 11:16:18 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 17 11:16:18 volumio sudo[4941]: pam_unix(sudo:session): session closed for user root Oct 17 11:16:18 volumio volumio[1189]: info: enable volumio-kiosk service success Oct 17 11:16:20 volumio wpa_supplicant[1104]: wlan0: WPA: Group rekeying completed with 24:43:e2:63:3a:17 [GTK=CCMP] Oct 17 11:16:32 volumio wpa_supplicant[1104]: wlan0: WPA: Group rekeying completed with 24:43:e2:63:3a:17 [GTK=CCMP] Oct 17 11:16:33 volumio volumio[1189]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 17 11:16:34 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 11:16:34 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 17 11:16:36 volumio volumio[1189]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 17 11:16:39 volumio volumio[1189]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 17 11:16:39 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 11:16:39 volumio volumio[1189]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 11:16:39 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 11:16:39 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 17 11:16:39 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 17 11:16:39 volumio volumio[1189]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 11:16:39 volumio volumio[1189]: TypeError: Cannot read property 'name' of undefined Oct 17 11:16:39 volumio volumio[1189]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Oct 17 11:16:39 volumio volumio[1189]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Oct 17 11:16:39 volumio volumio[1189]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Oct 17 11:16:39 volumio volumio[1189]: at Socket.emit (events.js:400:28) Oct 17 11:16:39 volumio volumio[1189]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 17 11:16:39 volumio volumio[1189]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Oct 17 11:16:39 volumio volumio[1189]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 11:16:40 volumio sudo[5371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-17 11:15 Oct 17 11:16:40 volumio sudo[5371]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"