This job view page is being replaced by Spyglass soon. Check out the new job view.
PRwangyysde: Create HPA v2 Stable API
ResultABORTED
Tests 0 failed / 45 succeeded
Started2021-07-29 13:40
Elapsed55m34s
Revision1dd5b878c38b816fdf83ba3dbd13d2e08b4b1cce
Refs 102534
job-versionv1.23.0-alpha.0.46+8b866129a9aa32
kubetest-version
revisionv1.23.0-alpha.0.46+8b866129a9aa32

No Test Failures!


Show 45 Passed Tests

Error lines from build-log.txt

... skipping 682 lines ...
Looking for address 'e2e-102534-95a39-master-ip'
Looking for address 'e2e-102534-95a39-master-internal-ip'
Using master: e2e-102534-95a39-master (external IP: 34.139.83.213; internal IP: 10.40.0.2)
Waiting up to 300 seconds for cluster initialization.

  This will continually check to see if the API for kubernetes is reachable.
  This may time out if there was some uncaught error during start up.

Kubernetes cluster created.
Cluster "k8s-infra-e2e-boskos-scale-14_e2e-102534-95a39" set.
User "k8s-infra-e2e-boskos-scale-14_e2e-102534-95a39" set.
Context "k8s-infra-e2e-boskos-scale-14_e2e-102534-95a39" created.
Switched to context "k8s-infra-e2e-boskos-scale-14_e2e-102534-95a39".
... skipping 229 lines ...
e2e-102534-95a39-minion-group-znpt   Ready                         <none>   66s    v1.23.0-alpha.0.46+8b866129a9aa32
e2e-102534-95a39-minion-group-ztrq   Ready                         <none>   67s    v1.23.0-alpha.0.46+8b866129a9aa32
e2e-102534-95a39-minion-group-zw3p   Ready                         <none>   68s    v1.23.0-alpha.0.46+8b866129a9aa32
Warning: v1 ComponentStatus is deprecated in v1.19+
Validate output:
Warning: v1 ComponentStatus is deprecated in v1.19+
NAME                 STATUS    MESSAGE                         ERROR
scheduler            Healthy   ok                              
etcd-1               Healthy   {"health":"true","reason":""}   
etcd-0               Healthy   {"health":"true","reason":""}   
controller-manager   Healthy   ok                              
Cluster validation encountered some problems, but cluster should be in working order
...ignoring non-fatal errors in validate-cluster
Done, listing cluster services:

Kubernetes control plane is running at https://34.139.83.213
GLBCDefaultBackend is running at https://34.139.83.213/api/v1/namespaces/kube-system/services/default-http-backend:http/proxy
CoreDNS is running at https://34.139.83.213/api/v1/namespaces/kube-system/services/kube-dns:dns/proxy
Metrics-server is running at https://34.139.83.213/api/v1/namespaces/kube-system/services/https:metrics-server:/proxy
... skipping 350 lines ...
I0729 14:11:06.673951  106189 util.go:96] All 3 expected targets are ready
I0729 14:11:06.674022  106189 prometheus_measurement.go:75] SLOMeasurement has started
I0729 14:11:06.863555  106189 util.go:93] 5/6 targets are ready, example not ready target: {map[container:ping-server endpoint:metrics instance:10.64.82.4:8080 job:ping-server namespace:probes pod:ping-server-596b77b4cb-rstkp service:ping-server] unknown}
I0729 14:11:21.904957  106189 util.go:96] All 6 expected targets are ready
I0729 14:11:21.905013  106189 simple_test_executor.go:173] Step "[step: 01] starting measurements" ended
I0729 14:11:21.905031  106189 simple_test_executor.go:145] Step "[step: 02] Creating k8s services" started
E0729 14:11:21.968332  106189 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0729 14:11:27.674009  106189 simple_test_executor.go:173] Step "[step: 02] Creating k8s services" ended
I0729 14:11:27.674042  106189 simple_test_executor.go:145] Step "[step: 03] Creating PriorityClass for DaemonSets" started
I0729 14:11:27.710972  106189 simple_test_executor.go:173] Step "[step: 03] Creating PriorityClass for DaemonSets" ended
I0729 14:11:27.711017  106189 simple_test_executor.go:145] Step "[step: 04] create objects configmaps and secrets" started
I0729 14:11:32.211032  106189 simple_test_executor.go:173] Step "[step: 04] create objects configmaps and secrets" ended
I0729 14:11:32.211087  106189 simple_test_executor.go:145] Step "[step: 05] Starting measurement for 'create objects'" started
... skipping 320 lines ...
I0729 14:12:20.326801  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-283)
I0729 14:12:20.751656  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=small-deployment-273): Pods: 5 out of 5 created, 5 running (5 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:12:20.751911  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-273)
I0729 14:12:21.023568  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=small-deployment-34): Pods: 5 out of 5 created, 5 running (5 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:12:21.023762  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-34)
I0729 14:12:21.821561  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-169)
E0729 14:12:22.505812  106189 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0729 14:12:22.934479  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=small-deployment-46): Pods: 5 out of 5 created, 5 running (5 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:12:22.934645  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-46)
I0729 14:12:23.264865  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=small-deployment-43): Pods: 5 out of 5 created, 5 running (5 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:12:23.265060  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-43)
I0729 14:12:23.292562  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-224)
I0729 14:12:23.362924  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(group=load,name=medium-statefulset-0): Pods: 30 out of 30 created, 29 running (29 updated), 1 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
... skipping 415 lines ...
I0729 14:13:22.580616  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-259)
I0729 14:13:22.599297  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-133)
I0729 14:13:22.832440  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-30)
I0729 14:13:22.852563  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=small-deployment-217): Pods: 5 out of 5 created, 5 running (5 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:13:22.852695  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-217)
I0729 14:13:23.033344  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-258)
E0729 14:13:23.062820  106189 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0729 14:13:23.068034  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-187)
I0729 14:13:23.094653  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-97)
I0729 14:13:23.129525  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=small-deployment-92): Pods: 5 out of 5 created, 5 running (5 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:13:23.129696  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-92)
I0729 14:13:23.314304  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=small-deployment-205): Pods: 5 out of 5 created, 5 running (5 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:13:23.314507  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-205)
... skipping 359 lines ...
I0729 14:14:23.359679  106189 phase_latency.go:146] PodStartupLatency: perc50: 0s, perc90: 0s, perc99: 0s
I0729 14:14:23.359944  106189 phase_latency.go:141] PodStartupLatency: 0 worst run_to_watch latencies: []
I0729 14:14:23.359954  106189 phase_latency.go:146] PodStartupLatency: perc50: 0s, perc90: 0s, perc99: 0s
I0729 14:14:23.360032  106189 simple_test_executor.go:173] Step "[step: 12] Collecting scheduler throughput measurements" ended
I0729 14:14:23.360053  106189 simple_test_executor.go:145] Step "[step: 13] delete scheduler throughput pods" started
I0729 14:14:23.399719  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-2), labelSelector(name=scheduler-throughput-deployment-0)
E0729 14:14:23.616801  106189 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0729 14:14:28.450425  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-2), labelSelector(name=scheduler-throughput-deployment-0): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:14:28.450607  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-2), labelSelector(name=scheduler-throughput-deployment-0)
I0729 14:14:53.400275  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-3), labelSelector(name=scheduler-throughput-deployment-0)
I0729 14:14:58.451860  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-3), labelSelector(name=scheduler-throughput-deployment-0): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:14:58.452064  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-3), labelSelector(name=scheduler-throughput-deployment-0)
I0729 14:15:23.401710  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-4), labelSelector(name=scheduler-throughput-deployment-0)
E0729 14:15:24.166384  106189 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0729 14:15:28.453955  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-4), labelSelector(name=scheduler-throughput-deployment-0): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:15:28.454130  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-4), labelSelector(name=scheduler-throughput-deployment-0)
I0729 14:15:53.401116  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-5), labelSelector(name=scheduler-throughput-deployment-0)
I0729 14:15:58.503052  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-5), labelSelector(name=scheduler-throughput-deployment-0): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:15:58.503258  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-5), labelSelector(name=scheduler-throughput-deployment-0)
I0729 14:16:23.403078  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-6), labelSelector(name=scheduler-throughput-deployment-0)
E0729 14:16:24.738151  106189 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0729 14:16:28.454668  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-6), labelSelector(name=scheduler-throughput-deployment-0): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:16:28.454831  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-6), labelSelector(name=scheduler-throughput-deployment-0)
I0729 14:16:53.403080  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-7), labelSelector(name=scheduler-throughput-deployment-0)
I0729 14:16:58.455468  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-7), labelSelector(name=scheduler-throughput-deployment-0): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:16:58.455659  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-7), labelSelector(name=scheduler-throughput-deployment-0)
I0729 14:17:23.403139  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-8), labelSelector(name=scheduler-throughput-deployment-0)
E0729 14:17:25.299805  106189 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0729 14:17:28.459751  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-8), labelSelector(name=scheduler-throughput-deployment-0): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:17:28.459913  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-8), labelSelector(name=scheduler-throughput-deployment-0)
I0729 14:17:53.404521  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-9), labelSelector(name=scheduler-throughput-deployment-0)
I0729 14:17:58.456413  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-9), labelSelector(name=scheduler-throughput-deployment-0): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:17:58.456623  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-9), labelSelector(name=scheduler-throughput-deployment-0)
I0729 14:18:23.405098  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-10), labelSelector(name=scheduler-throughput-deployment-0)
E0729 14:18:25.848066  106189 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0729 14:18:28.456979  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-10), labelSelector(name=scheduler-throughput-deployment-0): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:18:28.457152  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-10), labelSelector(name=scheduler-throughput-deployment-0)
I0729 14:18:53.406104  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-11), labelSelector(name=scheduler-throughput-deployment-0)
I0729 14:18:58.457577  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-11), labelSelector(name=scheduler-throughput-deployment-0): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:18:58.457854  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-11), labelSelector(name=scheduler-throughput-deployment-0)
I0729 14:19:23.372737  106189 simple_test_executor.go:173] Step "[step: 13] delete scheduler throughput pods" ended
I0729 14:19:23.372788  106189 simple_test_executor.go:145] Step "[step: 14] Waiting for scheduler throughput pods to be deleted" started
I0729 14:19:23.372877  106189 wait_for_controlled_pods.go:200] WaitForControlledPodsRunning: waiting for controlled pods measurement...
E0729 14:19:26.406832  106189 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0729 14:19:28.422634  106189 wait_for_controlled_pods.go:267] WaitForControlledPodsRunning: running 0, deleted 10, timeout: 0, unknown: 0
I0729 14:19:28.422688  106189 wait_for_controlled_pods.go:281] WaitForControlledPodsRunning: 0/0 Deployments are running with all pods
I0729 14:19:28.422706  106189 simple_test_executor.go:173] Step "[step: 14] Waiting for scheduler throughput pods to be deleted" ended
I0729 14:19:28.422727  106189 simple_test_executor.go:145] Step "[step: 15] delete huge services" started
I0729 14:19:28.840663  106189 simple_test_executor.go:173] Step "[step: 15] delete huge services" ended
I0729 14:19:28.840712  106189 simple_test_executor.go:145] Step "[step: 16] Starting latency pod measurements" started
... skipping 817 lines ...
I0729 14:20:26.547529  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-287)
I0729 14:20:26.583699  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-262): Pods: 1 out of 1 created, 1 running (1 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:20:26.583850  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-262)
I0729 14:20:26.749298  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-288)
I0729 14:20:26.783920  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-263): Pods: 1 out of 1 created, 1 running (1 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:20:26.784139  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-263)
E0729 14:20:26.945982  106189 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0729 14:20:26.949879  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-289)
I0729 14:20:26.985467  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-264): Pods: 1 out of 1 created, 1 running (1 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:20:26.985648  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-264)
I0729 14:20:27.150391  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-290)
I0729 14:20:27.187521  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-265): Pods: 1 out of 1 created, 1 running (1 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:20:27.187662  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-265)
... skipping 821 lines ...
I0729 14:21:27.126725  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-37): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:21:27.127057  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-37)
I0729 14:21:27.290330  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-63)
I0729 14:21:27.325884  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-38): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:21:27.326391  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-38)
I0729 14:21:27.488219  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-64)
E0729 14:21:27.498213  106189 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0729 14:21:27.526250  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-39): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:21:27.526493  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-39)
I0729 14:21:27.689769  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-65)
I0729 14:21:27.727568  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-40): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:21:27.727864  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-40)
I0729 14:21:27.892455  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-66)
... skipping 893 lines ...
I0729 14:22:27.690921  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-364)
I0729 14:22:27.727309  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-339): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:22:27.727513  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-339)
I0729 14:22:27.892703  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-365)
I0729 14:22:27.927723  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-340): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:22:27.928013  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-340)
E0729 14:22:28.057990  106189 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0729 14:22:28.091746  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-366)
I0729 14:22:28.129198  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-341): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:22:28.129348  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-341)
I0729 14:22:28.295197  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-367)
I0729 14:22:28.328066  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-342): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:22:28.328568  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=latency-deployment-342)
... skipping 1028 lines ...
I0729 14:23:28.276529  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=medium-deployment-8): Pods: 38 out of 38 created, 38 running (38 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:23:28.276800  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=medium-deployment-8)
I0729 14:23:28.317544  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=small-deployment-112): Pods: 7 out of 7 created, 7 running (7 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:23:28.317820  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-112)
I0729 14:23:28.403210  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-38)
I0729 14:23:28.517235  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-245)
E0729 14:23:28.599808  106189 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0729 14:23:28.685194  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-216)
I0729 14:23:28.721120  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-18)
I0729 14:23:28.732603  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=small-deployment-280): Pods: 2 out of 2 created, 2 running (2 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:23:28.732808  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-280)
I0729 14:23:28.751958  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-296)
I0729 14:23:28.832545  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-282)
... skipping 473 lines ...
I0729 14:24:28.124721  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=small-deployment-5): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:24:28.124895  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-5)
I0729 14:24:28.321675  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-143)
I0729 14:24:28.643758  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=small-deployment-169): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:24:28.643932  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-169)
I0729 14:24:29.119343  106189 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-252)
E0729 14:24:29.140689  106189 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0729 14:24:29.151992  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=small-deployment-198): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:24:29.152152  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-198)
I0729 14:24:29.475072  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=small-deployment-11): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:24:29.475439  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-11)
I0729 14:24:29.502486  106189 wait_for_pods.go:94] WaitForControlledPodsRunning: namespace(test-wjxzho-1), labelSelector(name=small-deployment-296): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0729 14:24:29.502694  106189 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-wjxzho-1), labelSelector(name=small-deployment-296)
... skipping 393 lines ...