This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 0 failed / 2 succeeded
Started2020-03-29 09:18
Elapsed2h0m
Revisionmaster
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/6b11fe09-b72a-4ac6-beca-c43fdf385943/targets/test'}}
resultstorehttps://source.cloud.google.com/results/invocations/6b11fe09-b72a-4ac6-beca-c43fdf385943/targets/test

No Test Failures!


Show 2 Passed Tests

Show 49 Skipped Tests

Error lines from build-log.txt

... skipping 344 lines ...
Trying to find master named 'e2e-cda6dc7bc5-b49e0-master'
Looking for address 'e2e-cda6dc7bc5-b49e0-master-ip'
Using master: e2e-cda6dc7bc5-b49e0-master (external IP: 35.247.104.160)
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-jkns-gci-gce-reboot-1-3_e2e-cda6dc7bc5-b49e0" set.
User "k8s-jkns-gci-gce-reboot-1-3_e2e-cda6dc7bc5-b49e0" set.
Context "k8s-jkns-gci-gce-reboot-1-3_e2e-cda6dc7bc5-b49e0" created.
Switched to context "k8s-jkns-gci-gce-reboot-1-3_e2e-cda6dc7bc5-b49e0".
... skipping 35 lines ...
e2e-cda6dc7bc5-b49e0-minion-group-8p4c         Ready                      <none>   3m50s   v1.15.12-beta.0.9+8de4013f5815f7
e2e-cda6dc7bc5-b49e0-minion-group-fm1v         Ready                      <none>   3m50s   v1.15.12-beta.0.9+8de4013f5815f7
e2e-cda6dc7bc5-b49e0-windows-node-group-cmp9   Ready                      <none>   9s      v1.15.12-beta.0.9+8de4013f5815f7
e2e-cda6dc7bc5-b49e0-windows-node-group-cqhg   Ready                      <none>   8s      v1.15.12-beta.0.9+8de4013f5815f7
e2e-cda6dc7bc5-b49e0-windows-node-group-r2mv   Ready                      <none>   6s      v1.15.12-beta.0.9+8de4013f5815f7
Validate output:
NAME                 STATUS    MESSAGE             ERROR
controller-manager   Healthy   ok                  
scheduler            Healthy   ok                  
etcd-0               Healthy   {"health":"true"}   
etcd-1               Healthy   {"health":"true"}   
Cluster validation succeeded
Done, listing cluster services:
... skipping 177 lines ...
[sig-storage] In-tree Volumes
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
  [Driver: local][LocalVolumeType: blockfs]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
    [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
      should fail if subpath directory is outside the volume [Slow] [BeforeEach]
      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216

      Driver local doesn't support DynamicPV -- skipping

      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
------------------------------
... skipping 83 lines ...
[sig-storage] In-tree Volumes
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
  [Driver: local][LocalVolumeType: dir-link-bindmounted]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
    [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
      should fail if subpath directory is outside the volume [Slow] [BeforeEach]
      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216

      Driver local doesn't support DynamicPV -- skipping

      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
------------------------------
... skipping 9 lines ...
[sig-storage] In-tree Volumes
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
  [Driver: local][LocalVolumeType: tmpfs]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
    [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
      should fail if subpath directory is outside the volume [Slow] [BeforeEach]
      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216

      Driver local doesn't support DynamicPV -- skipping

      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
------------------------------
... skipping 11 lines ...
[sig-storage] In-tree Volumes
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
  [Driver: emptydir]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
    [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
      should fail if subpath with backstepping is outside the volume [Slow] [BeforeEach]
      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254

      Driver emptydir doesn't support DynamicPV -- skipping

      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
------------------------------
... skipping 11 lines ...
[sig-storage] In-tree Volumes
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
  [Driver: hostPath]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
    [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
      should fail if subpath directory is outside the volume [Slow] [BeforeEach]
      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216

      Driver hostPath doesn't support DynamicPV -- skipping

      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
------------------------------
... skipping 202 lines ...
[sig-storage] In-tree Volumes
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
  [Driver: local][LocalVolumeType: dir-link-bindmounted]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
    [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
      should fail if subpath with backstepping is outside the volume [Slow] [BeforeEach]
      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:254

      Driver local doesn't support DynamicPV -- skipping

      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:142
------------------------------
... skipping 127 lines ...
[sig-storage] In-tree Volumes
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
  [Driver: vSphere]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/in_tree_volumes.go:66
    [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
    /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:91
      should fail if subpath directory is outside the volume [Slow] [BeforeEach]
      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:216

      Driver vSphere doesn't support ntfs -- skipping

      /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/storage/testsuites/base.go:147
------------------------------
... skipping 76 lines ...
• [SLOW TEST:38.350 seconds]
[sig-api-machinery] Garbage collector
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apimachinery/framework.go:23
  should orphan RS created by deployment when deleteOptions.PropagationPolicy is Orphan [Conformance]
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:697
------------------------------
SSSSSSSS{"component":"entrypoint","file":"prow/entrypoint/run.go:164","func":"k8s.io/test-infra/prow/entrypoint.Options.ExecuteProcess","level":"error","msg":"Process did not finish before 2h0m0s timeout","time":"2020-03-29T11:18:05Z"}
2020/03/29 11:18:06 process.go:199: Interrupt after 2h0m0s timeout during /home/prow/go/src/k8s.io/windows-testing/gce/run-e2e.sh --ginkgo.focus=\[Conformance\]|\[NodeConformance\]|\[sig-windows\] --ginkgo.skip=\[LinuxOnly\]|\[Serial\]|\[Feature:.+\] --minStartupPods=8 --node-os-distro=windows. Will terminate in another 15m

------------------------------
[BeforeEach] [sig-apps] Deployment
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:150
STEP: Creating a kubernetes client
... skipping 28 lines ...
Mar 29 11:18:06.393: INFO: Running AfterSuite actions on all nodes
Mar 29 11:18:06.393: INFO: Waiting up to 3m0s for all (but 3) nodes to be ready
STEP: Destroying namespace "deployment-1250" for this suite.
Mar 29 11:18:07.447: INFO: deployment status: v1.DeploymentStatus{ObservedGeneration:1, Replicas:1, UpdatedReplicas:1, ReadyReplicas:0, AvailableReplicas:0, UnavailableReplicas:1, Conditions:[]v1.DeploymentCondition{v1.DeploymentCondition{Type:"Available", Status:"False", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63721077459, loc:(*time.Location)(0x7eb3a20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63721077459, loc:(*time.Location)(0x7eb3a20)}}, Reason:"MinimumReplicasUnavailable", Message:"Deployment does not have minimum availability."}, v1.DeploymentCondition{Type:"Progressing", Status:"True", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63721077459, loc:(*time.Location)(0x7eb3a20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63721077459, loc:(*time.Location)(0x7eb3a20)}}, Reason:"ReplicaSetUpdated", Message:"ReplicaSet \"test-recreate-deployment-c988ddd56\" is progressing."}}, CollisionCount:(*int32)(nil)}
Mar 29 11:18:09.447: INFO: deployment status: v1.DeploymentStatus{ObservedGeneration:1, Replicas:1, UpdatedReplicas:1, ReadyReplicas:0, AvailableReplicas:0, UnavailableReplicas:1, Conditions:[]v1.DeploymentCondition{v1.DeploymentCondition{Type:"Available", Status:"False", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63721077459, loc:(*time.Location)(0x7eb3a20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63721077459, loc:(*time.Location)(0x7eb3a20)}}, Reason:"MinimumReplicasUnavailable", Message:"Deployment does not have minimum availability."}, v1.DeploymentCondition{Type:"Progressing", Status:"True", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63721077459, loc:(*time.Location)(0x7eb3a20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63721077459, loc:(*time.Location)(0x7eb3a20)}}, Reason:"ReplicaSetUpdated", Message:"ReplicaSet \"test-recreate-deployment-c988ddd56\" is progressing."}}, CollisionCount:(*int32)(nil)}
Mar 29 11:18:11.447: INFO: deployment status: v1.DeploymentStatus{ObservedGeneration:1, Replicas:1, UpdatedReplicas:1, ReadyReplicas:0, AvailableReplicas:0, UnavailableReplicas:1, Conditions:[]v1.DeploymentCondition{v1.DeploymentCondition{Type:"Available", Status:"False", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63721077459, loc:(*time.Location)(0x7eb3a20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63721077459, loc:(*time.Location)(0x7eb3a20)}}, Reason:"MinimumReplicasUnavailable", Message:"Deployment does not have minimum availability."}, v1.DeploymentCondition{Type:"Progressing", Status:"True", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63721077459, loc:(*time.Location)(0x7eb3a20)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63721077459, loc:(*time.Location)(0x7eb3a20)}}, Reason:"ReplicaSetUpdated", Message:"ReplicaSet \"test-recreate-deployment-c988ddd56\" is progressing."}}, CollisionCount:(*int32)(nil)}
Mar 29 11:18:13.447: INFO: Unexpected error occurred: error waiting for deployment "test-recreate-deployment" status to match expectation: deployments.apps "test-recreate-deployment" not found
[AfterEach] [sig-apps] Deployment
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apps/deployment.go:66
Mar 29 11:18:13.486: INFO: Log out all the ReplicaSets if there is no deployment created
[AfterEach] [sig-apps] Deployment
  /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
STEP: Collecting events from namespace "deployment-1250".
STEP: Found 0 events.
Mar 29 11:18:13.598: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Mar 29 11:18:13.598: INFO: 
Mar 29 11:18:13.680: INFO: 
Logging node info for node e2e-cda6dc7bc5-b49e0-master
Mar 29 11:18:13.717: INFO: Node Info: &Node{ObjectMeta:k8s_io_apimachinery_pkg_apis_meta_v1.ObjectMeta{Name:e2e-cda6dc7bc5-b49e0-master,GenerateName:,Namespace:,SelfLink:/api/v1/nodes/e2e-cda6dc7bc5-b49e0-master,UID:e6b05acf-4dc5-40d5-b506-173eb739f303,ResourceVersion:16817,Generation:0,CreationTimestamp:2020-03-29 09:23:47 +0000 UTC,DeletionTimestamp:<nil>,DeletionGracePeriodSeconds:nil,Labels:map[string]string{beta.kubernetes.io/arch: amd64,beta.kubernetes.io/instance-type: n1-standard-1,beta.kubernetes.io/metadata-proxy-ready: true,beta.kubernetes.io/os: linux,cloud.google.com/metadata-proxy-ready: true,failure-domain.beta.kubernetes.io/region: us-west1,failure-domain.beta.kubernetes.io/zone: us-west1-b,kubernetes.io/arch: amd64,kubernetes.io/hostname: e2e-cda6dc7bc5-b49e0-master,kubernetes.io/os: linux,},Annotations:map[string]string{node.alpha.kubernetes.io/ttl: 0,volumes.kubernetes.io/controller-managed-attach-detach: true,},OwnerReferences:[],Finalizers:[],ClusterName:,Initializers:nil,ManagedFields:[],},Spec:NodeSpec{PodCIDR:10.64.0.0/24,DoNotUse_ExternalID:,ProviderID:gce://k8s-jkns-gci-gce-reboot-1-3/us-west1-b/e2e-cda6dc7bc5-b49e0-master,Unschedulable:true,Taints:[{node-under-test false NoSchedule <nil>} {node-role.kubernetes.io/master  NoSchedule <nil>} {node.kubernetes.io/unschedulable  NoSchedule <nil>}],ConfigSource:nil,},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-gce-pd: {{127 0} {<nil>} 127 DecimalSI},cpu: {{1 0} {<nil>} 1 DecimalSI},ephemeral-storage: {{16684785664 0} {<nil>}  BinarySI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3878416384 0} {<nil>} 3787516Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{attachable-volumes-gce-pd: {{127 0} {<nil>} 127 DecimalSI},cpu: {{1 0} {<nil>} 1 DecimalSI},ephemeral-storage: {{15016307073 0} {<nil>} 15016307073 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3616272384 0} {<nil>} 3531516Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[{NetworkUnavailable False 2020-03-29 09:23:47 +0000 UTC 2020-03-29 09:23:47 +0000 UTC RouteCreated NodeController create implicit route} {MemoryPressure False 2020-03-29 11:17:40 +0000 UTC 2020-03-29 09:23:47 +0000 UTC KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2020-03-29 11:17:40 +0000 UTC 2020-03-29 09:23:47 +0000 UTC KubeletHasNoDiskPressure kubelet has no disk pressure} {PIDPressure False 2020-03-29 11:17:40 +0000 UTC 2020-03-29 09:23:47 +0000 UTC KubeletHasSufficientPID kubelet has sufficient PID available} {Ready True 2020-03-29 11:17:40 +0000 UTC 2020-03-29 09:23:47 +0000 UTC KubeletReady kubelet is posting ready status. AppArmor enabled}],Addresses:[{InternalIP 10.40.0.2} {ExternalIP 35.247.104.160} {InternalDNS e2e-cda6dc7bc5-b49e0-master.c.k8s-jkns-gci-gce-reboot-1-3.internal} {Hostname e2e-cda6dc7bc5-b49e0-master.c.k8s-jkns-gci-gce-reboot-1-3.internal}],DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:2325555cf2c5e941a84903d870443754,SystemUUID:2325555C-F2C5-E941-A849-03D870443754,BootID:097c8757-cfcb-46f6-8f95-38fa07a1868e,KernelVersion:4.14.94+,OSImage:Container-Optimized OS from Google,ContainerRuntimeVersion:docker://18.9.3,KubeletVersion:v1.15.12-beta.0.9+8de4013f5815f7,KubeProxyVersion:v1.15.12-beta.0.9+8de4013f5815f7,OperatingSystem:linux,Architecture:amd64,},Images:[{[k8s.gcr.io/etcd@sha256:02cd751eef4f7dcea7986e58d51903dab39baf4606f636b50891f30190abce2c k8s.gcr.io/etcd:3.3.10-1] 295923553} {[gcr.io/stackdriver-agents/stackdriver-logging-agent@sha256:6c8574a40816676cd908cfa89d16463002b56ca05fa76d0c912e116bc0ab867e gcr.io/stackdriver-agents/stackdriver-logging-agent:1.6.8] 264721247} {[k8s.gcr.io/kube-apiserver:v1.15.12-beta.0.9_8de4013f5815f7] 247635661} {[k8s.gcr.io/kube-controller-manager:v1.15.12-beta.0.9_8de4013f5815f7] 198439763} {[k8s.gcr.io/kube-scheduler:v1.15.12-beta.0.9_8de4013f5815f7] 95032786} {[k8s.gcr.io/kube-addon-manager@sha256:3e315022a842d782a28e729720f21091dde21f1efea28868d65ec595ad871616 k8s.gcr.io/kube-addon-manager:v9.0.2] 83076028} {[k8s.gcr.io/etcd-empty-dir-cleanup@sha256:13e18f320022be5cf7c1c38a6207d02c07d603b52c1dd47b7c69e9324bd3c641 k8s.gcr.io/etcd-empty-dir-cleanup:3.3.10.1] 73958468} {[k8s.gcr.io/ingress-gce-glbc-amd64@sha256:14f14351a03038b238232e60850a9cfa0dffbed0590321ef84216a432accc1ca k8s.gcr.io/ingress-gce-glbc-amd64:v1.2.3] 71797285} {[k8s.gcr.io/prometheus-to-sd@sha256:14666989f40bb7c896c3e775a93c6873e2b791d65bc65579f58a078b7f9a764e k8s.gcr.io/prometheus-to-sd:v0.5.0] 41861013} {[k8s.gcr.io/metadata-proxy@sha256:e914645f22e946bce5165737e1b244e0a296ad1f0f81a9531adc57af2780978a k8s.gcr.io/metadata-proxy:v0.1.12] 11337839} {[k8s.gcr.io/pause@sha256:f78411e19d84a252e53bff71a4407a5686c46983a2c2eeed83929b888179acea k8s.gcr.io/pause:3.1] 742472}],VolumesInUse:[],VolumesAttached:[],Config:&NodeConfigStatus{Assigned:nil,Active:nil,LastKnownGood:nil,Error:,},},}
Mar 29 11:18:13.718: INFO: 
Logging kubelet events for node e2e-cda6dc7bc5-b49e0-master
Mar 29 11:18:13.755: INFO: 
Logging pods the kubelet thinks is on node e2e-cda6dc7bc5-b49e0-master
Mar 29 11:18:13.811: INFO: kube-controller-manager-e2e-cda6dc7bc5-b49e0-master started at 2020-03-29 09:22:57 +0000 UTC (0+1 container statuses recorded)
Mar 29 11:18:13.811: INFO: 	Container kube-controller-manager ready: true, restart count 0
... skipping 18 lines ...
Mar 29 11:18:13.811: INFO: l7-lb-controller-v1.2.3-e2e-cda6dc7bc5-b49e0-master started at 2020-03-29 09:23:26 +0000 UTC (0+1 container statuses recorded)
Mar 29 11:18:13.811: INFO: 	Container l7-lb-controller ready: true, restart count 0
Mar 29 11:18:13.960: INFO: 
Latency metrics for node e2e-cda6dc7bc5-b49e0-master
Mar 29 11:18:13.960: INFO: 
Logging node info for node e2e-cda6dc7bc5-b49e0-minion-group-8p4c
Mar 29 11:18:13.997: INFO: Node Info: &Node{ObjectMeta:k8s_io_apimachinery_pkg_apis_meta_v1.ObjectMeta{Name:e2e-cda6dc7bc5-b49e0-minion-group-8p4c,GenerateName:,Namespace:,SelfLink:/api/v1/nodes/e2e-cda6dc7bc5-b49e0-minion-group-8p4c,UID:2ac3b0fd-2768-45ef-8d2e-b85064cf0aeb,ResourceVersion:16744,Generation:0,CreationTimestamp:2020-03-29 09:23:51 +0000 UTC,DeletionTimestamp:<nil>,DeletionGracePeriodSeconds:nil,Labels:map[string]string{beta.kubernetes.io/arch: amd64,beta.kubernetes.io/instance-type: n1-standard-2,beta.kubernetes.io/metadata-proxy-ready: true,beta.kubernetes.io/os: linux,cloud.google.com/metadata-proxy-ready: true,failure-domain.beta.kubernetes.io/region: us-west1,failure-domain.beta.kubernetes.io/zone: us-west1-b,kubernetes.io/arch: amd64,kubernetes.io/hostname: e2e-cda6dc7bc5-b49e0-minion-group-8p4c,kubernetes.io/os: linux,},Annotations:map[string]string{node.alpha.kubernetes.io/ttl: 0,volumes.kubernetes.io/controller-managed-attach-detach: true,},OwnerReferences:[],Finalizers:[],ClusterName:,Initializers:nil,ManagedFields:[],},Spec:NodeSpec{PodCIDR:10.64.5.0/24,DoNotUse_ExternalID:,ProviderID:gce://k8s-jkns-gci-gce-reboot-1-3/us-west1-b/e2e-cda6dc7bc5-b49e0-minion-group-8p4c,Unschedulable:false,Taints:[{node-under-test false NoSchedule <nil>}],ConfigSource:nil,},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-gce-pd: {{127 0} {<nil>} 127 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{101241290752 0} {<nil>}  BinarySI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{7841861632 0} {<nil>} 7658068Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{attachable-volumes-gce-pd: {{127 0} {<nil>} 127 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{91117161526 0} {<nil>} 91117161526 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{7579717632 0} {<nil>} 7402068Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[{CorruptDockerOverlay2 False 2020-03-29 11:17:26 +0000 UTC 2020-03-29 09:28:25 +0000 UTC CorruptDockerOverlay2 docker overlay2 is functioning properly} {KernelDeadlock False 2020-03-29 11:17:26 +0000 UTC 2020-03-29 09:23:23 +0000 UTC KernelHasNoDeadlock kernel has no deadlock} {ReadonlyFilesystem False 2020-03-29 11:17:26 +0000 UTC 2020-03-29 09:23:23 +0000 UTC FilesystemIsNotReadOnly Filesystem is not read-only} {FrequentUnregisterNetDevice False 2020-03-29 11:17:26 +0000 UTC 2020-03-29 09:28:25 +0000 UTC UnregisterNetDevice node is functioning properly} {FrequentKubeletRestart False 2020-03-29 11:17:26 +0000 UTC 2020-03-29 09:28:25 +0000 UTC FrequentKubeletRestart kubelet is functioning properly} {FrequentDockerRestart False 2020-03-29 11:17:26 +0000 UTC 2020-03-29 09:28:26 +0000 UTC FrequentDockerRestart docker is functioning properly} {FrequentContainerdRestart False 2020-03-29 11:17:26 +0000 UTC 2020-03-29 09:28:27 +0000 UTC FrequentContainerdRestart containerd is functioning properly} {NetworkUnavailable False 2020-03-29 09:23:52 +0000 UTC 2020-03-29 09:23:52 +0000 UTC RouteCreated NodeController create implicit route} {MemoryPressure False 2020-03-29 11:17:30 +0000 UTC 2020-03-29 09:23:51 +0000 UTC KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2020-03-29 11:17:30 +0000 UTC 2020-03-29 09:23:51 +0000 UTC KubeletHasNoDiskPressure kubelet has no disk pressure} {PIDPressure False 2020-03-29 11:17:30 +0000 UTC 2020-03-29 09:23:51 +0000 UTC KubeletHasSufficientPID kubelet has sufficient PID available} {Ready True 2020-03-29 11:17:30 +0000 UTC 2020-03-29 09:23:51 +0000 UTC KubeletReady kubelet is posting ready status. AppArmor enabled}],Addresses:[{InternalIP 10.40.0.7} {ExternalIP 35.197.27.54} {InternalDNS e2e-cda6dc7bc5-b49e0-minion-group-8p4c.c.k8s-jkns-gci-gce-reboot-1-3.internal} {Hostname e2e-cda6dc7bc5-b49e0-minion-group-8p4c.c.k8s-jkns-gci-gce-reboot-1-3.internal}],DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:6be95db01911d49f3cf4d9b259751b1d,SystemUUID:6BE95DB0-1911-D49F-3CF4-D9B259751B1D,BootID:7ac7ec90-6363-4f39-b94c-f723dec63164,KernelVersion:4.14.94+,OSImage:Container-Optimized OS from Google,ContainerRuntimeVersion:docker://18.9.3,KubeletVersion:v1.15.12-beta.0.9+8de4013f5815f7,KubeProxyVersion:v1.15.12-beta.0.9+8de4013f5815f7,OperatingSystem:linux,Architecture:amd64,},Images:[{[gcr.io/stackdriver-agents/stackdriver-logging-agent@sha256:6c8574a40816676cd908cfa89d16463002b56ca05fa76d0c912e116bc0ab867e gcr.io/stackdriver-agents/stackdriver-logging-agent:1.6.8] 264721247} {[k8s.gcr.io/kubernetes-dashboard-amd64@sha256:0ae6b69432e78069c5ce2bcde0fe409c5c4d6f0f4d9cd50a17974fea38898747 k8s.gcr.io/kubernetes-dashboard-amd64:v1.10.1] 121711221} {[k8s.gcr.io/kube-proxy:v1.15.12-beta.0.9_8de4013f5815f7] 95529390} {[k8s.gcr.io/fluentd-gcp-scaler@sha256:4f28f10fb89506768910b858f7a18ffb996824a16d70d5ac895e49687df9ff58 k8s.gcr.io/fluentd-gcp-scaler:0.5.2] 90498960} {[k8s.gcr.io/heapster-amd64@sha256:9fae0af136ce0cf4f88393b3670f7139ffc464692060c374d2ae748e13144521 k8s.gcr.io/heapster-amd64:v1.6.0-beta.1] 76016169} {[k8s.gcr.io/cluster-proportional-autoscaler-amd64@sha256:0abeb6a79ad5aec10e920110446a97fb75180da8680094acb6715de62507f4b0 k8s.gcr.io/cluster-proportional-autoscaler-amd64:1.6.0] 47668785} {[k8s.gcr.io/prometheus-to-sd@sha256:14666989f40bb7c896c3e775a93c6873e2b791d65bc65579f58a078b7f9a764e k8s.gcr.io/prometheus-to-sd:v0.5.0] 41861013} {[k8s.gcr.io/coredns@sha256:02382353821b12c21b062c59184e227e001079bb13ebd01f9d3270ba0fcbf1e4 k8s.gcr.io/coredns:1.3.1] 40303560} {[k8s.gcr.io/addon-resizer@sha256:8075ed6db9baad249d9cf2656c0ecaad8d87133baf20286b1953dfb3fb06e75d k8s.gcr.io/addon-resizer:1.8.5] 35110823} {[k8s.gcr.io/metadata-proxy@sha256:e914645f22e946bce5165737e1b244e0a296ad1f0f81a9531adc57af2780978a k8s.gcr.io/metadata-proxy:v0.1.12] 11337839} {[k8s.gcr.io/defaultbackend-amd64@sha256:4dc5e07c8ca4e23bddb3153737d7b8c556e5fb2f29c4558b7cd6e6df99c512c7 k8s.gcr.io/defaultbackend-amd64:1.5] 5132544} {[k8s.gcr.io/pause@sha256:f78411e19d84a252e53bff71a4407a5686c46983a2c2eeed83929b888179acea k8s.gcr.io/pause:3.1] 742472}],VolumesInUse:[],VolumesAttached:[],Config:&NodeConfigStatus{Assigned:nil,Active:nil,LastKnownGood:nil,Error:,},},}
Mar 29 11:18:13.997: INFO: 
Logging kubelet events for node e2e-cda6dc7bc5-b49e0-minion-group-8p4c
Mar 29 11:18:14.035: INFO: 
Logging pods the kubelet thinks is on node e2e-cda6dc7bc5-b49e0-minion-group-8p4c
Mar 29 11:18:14.088: INFO: fluentd-gcp-scaler-6848d689fb-m44cv started at 2020-03-29 09:23:51 +0000 UTC (0+1 container statuses recorded)
Mar 29 11:18:14.088: INFO: 	Container fluentd-gcp-scaler ready: true, restart count 0
... skipping 17 lines ...
Mar 29 11:18:14.088: INFO: kube-proxy-e2e-cda6dc7bc5-b49e0-minion-group-8p4c started at 2020-03-29 09:23:51 +0000 UTC (0+1 container statuses recorded)
Mar 29 11:18:14.088: INFO: 	Container kube-proxy ready: true, restart count 0
Mar 29 11:18:14.223: INFO: 
Latency metrics for node e2e-cda6dc7bc5-b49e0-minion-group-8p4c
Mar 29 11:18:14.223: INFO: 
Logging node info for node e2e-cda6dc7bc5-b49e0-minion-group-fm1v
Mar 29 11:18:14.260: INFO: Node Info: &Node{ObjectMeta:k8s_io_apimachinery_pkg_apis_meta_v1.ObjectMeta{Name:e2e-cda6dc7bc5-b49e0-minion-group-fm1v,GenerateName:,Namespace:,SelfLink:/api/v1/nodes/e2e-cda6dc7bc5-b49e0-minion-group-fm1v,UID:6903f694-ba51-4e36-8e7e-c1b65280f749,ResourceVersion:16767,Generation:0,CreationTimestamp:2020-03-29 09:23:51 +0000 UTC,DeletionTimestamp:<nil>,DeletionGracePeriodSeconds:nil,Labels:map[string]string{beta.kubernetes.io/arch: amd64,beta.kubernetes.io/instance-type: n1-standard-2,beta.kubernetes.io/metadata-proxy-ready: true,beta.kubernetes.io/os: linux,cloud.google.com/metadata-proxy-ready: true,failure-domain.beta.kubernetes.io/region: us-west1,failure-domain.beta.kubernetes.io/zone: us-west1-b,kubernetes.io/arch: amd64,kubernetes.io/hostname: e2e-cda6dc7bc5-b49e0-minion-group-fm1v,kubernetes.io/os: linux,},Annotations:map[string]string{node.alpha.kubernetes.io/ttl: 0,volumes.kubernetes.io/controller-managed-attach-detach: true,},OwnerReferences:[],Finalizers:[],ClusterName:,Initializers:nil,ManagedFields:[],},Spec:NodeSpec{PodCIDR:10.64.4.0/24,DoNotUse_ExternalID:,ProviderID:gce://k8s-jkns-gci-gce-reboot-1-3/us-west1-b/e2e-cda6dc7bc5-b49e0-minion-group-fm1v,Unschedulable:false,Taints:[{node-under-test false NoSchedule <nil>}],ConfigSource:nil,},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-gce-pd: {{127 0} {<nil>} 127 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{101241290752 0} {<nil>}  BinarySI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{7841853440 0} {<nil>} 7658060Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{attachable-volumes-gce-pd: {{127 0} {<nil>} 127 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{91117161526 0} {<nil>} 91117161526 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{7579709440 0} {<nil>} 7402060Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[{ReadonlyFilesystem False 2020-03-29 11:17:33 +0000 UTC 2020-03-29 09:23:29 +0000 UTC FilesystemIsNotReadOnly Filesystem is not read-only} {CorruptDockerOverlay2 False 2020-03-29 11:17:33 +0000 UTC 2020-03-29 09:28:29 +0000 UTC CorruptDockerOverlay2 docker overlay2 is functioning properly} {FrequentUnregisterNetDevice False 2020-03-29 11:17:33 +0000 UTC 2020-03-29 09:28:30 +0000 UTC UnregisterNetDevice node is functioning properly} {FrequentKubeletRestart False 2020-03-29 11:17:33 +0000 UTC 2020-03-29 09:28:30 +0000 UTC FrequentKubeletRestart kubelet is functioning properly} {FrequentDockerRestart False 2020-03-29 11:17:33 +0000 UTC 2020-03-29 09:28:31 +0000 UTC FrequentDockerRestart docker is functioning properly} {FrequentContainerdRestart False 2020-03-29 11:17:33 +0000 UTC 2020-03-29 09:28:32 +0000 UTC FrequentContainerdRestart containerd is functioning properly} {KernelDeadlock False 2020-03-29 11:17:33 +0000 UTC 2020-03-29 09:23:29 +0000 UTC KernelHasNoDeadlock kernel has no deadlock} {NetworkUnavailable False 2020-03-29 09:23:52 +0000 UTC 2020-03-29 09:23:52 +0000 UTC RouteCreated NodeController create implicit route} {MemoryPressure False 2020-03-29 11:17:30 +0000 UTC 2020-03-29 09:23:51 +0000 UTC KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2020-03-29 11:17:30 +0000 UTC 2020-03-29 09:23:51 +0000 UTC KubeletHasNoDiskPressure kubelet has no disk pressure} {PIDPressure False 2020-03-29 11:17:30 +0000 UTC 2020-03-29 09:23:51 +0000 UTC KubeletHasSufficientPID kubelet has sufficient PID available} {Ready True 2020-03-29 11:17:30 +0000 UTC 2020-03-29 09:23:52 +0000 UTC KubeletReady kubelet is posting ready status. AppArmor enabled}],Addresses:[{InternalIP 10.40.0.6} {ExternalIP 34.82.219.184} {InternalDNS e2e-cda6dc7bc5-b49e0-minion-group-fm1v.c.k8s-jkns-gci-gce-reboot-1-3.internal} {Hostname e2e-cda6dc7bc5-b49e0-minion-group-fm1v.c.k8s-jkns-gci-gce-reboot-1-3.internal}],DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:5295ee4f6c5835958890774765669156,SystemUUID:5295EE4F-6C58-3595-8890-774765669156,BootID:6478c35c-5978-4e94-8697-ac90c21dff0b,KernelVersion:4.14.94+,OSImage:Container-Optimized OS from Google,ContainerRuntimeVersion:docker://18.9.3,KubeletVersion:v1.15.12-beta.0.9+8de4013f5815f7,KubeProxyVersion:v1.15.12-beta.0.9+8de4013f5815f7,OperatingSystem:linux,Architecture:amd64,},Images:[{[gcr.io/stackdriver-agents/stackdriver-logging-agent@sha256:6c8574a40816676cd908cfa89d16463002b56ca05fa76d0c912e116bc0ab867e gcr.io/stackdriver-agents/stackdriver-logging-agent:1.6.8] 264721247} {[k8s.gcr.io/kube-proxy:v1.15.12-beta.0.9_8de4013f5815f7] 95529390} {[k8s.gcr.io/event-exporter@sha256:06acf489ab092b4fb49273e426549a52c0fcd1dbcb67e03d5935b5ee1a899c3e k8s.gcr.io/event-exporter:v0.2.5] 47261019} {[k8s.gcr.io/prometheus-to-sd@sha256:14666989f40bb7c896c3e775a93c6873e2b791d65bc65579f58a078b7f9a764e k8s.gcr.io/prometheus-to-sd:v0.5.0] 41861013} {[k8s.gcr.io/coredns@sha256:02382353821b12c21b062c59184e227e001079bb13ebd01f9d3270ba0fcbf1e4 k8s.gcr.io/coredns:1.3.1] 40303560} {[k8s.gcr.io/metrics-server-amd64@sha256:4ca116565ff6a46e582bada50ba3550f95b368db1d2415829241a565a6c38e2a k8s.gcr.io/metrics-server-amd64:v0.3.3] 39933796} {[k8s.gcr.io/addon-resizer@sha256:8075ed6db9baad249d9cf2656c0ecaad8d87133baf20286b1953dfb3fb06e75d k8s.gcr.io/addon-resizer:1.8.5] 35110823} {[k8s.gcr.io/metadata-proxy@sha256:e914645f22e946bce5165737e1b244e0a296ad1f0f81a9531adc57af2780978a k8s.gcr.io/metadata-proxy:v0.1.12] 11337839} {[k8s.gcr.io/pause@sha256:f78411e19d84a252e53bff71a4407a5686c46983a2c2eeed83929b888179acea k8s.gcr.io/pause:3.1] 742472}],VolumesInUse:[],VolumesAttached:[],Config:&NodeConfigStatus{Assigned:nil,Active:nil,LastKnownGood:nil,Error:,},},}
Mar 29 11:18:14.261: INFO: 
Logging kubelet events for node e2e-cda6dc7bc5-b49e0-minion-group-fm1v
Mar 29 11:18:14.298: INFO: 
Logging pods the kubelet thinks is on node e2e-cda6dc7bc5-b49e0-minion-group-fm1v
Mar 29 11:18:14.354: INFO: metadata-proxy-v0.1-cmbg2 started at 2020-03-29 09:23:51 +0000 UTC (0+2 container statuses recorded)
Mar 29 11:18:14.354: INFO: 	Container metadata-proxy ready: true, restart count 0
... skipping 54 lines ...
Mar 29 11:18:15.874: INFO: 	Container test-webserver4 ready: true, restart count 1
Mar 29 11:18:15.874: INFO: 	Container webhook-115v1 ready: false, restart count 0
Mar 29 11:18:15.874: INFO: netserver-2 started at 2020-03-29 11:17:21 +0000 UTC (0+1 container statuses recorded)
Mar 29 11:18:15.874: INFO: 	Container webserver ready: false, restart count 0
Mar 29 11:18:16.168: INFO: namespace deployment-1250 deletion completed in 9.7242929s

{"component":"entrypoint","file":"prow/entrypoint/run.go:245","func":"k8s.io/test-infra/prow/entrypoint.gracefullyTerminate","level":"error","msg":"Process did not exit before 15s grace period","time":"2020-03-29T11:18:20Z"}