Merge pull request #1184 from mwielgus/more-debug-3

Cluster-autoscaler: more debugs around scale down

Author: mwielgus

cluster-autoscaler/cluster_autoscaler.go

@@ -174,6 +174,7 @@ func main() {
 					newUnschedulablePodsToHelp := FilterOutSchedulable(unschedulablePodsToHelp, nodes, allScheduled, predicateChecker)
 
 					if len(newUnschedulablePodsToHelp) != len(unschedulablePodsToHelp) {
+						glog.V(2).Info("Schedulable pods present")
 						schedulablePodsPresent = true
 					}
 					unschedulablePodsToHelp = newUnschedulablePodsToHelp
@@ -208,7 +209,12 @@ func main() {
 						lastScaleDownFailedTrial.Add(*scaleDownTrialInterval).After(time.Now()) ||
 						schedulablePodsPresent
 
+					glog.V(4).Info("Scale down status: unneededOnly=%v lastScaleUpTime=%s "+
+						"lastScaleDownFailedTrail=%s schedulablePodsPresent=%v", calculateUnneededOnly,
+						lastScaleUpTime, lastScaleDownFailedTrial, schedulablePodsPresent)
+
 					updateLastTime("findUnneeded")
+					glog.V(4).Infof("Calculating unneded nodes")
 
 					unneededNodes = FindUnneededNodes(
 						nodes,
@@ -219,7 +225,15 @@ func main() {
 
 					updateDuration("findUnneeded", unneededStart)
 
+					for key, val := range unneededNodes {
+						if glog.V(4) {
+							glog.V(4).Infof("%s is unneeded since %s duration %s", key, val.String(), time.Now().Sub(val).String())
+						}
+					}
+
 					if !calculateUnneededOnly {
+						glog.V(4).Infof("Starting scale down")
+
 						scaleDownStart := time.Now()
 						updateLastTime("scaledown")
 

cluster-autoscaler/scale_down.go

@@ -115,7 +115,7 @@ func ScaleDown(
 	for _, node := range nodes {
 		if val, found := unneededNodes[node.Name]; found {
 
-			glog.V(2).Infof("%s was underutilized for %s", node.Name, now.Sub(val).String())
+			glog.V(2).Infof("%s was unneeded for %s", node.Name, now.Sub(val).String())
 
 			// Check how long the node was underutilized.
 			if !val.Add(unneededTime).Before(now) {