This job view page is being replaced by Spyglass soon. Check out the new job view.
PRkbasv: Implement NodeGetVolumeStats
ResultFAILURE
Tests 0 failed / 0 succeeded
Started2020-08-13 02:58
Elapsed1h54m
Revisionf521e62e015b454db22ce3bcff8835d1f6527b0c
Refs 238

No Test Failures!


Error lines from build-log.txt

... skipping 1514 lines ...

Using cluster from kubectl context: test-cluster-8282.k8s.local

Validating cluster test-cluster-8282.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-8282-k8s-hl5l47-663470792.us-west-2.elb.amazonaws.com/api/v1/nodes: dial tcp: lookup api-test-cluster-8282-k8s-hl5l47-663470792.us-west-2.elb.amazonaws.com on 10.63.240.10:53: no such host
Using cluster from kubectl context: test-cluster-8282.k8s.local

Validating cluster test-cluster-8282.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-8282-k8s-hl5l47-663470792.us-west-2.elb.amazonaws.com/api/v1/nodes: dial tcp: lookup api-test-cluster-8282-k8s-hl5l47-663470792.us-west-2.elb.amazonaws.com on 10.63.240.10:53: no such host
Using cluster from kubectl context: test-cluster-8282.k8s.local

Validating cluster test-cluster-8282.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-8282-k8s-hl5l47-663470792.us-west-2.elb.amazonaws.com/api/v1/nodes: dial tcp: lookup api-test-cluster-8282-k8s-hl5l47-663470792.us-west-2.elb.amazonaws.com on 10.63.240.10:53: no such host
Using cluster from kubectl context: test-cluster-8282.k8s.local

Validating cluster test-cluster-8282.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-8282-k8s-hl5l47-663470792.us-west-2.elb.amazonaws.com/api/v1/nodes: EOF
Using cluster from kubectl context: test-cluster-8282.k8s.local

Validating cluster test-cluster-8282.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-8282-k8s-hl5l47-663470792.us-west-2.elb.amazonaws.com/api/v1/nodes: EOF
Using cluster from kubectl context: test-cluster-8282.k8s.local

Validating cluster test-cluster-8282.k8s.local

INSTANCE GROUPS
NAME			ROLE	MACHINETYPE	MIN	MAX	SUBNETS
... skipping 11 lines ...
Machine	i-0c56ce9b824e3d5a8					machine "i-0c56ce9b824e3d5a8" has not yet joined cluster
Pod	kube-system/aws-node-5m5jx				kube-system pod "aws-node-5m5jx" is not ready (aws-node)
Pod	kube-system/dns-controller-865fd96754-9v5tt		kube-system pod "dns-controller-865fd96754-9v5tt" is pending
Pod	kube-system/kube-dns-autoscaler-594dcb44b5-5fk8s	kube-system pod "kube-dns-autoscaler-594dcb44b5-5fk8s" is pending
Pod	kube-system/kube-dns-b84c667f4-9rfk7			kube-system pod "kube-dns-b84c667f4-9rfk7" is pending

Validation Failed
Using cluster from kubectl context: test-cluster-8282.k8s.local

Validating cluster test-cluster-8282.k8s.local

INSTANCE GROUPS
NAME			ROLE	MACHINETYPE	MIN	MAX	SUBNETS
... skipping 11 lines ...
Machine	i-0c56ce9b824e3d5a8					machine "i-0c56ce9b824e3d5a8" has not yet joined cluster
Pod	kube-system/dns-controller-865fd96754-9v5tt		kube-system pod "dns-controller-865fd96754-9v5tt" is pending
Pod	kube-system/kops-controller-lsjsz			kube-system pod "kops-controller-lsjsz" is pending
Pod	kube-system/kube-dns-autoscaler-594dcb44b5-5fk8s	kube-system pod "kube-dns-autoscaler-594dcb44b5-5fk8s" is pending
Pod	kube-system/kube-dns-b84c667f4-9rfk7			kube-system pod "kube-dns-b84c667f4-9rfk7" is pending

Validation Failed
Using cluster from kubectl context: test-cluster-8282.k8s.local

Validating cluster test-cluster-8282.k8s.local

INSTANCE GROUPS
NAME			ROLE	MACHINETYPE	MIN	MAX	SUBNETS
... skipping 13 lines ...
Pod	kube-system/aws-node-csnbc				kube-system pod "aws-node-csnbc" is not ready (aws-node)
Pod	kube-system/aws-node-k7ssw				kube-system pod "aws-node-k7ssw" is not ready (aws-node)
Pod	kube-system/aws-node-rgq8r				kube-system pod "aws-node-rgq8r" is not ready (aws-node)
Pod	kube-system/kube-dns-autoscaler-594dcb44b5-5fk8s	kube-system pod "kube-dns-autoscaler-594dcb44b5-5fk8s" is pending
Pod	kube-system/kube-dns-b84c667f4-9rfk7			kube-system pod "kube-dns-b84c667f4-9rfk7" is not ready (kubedns)

Validation Failed
Using cluster from kubectl context: test-cluster-8282.k8s.local

Validating cluster test-cluster-8282.k8s.local

INSTANCE GROUPS
NAME			ROLE	MACHINETYPE	MIN	MAX	SUBNETS
... skipping 8 lines ...
ip-172-20-89-108.us-west-2.compute.internal	node	True

VALIDATION ERRORS
KIND	NAME				MESSAGE
Pod	kube-system/aws-node-rgq8r	kube-system pod "aws-node-rgq8r" is not ready (aws-node)

Validation Failed
Using cluster from kubectl context: test-cluster-8282.k8s.local

Validating cluster test-cluster-8282.k8s.local

INSTANCE GROUPS
NAME			ROLE	MACHINETYPE	MIN	MAX	SUBNETS
... skipping 402 lines ...
STEP: Creating efs pvc & pv with subpath /b
STEP: Creating pod to mount subpaths /a and /b
[AfterEach] [efs-csi] EFS CSI
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:152
STEP: Collecting events from namespace "efs-7726".
STEP: Found 13 events.
Aug 13 03:22:04.337: INFO: At 2020-08-13 03:16:07 +0000 UTC - event for pvc-tester-b2ntb: {default-scheduler } FailedScheduling: error while running "VolumeBinding" filter plugin for pod "pvc-tester-b2ntb": pod has unbound immediate PersistentVolumeClaims
Aug 13 03:22:04.337: INFO: At 2020-08-13 03:16:36 +0000 UTC - event for pvc-tester-b2ntb: {default-scheduler } Scheduled: Successfully assigned efs-7726/pvc-tester-b2ntb to ip-172-20-89-108.us-west-2.compute.internal
Aug 13 03:22:04.337: INFO: At 2020-08-13 03:16:59 +0000 UTC - event for pvc-tester-b2ntb: {kubelet ip-172-20-89-108.us-west-2.compute.internal} Pulling: Pulling image "docker.io/library/busybox:1.29"
Aug 13 03:22:04.337: INFO: At 2020-08-13 03:17:01 +0000 UTC - event for pvc-tester-b2ntb: {kubelet ip-172-20-89-108.us-west-2.compute.internal} Pulled: Successfully pulled image "docker.io/library/busybox:1.29"
Aug 13 03:22:04.337: INFO: At 2020-08-13 03:17:01 +0000 UTC - event for pvc-tester-b2ntb: {kubelet ip-172-20-89-108.us-west-2.compute.internal} Created: Created container write-pod
Aug 13 03:22:04.337: INFO: At 2020-08-13 03:17:02 +0000 UTC - event for pvc-tester-b2ntb: {kubelet ip-172-20-89-108.us-west-2.compute.internal} Started: Started container write-pod
Aug 13 03:22:04.337: INFO: At 2020-08-13 03:17:03 +0000 UTC - event for pvc-tester-b2ntb: {kubelet ip-172-20-89-108.us-west-2.compute.internal} SandboxChanged: Pod sandbox changed, it will be killed and re-created.
Aug 13 03:22:04.337: INFO: At 2020-08-13 03:17:03 +0000 UTC - event for pvc-tester-frz44: {default-scheduler } FailedScheduling: error while running "VolumeBinding" filter plugin for pod "pvc-tester-frz44": pod has unbound immediate PersistentVolumeClaims
Aug 13 03:22:04.337: INFO: At 2020-08-13 03:17:21 +0000 UTC - event for pvc-tester-frz44: {default-scheduler } Scheduled: Successfully assigned efs-7726/pvc-tester-frz44 to ip-172-20-89-108.us-west-2.compute.internal
Aug 13 03:22:04.337: INFO: At 2020-08-13 03:19:21 +0000 UTC - event for pvc-tester-frz44: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: MountVolume.SetUp failed for volume "efs-7726-b" : kubernetes.io/csi: mounter.SetupAt failed: rpc error: code = DeadlineExceeded desc = context deadline exceeded
Aug 13 03:22:04.337: INFO: At 2020-08-13 03:19:21 +0000 UTC - event for pvc-tester-frz44: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: MountVolume.SetUp failed for volume "efs-7726-a" : kubernetes.io/csi: mounter.SetupAt failed: rpc error: code = DeadlineExceeded desc = context deadline exceeded
Aug 13 03:22:04.337: INFO: At 2020-08-13 03:19:24 +0000 UTC - event for pvc-tester-frz44: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[volume1 volume2], unattached volumes=[volume1 volume2 default-token-tplqn]: timed out waiting for the condition
Aug 13 03:22:04.337: INFO: At 2020-08-13 03:21:38 +0000 UTC - event for pvc-tester-frz44: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[volume2 volume1], unattached volumes=[volume2 default-token-tplqn volume1]: timed out waiting for the condition
Aug 13 03:22:04.401: INFO: POD               NODE                                         PHASE      GRACE  CONDITIONS
Aug 13 03:22:04.401: INFO: pvc-tester-b2ntb  ip-172-20-89-108.us-west-2.compute.internal  Succeeded         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-08-13 03:16:36 +0000 UTC PodCompleted } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-08-13 03:16:36 +0000 UTC PodCompleted } {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-08-13 03:16:36 +0000 UTC PodCompleted } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-08-13 03:16:36 +0000 UTC  }]
Aug 13 03:22:04.401: INFO: pvc-tester-frz44  ip-172-20-89-108.us-west-2.compute.internal  Pending           [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-08-13 03:17:21 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-08-13 03:17:21 +0000 UTC ContainersNotReady containers with unready status: [write-pod]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-08-13 03:17:21 +0000 UTC ContainersNotReady containers with unready status: [write-pod]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-08-13 03:17:21 +0000 UTC  }]
Aug 13 03:22:04.401: INFO: 
... skipping 107 lines ...
  [Driver: efs.csi.aws.com]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:184
    should mount different paths on same volume on same node [It]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:185

    waiting for pod running
    Unexpected error:
        <*errors.errorString | 0xc00041a3f0>: {
            s: "timed out waiting for the condition",
        }
        timed out waiting for the condition
    occurred

... skipping 180 lines ...
Aug 13 03:27:10.885: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m49.089128603s
Aug 13 03:27:12.950: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m51.153575283s
Aug 13 03:27:15.014: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m53.217796978s
Aug 13 03:27:17.078: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m55.281983888s
Aug 13 03:27:19.143: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m57.346778532s
Aug 13 03:27:21.207: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m59.410846791s
Aug 13 03:27:23.344: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf" container "init-volume-efs-csi-aws-com-preprovisionedpv-kwsf": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf)
Aug 13 03:27:23.410: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf" container "test-container-subpath-efs-csi-aws-com-preprovisionedpv-kwsf": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf)
STEP: delete the pod
Aug 13 03:27:23.476: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf to disappear
Aug 13 03:27:23.541: INFO: Pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf still exists
Aug 13 03:27:25.541: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf to disappear
Aug 13 03:27:25.605: INFO: Pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf still exists
Aug 13 03:27:27.541: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf to disappear
... skipping 6 lines ...
[AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:152
STEP: Collecting events from namespace "provisioning-1387".
STEP: Found 5 events.
Aug 13 03:27:27.866: INFO: At 2020-08-13 03:22:06 +0000 UTC - event for pvc-9jr4b: {persistentvolume-controller } ProvisioningFailed: storageclass.storage.k8s.io "provisioning-1387" not found
Aug 13 03:27:27.866: INFO: At 2020-08-13 03:22:21 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf: {default-scheduler } Scheduled: Successfully assigned provisioning-1387/pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf to ip-172-20-89-108.us-west-2.compute.internal
Aug 13 03:27:27.866: INFO: At 2020-08-13 03:24:21 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: MountVolume.SetUp failed for volume "efs.csi.aws.com-mlkd8" : kubernetes.io/csi: mounter.SetupAt failed: rpc error: code = DeadlineExceeded desc = context deadline exceeded
Aug 13 03:27:27.866: INFO: At 2020-08-13 03:24:24 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume], unattached volumes=[liveness-probe-volume default-token-zkqtb test-volume]: timed out waiting for the condition
Aug 13 03:27:27.866: INFO: At 2020-08-13 03:26:42 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume], unattached volumes=[test-volume liveness-probe-volume default-token-zkqtb]: timed out waiting for the condition
Aug 13 03:27:27.929: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Aug 13 03:27:27.929: INFO: 
Aug 13 03:27:27.993: INFO: 
Logging node info for node ip-172-20-125-220.us-west-2.compute.internal
... skipping 207 lines ...
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Pre-provisioned PV (default fs)] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should support existing single file [LinuxOnly] [It]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:214

      Unexpected error:
          <*errors.errorString | 0xc000f53470>: {
              s: "expected pod \"pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf\" success: Gave up after waiting 5m0s for pod \"pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf\" to be \"success or failure\"",
          }
          expected pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf" success: Gave up after waiting 5m0s for pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-kwsf" to be "success or failure"
      occurred

... skipping 277 lines ...
Aug 13 03:32:27.126: INFO: Pod "exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs": Phase="Pending", Reason="", readiness=false. Elapsed: 4m49.103534844s
Aug 13 03:32:29.191: INFO: Pod "exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs": Phase="Pending", Reason="", readiness=false. Elapsed: 4m51.167979455s
Aug 13 03:32:31.255: INFO: Pod "exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs": Phase="Pending", Reason="", readiness=false. Elapsed: 4m53.232626281s
Aug 13 03:32:33.320: INFO: Pod "exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs": Phase="Pending", Reason="", readiness=false. Elapsed: 4m55.297078601s
Aug 13 03:32:35.384: INFO: Pod "exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs": Phase="Pending", Reason="", readiness=false. Elapsed: 4m57.360714113s
Aug 13 03:32:37.448: INFO: Pod "exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs": Phase="Pending", Reason="", readiness=false. Elapsed: 4m59.424988272s
Aug 13 03:32:39.588: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs" container "exec-container-efs-csi-aws-com-preprovisionedpv-hvgs": the server rejected our request for an unknown reason (get pods exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs)
STEP: delete the pod
Aug 13 03:32:39.655: INFO: Waiting for pod exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs to disappear
Aug 13 03:32:39.718: INFO: Pod exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs still exists
Aug 13 03:32:41.719: INFO: Waiting for pod exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs to disappear
Aug 13 03:32:41.783: INFO: Pod exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs still exists
Aug 13 03:32:43.719: INFO: Waiting for pod exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs to disappear
... skipping 9 lines ...
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:152
STEP: Collecting events from namespace "volume-7903".
STEP: Found 4 events.
Aug 13 03:32:47.982: INFO: At 2020-08-13 03:27:33 +0000 UTC - event for pvc-zpwnk: {persistentvolume-controller } ProvisioningFailed: storageclass.storage.k8s.io "volume-7903" not found
Aug 13 03:32:47.982: INFO: At 2020-08-13 03:27:38 +0000 UTC - event for exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs: {default-scheduler } Scheduled: Successfully assigned volume-7903/exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs to ip-172-20-89-108.us-west-2.compute.internal
Aug 13 03:32:47.982: INFO: At 2020-08-13 03:29:41 +0000 UTC - event for exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[vol1], unattached volumes=[vol1 default-token-5d485]: timed out waiting for the condition
Aug 13 03:32:47.982: INFO: At 2020-08-13 03:30:25 +0000 UTC - event for exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: MountVolume.SetUp failed for volume "efs.csi.aws.com-tswhq" : kubernetes.io/csi: mounter.SetupAt failed: rpc error: code = DeadlineExceeded desc = context deadline exceeded
Aug 13 03:32:48.046: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Aug 13 03:32:48.046: INFO: 
Aug 13 03:32:48.111: INFO: 
Logging node info for node ip-172-20-125-220.us-west-2.compute.internal
Aug 13 03:32:48.174: INFO: Node Info: &Node{ObjectMeta:{ip-172-20-125-220.us-west-2.compute.internal   /api/v1/nodes/ip-172-20-125-220.us-west-2.compute.internal 4195dba4-e009-4834-8589-65ad90ff5dcc 3911 0 2020-08-13 03:11:38 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:c5.large beta.kubernetes.io/os:linux failure-domain.beta.kubernetes.io/region:us-west-2 failure-domain.beta.kubernetes.io/zone:us-west-2c kops.k8s.io/instancegroup:nodes kubernetes.io/arch:amd64 kubernetes.io/hostname:ip-172-20-125-220.us-west-2.compute.internal kubernetes.io/os:linux kubernetes.io/role:node node-role.kubernetes.io/node: node.kubernetes.io/instance-type:c5.large topology.kubernetes.io/region:us-west-2 topology.kubernetes.io/zone:us-west-2c] map[csi.volume.kubernetes.io/nodeid:{"efs.csi.aws.com":"i-04be80e681240246b"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  []},Spec:NodeSpec{PodCIDR:172.20.130.0/24,DoNotUseExternalID:,ProviderID:aws:///us-west-2c/i-04be80e681240246b,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[172.20.130.0/24],},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{128771407872 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3925778432 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Allocatable:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{115894266893 0} {<nil>} 115894266893 DecimalSI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3820920832 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2020-08-13 03:28:40 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2020-08-13 03:28:40 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2020-08-13 03:28:40 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2020-08-13 03:28:40 +0000 UTC,LastTransitionTime:2020-08-13 03:11:58 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:172.20.125.220,},NodeAddress{Type:ExternalIP,Address:54.202.169.41,},NodeAddress{Type:Hostname,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:InternalDNS,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:ExternalDNS,Address:ec2-54-202-169-41.us-west-2.compute.amazonaws.com,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:ec21223433f5884b20f52ebea4903081,SystemUUID:EC212234-33F5-884B-20F5-2EBEA4903081,BootID:f22b750e-0314-45ec-bdc6-b0a3897ae066,KernelVersion:4.9.0-13-amd64,OSImage:Debian GNU/Linux 9 (stretch),ContainerRuntimeVersion:docker://19.3.4,KubeletVersion:v1.17.0,KubeProxyVersion:v1.17.0,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver@sha256:395c602f72f01f648497338bed6b3d37ddbff6f5b9a2a5941018d1a9833c1501 607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver:8282],SizeBytes:462031378,},ContainerImage{Names:[602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni@sha256:d50a182475c5ee6c18c3b81b01aa649367f30fb0dc60f7a619dcdbf45e10b3a3 602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni:v1.6.1],SizeBytes:293038272,},ContainerImage{Names:[protokube:1.17.0],SizeBytes:277401314,},ContainerImage{Names:[k8s.gcr.io/kube-proxy@sha256:b2ba9441af30261465e5c41be63e462d0050b09ad280001ae731f399b2b00b75 k8s.gcr.io/kube-proxy:v1.17.0],SizeBytes:115960823,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-kube-dns-amd64@sha256:618a82fa66cf0c75e4753369a6999032372be7308866fc9afb381789b1e5ad52 k8s.gcr.io/k8s-dns-kube-dns-amd64:1.14.13],SizeBytes:51157394,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-sidecar-amd64@sha256:cedc8fe2098dffc26d17f64061296b7aa54258a31513b6c52df271a98bb522b3 k8s.gcr.io/k8s-dns-sidecar-amd64:1.14.13],SizeBytes:42852039,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64@sha256:45df3e8e0c551bd0c79cdba48ae6677f817971dcbd1eeed7fd1f9a35118410e4 k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64:1.14.13],SizeBytes:41372492,},ContainerImage{Names:[quay.io/k8scsi/csi-node-driver-registrar@sha256:9622c6a6dac7499a055a382930f4de82905a3c5735c0753f7094115c9c871309 quay.io/k8scsi/csi-node-driver-registrar:v1.3.0],SizeBytes:16322467,},ContainerImage{Names:[quay.io/k8scsi/livenessprobe@sha256:ff8c925b509ebfcf0487e877a2722f87c01632f8d0c5c7eda94c66959542d290 quay.io/k8scsi/livenessprobe:v2.0.0],SizeBytes:15637127,},ContainerImage{Names:[k8s.gcr.io/pause-amd64@sha256:163ac025575b775d1c0f9bf0bdd0f086883171eb475b5068e7defa4ca9e76516 k8s.gcr.io/pause-amd64:3.0],SizeBytes:746888,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
Aug 13 03:32:48.174: INFO: 
... skipping 205 lines ...
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Pre-provisioned PV (default fs)] volumes
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should allow exec of files on the volume [It]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/volumes.go:191

      Unexpected error:
          <*errors.errorString | 0xc000ff2e30>: {
              s: "expected pod \"exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs\" success: Gave up after waiting 5m0s for pod \"exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs\" to be \"success or failure\"",
          }
          expected pod "exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs" success: Gave up after waiting 5m0s for pod "exec-volume-test-efs-csi-aws-com-preprovisionedpv-hvgs" to be "success or failure"
      occurred

... skipping 99 lines ...

      Driver efs.csi.aws.com doesn't support DynamicPV -- skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:154
------------------------------
[efs-csi] EFS CSI [Driver: efs.csi.aws.com] [Testpattern: Pre-provisioned PV (block volmode)] volumeMode 
  should fail to create pod by failing to mount volume [Slow]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/volumemode.go:194
[BeforeEach] [efs-csi] EFS CSI
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Aug 13 03:32:53.039: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename efs
... skipping 3 lines ...
[BeforeEach] [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Aug 13 03:32:53.294: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename volumemode
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail to create pod by failing to mount volume [Slow]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/volumemode.go:194
Aug 13 03:32:53.610: INFO: Test running for native CSI Driver, not checking metrics
STEP: Creating sc
STEP: Creating pv and pvc
Aug 13 03:32:53.804: INFO: Waiting for PV pvnx8bl to bind to PVC pvc-5sjbh
Aug 13 03:32:53.804: INFO: Waiting up to 3m0s for PersistentVolumeClaims [pvc-5sjbh] to have phase Bound
... skipping 27 lines ...
[efs-csi] EFS CSI
/home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:176
  [Driver: efs.csi.aws.com]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should fail to create pod by failing to mount volume [Slow]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/volumemode.go:194
------------------------------
[efs-csi] EFS CSI [Driver: efs.csi.aws.com] [Testpattern: Pre-provisioned PV (default fs)] volumeIO 
  should write files of various sizes, verify size, validate content [Slow]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/volume_io.go:143
[BeforeEach] [efs-csi] EFS CSI
... skipping 25 lines ...
Aug 13 03:35:22.394: INFO: PersistentVolume efs.csi.aws.com-wjd88 found and phase=Bound (63.999025ms)
STEP: starting efs-io-client
STEP: deleting test file /opt/efs-volumeio-540-dd_if...
Aug 13 03:40:22.783: INFO: ExecWithOptions {Command:[/bin/sh -c rm -f /opt/efs-volumeio-540-dd_if] Namespace:volumeio-540 PodName:efs-io-client ContainerName:efs-io-client Stdin:<nil> CaptureStdout:true CaptureStderr:true PreserveWhitespace:false}
Aug 13 03:40:22.783: INFO: >>> kubeConfig: /root/.kube/config
Aug 13 03:40:23.008: INFO: unable to delete test file /opt/efs-volumeio-540-dd_if: unable to upgrade connection: container not found ("efs-io-client")
error ignored, continuing test
STEP: deleting client pod "efs-io-client"...
Aug 13 03:40:23.008: INFO: Deleting pod "efs-io-client" in namespace "volumeio-540"
Aug 13 03:40:23.075: INFO: Wait up to 5m0s for pod "efs-io-client" to be fully deleted
Aug 13 03:40:27.202: INFO: sleeping a bit so kubelet can unmount and detach the volume
STEP: Deleting pv and pvc
Aug 13 03:40:47.203: INFO: Deleting PersistentVolumeClaim "pvc-2gj72"
Aug 13 03:40:47.269: INFO: Deleting PersistentVolume "efs.csi.aws.com-wjd88"
[AfterEach] [Testpattern: Pre-provisioned PV (default fs)] volumeIO
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:152
STEP: Collecting events from namespace "volumeio-540".
STEP: Found 4 events.
Aug 13 03:40:47.401: INFO: At 2020-08-13 03:35:18 +0000 UTC - event for pvc-2gj72: {persistentvolume-controller } ProvisioningFailed: storageclass.storage.k8s.io "volumeio-540" not found
Aug 13 03:40:47.401: INFO: At 2020-08-13 03:35:22 +0000 UTC - event for efs-io-client: {default-scheduler } Scheduled: Successfully assigned volumeio-540/efs-io-client to ip-172-20-89-108.us-west-2.compute.internal
Aug 13 03:40:47.401: INFO: At 2020-08-13 03:37:22 +0000 UTC - event for efs-io-client: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: MountVolume.SetUp failed for volume "efs.csi.aws.com-wjd88" : kubernetes.io/csi: mounter.SetupAt failed: rpc error: code = DeadlineExceeded desc = context deadline exceeded
Aug 13 03:40:47.401: INFO: At 2020-08-13 03:37:25 +0000 UTC - event for efs-io-client: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[io-volume-volumeio-540], unattached volumes=[io-volume-volumeio-540 default-token-kfrc8]: timed out waiting for the condition
Aug 13 03:40:47.464: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Aug 13 03:40:47.464: INFO: 
Aug 13 03:40:47.529: INFO: 
Logging node info for node ip-172-20-125-220.us-west-2.compute.internal
Aug 13 03:40:47.592: INFO: Node Info: &Node{ObjectMeta:{ip-172-20-125-220.us-west-2.compute.internal   /api/v1/nodes/ip-172-20-125-220.us-west-2.compute.internal 4195dba4-e009-4834-8589-65ad90ff5dcc 5828 0 2020-08-13 03:11:38 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:c5.large beta.kubernetes.io/os:linux failure-domain.beta.kubernetes.io/region:us-west-2 failure-domain.beta.kubernetes.io/zone:us-west-2c kops.k8s.io/instancegroup:nodes kubernetes.io/arch:amd64 kubernetes.io/hostname:ip-172-20-125-220.us-west-2.compute.internal kubernetes.io/os:linux kubernetes.io/role:node node-role.kubernetes.io/node: node.kubernetes.io/instance-type:c5.large topology.kubernetes.io/region:us-west-2 topology.kubernetes.io/zone:us-west-2c] map[csi.volume.kubernetes.io/nodeid:{"efs.csi.aws.com":"i-04be80e681240246b"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  []},Spec:NodeSpec{PodCIDR:172.20.130.0/24,DoNotUseExternalID:,ProviderID:aws:///us-west-2c/i-04be80e681240246b,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[172.20.130.0/24],},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{128771407872 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3925778432 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Allocatable:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{115894266893 0} {<nil>} 115894266893 DecimalSI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3820920832 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2020-08-13 03:38:41 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2020-08-13 03:38:41 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2020-08-13 03:38:41 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2020-08-13 03:38:41 +0000 UTC,LastTransitionTime:2020-08-13 03:11:58 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:172.20.125.220,},NodeAddress{Type:ExternalIP,Address:54.202.169.41,},NodeAddress{Type:Hostname,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:InternalDNS,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:ExternalDNS,Address:ec2-54-202-169-41.us-west-2.compute.amazonaws.com,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:ec21223433f5884b20f52ebea4903081,SystemUUID:EC212234-33F5-884B-20F5-2EBEA4903081,BootID:f22b750e-0314-45ec-bdc6-b0a3897ae066,KernelVersion:4.9.0-13-amd64,OSImage:Debian GNU/Linux 9 (stretch),ContainerRuntimeVersion:docker://19.3.4,KubeletVersion:v1.17.0,KubeProxyVersion:v1.17.0,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver@sha256:395c602f72f01f648497338bed6b3d37ddbff6f5b9a2a5941018d1a9833c1501 607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver:8282],SizeBytes:462031378,},ContainerImage{Names:[602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni@sha256:d50a182475c5ee6c18c3b81b01aa649367f30fb0dc60f7a619dcdbf45e10b3a3 602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni:v1.6.1],SizeBytes:293038272,},ContainerImage{Names:[protokube:1.17.0],SizeBytes:277401314,},ContainerImage{Names:[k8s.gcr.io/kube-proxy@sha256:b2ba9441af30261465e5c41be63e462d0050b09ad280001ae731f399b2b00b75 k8s.gcr.io/kube-proxy:v1.17.0],SizeBytes:115960823,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-kube-dns-amd64@sha256:618a82fa66cf0c75e4753369a6999032372be7308866fc9afb381789b1e5ad52 k8s.gcr.io/k8s-dns-kube-dns-amd64:1.14.13],SizeBytes:51157394,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-sidecar-amd64@sha256:cedc8fe2098dffc26d17f64061296b7aa54258a31513b6c52df271a98bb522b3 k8s.gcr.io/k8s-dns-sidecar-amd64:1.14.13],SizeBytes:42852039,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64@sha256:45df3e8e0c551bd0c79cdba48ae6677f817971dcbd1eeed7fd1f9a35118410e4 k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64:1.14.13],SizeBytes:41372492,},ContainerImage{Names:[quay.io/k8scsi/csi-node-driver-registrar@sha256:9622c6a6dac7499a055a382930f4de82905a3c5735c0753f7094115c9c871309 quay.io/k8scsi/csi-node-driver-registrar:v1.3.0],SizeBytes:16322467,},ContainerImage{Names:[quay.io/k8scsi/livenessprobe@sha256:ff8c925b509ebfcf0487e877a2722f87c01632f8d0c5c7eda94c66959542d290 quay.io/k8scsi/livenessprobe:v2.0.0],SizeBytes:15637127,},ContainerImage{Names:[k8s.gcr.io/pause-amd64@sha256:163ac025575b775d1c0f9bf0bdd0f086883171eb475b5068e7defa4ca9e76516 k8s.gcr.io/pause-amd64:3.0],SizeBytes:746888,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
... skipping 206 lines ...
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Pre-provisioned PV (default fs)] volumeIO
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should write files of various sizes, verify size, validate content [Slow] [It]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/volume_io.go:143

      Unexpected error:
          <*errors.errorString | 0xc00089de80>: {
              s: "client pod \"efs-io-client\" not running: timed out waiting for the condition",
          }
          client pod "efs-io-client" not running: timed out waiting for the condition
      occurred

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/volume_io.go:159
------------------------------
[efs-csi] EFS CSI [Driver: efs.csi.aws.com] [Testpattern: Dynamic PV (default fs)] subPath 
  should fail if subpath with backstepping is outside the volume [Slow]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:273
[BeforeEach] [efs-csi] EFS CSI
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Aug 13 03:40:51.231: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename efs
... skipping 13 lines ...
[efs-csi] EFS CSI
/home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:176
  [Driver: efs.csi.aws.com]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Dynamic PV (default fs)] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should fail if subpath with backstepping is outside the volume [Slow] [BeforeEach]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:273

      Driver efs.csi.aws.com doesn't support DynamicPV -- skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:154
------------------------------
... skipping 316 lines ...
Aug 13 03:45:57.021: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct": Phase="Pending", Reason="", readiness=false. Elapsed: 4m48.373489053s
Aug 13 03:45:59.075: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct": Phase="Pending", Reason="", readiness=false. Elapsed: 4m50.427797021s
Aug 13 03:46:01.130: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct": Phase="Pending", Reason="", readiness=false. Elapsed: 4m52.482461238s
Aug 13 03:46:03.185: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct": Phase="Pending", Reason="", readiness=false. Elapsed: 4m54.53743317s
Aug 13 03:46:05.240: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct": Phase="Pending", Reason="", readiness=false. Elapsed: 4m56.592767419s
Aug 13 03:46:07.295: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct": Phase="Pending", Reason="", readiness=false. Elapsed: 4m58.647421137s
Aug 13 03:46:09.413: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct" container "init-volume-efs-csi-aws-com-preprovisionedpv-m7ct": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct)
Aug 13 03:46:09.469: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct" container "test-init-subpath-efs-csi-aws-com-preprovisionedpv-m7ct": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct)
Aug 13 03:46:09.526: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct" container "test-container-subpath-efs-csi-aws-com-preprovisionedpv-m7ct": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct)
STEP: delete the pod
Aug 13 03:46:09.583: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct to disappear
Aug 13 03:46:09.637: INFO: Pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct still exists
Aug 13 03:46:11.637: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct to disappear
Aug 13 03:46:11.693: INFO: Pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct still exists
Aug 13 03:46:13.637: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct to disappear
... skipping 11 lines ...
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:152
STEP: Collecting events from namespace "provisioning-1223".
STEP: Found 5 events.
Aug 13 03:46:17.911: INFO: At 2020-08-13 03:40:53 +0000 UTC - event for pvc-k9thp: {persistentvolume-controller } ProvisioningFailed: storageclass.storage.k8s.io "provisioning-1223" not found
Aug 13 03:46:17.911: INFO: At 2020-08-13 03:41:08 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct: {default-scheduler } Scheduled: Successfully assigned provisioning-1223/pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct to ip-172-20-89-108.us-west-2.compute.internal
Aug 13 03:46:17.911: INFO: At 2020-08-13 03:43:11 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume], unattached volumes=[default-token-mzffk test-volume liveness-probe-volume]: timed out waiting for the condition
Aug 13 03:46:17.911: INFO: At 2020-08-13 03:43:26 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: MountVolume.SetUp failed for volume "efs.csi.aws.com-8slbv" : kubernetes.io/csi: mounter.SetupAt failed: rpc error: code = DeadlineExceeded desc = context deadline exceeded
Aug 13 03:46:17.911: INFO: At 2020-08-13 03:45:28 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume], unattached volumes=[liveness-probe-volume default-token-mzffk test-volume]: timed out waiting for the condition
Aug 13 03:46:17.964: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Aug 13 03:46:17.964: INFO: 
Aug 13 03:46:18.020: INFO: 
Logging node info for node ip-172-20-125-220.us-west-2.compute.internal
Aug 13 03:46:18.074: INFO: Node Info: &Node{ObjectMeta:{ip-172-20-125-220.us-west-2.compute.internal   /api/v1/nodes/ip-172-20-125-220.us-west-2.compute.internal 4195dba4-e009-4834-8589-65ad90ff5dcc 6814 0 2020-08-13 03:11:38 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:c5.large beta.kubernetes.io/os:linux failure-domain.beta.kubernetes.io/region:us-west-2 failure-domain.beta.kubernetes.io/zone:us-west-2c kops.k8s.io/instancegroup:nodes kubernetes.io/arch:amd64 kubernetes.io/hostname:ip-172-20-125-220.us-west-2.compute.internal kubernetes.io/os:linux kubernetes.io/role:node node-role.kubernetes.io/node: node.kubernetes.io/instance-type:c5.large topology.kubernetes.io/region:us-west-2 topology.kubernetes.io/zone:us-west-2c] map[csi.volume.kubernetes.io/nodeid:{"efs.csi.aws.com":"i-04be80e681240246b"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  []},Spec:NodeSpec{PodCIDR:172.20.130.0/24,DoNotUseExternalID:,ProviderID:aws:///us-west-2c/i-04be80e681240246b,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[172.20.130.0/24],},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{128771407872 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3925778432 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Allocatable:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{115894266893 0} {<nil>} 115894266893 DecimalSI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3820920832 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2020-08-13 03:43:42 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2020-08-13 03:43:42 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2020-08-13 03:43:42 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2020-08-13 03:43:42 +0000 UTC,LastTransitionTime:2020-08-13 03:11:58 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:172.20.125.220,},NodeAddress{Type:ExternalIP,Address:54.202.169.41,},NodeAddress{Type:Hostname,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:InternalDNS,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:ExternalDNS,Address:ec2-54-202-169-41.us-west-2.compute.amazonaws.com,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:ec21223433f5884b20f52ebea4903081,SystemUUID:EC212234-33F5-884B-20F5-2EBEA4903081,BootID:f22b750e-0314-45ec-bdc6-b0a3897ae066,KernelVersion:4.9.0-13-amd64,OSImage:Debian GNU/Linux 9 (stretch),ContainerRuntimeVersion:docker://19.3.4,KubeletVersion:v1.17.0,KubeProxyVersion:v1.17.0,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver@sha256:395c602f72f01f648497338bed6b3d37ddbff6f5b9a2a5941018d1a9833c1501 607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver:8282],SizeBytes:462031378,},ContainerImage{Names:[602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni@sha256:d50a182475c5ee6c18c3b81b01aa649367f30fb0dc60f7a619dcdbf45e10b3a3 602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni:v1.6.1],SizeBytes:293038272,},ContainerImage{Names:[protokube:1.17.0],SizeBytes:277401314,},ContainerImage{Names:[k8s.gcr.io/kube-proxy@sha256:b2ba9441af30261465e5c41be63e462d0050b09ad280001ae731f399b2b00b75 k8s.gcr.io/kube-proxy:v1.17.0],SizeBytes:115960823,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-kube-dns-amd64@sha256:618a82fa66cf0c75e4753369a6999032372be7308866fc9afb381789b1e5ad52 k8s.gcr.io/k8s-dns-kube-dns-amd64:1.14.13],SizeBytes:51157394,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-sidecar-amd64@sha256:cedc8fe2098dffc26d17f64061296b7aa54258a31513b6c52df271a98bb522b3 k8s.gcr.io/k8s-dns-sidecar-amd64:1.14.13],SizeBytes:42852039,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64@sha256:45df3e8e0c551bd0c79cdba48ae6677f817971dcbd1eeed7fd1f9a35118410e4 k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64:1.14.13],SizeBytes:41372492,},ContainerImage{Names:[quay.io/k8scsi/csi-node-driver-registrar@sha256:9622c6a6dac7499a055a382930f4de82905a3c5735c0753f7094115c9c871309 quay.io/k8scsi/csi-node-driver-registrar:v1.3.0],SizeBytes:16322467,},ContainerImage{Names:[quay.io/k8scsi/livenessprobe@sha256:ff8c925b509ebfcf0487e877a2722f87c01632f8d0c5c7eda94c66959542d290 quay.io/k8scsi/livenessprobe:v2.0.0],SizeBytes:15637127,},ContainerImage{Names:[k8s.gcr.io/pause-amd64@sha256:163ac025575b775d1c0f9bf0bdd0f086883171eb475b5068e7defa4ca9e76516 k8s.gcr.io/pause-amd64:3.0],SizeBytes:746888,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
... skipping 206 lines ...
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Pre-provisioned PV (default fs)] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should support existing directories when readOnly specified in the volumeSource [It]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:389

      Unexpected error:
          <*errors.errorString | 0xc000147ad0>: {
              s: "expected pod \"pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct\" success: Gave up after waiting 5m0s for pod \"pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct\" to be \"success or failure\"",
          }
          expected pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct" success: Gave up after waiting 5m0s for pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-m7ct" to be "success or failure"
      occurred

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/util.go:894
------------------------------
[efs-csi] EFS CSI [Driver: efs.csi.aws.com] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode 
  should fail to use a volume in a pod with mismatched mode [Slow]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/volumemode.go:286
[BeforeEach] [efs-csi] EFS CSI
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Aug 13 03:46:21.211: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename efs
... skipping 13 lines ...
[efs-csi] EFS CSI
/home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:176
  [Driver: efs.csi.aws.com]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should fail to use a volume in a pod with mismatched mode [Slow] [BeforeEach]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/volumemode.go:286

      Driver efs.csi.aws.com doesn't support DynamicPV -- skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:154
------------------------------
... skipping 78 lines ...
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:152
STEP: Collecting events from namespace "multivolume-1968".
STEP: Found 4 events.
Aug 13 03:51:47.686: INFO: At 2020-08-13 03:46:22 +0000 UTC - event for pvc-xrttv: {persistentvolume-controller } ProvisioningFailed: storageclass.storage.k8s.io "multivolume-1968" not found
Aug 13 03:51:47.686: INFO: At 2020-08-13 03:46:37 +0000 UTC - event for security-context-84accacd-60f1-488b-8f5a-db476ab50c08: {default-scheduler } Scheduled: Successfully assigned multivolume-1968/security-context-84accacd-60f1-488b-8f5a-db476ab50c08 to ip-172-20-89-108.us-west-2.compute.internal
Aug 13 03:51:47.686: INFO: At 2020-08-13 03:48:40 +0000 UTC - event for security-context-84accacd-60f1-488b-8f5a-db476ab50c08: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[volume1], unattached volumes=[volume1 default-token-snvvj]: timed out waiting for the condition
Aug 13 03:51:47.686: INFO: At 2020-08-13 03:49:54 +0000 UTC - event for security-context-84accacd-60f1-488b-8f5a-db476ab50c08: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: MountVolume.SetUp failed for volume "efs.csi.aws.com-d6p4v" : kubernetes.io/csi: mounter.SetupAt failed: rpc error: code = DeadlineExceeded desc = context deadline exceeded
Aug 13 03:51:47.740: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Aug 13 03:51:47.740: INFO: 
Aug 13 03:51:47.794: INFO: 
Logging node info for node ip-172-20-125-220.us-west-2.compute.internal
Aug 13 03:51:47.848: INFO: Node Info: &Node{ObjectMeta:{ip-172-20-125-220.us-west-2.compute.internal   /api/v1/nodes/ip-172-20-125-220.us-west-2.compute.internal 4195dba4-e009-4834-8589-65ad90ff5dcc 7774 0 2020-08-13 03:11:38 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:c5.large beta.kubernetes.io/os:linux failure-domain.beta.kubernetes.io/region:us-west-2 failure-domain.beta.kubernetes.io/zone:us-west-2c kops.k8s.io/instancegroup:nodes kubernetes.io/arch:amd64 kubernetes.io/hostname:ip-172-20-125-220.us-west-2.compute.internal kubernetes.io/os:linux kubernetes.io/role:node node-role.kubernetes.io/node: node.kubernetes.io/instance-type:c5.large topology.kubernetes.io/region:us-west-2 topology.kubernetes.io/zone:us-west-2c] map[csi.volume.kubernetes.io/nodeid:{"efs.csi.aws.com":"i-04be80e681240246b"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  []},Spec:NodeSpec{PodCIDR:172.20.130.0/24,DoNotUseExternalID:,ProviderID:aws:///us-west-2c/i-04be80e681240246b,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[172.20.130.0/24],},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{128771407872 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3925778432 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Allocatable:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{115894266893 0} {<nil>} 115894266893 DecimalSI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3820920832 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2020-08-13 03:48:42 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2020-08-13 03:48:42 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2020-08-13 03:48:42 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2020-08-13 03:48:42 +0000 UTC,LastTransitionTime:2020-08-13 03:11:58 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:172.20.125.220,},NodeAddress{Type:ExternalIP,Address:54.202.169.41,},NodeAddress{Type:Hostname,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:InternalDNS,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:ExternalDNS,Address:ec2-54-202-169-41.us-west-2.compute.amazonaws.com,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:ec21223433f5884b20f52ebea4903081,SystemUUID:EC212234-33F5-884B-20F5-2EBEA4903081,BootID:f22b750e-0314-45ec-bdc6-b0a3897ae066,KernelVersion:4.9.0-13-amd64,OSImage:Debian GNU/Linux 9 (stretch),ContainerRuntimeVersion:docker://19.3.4,KubeletVersion:v1.17.0,KubeProxyVersion:v1.17.0,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver@sha256:395c602f72f01f648497338bed6b3d37ddbff6f5b9a2a5941018d1a9833c1501 607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver:8282],SizeBytes:462031378,},ContainerImage{Names:[602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni@sha256:d50a182475c5ee6c18c3b81b01aa649367f30fb0dc60f7a619dcdbf45e10b3a3 602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni:v1.6.1],SizeBytes:293038272,},ContainerImage{Names:[protokube:1.17.0],SizeBytes:277401314,},ContainerImage{Names:[k8s.gcr.io/kube-proxy@sha256:b2ba9441af30261465e5c41be63e462d0050b09ad280001ae731f399b2b00b75 k8s.gcr.io/kube-proxy:v1.17.0],SizeBytes:115960823,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-kube-dns-amd64@sha256:618a82fa66cf0c75e4753369a6999032372be7308866fc9afb381789b1e5ad52 k8s.gcr.io/k8s-dns-kube-dns-amd64:1.14.13],SizeBytes:51157394,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-sidecar-amd64@sha256:cedc8fe2098dffc26d17f64061296b7aa54258a31513b6c52df271a98bb522b3 k8s.gcr.io/k8s-dns-sidecar-amd64:1.14.13],SizeBytes:42852039,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64@sha256:45df3e8e0c551bd0c79cdba48ae6677f817971dcbd1eeed7fd1f9a35118410e4 k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64:1.14.13],SizeBytes:41372492,},ContainerImage{Names:[quay.io/k8scsi/csi-node-driver-registrar@sha256:9622c6a6dac7499a055a382930f4de82905a3c5735c0753f7094115c9c871309 quay.io/k8scsi/csi-node-driver-registrar:v1.3.0],SizeBytes:16322467,},ContainerImage{Names:[quay.io/k8scsi/livenessprobe@sha256:ff8c925b509ebfcf0487e877a2722f87c01632f8d0c5c7eda94c66959542d290 quay.io/k8scsi/livenessprobe:v2.0.0],SizeBytes:15637127,},ContainerImage{Names:[k8s.gcr.io/pause-amd64@sha256:163ac025575b775d1c0f9bf0bdd0f086883171eb475b5068e7defa4ca9e76516 k8s.gcr.io/pause-amd64:3.0],SizeBytes:746888,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
Aug 13 03:51:47.849: INFO: 
... skipping 205 lines ...
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Pre-provisioned PV (filesystem volmode)] multiVolume [Slow]
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should concurrently access the single volume from pods on different node [It]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/multivolume.go:318

      Unexpected error:
          <*errors.errorString | 0xc000ef3120>: {
              s: "pod \"security-context-84accacd-60f1-488b-8f5a-db476ab50c08\" is not Running: timed out waiting for the condition",
          }
          pod "security-context-84accacd-60f1-488b-8f5a-db476ab50c08" is not Running: timed out waiting for the condition
      occurred

... skipping 250 lines ...
Aug 13 03:56:54.687: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw": Phase="Pending", Reason="", readiness=false. Elapsed: 4m47.766770036s
Aug 13 03:56:56.742: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw": Phase="Pending", Reason="", readiness=false. Elapsed: 4m49.821960568s
Aug 13 03:56:58.797: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw": Phase="Pending", Reason="", readiness=false. Elapsed: 4m51.877172483s
Aug 13 03:57:00.852: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw": Phase="Pending", Reason="", readiness=false. Elapsed: 4m53.932247534s
Aug 13 03:57:02.907: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw": Phase="Pending", Reason="", readiness=false. Elapsed: 4m55.987286033s
Aug 13 03:57:04.963: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw": Phase="Pending", Reason="", readiness=false. Elapsed: 4m58.042978202s
Aug 13 03:57:07.081: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw" container "init-volume-efs-csi-aws-com-preprovisionedpv-5hhw": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw)
Aug 13 03:57:07.137: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw" container "test-init-volume-efs-csi-aws-com-preprovisionedpv-5hhw": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw)
Aug 13 03:57:07.194: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw" container "test-container-subpath-efs-csi-aws-com-preprovisionedpv-5hhw": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw)
STEP: delete the pod
Aug 13 03:57:07.249: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw to disappear
Aug 13 03:57:07.305: INFO: Pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw still exists
Aug 13 03:57:09.305: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw to disappear
Aug 13 03:57:09.360: INFO: Pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw still exists
Aug 13 03:57:11.305: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw to disappear
... skipping 13 lines ...
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:152
STEP: Collecting events from namespace "provisioning-440".
STEP: Found 4 events.
Aug 13 03:57:17.581: INFO: At 2020-08-13 03:51:52 +0000 UTC - event for pvc-wmq9f: {persistentvolume-controller } ProvisioningFailed: storageclass.storage.k8s.io "provisioning-440" not found
Aug 13 03:57:17.581: INFO: At 2020-08-13 03:52:06 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw: {default-scheduler } Scheduled: Successfully assigned provisioning-440/pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw to ip-172-20-89-108.us-west-2.compute.internal
Aug 13 03:57:17.581: INFO: At 2020-08-13 03:54:09 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume], unattached volumes=[test-volume liveness-probe-volume default-token-m22t5]: timed out waiting for the condition
Aug 13 03:57:17.581: INFO: At 2020-08-13 03:55:30 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: MountVolume.SetUp failed for volume "efs.csi.aws.com-b2hp8" : kubernetes.io/csi: mounter.SetupAt failed: rpc error: code = DeadlineExceeded desc = context deadline exceeded
Aug 13 03:57:17.636: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Aug 13 03:57:17.636: INFO: 
Aug 13 03:57:17.691: INFO: 
Logging node info for node ip-172-20-125-220.us-west-2.compute.internal
Aug 13 03:57:17.745: INFO: Node Info: &Node{ObjectMeta:{ip-172-20-125-220.us-west-2.compute.internal   /api/v1/nodes/ip-172-20-125-220.us-west-2.compute.internal 4195dba4-e009-4834-8589-65ad90ff5dcc 8733 0 2020-08-13 03:11:38 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:c5.large beta.kubernetes.io/os:linux failure-domain.beta.kubernetes.io/region:us-west-2 failure-domain.beta.kubernetes.io/zone:us-west-2c kops.k8s.io/instancegroup:nodes kubernetes.io/arch:amd64 kubernetes.io/hostname:ip-172-20-125-220.us-west-2.compute.internal kubernetes.io/os:linux kubernetes.io/role:node node-role.kubernetes.io/node: node.kubernetes.io/instance-type:c5.large topology.kubernetes.io/region:us-west-2 topology.kubernetes.io/zone:us-west-2c] map[csi.volume.kubernetes.io/nodeid:{"efs.csi.aws.com":"i-04be80e681240246b"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  []},Spec:NodeSpec{PodCIDR:172.20.130.0/24,DoNotUseExternalID:,ProviderID:aws:///us-west-2c/i-04be80e681240246b,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[172.20.130.0/24],},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{128771407872 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3925778432 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Allocatable:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{115894266893 0} {<nil>} 115894266893 DecimalSI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3820920832 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2020-08-13 03:53:43 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2020-08-13 03:53:43 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2020-08-13 03:53:43 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2020-08-13 03:53:43 +0000 UTC,LastTransitionTime:2020-08-13 03:11:58 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:172.20.125.220,},NodeAddress{Type:ExternalIP,Address:54.202.169.41,},NodeAddress{Type:Hostname,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:InternalDNS,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:ExternalDNS,Address:ec2-54-202-169-41.us-west-2.compute.amazonaws.com,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:ec21223433f5884b20f52ebea4903081,SystemUUID:EC212234-33F5-884B-20F5-2EBEA4903081,BootID:f22b750e-0314-45ec-bdc6-b0a3897ae066,KernelVersion:4.9.0-13-amd64,OSImage:Debian GNU/Linux 9 (stretch),ContainerRuntimeVersion:docker://19.3.4,KubeletVersion:v1.17.0,KubeProxyVersion:v1.17.0,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver@sha256:395c602f72f01f648497338bed6b3d37ddbff6f5b9a2a5941018d1a9833c1501 607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver:8282],SizeBytes:462031378,},ContainerImage{Names:[602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni@sha256:d50a182475c5ee6c18c3b81b01aa649367f30fb0dc60f7a619dcdbf45e10b3a3 602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni:v1.6.1],SizeBytes:293038272,},ContainerImage{Names:[protokube:1.17.0],SizeBytes:277401314,},ContainerImage{Names:[k8s.gcr.io/kube-proxy@sha256:b2ba9441af30261465e5c41be63e462d0050b09ad280001ae731f399b2b00b75 k8s.gcr.io/kube-proxy:v1.17.0],SizeBytes:115960823,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-kube-dns-amd64@sha256:618a82fa66cf0c75e4753369a6999032372be7308866fc9afb381789b1e5ad52 k8s.gcr.io/k8s-dns-kube-dns-amd64:1.14.13],SizeBytes:51157394,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-sidecar-amd64@sha256:cedc8fe2098dffc26d17f64061296b7aa54258a31513b6c52df271a98bb522b3 k8s.gcr.io/k8s-dns-sidecar-amd64:1.14.13],SizeBytes:42852039,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64@sha256:45df3e8e0c551bd0c79cdba48ae6677f817971dcbd1eeed7fd1f9a35118410e4 k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64:1.14.13],SizeBytes:41372492,},ContainerImage{Names:[quay.io/k8scsi/csi-node-driver-registrar@sha256:9622c6a6dac7499a055a382930f4de82905a3c5735c0753f7094115c9c871309 quay.io/k8scsi/csi-node-driver-registrar:v1.3.0],SizeBytes:16322467,},ContainerImage{Names:[quay.io/k8scsi/livenessprobe@sha256:ff8c925b509ebfcf0487e877a2722f87c01632f8d0c5c7eda94c66959542d290 quay.io/k8scsi/livenessprobe:v2.0.0],SizeBytes:15637127,},ContainerImage{Names:[k8s.gcr.io/pause-amd64@sha256:163ac025575b775d1c0f9bf0bdd0f086883171eb475b5068e7defa4ca9e76516 k8s.gcr.io/pause-amd64:3.0],SizeBytes:746888,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
Aug 13 03:57:17.745: INFO: 
... skipping 205 lines ...
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Pre-provisioned PV (default fs)] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should support readOnly directory specified in the volumeMount [It]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:359

      Unexpected error:
          <*errors.errorString | 0xc00116f770>: {
              s: "expected pod \"pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw\" success: Gave up after waiting 5m0s for pod \"pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw\" to be \"success or failure\"",
          }
          expected pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw" success: Gave up after waiting 5m0s for pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-5hhw" to be "success or failure"
      occurred

... skipping 99 lines ...

      Driver efs.csi.aws.com doesn't support DynamicPV -- skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:154
------------------------------
[efs-csi] EFS CSI [Driver: efs.csi.aws.com] [Testpattern: Inline-volume (default fs)] subPath 
  should fail if subpath directory is outside the volume [Slow]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:235
[BeforeEach] [efs-csi] EFS CSI
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Aug 13 03:57:21.900: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename efs
... skipping 13 lines ...
[efs-csi] EFS CSI
/home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:176
  [Driver: efs.csi.aws.com]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Inline-volume (default fs)] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should fail if subpath directory is outside the volume [Slow] [BeforeEach]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:235

      Driver efs.csi.aws.com doesn't support InlineVolume -- skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:154
------------------------------
... skipping 63 lines ...

      Driver efs.csi.aws.com doesn't support DynamicPV -- skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:154
------------------------------
[efs-csi] EFS CSI [Driver: efs.csi.aws.com] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
  should fail if subpath with backstepping is outside the volume [Slow]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:273
[BeforeEach] [efs-csi] EFS CSI
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Aug 13 03:57:22.893: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename efs
... skipping 13 lines ...
[efs-csi] EFS CSI
/home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:176
  [Driver: efs.csi.aws.com]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should fail if subpath with backstepping is outside the volume [Slow] [BeforeEach]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:273

      Driver efs.csi.aws.com doesn't support DynamicPV -- skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:154
------------------------------
... skipping 392 lines ...
[AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:152
STEP: Collecting events from namespace "provisioning-7920".
STEP: Found 5 events.
Aug 13 04:02:46.613: INFO: At 2020-08-13 03:57:27 +0000 UTC - event for pvc-mg7dp: {persistentvolume-controller } ProvisioningFailed: storageclass.storage.k8s.io "provisioning-7920" not found
Aug 13 04:02:46.613: INFO: At 2020-08-13 03:57:38 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-g9k6: {default-scheduler } Scheduled: Successfully assigned provisioning-7920/pod-subpath-test-efs-csi-aws-com-preprovisionedpv-g9k6 to ip-172-20-89-108.us-west-2.compute.internal
Aug 13 04:02:46.613: INFO: At 2020-08-13 03:59:38 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-g9k6: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: MountVolume.SetUp failed for volume "efs.csi.aws.com-b6wrl" : kubernetes.io/csi: mounter.SetupAt failed: rpc error: code = DeadlineExceeded desc = context deadline exceeded
Aug 13 04:02:46.613: INFO: At 2020-08-13 03:59:41 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-g9k6: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume], unattached volumes=[liveness-probe-volume default-token-7745w test-volume]: timed out waiting for the condition
Aug 13 04:02:46.613: INFO: At 2020-08-13 04:01:59 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-g9k6: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume], unattached volumes=[test-volume liveness-probe-volume default-token-7745w]: timed out waiting for the condition
Aug 13 04:02:46.669: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Aug 13 04:02:46.669: INFO: 
Aug 13 04:02:46.727: INFO: 
Logging node info for node ip-172-20-125-220.us-west-2.compute.internal
... skipping 208 lines ...
    [Testpattern: Pre-provisioned PV (default fs)] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should support restarting containers using directory as subpath [Slow] [It]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:315

      while waiting for pod to be running
      Unexpected error:
          <*errors.errorString | 0xc00041a3f0>: {
              s: "timed out waiting for the condition",
          }
          timed out waiting for the condition
      occurred

... skipping 208 lines ...
Aug 13 04:07:41.421: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc": Phase="Pending", Reason="", readiness=false. Elapsed: 4m48.096937351s
Aug 13 04:07:43.479: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc": Phase="Pending", Reason="", readiness=false. Elapsed: 4m50.154502497s
Aug 13 04:07:45.536: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc": Phase="Pending", Reason="", readiness=false. Elapsed: 4m52.212082519s
Aug 13 04:07:47.593: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc": Phase="Pending", Reason="", readiness=false. Elapsed: 4m54.268548555s
Aug 13 04:07:49.649: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc": Phase="Pending", Reason="", readiness=false. Elapsed: 4m56.325378972s
Aug 13 04:07:51.709: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc": Phase="Pending", Reason="", readiness=false. Elapsed: 4m58.384498954s
Aug 13 04:07:53.836: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc" container "init-volume-efs-csi-aws-com-preprovisionedpv-97bc": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc)
Aug 13 04:07:53.895: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc" container "test-container-subpath-efs-csi-aws-com-preprovisionedpv-97bc": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc)
STEP: delete the pod
Aug 13 04:07:53.954: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc to disappear
Aug 13 04:07:54.015: INFO: Pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc still exists
Aug 13 04:07:56.016: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc to disappear
Aug 13 04:07:56.072: INFO: Pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc still exists
Aug 13 04:07:58.016: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc to disappear
... skipping 7 lines ...
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:152
STEP: Collecting events from namespace "provisioning-5695".
STEP: Found 5 events.
Aug 13 04:07:58.302: INFO: At 2020-08-13 04:02:50 +0000 UTC - event for pvc-qvtrt: {persistentvolume-controller } ProvisioningFailed: storageclass.storage.k8s.io "provisioning-5695" not found
Aug 13 04:07:58.302: INFO: At 2020-08-13 04:02:53 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc: {default-scheduler } Scheduled: Successfully assigned provisioning-5695/pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc to ip-172-20-89-108.us-west-2.compute.internal
Aug 13 04:07:58.302: INFO: At 2020-08-13 04:04:56 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume], unattached volumes=[test-volume liveness-probe-volume default-token-ql8qj]: timed out waiting for the condition
Aug 13 04:07:58.302: INFO: At 2020-08-13 04:05:42 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: MountVolume.SetUp failed for volume "efs.csi.aws.com-jb8hj" : kubernetes.io/csi: mounter.SetupAt failed: rpc error: code = DeadlineExceeded desc = context deadline exceeded
Aug 13 04:07:58.302: INFO: At 2020-08-13 04:07:12 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume], unattached volumes=[liveness-probe-volume default-token-ql8qj test-volume]: timed out waiting for the condition
Aug 13 04:07:58.358: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Aug 13 04:07:58.358: INFO: 
Aug 13 04:07:58.416: INFO: 
Logging node info for node ip-172-20-125-220.us-west-2.compute.internal
Aug 13 04:07:58.473: INFO: Node Info: &Node{ObjectMeta:{ip-172-20-125-220.us-west-2.compute.internal   /api/v1/nodes/ip-172-20-125-220.us-west-2.compute.internal 4195dba4-e009-4834-8589-65ad90ff5dcc 10799 0 2020-08-13 03:11:38 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:c5.large beta.kubernetes.io/os:linux failure-domain.beta.kubernetes.io/region:us-west-2 failure-domain.beta.kubernetes.io/zone:us-west-2c kops.k8s.io/instancegroup:nodes kubernetes.io/arch:amd64 kubernetes.io/hostname:ip-172-20-125-220.us-west-2.compute.internal kubernetes.io/os:linux kubernetes.io/role:node node-role.kubernetes.io/node: node.kubernetes.io/instance-type:c5.large topology.kubernetes.io/region:us-west-2 topology.kubernetes.io/zone:us-west-2c] map[csi.volume.kubernetes.io/nodeid:{"efs.csi.aws.com":"i-04be80e681240246b"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  []},Spec:NodeSpec{PodCIDR:172.20.130.0/24,DoNotUseExternalID:,ProviderID:aws:///us-west-2c/i-04be80e681240246b,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[172.20.130.0/24],},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{128771407872 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3925778432 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Allocatable:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{115894266893 0} {<nil>} 115894266893 DecimalSI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3820920832 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2020-08-13 04:03:44 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2020-08-13 04:03:44 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2020-08-13 04:03:44 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2020-08-13 04:03:44 +0000 UTC,LastTransitionTime:2020-08-13 03:11:58 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:172.20.125.220,},NodeAddress{Type:ExternalIP,Address:54.202.169.41,},NodeAddress{Type:Hostname,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:InternalDNS,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:ExternalDNS,Address:ec2-54-202-169-41.us-west-2.compute.amazonaws.com,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:ec21223433f5884b20f52ebea4903081,SystemUUID:EC212234-33F5-884B-20F5-2EBEA4903081,BootID:f22b750e-0314-45ec-bdc6-b0a3897ae066,KernelVersion:4.9.0-13-amd64,OSImage:Debian GNU/Linux 9 (stretch),ContainerRuntimeVersion:docker://19.3.4,KubeletVersion:v1.17.0,KubeProxyVersion:v1.17.0,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver@sha256:395c602f72f01f648497338bed6b3d37ddbff6f5b9a2a5941018d1a9833c1501 607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver:8282],SizeBytes:462031378,},ContainerImage{Names:[602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni@sha256:d50a182475c5ee6c18c3b81b01aa649367f30fb0dc60f7a619dcdbf45e10b3a3 602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni:v1.6.1],SizeBytes:293038272,},ContainerImage{Names:[protokube:1.17.0],SizeBytes:277401314,},ContainerImage{Names:[k8s.gcr.io/kube-proxy@sha256:b2ba9441af30261465e5c41be63e462d0050b09ad280001ae731f399b2b00b75 k8s.gcr.io/kube-proxy:v1.17.0],SizeBytes:115960823,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-kube-dns-amd64@sha256:618a82fa66cf0c75e4753369a6999032372be7308866fc9afb381789b1e5ad52 k8s.gcr.io/k8s-dns-kube-dns-amd64:1.14.13],SizeBytes:51157394,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-sidecar-amd64@sha256:cedc8fe2098dffc26d17f64061296b7aa54258a31513b6c52df271a98bb522b3 k8s.gcr.io/k8s-dns-sidecar-amd64:1.14.13],SizeBytes:42852039,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64@sha256:45df3e8e0c551bd0c79cdba48ae6677f817971dcbd1eeed7fd1f9a35118410e4 k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64:1.14.13],SizeBytes:41372492,},ContainerImage{Names:[quay.io/k8scsi/csi-node-driver-registrar@sha256:9622c6a6dac7499a055a382930f4de82905a3c5735c0753f7094115c9c871309 quay.io/k8scsi/csi-node-driver-registrar:v1.3.0],SizeBytes:16322467,},ContainerImage{Names:[quay.io/k8scsi/livenessprobe@sha256:ff8c925b509ebfcf0487e877a2722f87c01632f8d0c5c7eda94c66959542d290 quay.io/k8scsi/livenessprobe:v2.0.0],SizeBytes:15637127,},ContainerImage{Names:[k8s.gcr.io/pause-amd64@sha256:163ac025575b775d1c0f9bf0bdd0f086883171eb475b5068e7defa4ca9e76516 k8s.gcr.io/pause-amd64:3.0],SizeBytes:746888,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
... skipping 206 lines ...
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Pre-provisioned PV (default fs)] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should support creating multiple subpath from same volumes [Slow] [It]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:289

      Unexpected error:
          <*errors.errorString | 0xc0004d7ba0>: {
              s: "expected pod \"pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc\" success: Gave up after waiting 5m0s for pod \"pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc\" to be \"success or failure\"",
          }
          expected pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc" success: Gave up after waiting 5m0s for pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-97bc" to be "success or failure"
      occurred

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/util.go:894
------------------------------
[efs-csi] EFS CSI [Driver: efs.csi.aws.com] [Testpattern: Inline-volume (default fs)] subPath 
  should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:262
[BeforeEach] [efs-csi] EFS CSI
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Aug 13 04:08:01.714: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename efs
... skipping 13 lines ...
[efs-csi] EFS CSI
/home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:176
  [Driver: efs.csi.aws.com]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Inline-volume (default fs)] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:262

      Driver efs.csi.aws.com doesn't support InlineVolume -- skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:154
------------------------------
... skipping 194 lines ...
Aug 13 04:13:03.260: INFO: pod "pvc-tester-zdsr7" logs:
 
[AfterEach] [efs-csi] EFS CSI
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:152
STEP: Collecting events from namespace "efs-9456".
STEP: Found 4 events.
Aug 13 04:13:03.376: INFO: At 2020-08-13 04:08:02 +0000 UTC - event for pvc-tester-zdsr7: {default-scheduler } FailedScheduling: error while running "VolumeBinding" filter plugin for pod "pvc-tester-zdsr7": pod has unbound immediate PersistentVolumeClaims
Aug 13 04:13:03.376: INFO: At 2020-08-13 04:08:21 +0000 UTC - event for pvc-tester-zdsr7: {default-scheduler } Scheduled: Successfully assigned efs-9456/pvc-tester-zdsr7 to ip-172-20-89-108.us-west-2.compute.internal
Aug 13 04:13:03.376: INFO: At 2020-08-13 04:10:21 +0000 UTC - event for pvc-tester-zdsr7: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: MountVolume.SetUp failed for volume "efs-9456" : kubernetes.io/csi: mounter.SetupAt failed: rpc error: code = DeadlineExceeded desc = context deadline exceeded
Aug 13 04:13:03.376: INFO: At 2020-08-13 04:10:24 +0000 UTC - event for pvc-tester-zdsr7: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[volume1], unattached volumes=[volume1 default-token-2gf6m]: timed out waiting for the condition
Aug 13 04:13:03.432: INFO: POD               NODE                                         PHASE    GRACE  CONDITIONS
Aug 13 04:13:03.432: INFO: pvc-tester-zdsr7  ip-172-20-89-108.us-west-2.compute.internal  Pending         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-08-13 04:08:21 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-08-13 04:08:21 +0000 UTC ContainersNotReady containers with unready status: [write-pod]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-08-13 04:08:21 +0000 UTC ContainersNotReady containers with unready status: [write-pod]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-08-13 04:08:21 +0000 UTC  }]
Aug 13 04:13:03.433: INFO: 
Aug 13 04:13:03.490: INFO: 
Logging node info for node ip-172-20-125-220.us-west-2.compute.internal
... skipping 103 lines ...
  [Driver: efs.csi.aws.com]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:184
    should mount with option tls when encryptInTransit set true [It]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:307

    waiting for pod success
    Unexpected error:
        <*errors.errorString | 0xc000edad50>: {
            s: "Gave up after waiting 5m0s for pod \"pvc-tester-zdsr7\" to be \"success or failure\"",
        }
        Gave up after waiting 5m0s for pod "pvc-tester-zdsr7" to be "success or failure"
    occurred

... skipping 65 lines ...

      Driver efs.csi.aws.com doesn't support DynamicPV -- skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:154
------------------------------
[efs-csi] EFS CSI [Driver: efs.csi.aws.com] [Testpattern: Pre-provisioned PV (filesystem volmode)] volumeMode 
  should fail to use a volume in a pod with mismatched mode [Slow]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/volumemode.go:286
[BeforeEach] [efs-csi] EFS CSI
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Aug 13 04:13:05.761: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename efs
... skipping 3 lines ...
[BeforeEach] [Testpattern: Pre-provisioned PV (filesystem volmode)] volumeMode
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Aug 13 04:13:05.995: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename volumemode
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail to use a volume in a pod with mismatched mode [Slow]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/volumemode.go:286
Aug 13 04:13:06.221: INFO: Driver "efs.csi.aws.com" does not provide raw block - skipping
[AfterEach] [Testpattern: Pre-provisioned PV (filesystem volmode)] volumeMode
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:152
Aug 13 04:13:06.222: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "volumemode-7577" for this suite.
... skipping 6 lines ...
[efs-csi] EFS CSI
/home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:176
  [Driver: efs.csi.aws.com]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Pre-provisioned PV (filesystem volmode)] volumeMode
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should fail to use a volume in a pod with mismatched mode [Slow] [It]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/volumemode.go:286

      Driver "efs.csi.aws.com" does not provide raw block - skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/volumes.go:99
------------------------------
[efs-csi] EFS CSI [Driver: efs.csi.aws.com] [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath 
  should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:262
[BeforeEach] [efs-csi] EFS CSI
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Aug 13 04:13:06.451: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename efs
... skipping 13 lines ...
[efs-csi] EFS CSI
/home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:176
  [Driver: efs.csi.aws.com]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Dynamic PV (ntfs)][sig-windows] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:262

      Driver efs.csi.aws.com doesn't support DynamicPV -- skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:154
------------------------------
... skipping 178 lines ...
Aug 13 04:18:10.138: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9": Phase="Pending", Reason="", readiness=false. Elapsed: 4m48.036032987s
Aug 13 04:18:12.193: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9": Phase="Pending", Reason="", readiness=false. Elapsed: 4m50.091129969s
Aug 13 04:18:14.248: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9": Phase="Pending", Reason="", readiness=false. Elapsed: 4m52.145584315s
Aug 13 04:18:16.302: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9": Phase="Pending", Reason="", readiness=false. Elapsed: 4m54.200491191s
Aug 13 04:18:18.357: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9": Phase="Pending", Reason="", readiness=false. Elapsed: 4m56.254873016s
Aug 13 04:18:20.411: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9": Phase="Pending", Reason="", readiness=false. Elapsed: 4m58.30931688s
Aug 13 04:18:22.529: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9" container "init-volume-efs-csi-aws-com-preprovisionedpv-2cp9": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9)
Aug 13 04:18:22.585: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9" container "test-init-volume-efs-csi-aws-com-preprovisionedpv-2cp9": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9)
Aug 13 04:18:22.641: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9" container "test-container-subpath-efs-csi-aws-com-preprovisionedpv-2cp9": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9)
STEP: delete the pod
Aug 13 04:18:22.717: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9 to disappear
Aug 13 04:18:22.771: INFO: Pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9 still exists
Aug 13 04:18:24.772: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9 to disappear
Aug 13 04:18:24.826: INFO: Pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9 still exists
Aug 13 04:18:26.772: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9 to disappear
... skipping 6 lines ...
[AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:152
STEP: Collecting events from namespace "provisioning-5399".
STEP: Found 4 events.
Aug 13 04:18:27.044: INFO: At 2020-08-13 04:13:07 +0000 UTC - event for pvc-kk6xx: {persistentvolume-controller } ProvisioningFailed: storageclass.storage.k8s.io "provisioning-5399" not found
Aug 13 04:18:27.044: INFO: At 2020-08-13 04:13:22 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9: {default-scheduler } Scheduled: Successfully assigned provisioning-5399/pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9 to ip-172-20-89-108.us-west-2.compute.internal
Aug 13 04:18:27.044: INFO: At 2020-08-13 04:15:22 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: MountVolume.SetUp failed for volume "efs.csi.aws.com-rkp6p" : kubernetes.io/csi: mounter.SetupAt failed: rpc error: code = DeadlineExceeded desc = context deadline exceeded
Aug 13 04:18:27.044: INFO: At 2020-08-13 04:15:25 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume], unattached volumes=[test-volume liveness-probe-volume default-token-96p8c]: timed out waiting for the condition
Aug 13 04:18:27.098: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Aug 13 04:18:27.098: INFO: 
Aug 13 04:18:27.153: INFO: 
Logging node info for node ip-172-20-125-220.us-west-2.compute.internal
Aug 13 04:18:27.207: INFO: Node Info: &Node{ObjectMeta:{ip-172-20-125-220.us-west-2.compute.internal   /api/v1/nodes/ip-172-20-125-220.us-west-2.compute.internal 4195dba4-e009-4834-8589-65ad90ff5dcc 12735 0 2020-08-13 03:11:38 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:c5.large beta.kubernetes.io/os:linux failure-domain.beta.kubernetes.io/region:us-west-2 failure-domain.beta.kubernetes.io/zone:us-west-2c kops.k8s.io/instancegroup:nodes kubernetes.io/arch:amd64 kubernetes.io/hostname:ip-172-20-125-220.us-west-2.compute.internal kubernetes.io/os:linux kubernetes.io/role:node node-role.kubernetes.io/node: node.kubernetes.io/instance-type:c5.large topology.kubernetes.io/region:us-west-2 topology.kubernetes.io/zone:us-west-2c] map[csi.volume.kubernetes.io/nodeid:{"efs.csi.aws.com":"i-04be80e681240246b"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  []},Spec:NodeSpec{PodCIDR:172.20.130.0/24,DoNotUseExternalID:,ProviderID:aws:///us-west-2c/i-04be80e681240246b,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[172.20.130.0/24],},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{128771407872 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3925778432 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Allocatable:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{115894266893 0} {<nil>} 115894266893 DecimalSI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3820920832 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2020-08-13 04:13:45 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2020-08-13 04:13:45 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2020-08-13 04:13:45 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2020-08-13 04:13:45 +0000 UTC,LastTransitionTime:2020-08-13 03:11:58 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:172.20.125.220,},NodeAddress{Type:ExternalIP,Address:54.202.169.41,},NodeAddress{Type:Hostname,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:InternalDNS,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:ExternalDNS,Address:ec2-54-202-169-41.us-west-2.compute.amazonaws.com,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:ec21223433f5884b20f52ebea4903081,SystemUUID:EC212234-33F5-884B-20F5-2EBEA4903081,BootID:f22b750e-0314-45ec-bdc6-b0a3897ae066,KernelVersion:4.9.0-13-amd64,OSImage:Debian GNU/Linux 9 (stretch),ContainerRuntimeVersion:docker://19.3.4,KubeletVersion:v1.17.0,KubeProxyVersion:v1.17.0,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver@sha256:395c602f72f01f648497338bed6b3d37ddbff6f5b9a2a5941018d1a9833c1501 607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver:8282],SizeBytes:462031378,},ContainerImage{Names:[602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni@sha256:d50a182475c5ee6c18c3b81b01aa649367f30fb0dc60f7a619dcdbf45e10b3a3 602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni:v1.6.1],SizeBytes:293038272,},ContainerImage{Names:[protokube:1.17.0],SizeBytes:277401314,},ContainerImage{Names:[k8s.gcr.io/kube-proxy@sha256:b2ba9441af30261465e5c41be63e462d0050b09ad280001ae731f399b2b00b75 k8s.gcr.io/kube-proxy:v1.17.0],SizeBytes:115960823,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-kube-dns-amd64@sha256:618a82fa66cf0c75e4753369a6999032372be7308866fc9afb381789b1e5ad52 k8s.gcr.io/k8s-dns-kube-dns-amd64:1.14.13],SizeBytes:51157394,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-sidecar-amd64@sha256:cedc8fe2098dffc26d17f64061296b7aa54258a31513b6c52df271a98bb522b3 k8s.gcr.io/k8s-dns-sidecar-amd64:1.14.13],SizeBytes:42852039,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64@sha256:45df3e8e0c551bd0c79cdba48ae6677f817971dcbd1eeed7fd1f9a35118410e4 k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64:1.14.13],SizeBytes:41372492,},ContainerImage{Names:[quay.io/k8scsi/csi-node-driver-registrar@sha256:9622c6a6dac7499a055a382930f4de82905a3c5735c0753f7094115c9c871309 quay.io/k8scsi/csi-node-driver-registrar:v1.3.0],SizeBytes:16322467,},ContainerImage{Names:[quay.io/k8scsi/livenessprobe@sha256:ff8c925b509ebfcf0487e877a2722f87c01632f8d0c5c7eda94c66959542d290 quay.io/k8scsi/livenessprobe:v2.0.0],SizeBytes:15637127,},ContainerImage{Names:[k8s.gcr.io/pause-amd64@sha256:163ac025575b775d1c0f9bf0bdd0f086883171eb475b5068e7defa4ca9e76516 k8s.gcr.io/pause-amd64:3.0],SizeBytes:746888,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
... skipping 206 lines ...
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Pre-provisioned PV (default fs)] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should support readOnly file specified in the volumeMount [LinuxOnly] [It]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:374

      Unexpected error:
          <*errors.errorString | 0xc000e6b6f0>: {
              s: "expected pod \"pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9\" success: Gave up after waiting 5m0s for pod \"pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9\" to be \"success or failure\"",
          }
          expected pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9" success: Gave up after waiting 5m0s for pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-2cp9" to be "success or failure"
      occurred

... skipping 31 lines ...

      Driver efs.csi.aws.com doesn't support DynamicPV -- skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:154
------------------------------
[efs-csi] EFS CSI [Driver: efs.csi.aws.com] [Testpattern: Dynamic PV (default fs)] subPath 
  should fail if subpath file is outside the volume [Slow][LinuxOnly]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:251
[BeforeEach] [efs-csi] EFS CSI
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Aug 13 04:18:30.635: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename efs
... skipping 13 lines ...
[efs-csi] EFS CSI
/home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:176
  [Driver: efs.csi.aws.com]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Dynamic PV (default fs)] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:251

      Driver efs.csi.aws.com doesn't support DynamicPV -- skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:154
------------------------------
... skipping 108 lines ...
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:152
STEP: Collecting events from namespace "provisioning-9103".
STEP: Found 5 events.
Aug 13 04:23:47.208: INFO: At 2020-08-13 04:18:32 +0000 UTC - event for pvc-2n8s2: {persistentvolume-controller } ProvisioningFailed: storageclass.storage.k8s.io "provisioning-9103" not found
Aug 13 04:23:47.208: INFO: At 2020-08-13 04:18:38 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-v4w4: {default-scheduler } Scheduled: Successfully assigned provisioning-9103/pod-subpath-test-efs-csi-aws-com-preprovisionedpv-v4w4 to ip-172-20-89-108.us-west-2.compute.internal
Aug 13 04:23:47.208: INFO: At 2020-08-13 04:20:41 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-v4w4: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume], unattached volumes=[test-volume liveness-probe-volume default-token-qpvrz]: timed out waiting for the condition
Aug 13 04:23:47.208: INFO: At 2020-08-13 04:21:26 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-v4w4: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: MountVolume.SetUp failed for volume "efs.csi.aws.com-svhk9" : kubernetes.io/csi: mounter.SetupAt failed: rpc error: code = DeadlineExceeded desc = context deadline exceeded
Aug 13 04:23:47.208: INFO: At 2020-08-13 04:22:56 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-v4w4: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume], unattached volumes=[default-token-qpvrz test-volume liveness-probe-volume]: timed out waiting for the condition
Aug 13 04:23:47.262: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Aug 13 04:23:47.262: INFO: 
Aug 13 04:23:47.317: INFO: 
Logging node info for node ip-172-20-125-220.us-west-2.compute.internal
Aug 13 04:23:47.371: INFO: Node Info: &Node{ObjectMeta:{ip-172-20-125-220.us-west-2.compute.internal   /api/v1/nodes/ip-172-20-125-220.us-west-2.compute.internal 4195dba4-e009-4834-8589-65ad90ff5dcc 14622 0 2020-08-13 03:11:38 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:c5.large beta.kubernetes.io/os:linux failure-domain.beta.kubernetes.io/region:us-west-2 failure-domain.beta.kubernetes.io/zone:us-west-2c kops.k8s.io/instancegroup:nodes kubernetes.io/arch:amd64 kubernetes.io/hostname:ip-172-20-125-220.us-west-2.compute.internal kubernetes.io/os:linux kubernetes.io/role:node node-role.kubernetes.io/node: node.kubernetes.io/instance-type:c5.large topology.kubernetes.io/region:us-west-2 topology.kubernetes.io/zone:us-west-2c] map[csi.volume.kubernetes.io/nodeid:{"efs.csi.aws.com":"i-04be80e681240246b"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  []},Spec:NodeSpec{PodCIDR:172.20.130.0/24,DoNotUseExternalID:,ProviderID:aws:///us-west-2c/i-04be80e681240246b,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[172.20.130.0/24],},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{128771407872 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3925778432 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Allocatable:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{115894266893 0} {<nil>} 115894266893 DecimalSI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3820920832 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2020-08-13 04:23:46 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2020-08-13 04:23:46 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2020-08-13 04:23:46 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2020-08-13 04:23:46 +0000 UTC,LastTransitionTime:2020-08-13 03:11:58 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:172.20.125.220,},NodeAddress{Type:ExternalIP,Address:54.202.169.41,},NodeAddress{Type:Hostname,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:InternalDNS,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:ExternalDNS,Address:ec2-54-202-169-41.us-west-2.compute.amazonaws.com,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:ec21223433f5884b20f52ebea4903081,SystemUUID:EC212234-33F5-884B-20F5-2EBEA4903081,BootID:f22b750e-0314-45ec-bdc6-b0a3897ae066,KernelVersion:4.9.0-13-amd64,OSImage:Debian GNU/Linux 9 (stretch),ContainerRuntimeVersion:docker://19.3.4,KubeletVersion:v1.17.0,KubeProxyVersion:v1.17.0,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver@sha256:395c602f72f01f648497338bed6b3d37ddbff6f5b9a2a5941018d1a9833c1501 607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver:8282],SizeBytes:462031378,},ContainerImage{Names:[602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni@sha256:d50a182475c5ee6c18c3b81b01aa649367f30fb0dc60f7a619dcdbf45e10b3a3 602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni:v1.6.1],SizeBytes:293038272,},ContainerImage{Names:[protokube:1.17.0],SizeBytes:277401314,},ContainerImage{Names:[k8s.gcr.io/kube-proxy@sha256:b2ba9441af30261465e5c41be63e462d0050b09ad280001ae731f399b2b00b75 k8s.gcr.io/kube-proxy:v1.17.0],SizeBytes:115960823,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-kube-dns-amd64@sha256:618a82fa66cf0c75e4753369a6999032372be7308866fc9afb381789b1e5ad52 k8s.gcr.io/k8s-dns-kube-dns-amd64:1.14.13],SizeBytes:51157394,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-sidecar-amd64@sha256:cedc8fe2098dffc26d17f64061296b7aa54258a31513b6c52df271a98bb522b3 k8s.gcr.io/k8s-dns-sidecar-amd64:1.14.13],SizeBytes:42852039,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64@sha256:45df3e8e0c551bd0c79cdba48ae6677f817971dcbd1eeed7fd1f9a35118410e4 k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64:1.14.13],SizeBytes:41372492,},ContainerImage{Names:[quay.io/k8scsi/csi-node-driver-registrar@sha256:9622c6a6dac7499a055a382930f4de82905a3c5735c0753f7094115c9c871309 quay.io/k8scsi/csi-node-driver-registrar:v1.3.0],SizeBytes:16322467,},ContainerImage{Names:[quay.io/k8scsi/livenessprobe@sha256:ff8c925b509ebfcf0487e877a2722f87c01632f8d0c5c7eda94c66959542d290 quay.io/k8scsi/livenessprobe:v2.0.0],SizeBytes:15637127,},ContainerImage{Names:[k8s.gcr.io/pause-amd64@sha256:163ac025575b775d1c0f9bf0bdd0f086883171eb475b5068e7defa4ca9e76516 k8s.gcr.io/pause-amd64:3.0],SizeBytes:746888,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
... skipping 207 lines ...
    [Testpattern: Pre-provisioned PV (default fs)] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should support restarting containers using file as subpath [Slow][LinuxOnly] [It]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:330

      while waiting for pod to be running
      Unexpected error:
          <*errors.errorString | 0xc00041a3f0>: {
              s: "timed out waiting for the condition",
          }
          timed out waiting for the condition
      occurred

... skipping 215 lines ...
Aug 13 04:28:55.861: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk": Phase="Pending", Reason="", readiness=false. Elapsed: 4m47.753824674s
Aug 13 04:28:57.915: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk": Phase="Pending", Reason="", readiness=false. Elapsed: 4m49.808377461s
Aug 13 04:28:59.970: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk": Phase="Pending", Reason="", readiness=false. Elapsed: 4m51.862717937s
Aug 13 04:29:02.028: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk": Phase="Pending", Reason="", readiness=false. Elapsed: 4m53.92123816s
Aug 13 04:29:04.083: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk": Phase="Pending", Reason="", readiness=false. Elapsed: 4m55.975812349s
Aug 13 04:29:06.138: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk": Phase="Pending", Reason="", readiness=false. Elapsed: 4m58.03085803s
Aug 13 04:29:08.257: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk" container "test-init-subpath-efs-csi-aws-com-preprovisionedpv-ksmk": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk)
Aug 13 04:29:08.313: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk" container "test-container-subpath-efs-csi-aws-com-preprovisionedpv-ksmk": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk)
Aug 13 04:29:08.369: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk" container "test-container-volume-efs-csi-aws-com-preprovisionedpv-ksmk": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk)
STEP: delete the pod
Aug 13 04:29:08.426: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk to disappear
Aug 13 04:29:08.483: INFO: Pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk still exists
Aug 13 04:29:10.483: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk to disappear
Aug 13 04:29:10.539: INFO: Pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk still exists
Aug 13 04:29:12.483: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk to disappear
... skipping 11 lines ...
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:152
STEP: Collecting events from namespace "provisioning-3756".
STEP: Found 4 events.
Aug 13 04:29:16.757: INFO: At 2020-08-13 04:23:51 +0000 UTC - event for pvc-cqv7z: {persistentvolume-controller } ProvisioningFailed: storageclass.storage.k8s.io "provisioning-3756" not found
Aug 13 04:29:16.757: INFO: At 2020-08-13 04:24:08 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk: {default-scheduler } Scheduled: Successfully assigned provisioning-3756/pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk to ip-172-20-89-108.us-west-2.compute.internal
Aug 13 04:29:16.757: INFO: At 2020-08-13 04:26:11 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume], unattached volumes=[test-volume liveness-probe-volume default-token-cgdvq]: timed out waiting for the condition
Aug 13 04:29:16.757: INFO: At 2020-08-13 04:27:54 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: MountVolume.SetUp failed for volume "efs.csi.aws.com-5b5v9" : kubernetes.io/csi: mounter.SetupAt failed: rpc error: code = DeadlineExceeded desc = context deadline exceeded
Aug 13 04:29:16.811: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Aug 13 04:29:16.811: INFO: 
Aug 13 04:29:16.866: INFO: 
Logging node info for node ip-172-20-125-220.us-west-2.compute.internal
Aug 13 04:29:16.919: INFO: Node Info: &Node{ObjectMeta:{ip-172-20-125-220.us-west-2.compute.internal   /api/v1/nodes/ip-172-20-125-220.us-west-2.compute.internal 4195dba4-e009-4834-8589-65ad90ff5dcc 15566 0 2020-08-13 03:11:38 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/instance-type:c5.large beta.kubernetes.io/os:linux failure-domain.beta.kubernetes.io/region:us-west-2 failure-domain.beta.kubernetes.io/zone:us-west-2c kops.k8s.io/instancegroup:nodes kubernetes.io/arch:amd64 kubernetes.io/hostname:ip-172-20-125-220.us-west-2.compute.internal kubernetes.io/os:linux kubernetes.io/role:node node-role.kubernetes.io/node: node.kubernetes.io/instance-type:c5.large topology.kubernetes.io/region:us-west-2 topology.kubernetes.io/zone:us-west-2c] map[csi.volume.kubernetes.io/nodeid:{"efs.csi.aws.com":"i-04be80e681240246b"} node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] []  []},Spec:NodeSpec{PodCIDR:172.20.130.0/24,DoNotUseExternalID:,ProviderID:aws:///us-west-2c/i-04be80e681240246b,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[172.20.130.0/24],},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{128771407872 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3925778432 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Allocatable:ResourceList{attachable-volumes-aws-ebs: {{25 0} {<nil>} 25 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{115894266893 0} {<nil>} 115894266893 DecimalSI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3820920832 0} {<nil>}  BinarySI},pods: {{29 0} {<nil>} 29 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2020-08-13 04:28:47 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2020-08-13 04:28:47 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2020-08-13 04:28:47 +0000 UTC,LastTransitionTime:2020-08-13 03:11:37 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2020-08-13 04:28:47 +0000 UTC,LastTransitionTime:2020-08-13 03:11:58 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:172.20.125.220,},NodeAddress{Type:ExternalIP,Address:54.202.169.41,},NodeAddress{Type:Hostname,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:InternalDNS,Address:ip-172-20-125-220.us-west-2.compute.internal,},NodeAddress{Type:ExternalDNS,Address:ec2-54-202-169-41.us-west-2.compute.amazonaws.com,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:ec21223433f5884b20f52ebea4903081,SystemUUID:EC212234-33F5-884B-20F5-2EBEA4903081,BootID:f22b750e-0314-45ec-bdc6-b0a3897ae066,KernelVersion:4.9.0-13-amd64,OSImage:Debian GNU/Linux 9 (stretch),ContainerRuntimeVersion:docker://19.3.4,KubeletVersion:v1.17.0,KubeProxyVersion:v1.17.0,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver@sha256:395c602f72f01f648497338bed6b3d37ddbff6f5b9a2a5941018d1a9833c1501 607362164682.dkr.ecr.us-west-2.amazonaws.com/aws-efs-csi-driver:8282],SizeBytes:462031378,},ContainerImage{Names:[602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni@sha256:d50a182475c5ee6c18c3b81b01aa649367f30fb0dc60f7a619dcdbf45e10b3a3 602401143452.dkr.ecr.us-west-2.amazonaws.com/amazon-k8s-cni:v1.6.1],SizeBytes:293038272,},ContainerImage{Names:[protokube:1.17.0],SizeBytes:277401314,},ContainerImage{Names:[k8s.gcr.io/kube-proxy@sha256:b2ba9441af30261465e5c41be63e462d0050b09ad280001ae731f399b2b00b75 k8s.gcr.io/kube-proxy:v1.17.0],SizeBytes:115960823,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-kube-dns-amd64@sha256:618a82fa66cf0c75e4753369a6999032372be7308866fc9afb381789b1e5ad52 k8s.gcr.io/k8s-dns-kube-dns-amd64:1.14.13],SizeBytes:51157394,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-sidecar-amd64@sha256:cedc8fe2098dffc26d17f64061296b7aa54258a31513b6c52df271a98bb522b3 k8s.gcr.io/k8s-dns-sidecar-amd64:1.14.13],SizeBytes:42852039,},ContainerImage{Names:[k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64@sha256:45df3e8e0c551bd0c79cdba48ae6677f817971dcbd1eeed7fd1f9a35118410e4 k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64:1.14.13],SizeBytes:41372492,},ContainerImage{Names:[quay.io/k8scsi/csi-node-driver-registrar@sha256:9622c6a6dac7499a055a382930f4de82905a3c5735c0753f7094115c9c871309 quay.io/k8scsi/csi-node-driver-registrar:v1.3.0],SizeBytes:16322467,},ContainerImage{Names:[quay.io/k8scsi/livenessprobe@sha256:ff8c925b509ebfcf0487e877a2722f87c01632f8d0c5c7eda94c66959542d290 quay.io/k8scsi/livenessprobe:v2.0.0],SizeBytes:15637127,},ContainerImage{Names:[k8s.gcr.io/pause-amd64@sha256:163ac025575b775d1c0f9bf0bdd0f086883171eb475b5068e7defa4ca9e76516 k8s.gcr.io/pause-amd64:3.0],SizeBytes:746888,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
Aug 13 04:29:16.920: INFO: 
... skipping 205 lines ...
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Pre-provisioned PV (default fs)] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should support non-existent path [It]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:189

      Unexpected error:
          <*errors.errorString | 0xc0006feec0>: {
              s: "expected pod \"pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk\" success: Gave up after waiting 5m0s for pod \"pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk\" to be \"success or failure\"",
          }
          expected pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk" success: Gave up after waiting 5m0s for pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-ksmk" to be "success or failure"
      occurred

... skipping 209 lines ...
Aug 13 04:34:21.276: INFO: pod "pvc-tester-27lvc" logs:
 
[AfterEach] [efs-csi] EFS CSI
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:152
STEP: Collecting events from namespace "efs-6157".
STEP: Found 5 events.
Aug 13 04:34:21.389: INFO: At 2020-08-13 04:29:21 +0000 UTC - event for pvc-tester-27lvc: {default-scheduler } FailedScheduling: error while running "VolumeBinding" filter plugin for pod "pvc-tester-27lvc": pod has unbound immediate PersistentVolumeClaims
Aug 13 04:34:21.389: INFO: At 2020-08-13 04:29:36 +0000 UTC - event for pvc-tester-27lvc: {default-scheduler } Scheduled: Successfully assigned efs-6157/pvc-tester-27lvc to ip-172-20-89-108.us-west-2.compute.internal
Aug 13 04:34:21.389: INFO: At 2020-08-13 04:31:36 +0000 UTC - event for pvc-tester-27lvc: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: MountVolume.SetUp failed for volume "efs-6157" : kubernetes.io/csi: mounter.SetupAt failed: rpc error: code = DeadlineExceeded desc = context deadline exceeded
Aug 13 04:34:21.389: INFO: At 2020-08-13 04:31:39 +0000 UTC - event for pvc-tester-27lvc: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[volume1], unattached volumes=[volume1 default-token-pbq8x]: timed out waiting for the condition
Aug 13 04:34:21.389: INFO: At 2020-08-13 04:33:55 +0000 UTC - event for pvc-tester-27lvc: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[volume1], unattached volumes=[default-token-pbq8x volume1]: timed out waiting for the condition
Aug 13 04:34:21.445: INFO: POD               NODE                                         PHASE    GRACE  CONDITIONS
Aug 13 04:34:21.445: INFO: pvc-tester-27lvc  ip-172-20-89-108.us-west-2.compute.internal  Pending         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-08-13 04:29:36 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-08-13 04:29:36 +0000 UTC ContainersNotReady containers with unready status: [write-pod]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-08-13 04:29:36 +0000 UTC ContainersNotReady containers with unready status: [write-pod]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-08-13 04:29:36 +0000 UTC  }]
Aug 13 04:34:21.445: INFO: 
Aug 13 04:34:21.502: INFO: 
... skipping 104 lines ...
  [Driver: efs.csi.aws.com]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:184
    should mount with option tls when encryptInTransit unset [It]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:303

    waiting for pod success
    Unexpected error:
        <*errors.errorString | 0xc000dd2a40>: {
            s: "Gave up after waiting 5m0s for pod \"pvc-tester-27lvc\" to be \"success or failure\"",
        }
        Gave up after waiting 5m0s for pod "pvc-tester-27lvc" to be "success or failure"
    occurred

    /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:300
------------------------------
[efs-csi] EFS CSI [Driver: efs.csi.aws.com] [Testpattern: Dynamic PV (default fs)] subPath 
  should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:262
[BeforeEach] [efs-csi] EFS CSI
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Aug 13 04:34:23.067: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename efs
... skipping 13 lines ...
[efs-csi] EFS CSI
/home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:176
  [Driver: efs.csi.aws.com]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Dynamic PV (default fs)] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:262

      Driver efs.csi.aws.com doesn't support DynamicPV -- skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:154
------------------------------
... skipping 29 lines ...
  [Driver: efs.csi.aws.com]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:184
    should continue reading/writing without hanging after the driver pod is restarted
    /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:214
------------------------------
[efs-csi] EFS CSI [Driver: efs.csi.aws.com] [Testpattern: Inline-volume (default fs)] subPath 
  should fail if subpath with backstepping is outside the volume [Slow]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:273
[BeforeEach] [efs-csi] EFS CSI
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Aug 13 04:34:58.845: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename efs
... skipping 13 lines ...
[efs-csi] EFS CSI
/home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:176
  [Driver: efs.csi.aws.com]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Inline-volume (default fs)] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should fail if subpath with backstepping is outside the volume [Slow] [BeforeEach]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:273

      Driver efs.csi.aws.com doesn't support InlineVolume -- skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:154
------------------------------
[efs-csi] EFS CSI [Driver: efs.csi.aws.com] [Testpattern: Dynamic PV (block volmode)] volumeMode 
  should fail in binding dynamic provisioned PV to PVC [Slow]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/volumemode.go:248
[BeforeEach] [efs-csi] EFS CSI
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Aug 13 04:34:59.194: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename efs
... skipping 13 lines ...
[efs-csi] EFS CSI
/home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:176
  [Driver: efs.csi.aws.com]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Dynamic PV (block volmode)] volumeMode
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should fail in binding dynamic provisioned PV to PVC [Slow] [BeforeEach]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/volumemode.go:248

      Driver efs.csi.aws.com doesn't support DynamicPV -- skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:154
------------------------------
... skipping 208 lines ...
Aug 13 04:39:54.982: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm": Phase="Pending", Reason="", readiness=false. Elapsed: 4m48.013836259s
Aug 13 04:39:57.039: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm": Phase="Pending", Reason="", readiness=false. Elapsed: 4m50.070938207s
Aug 13 04:39:59.097: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm": Phase="Pending", Reason="", readiness=false. Elapsed: 4m52.128284182s
Aug 13 04:40:01.153: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm": Phase="Pending", Reason="", readiness=false. Elapsed: 4m54.184734056s
Aug 13 04:40:03.210: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm": Phase="Pending", Reason="", readiness=false. Elapsed: 4m56.242200215s
Aug 13 04:40:05.267: INFO: Pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm": Phase="Pending", Reason="", readiness=false. Elapsed: 4m58.298763744s
Aug 13 04:40:07.387: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm" container "init-volume-efs-csi-aws-com-preprovisionedpv-8xvm": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm)
Aug 13 04:40:07.444: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm" container "test-init-subpath-efs-csi-aws-com-preprovisionedpv-8xvm": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm)
Aug 13 04:40:07.501: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm" container "test-container-subpath-efs-csi-aws-com-preprovisionedpv-8xvm": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm)
Aug 13 04:40:07.559: INFO: Failed to get logs from node "ip-172-20-89-108.us-west-2.compute.internal" pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm" container "test-container-volume-efs-csi-aws-com-preprovisionedpv-8xvm": the server rejected our request for an unknown reason (get pods pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm)
STEP: delete the pod
Aug 13 04:40:07.617: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm to disappear
Aug 13 04:40:07.673: INFO: Pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm still exists
Aug 13 04:40:09.674: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm to disappear
Aug 13 04:40:09.730: INFO: Pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm still exists
Aug 13 04:40:11.674: INFO: Waiting for pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm to disappear
... skipping 12 lines ...
[AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:152
STEP: Collecting events from namespace "provisioning-1079".
STEP: Found 5 events.
Aug 13 04:40:17.961: INFO: At 2020-08-13 04:35:00 +0000 UTC - event for pvc-ct9vc: {persistentvolume-controller } ProvisioningFailed: storageclass.storage.k8s.io "provisioning-1079" not found
Aug 13 04:40:17.961: INFO: At 2020-08-13 04:35:06 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm: {default-scheduler } Scheduled: Successfully assigned provisioning-1079/pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm to ip-172-20-89-108.us-west-2.compute.internal
Aug 13 04:40:17.961: INFO: At 2020-08-13 04:37:07 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: MountVolume.SetUp failed for volume "efs.csi.aws.com-jfppd" : kubernetes.io/csi: mounter.SetupAt failed: rpc error: code = DeadlineExceeded desc = context deadline exceeded
Aug 13 04:40:17.961: INFO: At 2020-08-13 04:37:09 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume], unattached volumes=[test-volume liveness-probe-volume default-token-kv8tz]: timed out waiting for the condition
Aug 13 04:40:17.961: INFO: At 2020-08-13 04:39:25 +0000 UTC - event for pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm: {kubelet ip-172-20-89-108.us-west-2.compute.internal} FailedMount: Unable to attach or mount volumes: unmounted volumes=[test-volume], unattached volumes=[default-token-kv8tz test-volume liveness-probe-volume]: timed out waiting for the condition
Aug 13 04:40:18.016: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Aug 13 04:40:18.016: INFO: 
Aug 13 04:40:18.073: INFO: 
Logging node info for node ip-172-20-125-220.us-west-2.compute.internal
... skipping 207 lines ...
  /home/prow/go/src/github.com/kubernetes-sigs/aws-efs-csi-driver/test/e2e/e2e.go:178
    [Testpattern: Pre-provisioned PV (default fs)] subPath
    /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:100
      should support existing directory [It]
      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:200

      Unexpected error:
          <*errors.errorString | 0xc000b03b50>: {
              s: "expected pod \"pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm\" success: Gave up after waiting 5m0s for pod \"pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm\" to be \"success or failure\"",
          }
          expected pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm" success: Gave up after waiting 5m0s for pod "pod-subpath-test-efs-csi-aws-com-preprovisionedpv-8xvm" to be "success or failure"
      occurred

... skipping 31 lines ...

      Driver efs.csi.aws.com doesn't support InlineVolume -- skipping

      /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/base.go:154
------------------------------
[efs-csi] EFS CSI [Driver: efs.csi.aws.com] [Testpattern: Pre-provisioned PV (default fs)] subPath 
  should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:262
[BeforeEach] [efs-csi] EFS CSI
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Aug 13 04:40:21.689: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename efs
... skipping 3 lines ...
[BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/framework/framework.go:151
STEP: Creating a kubernetes client
Aug 13 04:40:21.913: INFO: >>> kubeConfig: /root/.kube/config
STEP: Building a namespace api object, basename provisioning
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.17.6/test/e2e/storage/testsuites/subpath.go:262
Aug 13 04:40:22.192: INFO: Test running for native CSI Driver, not checking metrics
Aug 13 04:40:22.192: INFO: Creating resource for pre-provisioned PV
Aug 13 04:40:22.192: INFO: Creating PVC and PV
STEP: Creating a PVC followed by a PV
Aug 13 04:40:22.306: INFO: Waiting for PV efs.csi.aws.com-7xbsz to bind to PVC pvc-rdtbr
... skipping 6 lines ...
Aug 13 04:40:32.647: INFO: PersistentVolumeClaim pvc-rdtbr found but phase is Pending instead of Bound.
Aug 13 04:40:34.703: INFO: PersistentVolumeClaim pvc-rdtbr found but phase is Pending instead of Bound.
Aug 13 04:40:36.759: INFO: PersistentVolumeClaim pvc-rdtbr found and phase=Bound (14.453218291s)
Aug 13 04:40:36.759: INFO: Waiting up to 3m0s for PersistentVolume efs.csi.aws.com-7xbsz to have phase Bound
Aug 13 04:40:36.815: INFO: PersistentVolume efs.csi.aws.com-7xbsz found and phase=Bound (55.641536ms)
STEP: Creating pod pod-subpath-test-efs-csi-aws-com-preprovisionedpv-s4x8
STEP: Checking for subpath error in container status
2020/08/13 04:41:42 Test times out after 1h40m0s Cancel the workflow
Removing driver
release "aws-efs-csi-driver" uninstalled
2020/08/13 04:41:43 Deleting cluster test-cluster-8282.k8s.local
TYPE			NAME											ID
autoscaling-config	master-us-west-2a.masters.test-cluster-8282.k8s.local-20200813030800			master-us-west-2a.masters.test-cluster-8282.k8s.local-20200813030800
... skipping 835 lines ...
	subnet:subnet-06ee3bb4266e244d0
	subnet:subnet-03370612371cfe48b
	subnet:subnet-0257a9518182da40a
	route-table:rtb-08676a644b8f0f608

not making progress deleting resources; giving up
2020/08/13 04:51:47 Failed to run tear down step: exit status 1
2020/08/13 04:51:47 signal: killed
make: *** [Makefile:57: test-e2e] Error 1
+ EXIT_VALUE=2
+ set +o xtrace
Cleaning up after docker in docker.
================================================================================
Cleaning up after docker
Stopping Docker: dockerProgram process in pidfile '/var/run/docker-ssd.pid', 1 process(es), refused to die.
... skipping 3 lines ...