This job view page is being replaced by Spyglass soon. Check out the new job view.
PRcofyc: Add builtin storage class label
ResultFAILURE
Tests 0 failed / 0 succeeded
Started2019-06-27 09:47
Elapsed30m20s
Revisiona17ec7e3c4b91df32479414cd85fe1615523060f
Refs 121

No Test Failures!


Error lines from build-log.txt

... skipping 260 lines ...
Trying to find master named 'e2e-test-prow-master'
Looking for address 'e2e-test-prow-master-ip'
Using master: e2e-test-prow-master (external IP: 35.238.120.127)
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-e2e-gce-slow_e2e-test-prow" set.
User "k8s-jkns-e2e-gce-slow_e2e-test-prow" set.
Context "k8s-jkns-e2e-gce-slow_e2e-test-prow" created.
Switched to context "k8s-jkns-e2e-gce-slow_e2e-test-prow".
... skipping 20 lines ...
NAME                              STATUS                     ROLES    AGE   VERSION
e2e-test-prow-master              Ready,SchedulingDisabled   <none>   38s   v1.16.0-alpha.0.1573+376a2cb3449fe7
e2e-test-prow-minion-group-2ldr   Ready                      <none>   26s   v1.16.0-alpha.0.1573+376a2cb3449fe7
e2e-test-prow-minion-group-n5wm   Ready                      <none>   12s   v1.16.0-alpha.0.1573+376a2cb3449fe7
e2e-test-prow-minion-group-x7mj   Ready                      <none>   14s   v1.16.0-alpha.0.1573+376a2cb3449fe7
Validate output:
NAME                 STATUS    MESSAGE             ERROR
etcd-0               Healthy   {"health":"true"}   
scheduler            Healthy   ok                  
etcd-1               Healthy   {"health":"true"}   
controller-manager   Healthy   ok                  
Cluster validation succeeded
Done, listing cluster services:
... skipping 246 lines ...
Fetched 4829 kB in 0s (8735 kB/s)
Selecting previously unselected package bash.
(Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 3937 files and directories currently installed.)
Preparing to unpack .../archives/bash_4.4-5_amd64.deb ...
Unpacking bash (4.4-5) ...
Setting up bash (4.4-5) ...
update-alternatives: error: alternative path /usr/share/man/man7/bash-builtins.7.gz doesn't exist

Selecting previously unselected package e2fslibs:amd64.
(Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 4004 files and directories currently installed.)
Preparing to unpack .../e2fslibs_1.43.4-2_amd64.deb ...
Unpacking e2fslibs:amd64 (1.43.4-2) ...
Setting up e2fslibs:amd64 (1.43.4-2) ...
Selecting previously unselected package libblkid1:amd64.
... skipping 233 lines ...
Jun 27 10:08:54.926: INFO: hostexec-e2e-test-prow-minion-group-2ldr      e2e-test-prow-minion-group-2ldr  Running         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-06-27 09:58:25 +0000 UTC  } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-06-27 09:58:28 +0000 UTC  } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-06-27 09:58:28 +0000 UTC  } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-06-27 09:58:25 +0000 UTC  }]
Jun 27 10:08:54.926: INFO: hostexec-e2e-test-prow-minion-group-n5wm      e2e-test-prow-minion-group-n5wm  Running         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-06-27 09:58:30 +0000 UTC  } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-06-27 09:58:32 +0000 UTC  } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-06-27 09:58:32 +0000 UTC  } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-06-27 09:58:30 +0000 UTC  }]
Jun 27 10:08:54.927: INFO: hostexec-e2e-test-prow-minion-group-x7mj      e2e-test-prow-minion-group-x7mj  Running         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-06-27 09:58:34 +0000 UTC  } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-06-27 09:58:37 +0000 UTC  } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-06-27 09:58:37 +0000 UTC  } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-06-27 09:58:34 +0000 UTC  }]
Jun 27 10:08:54.927: INFO: 
Jun 27 10:08:54.932: INFO: 
Logging node info for node e2e-test-prow-master
Jun 27 10:08:54.936: INFO: Node Info: &Node{ObjectMeta:k8s_io_apimachinery_pkg_apis_meta_v1.ObjectMeta{Name:e2e-test-prow-master,GenerateName:,Namespace:,SelfLink:/api/v1/nodes/e2e-test-prow-master,UID:b13fc244-51b1-4eb4-bd14-e21be120ffe0,ResourceVersion:2526,Generation:0,CreationTimestamp:2019-06-27 09:54:18 +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-central1,failure-domain.beta.kubernetes.io/zone: us-central1-b,kubernetes.io/arch: amd64,kubernetes.io/hostname: e2e-test-prow-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-e2e-gce-slow/us-central1-b/e2e-test-prow-master,Unschedulable:true,Taints:[{node-role.kubernetes.io/master  NoSchedule <nil>} {node.kubernetes.io/unschedulable  NoSchedule <nil>}],ConfigSource:nil,},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-gce-pd: {{128 0} {<nil>} 128 DecimalSI},cpu: {{1 0} {<nil>} 1 DecimalSI},ephemeral-storage: {{16684785664 0} {<nil>}  BinarySI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3878420480 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{attachable-volumes-gce-pd: {{128 0} {<nil>} 128 DecimalSI},cpu: {{1 0} {<nil>} 1 DecimalSI},ephemeral-storage: {{15016307073 0} {<nil>} 15016307073 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3616276480 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[{NetworkUnavailable False 2019-06-27 09:54:30 +0000 UTC 2019-06-27 09:54:30 +0000 UTC RouteCreated RouteController created a route} {MemoryPressure False 2019-06-27 10:08:40 +0000 UTC 2019-06-27 09:54:18 +0000 UTC KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2019-06-27 10:08:40 +0000 UTC 2019-06-27 09:54:18 +0000 UTC KubeletHasNoDiskPressure kubelet has no disk pressure} {PIDPressure False 2019-06-27 10:08:40 +0000 UTC 2019-06-27 09:54:18 +0000 UTC KubeletHasSufficientPID kubelet has sufficient PID available} {Ready True 2019-06-27 10:08:40 +0000 UTC 2019-06-27 09:54:18 +0000 UTC KubeletReady kubelet is posting ready status. AppArmor enabled}],Addresses:[{InternalIP 10.128.0.2} {ExternalIP 35.238.120.127} {InternalDNS e2e-test-prow-master.c.k8s-jkns-e2e-gce-slow.internal} {Hostname e2e-test-prow-master.c.k8s-jkns-e2e-gce-slow.internal}],DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:6401633ad806a1bc2be60f88155629d1,SystemUUID:6401633A-D806-A1BC-2BE6-0F88155629D1,BootID:0f6e681a-2938-441e-8d3e-2b4e6f12f357,KernelVersion:4.14.94+,OSImage:Container-Optimized OS from Google,ContainerRuntimeVersion:docker://18.9.3,KubeletVersion:v1.16.0-alpha.0.1573+376a2cb3449fe7,KubeProxyVersion:v1.16.0-alpha.0.1573+376a2cb3449fe7,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.16.0-alpha.0.1573_376a2cb3449fe7] 240130461} {[k8s.gcr.io/kube-controller-manager:v1.16.0-alpha.0.1573_376a2cb3449fe7] 189964695} {[k8s.gcr.io/kube-scheduler:v1.16.0-alpha.0.1573_376a2cb3449fe7] 94846106} {[k8s.gcr.io/kube-addon-manager@sha256:382c220b3531d9f95bf316a16b7282cc2ef929cd8a89a9dd3f5933edafc41a8e k8s.gcr.io/kube-addon-manager:v9.0.1] 83076194} {[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:,},},}
Jun 27 10:08:54.936: INFO: 
Logging kubelet events for node e2e-test-prow-master
Jun 27 10:08:54.943: INFO: 
Logging pods the kubelet thinks is on node e2e-test-prow-master
Jun 27 10:08:54.962: INFO: kube-scheduler-e2e-test-prow-master started at 2019-06-27 09:53:32 +0000 UTC (0+1 container statuses recorded)
Jun 27 10:08:54.962: INFO: 	Container kube-scheduler ready: true, restart count 0
... skipping 18 lines ...
Jun 27 10:08:54.962: INFO: etcd-server-events-e2e-test-prow-master started at 2019-06-27 09:53:32 +0000 UTC (0+1 container statuses recorded)
Jun 27 10:08:54.962: INFO: 	Container etcd-container ready: true, restart count 0
Jun 27 10:08:55.031: INFO: 
Latency metrics for node e2e-test-prow-master
Jun 27 10:08:55.031: INFO: 
Logging node info for node e2e-test-prow-minion-group-2ldr
Jun 27 10:08:55.035: INFO: Node Info: &Node{ObjectMeta:k8s_io_apimachinery_pkg_apis_meta_v1.ObjectMeta{Name:e2e-test-prow-minion-group-2ldr,GenerateName:,Namespace:,SelfLink:/api/v1/nodes/e2e-test-prow-minion-group-2ldr,UID:d5b9cd56-05a8-492f-bda0-91078da27895,ResourceVersion:2522,Generation:0,CreationTimestamp:2019-06-27 09:54:30 +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-central1,failure-domain.beta.kubernetes.io/zone: us-central1-b,kubernetes.io/arch: amd64,kubernetes.io/hostname: e2e-test-prow-minion-group-2ldr,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.1.0/24,DoNotUse_ExternalID:,ProviderID:gce://k8s-jkns-e2e-gce-slow/us-central1-b/e2e-test-prow-minion-group-2ldr,Unschedulable:false,Taints:[],ConfigSource:nil,},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-gce-pd: {{128 0} {<nil>} 128 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{101241290752 0} {<nil>}  BinarySI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{7841865728 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{attachable-volumes-gce-pd: {{128 0} {<nil>} 128 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{91117161526 0} {<nil>} 91117161526 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{7579721728 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[{CorruptDockerOverlay2 False 2019-06-27 10:08:38 +0000 UTC 2019-06-27 09:59:30 +0000 UTC CorruptDockerOverlay2 docker overlay2 is functioning properly} {FrequentKubeletRestart False 2019-06-27 10:08:38 +0000 UTC 2019-06-27 09:59:30 +0000 UTC FrequentKubeletRestart kubelet is functioning properly} {FrequentDockerRestart False 2019-06-27 10:08:38 +0000 UTC 2019-06-27 09:59:31 +0000 UTC FrequentDockerRestart docker is functioning properly} {FrequentContainerdRestart False 2019-06-27 10:08:38 +0000 UTC 2019-06-27 09:59:32 +0000 UTC FrequentContainerdRestart containerd is functioning properly} {KernelDeadlock False 2019-06-27 10:08:38 +0000 UTC 2019-06-27 09:54:29 +0000 UTC KernelHasNoDeadlock kernel has no deadlock} {ReadonlyFilesystem False 2019-06-27 10:08:38 +0000 UTC 2019-06-27 09:54:29 +0000 UTC FilesystemIsNotReadOnly Filesystem is not read-only} {FrequentUnregisterNetDevice False 2019-06-27 10:08:38 +0000 UTC 2019-06-27 09:59:30 +0000 UTC UnregisterNetDevice node is functioning properly} {NetworkUnavailable False 2019-06-27 09:54:55 +0000 UTC 2019-06-27 09:54:55 +0000 UTC RouteCreated RouteController created a route} {MemoryPressure False 2019-06-27 10:08:02 +0000 UTC 2019-06-27 09:54:30 +0000 UTC KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2019-06-27 10:08:02 +0000 UTC 2019-06-27 09:54:30 +0000 UTC KubeletHasNoDiskPressure kubelet has no disk pressure} {PIDPressure False 2019-06-27 10:08:02 +0000 UTC 2019-06-27 09:54:30 +0000 UTC KubeletHasSufficientPID kubelet has sufficient PID available} {Ready True 2019-06-27 10:08:02 +0000 UTC 2019-06-27 09:54:30 +0000 UTC KubeletReady kubelet is posting ready status. AppArmor enabled}],Addresses:[{InternalIP 10.128.0.5} {ExternalIP 34.68.143.218} {InternalDNS e2e-test-prow-minion-group-2ldr.c.k8s-jkns-e2e-gce-slow.internal} {Hostname e2e-test-prow-minion-group-2ldr.c.k8s-jkns-e2e-gce-slow.internal}],DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:fd4f769971dc74a2850817424e0badbe,SystemUUID:FD4F7699-71DC-74A2-8508-17424E0BADBE,BootID:2fbd5907-3103-43a8-a922-1a553ab87df1,KernelVersion:4.14.94+,OSImage:Container-Optimized OS from Google,ContainerRuntimeVersion:docker://18.9.3,KubeletVersion:v1.16.0-alpha.0.1573+376a2cb3449fe7,KubeProxyVersion:v1.16.0-alpha.0.1573+376a2cb3449fe7,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} {[gcr.io/k8s-jkns-e2e-gce-slow/local-volume-provisioner@sha256:712584d8e0f877ae53eaabc3c9092ad9eb9c4f9cb4655ad36ece73d9bb147f0d gcr.io/k8s-jkns-e2e-gce-slow/local-volume-provisioner:v2.3.2-30-geffdd2d7] 111849633} {[k8s.gcr.io/kube-proxy:v1.16.0-alpha.0.1573_376a2cb3449fe7] 94844585} {[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/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/ingress-gce-404-server-with-metrics-amd64@sha256:d83d8a481145d0eb71f8bd71ae236d1c6a931dd3bdcaf80919a8ec4a4d8aff74 k8s.gcr.io/ingress-gce-404-server-with-metrics-amd64:v1.6.0] 13513083} {[k8s.gcr.io/metadata-proxy@sha256:e914645f22e946bce5165737e1b244e0a296ad1f0f81a9531adc57af2780978a k8s.gcr.io/metadata-proxy:v0.1.12] 11337839} {[gcr.io/kubernetes-e2e-test-images/hostexec@sha256:90dfe59da029f9e536385037bc64e86cd3d6e55bae613ddbe69e554d79b0639d gcr.io/kubernetes-e2e-test-images/hostexec:1.1] 8490662} {[k8s.gcr.io/pause@sha256:f78411e19d84a252e53bff71a4407a5686c46983a2c2eeed83929b888179acea k8s.gcr.io/pause:3.1] 742472}],VolumesInUse:[],VolumesAttached:[],Config:&NodeConfigStatus{Assigned:nil,Active:nil,LastKnownGood:nil,Error:,},},}
Jun 27 10:08:55.035: INFO: 
Logging kubelet events for node e2e-test-prow-minion-group-2ldr
Jun 27 10:08:55.043: INFO: 
Logging pods the kubelet thinks is on node e2e-test-prow-minion-group-2ldr
Jun 27 10:08:55.053: INFO: event-exporter-v0.2.5-74995d487b-d5jsd started at 2019-06-27 09:55:05 +0000 UTC (0+2 container statuses recorded)
Jun 27 10:08:55.053: INFO: 	Container event-exporter ready: true, restart count 0
... skipping 25 lines ...
Jun 27 10:08:55.053: INFO: l7-default-backend-5dcf6857c6-pk5f2 started at 2019-06-27 09:55:01 +0000 UTC (0+1 container statuses recorded)
Jun 27 10:08:55.053: INFO: 	Container default-http-backend ready: true, restart count 0
Jun 27 10:08:55.092: INFO: 
Latency metrics for node e2e-test-prow-minion-group-2ldr
Jun 27 10:08:55.092: INFO: 
Logging node info for node e2e-test-prow-minion-group-n5wm
Jun 27 10:08:55.096: INFO: Node Info: &Node{ObjectMeta:k8s_io_apimachinery_pkg_apis_meta_v1.ObjectMeta{Name:e2e-test-prow-minion-group-n5wm,GenerateName:,Namespace:,SelfLink:/api/v1/nodes/e2e-test-prow-minion-group-n5wm,UID:c8d4bcfa-f3e4-40cb-a677-4dfd73dbf6d5,ResourceVersion:2570,Generation:0,CreationTimestamp:2019-06-27 09:54:44 +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-central1,failure-domain.beta.kubernetes.io/zone: us-central1-b,kubernetes.io/arch: amd64,kubernetes.io/hostname: e2e-test-prow-minion-group-n5wm,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.3.0/24,DoNotUse_ExternalID:,ProviderID:gce://k8s-jkns-e2e-gce-slow/us-central1-b/e2e-test-prow-minion-group-n5wm,Unschedulable:false,Taints:[],ConfigSource:nil,},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-gce-pd: {{128 0} {<nil>} 128 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{101241290752 0} {<nil>}  BinarySI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{7841865728 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{attachable-volumes-gce-pd: {{128 0} {<nil>} 128 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{91117161526 0} {<nil>} 91117161526 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{7579721728 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[{CorruptDockerOverlay2 False 2019-06-27 10:08:52 +0000 UTC 2019-06-27 09:59:43 +0000 UTC CorruptDockerOverlay2 docker overlay2 is functioning properly} {FrequentUnregisterNetDevice False 2019-06-27 10:08:52 +0000 UTC 2019-06-27 09:59:43 +0000 UTC UnregisterNetDevice node is functioning properly} {FrequentKubeletRestart False 2019-06-27 10:08:52 +0000 UTC 2019-06-27 09:59:43 +0000 UTC FrequentKubeletRestart kubelet is functioning properly} {FrequentDockerRestart False 2019-06-27 10:08:52 +0000 UTC 2019-06-27 09:59:44 +0000 UTC FrequentDockerRestart docker is functioning properly} {FrequentContainerdRestart False 2019-06-27 10:08:52 +0000 UTC 2019-06-27 09:59:45 +0000 UTC FrequentContainerdRestart containerd is functioning properly} {KernelDeadlock False 2019-06-27 10:08:52 +0000 UTC 2019-06-27 09:54:42 +0000 UTC KernelHasNoDeadlock kernel has no deadlock} {ReadonlyFilesystem False 2019-06-27 10:08:52 +0000 UTC 2019-06-27 09:54:42 +0000 UTC FilesystemIsNotReadOnly Filesystem is not read-only} {NetworkUnavailable False 2019-06-27 09:55:07 +0000 UTC 2019-06-27 09:55:07 +0000 UTC RouteCreated RouteController created a route} {MemoryPressure False 2019-06-27 10:08:45 +0000 UTC 2019-06-27 09:54:44 +0000 UTC KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2019-06-27 10:08:45 +0000 UTC 2019-06-27 09:54:44 +0000 UTC KubeletHasNoDiskPressure kubelet has no disk pressure} {PIDPressure False 2019-06-27 10:08:45 +0000 UTC 2019-06-27 09:54:44 +0000 UTC KubeletHasSufficientPID kubelet has sufficient PID available} {Ready True 2019-06-27 10:08:45 +0000 UTC 2019-06-27 09:54:44 +0000 UTC KubeletReady kubelet is posting ready status. AppArmor enabled}],Addresses:[{InternalIP 10.128.0.3} {ExternalIP 35.194.42.181} {InternalDNS e2e-test-prow-minion-group-n5wm.c.k8s-jkns-e2e-gce-slow.internal} {Hostname e2e-test-prow-minion-group-n5wm.c.k8s-jkns-e2e-gce-slow.internal}],DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:a38401f5219743b6bda962efedaca048,SystemUUID:A38401F5-2197-43B6-BDA9-62EFEDACA048,BootID:45e7752e-c1b4-4c67-b728-96237c2bafc0,KernelVersion:4.14.94+,OSImage:Container-Optimized OS from Google,ContainerRuntimeVersion:docker://18.9.3,KubeletVersion:v1.16.0-alpha.0.1573+376a2cb3449fe7,KubeProxyVersion:v1.16.0-alpha.0.1573+376a2cb3449fe7,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} {[gcr.io/k8s-jkns-e2e-gce-slow/local-volume-provisioner@sha256:712584d8e0f877ae53eaabc3c9092ad9eb9c4f9cb4655ad36ece73d9bb147f0d gcr.io/k8s-jkns-e2e-gce-slow/local-volume-provisioner:v2.3.2-30-geffdd2d7] 111849633} {[k8s.gcr.io/kube-proxy:v1.16.0-alpha.0.1573_376a2cb3449fe7] 94844585} {[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} {[gcr.io/kubernetes-e2e-test-images/hostexec@sha256:90dfe59da029f9e536385037bc64e86cd3d6e55bae613ddbe69e554d79b0639d gcr.io/kubernetes-e2e-test-images/hostexec:1.1] 8490662} {[k8s.gcr.io/pause@sha256:f78411e19d84a252e53bff71a4407a5686c46983a2c2eeed83929b888179acea k8s.gcr.io/pause:3.1] 742472}],VolumesInUse:[],VolumesAttached:[],Config:&NodeConfigStatus{Assigned:nil,Active:nil,LastKnownGood:nil,Error:,},},}
Jun 27 10:08:55.096: INFO: 
Logging kubelet events for node e2e-test-prow-minion-group-n5wm
Jun 27 10:08:55.105: INFO: 
Logging pods the kubelet thinks is on node e2e-test-prow-minion-group-n5wm
Jun 27 10:08:55.113: INFO: fluentd-gcp-v3.2.0-cxtjl started at 2019-06-27 09:54:44 +0000 UTC (0+2 container statuses recorded)
Jun 27 10:08:55.113: INFO: 	Container fluentd-gcp ready: true, restart count 0
... skipping 6 lines ...
Jun 27 10:08:55.113: INFO: kube-proxy-e2e-test-prow-minion-group-n5wm started at 2019-06-27 09:54:44 +0000 UTC (0+1 container statuses recorded)
Jun 27 10:08:55.113: INFO: 	Container kube-proxy ready: true, restart count 0
Jun 27 10:08:55.161: INFO: 
Latency metrics for node e2e-test-prow-minion-group-n5wm
Jun 27 10:08:55.161: INFO: 
Logging node info for node e2e-test-prow-minion-group-x7mj
Jun 27 10:08:55.165: INFO: Node Info: &Node{ObjectMeta:k8s_io_apimachinery_pkg_apis_meta_v1.ObjectMeta{Name:e2e-test-prow-minion-group-x7mj,GenerateName:,Namespace:,SelfLink:/api/v1/nodes/e2e-test-prow-minion-group-x7mj,UID:b9a94adb-3c3e-4e29-873c-e0d2fa7da60b,ResourceVersion:2565,Generation:0,CreationTimestamp:2019-06-27 09:54:42 +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-central1,failure-domain.beta.kubernetes.io/zone: us-central1-b,kubernetes.io/arch: amd64,kubernetes.io/hostname: e2e-test-prow-minion-group-x7mj,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.2.0/24,DoNotUse_ExternalID:,ProviderID:gce://k8s-jkns-e2e-gce-slow/us-central1-b/e2e-test-prow-minion-group-x7mj,Unschedulable:false,Taints:[],ConfigSource:nil,},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-gce-pd: {{128 0} {<nil>} 128 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{101241290752 0} {<nil>}  BinarySI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{7841865728 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{attachable-volumes-gce-pd: {{128 0} {<nil>} 128 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{91117161526 0} {<nil>} 91117161526 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{7579721728 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[{ReadonlyFilesystem False 2019-06-27 10:08:50 +0000 UTC 2019-06-27 09:54:41 +0000 UTC FilesystemIsNotReadOnly Filesystem is not read-only} {FrequentUnregisterNetDevice False 2019-06-27 10:08:50 +0000 UTC 2019-06-27 09:59:42 +0000 UTC UnregisterNetDevice node is functioning properly} {CorruptDockerOverlay2 False 2019-06-27 10:08:50 +0000 UTC 2019-06-27 09:59:42 +0000 UTC CorruptDockerOverlay2 docker overlay2 is functioning properly} {FrequentKubeletRestart False 2019-06-27 10:08:50 +0000 UTC 2019-06-27 09:59:42 +0000 UTC FrequentKubeletRestart kubelet is functioning properly} {FrequentDockerRestart False 2019-06-27 10:08:50 +0000 UTC 2019-06-27 09:59:43 +0000 UTC FrequentDockerRestart docker is functioning properly} {FrequentContainerdRestart False 2019-06-27 10:08:50 +0000 UTC 2019-06-27 09:59:44 +0000 UTC FrequentContainerdRestart containerd is functioning properly} {KernelDeadlock False 2019-06-27 10:08:50 +0000 UTC 2019-06-27 09:54:41 +0000 UTC KernelHasNoDeadlock kernel has no deadlock} {NetworkUnavailable False 2019-06-27 09:55:10 +0000 UTC 2019-06-27 09:55:10 +0000 UTC RouteCreated RouteController created a route} {MemoryPressure False 2019-06-27 10:08:13 +0000 UTC 2019-06-27 09:54:42 +0000 UTC KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2019-06-27 10:08:13 +0000 UTC 2019-06-27 09:54:42 +0000 UTC KubeletHasNoDiskPressure kubelet has no disk pressure} {PIDPressure False 2019-06-27 10:08:13 +0000 UTC 2019-06-27 09:54:42 +0000 UTC KubeletHasSufficientPID kubelet has sufficient PID available} {Ready True 2019-06-27 10:08:13 +0000 UTC 2019-06-27 09:54:42 +0000 UTC KubeletReady kubelet is posting ready status. AppArmor enabled}],Addresses:[{InternalIP 10.128.0.4} {ExternalIP 104.198.133.113} {InternalDNS e2e-test-prow-minion-group-x7mj.c.k8s-jkns-e2e-gce-slow.internal} {Hostname e2e-test-prow-minion-group-x7mj.c.k8s-jkns-e2e-gce-slow.internal}],DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:1ddcb471fa67b6436ab05c3010732014,SystemUUID:1DDCB471-FA67-B643-6AB0-5C3010732014,BootID:ad3c535e-aac1-426b-b152-20c1a2852395,KernelVersion:4.14.94+,OSImage:Container-Optimized OS from Google,ContainerRuntimeVersion:docker://18.9.3,KubeletVersion:v1.16.0-alpha.0.1573+376a2cb3449fe7,KubeProxyVersion:v1.16.0-alpha.0.1573+376a2cb3449fe7,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} {[gcr.io/k8s-jkns-e2e-gce-slow/local-volume-provisioner@sha256:712584d8e0f877ae53eaabc3c9092ad9eb9c4f9cb4655ad36ece73d9bb147f0d gcr.io/k8s-jkns-e2e-gce-slow/local-volume-provisioner:v2.3.2-30-geffdd2d7] 111849633} {[k8s.gcr.io/kube-proxy:v1.16.0-alpha.0.1573_376a2cb3449fe7] 94844585} {[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/metadata-proxy@sha256:e914645f22e946bce5165737e1b244e0a296ad1f0f81a9531adc57af2780978a k8s.gcr.io/metadata-proxy:v0.1.12] 11337839} {[gcr.io/kubernetes-e2e-test-images/hostexec@sha256:90dfe59da029f9e536385037bc64e86cd3d6e55bae613ddbe69e554d79b0639d gcr.io/kubernetes-e2e-test-images/hostexec:1.1] 8490662} {[k8s.gcr.io/pause@sha256:f78411e19d84a252e53bff71a4407a5686c46983a2c2eeed83929b888179acea k8s.gcr.io/pause:3.1] 742472}],VolumesInUse:[],VolumesAttached:[],Config:&NodeConfigStatus{Assigned:nil,Active:nil,LastKnownGood:nil,Error:,},},}
Jun 27 10:08:55.165: INFO: 
Logging kubelet events for node e2e-test-prow-minion-group-x7mj
Jun 27 10:08:55.175: INFO: 
Logging pods the kubelet thinks is on node e2e-test-prow-minion-group-x7mj
Jun 27 10:08:55.183: INFO: kube-proxy-e2e-test-prow-minion-group-x7mj started at 2019-06-27 09:54:42 +0000 UTC (0+1 container statuses recorded)
Jun 27 10:08:55.183: INFO: 	Container kube-proxy ready: true, restart count 0
... skipping 19 lines ...
/home/prow/go/src/sigs.k8s.io/sig-storage-local-static-provisioner/vendor/k8s.io/kubernetes/test/e2e/storage/utils/framework.go:22
  Local volume provisioner [Serial][UseJobForCleaning: true][VolumeType: block]
  /home/prow/go/src/sigs.k8s.io/sig-storage-local-static-provisioner/test/e2e/e2e_test.go:186
    should create and recreate local persistent volume [It]
    /home/prow/go/src/sigs.k8s.io/sig-storage-local-static-provisioner/test/e2e/e2e_test.go:199

    Expected error:
        <*errors.errorString | 0xc000444330>: {
            s: "Timeout while waiting for local persistent volume with path /tmp/persistent-local-volumes-test-9789/vol-22f740df-98c2-11e9-a08e-5e5e49af1ad3 to be available",
        }
        Timeout while waiting for local persistent volume with path /tmp/persistent-local-volumes-test-9789/vol-22f740df-98c2-11e9-a08e-5e5e49af1ad3 to be available
    not to have occurred

... skipping 39 lines ...
Jun 27 10:09:12.108: INFO: stdout: "/dev/loop1\n"
STEP: Linking /dev/loop1 at /tmp/persistent-local-volumes-test-9705/vol-9b844ccc-98c3-11e9-a08e-5e5e49af1ad3
Jun 27 10:09:12.108: INFO: Running '/home/prow/go/src/sigs.k8s.io/sig-storage-local-static-provisioner/kubernetes/cluster/kubectl.sh --kubeconfig=/root/.kube/config exec --namespace=persistent-local-volumes-test-9705 hostexec-e2e-test-prow-minion-group-2ldr -- nsenter --mount=/rootfs/proc/1/ns/mnt -- sh -c sudo ln -s /dev/loop1 /tmp/persistent-local-volumes-test-9705/vol-9b844ccc-98c3-11e9-a08e-5e5e49af1ad3'
Jun 27 10:09:12.532: INFO: stderr: ""
Jun 27 10:09:12.533: INFO: stdout: ""
STEP: Waiting for a PersistentVolume to be created
{"component":"entrypoint","file":"prow/entrypoint/run.go:163","func":"k8s.io/test-infra/prow/entrypoint.Options.ExecuteProcess","level":"error","msg":"Entrypoint received interrupt: terminated","time":"2019-06-27T10:18:17Z"}