Additional scheduler output

kube-scheduler.log

I1113 15:44:05.983777       5 feature_gate.go:156] feature gates: map[Accelerators:true DynamicKubeletConfig:true MountPropagation:true Initializers:true KubeletConfigFile:true SupportIPVSProxyMode:true RotateKubeletServerCertificate:true TaintBasedEvictions:true CustomResourceValidation:true PersistentLocalVolumes:true APIListChunking:true ExperimentalCriticalPodAnnotation:true EnableEquivalenceClassCache:true LocalStorageCapacityIsolation:true APIResponseCompression:true PodPriority:true DebugContainers:true HugePages:true DevicePlugins:true AllAlpha:true CPUManager:true ExpandPersistentVolumes:true TaintNodesByCondition:true]
I1113 15:44:05.990382       5 factory.go:616] Creating scheduler from algorithm provider 'DefaultProvider'
I1113 15:44:05.990407       5 factory.go:677] Creating scheduler with fit predicates 'map[MaxGCEPDVolumeCount:{} MaxAzureDiskVolumeCount:{} MatchInterPodAffinity:{} NoDiskConflict:{} CheckNodeMemoryPressure:{} CheckNodeDiskPressure:{} NoVolumeNodeConflict:{} NoVolumeZoneConflict:{} PodToleratesNodeTaints:{} CheckNodeCondition:{} GeneralPredicates:{} MaxEBSVolumeCount:{}]' and priority functions 'map[BalancedResourceAllocation:{} NodePreferAvoidPodsPriority:{} NodeAffinityPriority:{} TaintTolerationPriority:{} SelectorSpreadPriority:{} InterPodAffinityPriority:{} LeastRequestedPriority:{}]'
I1113 15:44:05.990536       5 factory.go:706] Created equivalence class cache
I1113 15:44:05.991167       5 reflector.go:202] Starting reflector *v1.PersistentVolumeClaim (0s) from k8s.io/kubernetes/vendor/k8s.io/client-go/informers/factory.go:73
I1113 15:44:05.991227       5 reflector.go:240] Listing and watching *v1.PersistentVolumeClaim from k8s.io/kubernetes/vendor/k8s.io/client-go/informers/factory.go:73
I1113 15:44:05.991255       5 reflector.go:202] Starting reflector *v1.Service (0s) from k8s.io/kubernetes/vendor/k8s.io/client-go/informers/factory.go:73
I1113 15:44:05.991273       5 reflector.go:240] Listing and watching *v1.Service from k8s.io/kubernetes/vendor/k8s.io/client-go/informers/factory.go:73
I1113 15:44:05.991928       5 reflector.go:202] Starting reflector *v1.ReplicationController (0s) from k8s.io/kubernetes/vendor/k8s.io/client-go/informers/factory.go:73
I1113 15:44:05.991945       5 reflector.go:240] Listing and watching *v1.ReplicationController from k8s.io/kubernetes/vendor/k8s.io/client-go/informers/factory.go:73
I1113 15:44:05.992476       5 reflector.go:202] Starting reflector *v1.Pod (0s) from k8s.io/kubernetes/plugin/cmd/kube-scheduler/app/server.go:103
I1113 15:44:05.992502       5 reflector.go:240] Listing and watching *v1.Pod from k8s.io/kubernetes/plugin/cmd/kube-scheduler/app/server.go:103
I1113 15:44:05.992990       5 reflector.go:202] Starting reflector *v1beta1.StatefulSet (0s) from k8s.io/kubernetes/vendor/k8s.io/client-go/informers/factory.go:73
I1113 15:44:05.993007       5 reflector.go:240] Listing and watching *v1beta1.StatefulSet from k8s.io/kubernetes/vendor/k8s.io/client-go/informers/factory.go:73
I1113 15:44:05.993219       5 reflector.go:202] Starting reflector *v1beta1.ReplicaSet (0s) from k8s.io/kubernetes/vendor/k8s.io/client-go/informers/factory.go:73
I1113 15:44:05.993234       5 reflector.go:240] Listing and watching *v1beta1.ReplicaSet from k8s.io/kubernetes/vendor/k8s.io/client-go/informers/factory.go:73
I1113 15:44:05.993543       5 reflector.go:202] Starting reflector *v1.Node (0s) from k8s.io/kubernetes/vendor/k8s.io/client-go/informers/factory.go:73
I1113 15:44:05.993559       5 reflector.go:240] Listing and watching *v1.Node from k8s.io/kubernetes/vendor/k8s.io/client-go/informers/factory.go:73
I1113 15:44:05.993913       5 reflector.go:202] Starting reflector *v1.PersistentVolume (0s) from k8s.io/kubernetes/vendor/k8s.io/client-go/informers/factory.go:73
I1113 15:44:05.993929       5 reflector.go:240] Listing and watching *v1.PersistentVolume from k8s.io/kubernetes/vendor/k8s.io/client-go/informers/factory.go:73
I1113 15:44:06.090786       5 shared_informer.go:116] caches populated
I1113 15:44:06.190944       5 shared_informer.go:116] caches populated
I1113 15:44:06.291191       5 shared_informer.go:116] caches populated
I1113 15:44:06.391405       5 shared_informer.go:116] caches populated
I1113 15:44:06.491603       5 shared_informer.go:116] caches populated
I1113 15:44:06.591798       5 shared_informer.go:116] caches populated
I1113 15:44:06.692024       5 shared_informer.go:116] caches populated
I1113 15:44:06.692055       5 controller_utils.go:1041] Waiting for caches to sync for scheduler controller
I1113 15:44:06.792252       5 shared_informer.go:116] caches populated
I1113 15:44:06.792272       5 controller_utils.go:1048] Caches are synced for scheduler controller
I1113 15:44:06.792344       5 leaderelection.go:174] attempting to acquire leader lease...
I1113 15:44:06.802842       5 leaderelection.go:184] successfully acquired lease kube-system/kube-scheduler
I1113 15:44:06.802924       5 event.go:218] Event(v1.ObjectReference{Kind:"Endpoints", Namespace:"kube-system", Name:"kube-scheduler", UID:"b6ccaa0e-c552-11e7-9644-12d2abc93736", APIVersion:"v1", ResourceVersion:"499219", FieldPath:""}): type: 'Normal' reason: 'LeaderElection' master0 became leader
I1113 15:44:06.903349       5 shared_informer.go:116] caches populated
I1113 15:44:18.767128       5 factory.go:793] About to try and schedule pod cassandra-cassandra-0
I1113 15:44:18.767152       5 scheduler.go:301] Attempting to schedule pod: cassandra/cassandra-cassandra-0
I1113 15:44:18.767354       5 predicates.go:1398] Checking for prebound volumes with node affinity
I1113 15:44:18.767376       5 predicates.go:1436] VolumeNode predicate allows node "ip-10-39-0-113.ec2.internal" for pod "cassandra-cassandra-0" due to volume "pvc-f74642a7-c639-11e7-9644-12d2abc93736"
jakexks: Entering VolumeZoneChecker predicate
jakexks: nodeConstraints: map[string]string{"failure-domain.beta.kubernetes.io/zone":"us-east-1a", "failure-domain.beta.kubernetes.io/region":"us-east-1"}
jakexks: Examining label failure-domain.beta.kubernetes.io/region = us-east-1
jakexks: Examining label failure-domain.beta.kubernetes.io/zone = us-east-1b
I1113 15:44:18.767486       5 predicates.go:1398] Checking for prebound volumes with node affinity
I1113 15:44:18.767493       5 predicates.go:1436] VolumeNode predicate allows node "ip-10-39-1-58.ec2.internal" for pod "cassandra-cassandra-0" due to volume "pvc-f74642a7-c639-11e7-9644-12d2abc93736"
jakexks: Entering VolumeZoneChecker predicate
jakexks: nodeConstraints: map[string]string{"failure-domain.beta.kubernetes.io/region":"us-east-1", "failure-domain.beta.kubernetes.io/zone":"us-east-1b"}
jakexks: Examining label failure-domain.beta.kubernetes.io/region = us-east-1
jakexks: Examining label failure-domain.beta.kubernetes.io/zone = us-east-1b
jakexks: fell through volume zone checker predicate
jakexks: Entering VolumeZoneChecker predicate
jakexks: nodeConstraints: map[string]string{"failure-domain.beta.kubernetes.io/zone":"us-east-1a", "failure-domain.beta.kubernetes.io/region":"us-east-1"}
jakexks: Examining label failure-domain.beta.kubernetes.io/region = us-east-1
jakexks: Examining label failure-domain.beta.kubernetes.io/zone = us-east-1b
I1113 15:44:18.767612       5 predicates.go:1398] Checking for prebound volumes with node affinity
I1113 15:44:18.767618       5 predicates.go:1436] VolumeNode predicate allows node "ip-10-39-0-98.ec2.internal" for pod "cassandra-cassandra-0" due to volume "pvc-f74642a7-c639-11e7-9644-12d2abc93736"
I1113 15:44:18.769541       5 factory.go:979] Attempting to bind cassandra-cassandra-0 to ip-10-39-1-58.ec2.internal
I1113 15:44:18.779436       5 event.go:218] Event(v1.ObjectReference{Kind:"Pod", Namespace:"cassandra", Name:"cassandra-cassandra-0", UID:"82bd33c5-c889-11e7-9644-12d2abc93736", APIVersion:"v1", ResourceVersion:"499243", FieldPath:""}): type: 'Normal' reason: 'Scheduled' Successfully assigned cassandra-cassandra-0 to ip-10-39-1-58.ec2.internal
I1113 15:46:29.254741       5 factory.go:793] About to try and schedule pod cassandra-cassandra-1
I1113 15:46:29.254836       5 scheduler.go:301] Attempting to schedule pod: cassandra/cassandra-cassandra-1
I1113 15:46:29.255607       5 factory.go:979] Attempting to bind cassandra-cassandra-1 to ip-10-39-1-58.ec2.internal
I1113 15:46:29.270300       5 event.go:218] Event(v1.ObjectReference{Kind:"Pod", Namespace:"cassandra", Name:"cassandra-cassandra-1", UID:"d0842164-c889-11e7-9644-12d2abc93736", APIVersion:"v1", ResourceVersion:"499446", FieldPath:""}): type: 'Normal' reason: 'Scheduled' Successfully assigned cassandra-cassandra-1 to ip-10-39-1-58.ec2.internal