This job view page is being replaced by Spyglass soon. Check out the new job view.
PRwojtek-t: Unify towards k8s.io/utils/clock - part 2
ResultABORTED
Tests 0 failed / 45 succeeded
Started2021-09-15 08:10
Elapsed53m51s
Revisionbf98c9ad185ed30f3bf58a2b8b5f56f90b26dd45
Refs 105026
job-versionv1.23.0-alpha.2.15+2f8a407bba2ebe
kubetest-version
revisionv1.23.0-alpha.2.15+2f8a407bba2ebe

No Test Failures!


Show 45 Passed Tests

Error lines from build-log.txt

... skipping 694 lines ...
Looking for address 'e2e-105026-95a39-master-ip'
Looking for address 'e2e-105026-95a39-master-internal-ip'
Using master: e2e-105026-95a39-master (external IP: 34.139.128.199; 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-05_e2e-105026-95a39" set.
User "k8s-infra-e2e-boskos-scale-05_e2e-105026-95a39" set.
Context "k8s-infra-e2e-boskos-scale-05_e2e-105026-95a39" created.
Switched to context "k8s-infra-e2e-boskos-scale-05_e2e-105026-95a39".
... skipping 228 lines ...
e2e-105026-95a39-minion-group-zjx8   Ready                         <none>   52s   v1.23.0-alpha.2.15+2f8a407bba2ebe
e2e-105026-95a39-minion-group-zlvg   Ready                         <none>   51s   v1.23.0-alpha.2.15+2f8a407bba2ebe
e2e-105026-95a39-minion-group-zp1j   Ready                         <none>   55s   v1.23.0-alpha.2.15+2f8a407bba2ebe
Warning: v1 ComponentStatus is deprecated in v1.19+
Validate output:
Warning: v1 ComponentStatus is deprecated in v1.19+
NAME                 STATUS    MESSAGE                         ERROR
etcd-1               Healthy   {"health":"true","reason":""}   
etcd-0               Healthy   {"health":"true","reason":""}   
controller-manager   Healthy   ok                              
scheduler            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.128.199
GLBCDefaultBackend is running at https://34.139.128.199/api/v1/namespaces/kube-system/services/default-http-backend:http/proxy
CoreDNS is running at https://34.139.128.199/api/v1/namespaces/kube-system/services/kube-dns:dns/proxy
Metrics-server is running at https://34.139.128.199/api/v1/namespaces/kube-system/services/https:metrics-server:/proxy
... skipping 349 lines ...
I0915 08:38:17.642443  102222 util.go:96] All 3 expected targets are ready
I0915 08:38:17.642550  102222 prometheus_measurement.go:75] SLOMeasurement has started
I0915 08:38:17.844139  102222 util.go:93] 2/6 targets are ready, example not ready target: {map[container:ping-server endpoint:metrics instance:10.64.27.3:8080 job:ping-server namespace:probes pod:ping-server-596b77b4cb-ttdxq service:ping-server] unknown}
I0915 08:38:32.850797  102222 util.go:96] All 6 expected targets are ready
I0915 08:38:32.850868  102222 simple_test_executor.go:173] Step "[step: 01] starting measurements" ended
I0915 08:38:32.850888  102222 simple_test_executor.go:145] Step "[step: 02] Creating k8s services" started
E0915 08:38:32.940404  102222 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0915 08:38:38.714515  102222 simple_test_executor.go:173] Step "[step: 02] Creating k8s services" ended
I0915 08:38:38.714553  102222 simple_test_executor.go:145] Step "[step: 03] Creating PriorityClass for DaemonSets" started
I0915 08:38:38.752470  102222 simple_test_executor.go:173] Step "[step: 03] Creating PriorityClass for DaemonSets" ended
I0915 08:38:38.752518  102222 simple_test_executor.go:145] Step "[step: 04] create objects configmaps and secrets" started
I0915 08:38:43.252041  102222 simple_test_executor.go:173] Step "[step: 04] create objects configmaps and secrets" ended
I0915 08:38:43.252121  102222 simple_test_executor.go:145] Step "[step: 05] Starting measurement for 'create objects'" started
... skipping 334 lines ...
I0915 08:39:32.883520  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-199)
I0915 08:39:33.035890  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-111): Pods: 5 out of 5 created, 5 running (5 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:39:33.036067  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-111)
I0915 08:39:33.206439  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-73)
I0915 08:39:33.406360  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-31)
I0915 08:39:33.469430  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-58)
E0915 08:39:33.520600  102222 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0915 08:39:33.823353  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-123)
I0915 08:39:34.037154  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=medium-deployment-1)
I0915 08:39:34.070231  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-193): Pods: 5 out of 5 created, 5 running (5 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:39:34.070386  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-193)
I0915 08:39:34.180676  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-52)
I0915 08:39:34.784759  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-35): Pods: 5 out of 5 created, 5 running (5 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
... skipping 386 lines ...
I0915 08:40:33.648666  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-296): Pods: 5 out of 5 created, 5 running (5 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:40:33.648872  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-296)
I0915 08:40:33.817770  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-221): Pods: 5 out of 5 created, 5 running (5 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:40:33.817953  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-221)
I0915 08:40:33.858834  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-126): Pods: 5 out of 5 created, 5 running (5 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:40:33.859077  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-126)
E0915 08:40:34.069668  102222 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0915 08:40:34.164876  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-156): Pods: 5 out of 5 created, 5 running (5 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:40:34.165088  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-156)
I0915 08:40:34.353672  102222 reflector.go:175] Starting reflector <unspecified> (0s) from pkg/mod/k8s.io/client-go@v0.18.0/tools/cache/reflector.go:125
I0915 08:40:34.454628  102222 reflector.go:175] Starting reflector <unspecified> (0s) from pkg/mod/k8s.io/client-go@v0.18.0/tools/cache/reflector.go:125
I0915 08:40:34.460002  102222 reflector.go:181] Stopping reflector <unspecified> (0s) from pkg/mod/k8s.io/client-go@v0.18.0/tools/cache/reflector.go:125
I0915 08:40:34.554676  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=daemonset-0)
... skipping 384 lines ...
I0915 08:41:34.266204  102222 phase_latency.go:146] PodStartupLatency: perc50: 0s, perc90: 0s, perc99: 0s
I0915 08:41:34.266528  102222 phase_latency.go:141] PodStartupLatency: 0 worst schedule_to_run latencies: []
I0915 08:41:34.266549  102222 phase_latency.go:146] PodStartupLatency: perc50: 0s, perc90: 0s, perc99: 0s
I0915 08:41:34.266636  102222 simple_test_executor.go:173] Step "[step: 12] Collecting scheduler throughput measurements" ended
I0915 08:41:34.266661  102222 simple_test_executor.go:145] Step "[step: 13] delete scheduler throughput pods" started
I0915 08:41:34.308162  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-2), labelSelector(name=scheduler-throughput-deployment-0)
E0915 08:41:34.614023  102222 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0915 08:41:39.359909  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:41:39.360133  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-2), labelSelector(name=scheduler-throughput-deployment-0)
I0915 08:42:04.308555  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-3), labelSelector(name=scheduler-throughput-deployment-0)
I0915 08:42:09.359927  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:42:09.360173  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-3), labelSelector(name=scheduler-throughput-deployment-0)
I0915 08:42:34.309560  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-4), labelSelector(name=scheduler-throughput-deployment-0)
E0915 08:42:35.170509  102222 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0915 08:42:39.410882  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:42:39.411057  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-4), labelSelector(name=scheduler-throughput-deployment-0)
I0915 08:43:04.309796  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-5), labelSelector(name=scheduler-throughput-deployment-0)
I0915 08:43:09.361414  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:43:09.361618  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-5), labelSelector(name=scheduler-throughput-deployment-0)
I0915 08:43:34.310027  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-6), labelSelector(name=scheduler-throughput-deployment-0)
E0915 08:43:35.728412  102222 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0915 08:43:39.410610  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:43:39.410815  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-6), labelSelector(name=scheduler-throughput-deployment-0)
I0915 08:44:04.310719  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-7), labelSelector(name=scheduler-throughput-deployment-0)
I0915 08:44:09.362695  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:44:09.362882  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-7), labelSelector(name=scheduler-throughput-deployment-0)
I0915 08:44:34.313509  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-8), labelSelector(name=scheduler-throughput-deployment-0)
E0915 08:44:36.275966  102222 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0915 08:44:39.365680  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:44:39.365898  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-8), labelSelector(name=scheduler-throughput-deployment-0)
I0915 08:45:04.312755  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-9), labelSelector(name=scheduler-throughput-deployment-0)
I0915 08:45:09.363696  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:45:09.363821  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-9), labelSelector(name=scheduler-throughput-deployment-0)
I0915 08:45:34.313859  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-10), labelSelector(name=scheduler-throughput-deployment-0)
E0915 08:45:36.814634  102222 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0915 08:45:39.364676  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:45:39.364886  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-10), labelSelector(name=scheduler-throughput-deployment-0)
I0915 08:46:04.314619  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-11), labelSelector(name=scheduler-throughput-deployment-0)
I0915 08:46:09.365022  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:46:09.365183  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-11), labelSelector(name=scheduler-throughput-deployment-0)
I0915 08:46:34.280154  102222 simple_test_executor.go:173] Step "[step: 13] delete scheduler throughput pods" ended
I0915 08:46:34.280195  102222 simple_test_executor.go:145] Step "[step: 14] Waiting for scheduler throughput pods to be deleted" started
I0915 08:46:34.280233  102222 wait_for_controlled_pods.go:200] WaitForControlledPodsRunning: waiting for controlled pods measurement...
E0915 08:46:37.358173  102222 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0915 08:46:39.327932  102222 wait_for_controlled_pods.go:267] WaitForControlledPodsRunning: running 0, deleted 10, timeout: 0, unknown: 0
I0915 08:46:39.327976  102222 wait_for_controlled_pods.go:281] WaitForControlledPodsRunning: 0/0 Deployments are running with all pods
I0915 08:46:39.327994  102222 simple_test_executor.go:173] Step "[step: 14] Waiting for scheduler throughput pods to be deleted" ended
I0915 08:46:39.328015  102222 simple_test_executor.go:145] Step "[step: 15] delete huge services" started
I0915 08:46:39.763006  102222 simple_test_executor.go:173] Step "[step: 15] delete huge services" ended
I0915 08:46:39.763046  102222 simple_test_executor.go:145] Step "[step: 16] Starting latency pod measurements" started
... skipping 818 lines ...
I0915 08:47:37.522852  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:47:37.522974  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-262)
I0915 08:47:37.689066  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-288)
I0915 08:47:37.723854  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:47:37.724014  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-263)
I0915 08:47:37.889481  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-289)
E0915 08:47:37.924306  102222 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0915 08:47:37.924530  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:47:37.924658  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-264)
I0915 08:47:38.091670  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-290)
I0915 08:47:38.124837  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:47:38.124978  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-265)
I0915 08:47:38.292523  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-291)
... skipping 822 lines ...
I0915 08:48:38.075972  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-63)
I0915 08:48:38.113571  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:48:38.113690  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-38)
I0915 08:48:38.282549  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-64)
I0915 08:48:38.314621  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:48:38.314758  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-39)
E0915 08:48:38.465482  102222 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0915 08:48:38.476968  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-65)
I0915 08:48:38.517599  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:48:38.517766  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-40)
I0915 08:48:38.677039  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-66)
I0915 08:48:38.714455  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-41): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:48:38.714596  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-41)
... skipping 894 lines ...
I0915 08:49:38.661982  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-365)
I0915 08:49:38.697167  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:49:38.697421  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-340)
I0915 08:49:38.862422  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-366)
I0915 08:49:38.897503  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:49:38.897661  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-341)
E0915 08:49:39.009540  102222 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0915 08:49:39.063238  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-367)
I0915 08:49:39.098082  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-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 
I0915 08:49:39.098297  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-342)
I0915 08:49:39.264131  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-368)
I0915 08:49:39.299504  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-343): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:49:39.299694  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=latency-deployment-343)
... skipping 1071 lines ...
I0915 08:50:39.210782  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-188)
I0915 08:50:39.281763  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-245)
I0915 08:50:39.381014  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-68)
I0915 08:50:39.430933  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-105): Pods: 2 out of 2 created, 2 running (2 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:50:39.431078  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-105)
I0915 08:50:39.495748  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-251)
E0915 08:50:39.561903  102222 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0915 08:50:39.581537  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=medium-deployment-20)
I0915 08:50:39.602692  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-37): Pods: 7 out of 7 created, 7 running (7 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:50:39.602818  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-37)
I0915 08:50:39.606471  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-243): Pods: 2 out of 2 created, 2 running (2 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 1 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:50:39.681533  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-272): Pods: 7 out of 7 created, 7 running (7 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:50:39.681700  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-272)
... skipping 469 lines ...
I0915 08:51:39.238335  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-277)
I0915 08:51:39.296278  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-192)
I0915 08:51:39.964765  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-274): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:51:39.964886  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-274)
I0915 08:51:39.973258  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-119): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:51:39.973399  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-119)
E0915 08:51:40.108268  102222 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0915 08:51:40.279036  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-12)
I0915 08:51:40.465145  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-149)
I0915 08:51:40.501699  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-170)
I0915 08:51:40.563794  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=medium-deployment-15)
I0915 08:51:40.944801  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-177): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:51:40.944999  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-177)
... skipping 388 lines ...
I0915 08:52:40.289080  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-28)
I0915 08:52:40.432241  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-262): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:52:40.432435  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-262)
I0915 08:52:40.458300  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-290): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:52:40.458476  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-290)
I0915 08:52:40.549548  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-2)
E0915 08:52:40.677425  102222 etcd_metrics.go:147] EtcdMetrics: failed to collect etcd database size
I0915 08:52:40.793660  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-77): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:52:40.793843  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-77)
I0915 08:52:41.146320  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-230): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:52:41.146423  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-230)
I0915 08:52:41.651436  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-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 
I0915 08:52:41.651597  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-39)
... skipping 36 lines ...
I0915 08:52:44.828233  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-41)
I0915 08:52:44.855811  102222 reflector.go:175] Starting reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-247)
I0915 08:52:44.931103  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-181): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:52:44.931260  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-181)
I0915 08:52:45.600909  102222 wait_for_pods.go:96] WaitForControlledPodsRunning: namespace(test-f7hse2-1), labelSelector(name=small-deployment-2): Pods: 0 out of 0 created, 0 running (0 updated), 0 pending scheduled, 0 not scheduled, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I0915 08:52:45.601078  102222 reflector.go:181] Stopping reflector *v1.Pod (0s) from *v1.PodStore: namespace(test-f7hse2-1), labelSelector(name=small-deployment-2)
{"component":"entrypoint","file":"prow/entrypoint/run.go:169","func":"k8s.io/test-infra/prow/entrypoint.Options.ExecuteProcess","level":"error","msg":"Entrypoint received interrupt: terminated","severity":"error","time":"2021-09-15T08:52:45Z"}
++ early_exit_handler
++ '[' -n 173 ']'
++ kill -TERM 173
++ cleanup_dind
++ [[ true == \t\r\u\e ]]
++ echo 'Cleaning up after docker'
... skipping 4 lines ...