This job view page is being replaced by Spyglass soon. Check out the new job view.
PRtorredil: Use minimal base image for linux builds
ResultABORTED
Tests 0 failed / 58 succeeded
Started2022-06-02 21:02
Elapsed26m48s
Revision77f242177913b1cd163428d72251c44197316384
Refs 1233

No Test Failures!


Show 58 Passed Tests

Show 427 Skipped Tests

Error lines from build-log.txt

... skipping 271 lines ...
## Validating cluster test-cluster-26183.k8s.local
#
Using cluster from kubectl context: test-cluster-26183.k8s.local

Validating cluster test-cluster-26183.k8s.local

W0602 21:05:59.447253    6084 validate_cluster.go:173] (will retry): unexpected error during validation: error listing nodes: Get "https://api-test-cluster-26183-k8-8nht0r-1841681954.us-west-2.elb.amazonaws.com/api/v1/nodes": dial tcp: lookup api-test-cluster-26183-k8-8nht0r-1841681954.us-west-2.elb.amazonaws.com on 10.63.240.10:53: no such host
W0602 21:06:09.488263    6084 validate_cluster.go:173] (will retry): unexpected error during validation: error listing nodes: Get "https://api-test-cluster-26183-k8-8nht0r-1841681954.us-west-2.elb.amazonaws.com/api/v1/nodes": dial tcp: lookup api-test-cluster-26183-k8-8nht0r-1841681954.us-west-2.elb.amazonaws.com on 10.63.240.10:53: no such host
W0602 21:06:19.528683    6084 validate_cluster.go:173] (will retry): unexpected error during validation: error listing nodes: Get "https://api-test-cluster-26183-k8-8nht0r-1841681954.us-west-2.elb.amazonaws.com/api/v1/nodes": dial tcp: lookup api-test-cluster-26183-k8-8nht0r-1841681954.us-west-2.elb.amazonaws.com on 10.63.240.10:53: no such host
W0602 21:06:29.574261    6084 validate_cluster.go:173] (will retry): unexpected error during validation: error listing nodes: Get "https://api-test-cluster-26183-k8-8nht0r-1841681954.us-west-2.elb.amazonaws.com/api/v1/nodes": dial tcp: lookup api-test-cluster-26183-k8-8nht0r-1841681954.us-west-2.elb.amazonaws.com on 10.63.240.10:53: no such host
W0602 21:06:39.617586    6084 validate_cluster.go:173] (will retry): unexpected error during validation: error listing nodes: Get "https://api-test-cluster-26183-k8-8nht0r-1841681954.us-west-2.elb.amazonaws.com/api/v1/nodes": dial tcp: lookup api-test-cluster-26183-k8-8nht0r-1841681954.us-west-2.elb.amazonaws.com on 10.63.240.10:53: no such host
W0602 21:06:49.642455    6084 validate_cluster.go:173] (will retry): unexpected error during validation: error listing nodes: Get "https://api-test-cluster-26183-k8-8nht0r-1841681954.us-west-2.elb.amazonaws.com/api/v1/nodes": dial tcp: lookup api-test-cluster-26183-k8-8nht0r-1841681954.us-west-2.elb.amazonaws.com on 10.63.240.10:53: no such host
W0602 21:07:12.347910    6084 validate_cluster.go:173] (will retry): unexpected error during validation: error listing nodes: an error on the server ("") has prevented the request from succeeding (get nodes)
W0602 21:07:33.910708    6084 validate_cluster.go:173] (will retry): unexpected error during validation: error listing nodes: an error on the server ("") has prevented the request from succeeding (get nodes)
W0602 21:07:55.544773    6084 validate_cluster.go:173] (will retry): unexpected error during validation: error listing nodes: an error on the server ("") has prevented the request from succeeding (get nodes)
W0602 21:08:17.158426    6084 validate_cluster.go:173] (will retry): unexpected error during validation: error listing nodes: an error on the server ("") has prevented the request from succeeding (get nodes)
W0602 21:08:38.844842    6084 validate_cluster.go:173] (will retry): unexpected error during validation: error listing nodes: an error on the server ("") has prevented the request from succeeding (get nodes)
INSTANCE GROUPS
NAME			ROLE	MACHINETYPE	MIN	MAX	SUBNETS
master-us-west-2a	Master	t3.medium	1	1	us-west-2a
nodes-us-west-2a	Node	c5.large	1	1	us-west-2a
nodes-us-west-2b	Node	c5.large	1	1	us-west-2b
nodes-us-west-2c	Node	c5.large	1	1	us-west-2c
... skipping 10 lines ...
Node	ip-172-20-45-128.us-west-2.compute.internal	node "ip-172-20-45-128.us-west-2.compute.internal" of role "master" is not ready
Pod	kube-system/coredns-8f5559c9b-99lh4		system-cluster-critical pod "coredns-8f5559c9b-99lh4" is pending
Pod	kube-system/coredns-autoscaler-6f594f4c58-pdmvz	system-cluster-critical pod "coredns-autoscaler-6f594f4c58-pdmvz" is pending
Pod	kube-system/dns-controller-5d59c585d8-fggtn	system-cluster-critical pod "dns-controller-5d59c585d8-fggtn" is pending
Pod	kube-system/kops-controller-2ghvc		system-node-critical pod "kops-controller-2ghvc" is pending

Validation Failed
W0602 21:08:51.753279    6084 validate_cluster.go:221] (will retry): cluster not yet healthy
INSTANCE GROUPS
NAME			ROLE	MACHINETYPE	MIN	MAX	SUBNETS
master-us-west-2a	Master	t3.medium	1	1	us-west-2a
nodes-us-west-2a	Node	c5.large	1	1	us-west-2a
nodes-us-west-2b	Node	c5.large	1	1	us-west-2b
... skipping 11 lines ...
Node	ip-172-20-45-128.us-west-2.compute.internal	master "ip-172-20-45-128.us-west-2.compute.internal" is missing kube-apiserver pod
Node	ip-172-20-45-128.us-west-2.compute.internal	master "ip-172-20-45-128.us-west-2.compute.internal" is missing kube-controller-manager pod
Node	ip-172-20-45-128.us-west-2.compute.internal	master "ip-172-20-45-128.us-west-2.compute.internal" is missing kube-scheduler pod
Pod	kube-system/coredns-8f5559c9b-99lh4		system-cluster-critical pod "coredns-8f5559c9b-99lh4" is pending
Pod	kube-system/coredns-autoscaler-6f594f4c58-pdmvz	system-cluster-critical pod "coredns-autoscaler-6f594f4c58-pdmvz" is pending

Validation Failed
W0602 21:09:04.606129    6084 validate_cluster.go:221] (will retry): cluster not yet healthy
INSTANCE GROUPS
NAME			ROLE	MACHINETYPE	MIN	MAX	SUBNETS
master-us-west-2a	Master	t3.medium	1	1	us-west-2a
nodes-us-west-2a	Node	c5.large	1	1	us-west-2a
nodes-us-west-2b	Node	c5.large	1	1	us-west-2b
... skipping 10 lines ...
Machine	i-0e37c5a27089b996b				machine "i-0e37c5a27089b996b" has not yet joined cluster
Node	ip-172-20-45-128.us-west-2.compute.internal	master "ip-172-20-45-128.us-west-2.compute.internal" is missing kube-apiserver pod
Node	ip-172-20-45-128.us-west-2.compute.internal	master "ip-172-20-45-128.us-west-2.compute.internal" is missing kube-controller-manager pod
Pod	kube-system/coredns-8f5559c9b-99lh4		system-cluster-critical pod "coredns-8f5559c9b-99lh4" is pending
Pod	kube-system/coredns-autoscaler-6f594f4c58-pdmvz	system-cluster-critical pod "coredns-autoscaler-6f594f4c58-pdmvz" is pending

Validation Failed
W0602 21:09:16.831632    6084 validate_cluster.go:221] (will retry): cluster not yet healthy
INSTANCE GROUPS
NAME			ROLE	MACHINETYPE	MIN	MAX	SUBNETS
master-us-west-2a	Master	t3.medium	1	1	us-west-2a
nodes-us-west-2a	Node	c5.large	1	1	us-west-2a
nodes-us-west-2b	Node	c5.large	1	1	us-west-2b
... skipping 9 lines ...
Machine	i-0c41306e7fed7c4c1				machine "i-0c41306e7fed7c4c1" has not yet joined cluster
Machine	i-0e37c5a27089b996b				machine "i-0e37c5a27089b996b" has not yet joined cluster
Node	ip-172-20-45-128.us-west-2.compute.internal	master "ip-172-20-45-128.us-west-2.compute.internal" is missing kube-controller-manager pod
Pod	kube-system/coredns-8f5559c9b-99lh4		system-cluster-critical pod "coredns-8f5559c9b-99lh4" is pending
Pod	kube-system/coredns-autoscaler-6f594f4c58-pdmvz	system-cluster-critical pod "coredns-autoscaler-6f594f4c58-pdmvz" is pending

Validation Failed
W0602 21:09:28.981949    6084 validate_cluster.go:221] (will retry): cluster not yet healthy
INSTANCE GROUPS
NAME			ROLE	MACHINETYPE	MIN	MAX	SUBNETS
master-us-west-2a	Master	t3.medium	1	1	us-west-2a
nodes-us-west-2a	Node	c5.large	1	1	us-west-2a
nodes-us-west-2b	Node	c5.large	1	1	us-west-2b
... skipping 11 lines ...
Node	ip-172-20-105-168.us-west-2.compute.internal	node "ip-172-20-105-168.us-west-2.compute.internal" of role "node" is not ready
Node	ip-172-20-35-11.us-west-2.compute.internal	node "ip-172-20-35-11.us-west-2.compute.internal" of role "node" is not ready
Node	ip-172-20-87-181.us-west-2.compute.internal	node "ip-172-20-87-181.us-west-2.compute.internal" of role "node" is not ready
Pod	kube-system/coredns-8f5559c9b-99lh4		system-cluster-critical pod "coredns-8f5559c9b-99lh4" is pending
Pod	kube-system/coredns-autoscaler-6f594f4c58-pdmvz	system-cluster-critical pod "coredns-autoscaler-6f594f4c58-pdmvz" is pending

Validation Failed
W0602 21:09:41.274227    6084 validate_cluster.go:221] (will retry): cluster not yet healthy
INSTANCE GROUPS
NAME			ROLE	MACHINETYPE	MIN	MAX	SUBNETS
master-us-west-2a	Master	t3.medium	1	1	us-west-2a
nodes-us-west-2a	Node	c5.large	1	1	us-west-2a
nodes-us-west-2b	Node	c5.large	1	1	us-west-2b
... skipping 9 lines ...
VALIDATION ERRORS
KIND	NAME						MESSAGE
Node	ip-172-20-35-11.us-west-2.compute.internal	node "ip-172-20-35-11.us-west-2.compute.internal" of role "node" is not ready
Pod	kube-system/coredns-8f5559c9b-99lh4		system-cluster-critical pod "coredns-8f5559c9b-99lh4" is not ready (coredns)
Pod	kube-system/coredns-8f5559c9b-fps5p		system-cluster-critical pod "coredns-8f5559c9b-fps5p" is not ready (coredns)

Validation Failed
W0602 21:09:53.583806    6084 validate_cluster.go:221] (will retry): cluster not yet healthy
INSTANCE GROUPS
NAME			ROLE	MACHINETYPE	MIN	MAX	SUBNETS
master-us-west-2a	Master	t3.medium	1	1	us-west-2a
nodes-us-west-2a	Node	c5.large	1	1	us-west-2a
nodes-us-west-2b	Node	c5.large	1	1	us-west-2b
... skipping 7 lines ...
ip-172-20-87-181.us-west-2.compute.internal	node	True

VALIDATION ERRORS
KIND	NAME					MESSAGE
Pod	kube-system/coredns-8f5559c9b-99lh4	system-cluster-critical pod "coredns-8f5559c9b-99lh4" is not ready (coredns)

Validation Failed
W0602 21:10:05.919312    6084 validate_cluster.go:221] (will retry): cluster not yet healthy
INSTANCE GROUPS
NAME			ROLE	MACHINETYPE	MIN	MAX	SUBNETS
master-us-west-2a	Master	t3.medium	1	1	us-west-2a
nodes-us-west-2a	Node	c5.large	1	1	us-west-2a
nodes-us-west-2b	Node	c5.large	1	1	us-west-2b
... skipping 7 lines ...
ip-172-20-87-181.us-west-2.compute.internal	node	True

VALIDATION ERRORS
KIND	NAME					MESSAGE
Pod	kube-system/coredns-8f5559c9b-99lh4	system-cluster-critical pod "coredns-8f5559c9b-99lh4" is not ready (coredns)

Validation Failed
W0602 21:10:18.590627    6084 validate_cluster.go:221] (will retry): cluster not yet healthy
INSTANCE GROUPS
NAME			ROLE	MACHINETYPE	MIN	MAX	SUBNETS
master-us-west-2a	Master	t3.medium	1	1	us-west-2a
nodes-us-west-2a	Node	c5.large	1	1	us-west-2a
nodes-us-west-2b	Node	c5.large	1	1	us-west-2b
... skipping 82 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:278

    Driver "ebs.csi.aws.com" does not support volume type "InlineVolume" - skipping

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:263
------------------------------
SSS
------------------------------
External Storage [Driver: ebs.csi.aws.com] [Testpattern: Dynamic PV (default fs)] subPath 
  should fail if subpath file is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:256

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:185
STEP: Creating a kubernetes client
Jun  2 21:12:09.381: INFO: >>> kubeConfig: /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/hack/e2e/csi-test-artifacts/test-cluster-26183.k8s.local.kops.kubeconfig
STEP: Building a namespace api object, basename provisioning
Jun  2 21:12:09.748: INFO: No PodSecurityPolicies found; assuming PodSecurityPolicy is disabled.
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail if subpath file is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:256
Jun  2 21:12:09.879: INFO: Creating resource for dynamic PV
Jun  2 21:12:09.879: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-2924-e2e-sc9rbvm
STEP: creating a claim
Jun  2 21:12:09.947: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: Creating pod pod-subpath-test-dynamicpv-8brl
STEP: Checking for subpath error in container status
Jun  2 21:12:30.299: INFO: Deleting pod "pod-subpath-test-dynamicpv-8brl" in namespace "provisioning-2924"
Jun  2 21:12:30.368: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-8brl" to be fully deleted
STEP: Deleting pod
Jun  2 21:12:42.502: INFO: Deleting pod "pod-subpath-test-dynamicpv-8brl" in namespace "provisioning-2924"
STEP: Deleting pvc
Jun  2 21:12:42.699: INFO: Deleting PersistentVolumeClaim "ebs.csi.aws.comf66fj"
... skipping 9 lines ...

• [SLOW TEST:38.790 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail if subpath file is outside the volume [Slow][LinuxOnly]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:256
------------------------------
S
------------------------------
[BeforeEach] [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
... skipping 106 lines ...
Jun  2 21:13:15.909: INFO: In creating storage class object and pvc objects for driver - sc: &StorageClass{ObjectMeta:{provisioning-9719-e2e-scjtv7r      0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] []  []},Provisioner:ebs.csi.aws.com,Parameters:map[string]string{},ReclaimPolicy:nil,MountOptions:[],AllowVolumeExpansion:nil,VolumeBindingMode:*WaitForFirstConsumer,AllowedTopologies:[]TopologySelectorTerm{},}, pvc: &PersistentVolumeClaim{ObjectMeta:{ pvc- provisioning-9719    0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] []  []},Spec:PersistentVolumeClaimSpec{AccessModes:[ReadWriteOnce],Resources:ResourceRequirements{Limits:ResourceList{},Requests:ResourceList{storage: {{1073741824 0} {<nil>} 1Gi BinarySI},},},VolumeName:,Selector:nil,StorageClassName:*provisioning-9719-e2e-scjtv7r,VolumeMode:nil,DataSource:nil,},Status:PersistentVolumeClaimStatus{Phase:,AccessModes:[],Capacity:ResourceList{},Conditions:[]PersistentVolumeClaimCondition{},},}, src-pvc: &PersistentVolumeClaim{ObjectMeta:{ pvc- provisioning-9719    0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] []  []},Spec:PersistentVolumeClaimSpec{AccessModes:[ReadWriteOnce],Resources:ResourceRequirements{Limits:ResourceList{},Requests:ResourceList{storage: {{1073741824 0} {<nil>} 1Gi BinarySI},},},VolumeName:,Selector:nil,StorageClassName:*provisioning-9719-e2e-scjtv7r,VolumeMode:nil,DataSource:nil,},Status:PersistentVolumeClaimStatus{Phase:,AccessModes:[],Capacity:ResourceList{},Conditions:[]PersistentVolumeClaimCondition{},},}
STEP: Creating a StorageClass
STEP: creating claim=&PersistentVolumeClaim{ObjectMeta:{ pvc- provisioning-9719    0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] []  []},Spec:PersistentVolumeClaimSpec{AccessModes:[ReadWriteOnce],Resources:ResourceRequirements{Limits:ResourceList{},Requests:ResourceList{storage: {{1073741824 0} {<nil>} 1Gi BinarySI},},},VolumeName:,Selector:nil,StorageClassName:*provisioning-9719-e2e-scjtv7r,VolumeMode:nil,DataSource:nil,},Status:PersistentVolumeClaimStatus{Phase:,AccessModes:[],Capacity:ResourceList{},Conditions:[]PersistentVolumeClaimCondition{},},}
STEP: creating a pod referring to the class=&StorageClass{ObjectMeta:{provisioning-9719-e2e-scjtv7r    7e4d8c51-428f-4429-83d7-07ff02b2bf41 1706 0 2022-06-02 21:13:16 +0000 UTC <nil> <nil> map[] map[] [] []  [{e2e-kubernetes.test Update storage.k8s.io/v1 2022-06-02 21:13:16 +0000 UTC FieldsV1 {"f:mountOptions":{},"f:provisioner":{},"f:reclaimPolicy":{},"f:volumeBindingMode":{}}}]},Provisioner:ebs.csi.aws.com,Parameters:map[string]string{},ReclaimPolicy:*Delete,MountOptions:[dirsync],AllowVolumeExpansion:nil,VolumeBindingMode:*WaitForFirstConsumer,AllowedTopologies:[]TopologySelectorTerm{},} claim=&PersistentVolumeClaim{ObjectMeta:{pvc-f6xmg pvc- provisioning-9719  6b87c009-15c5-45c1-9e59-c3ce3bf51454 1708 0 2022-06-02 21:13:16 +0000 UTC <nil> <nil> map[] map[] [] [kubernetes.io/pvc-protection]  [{e2e-kubernetes.test Update v1 2022-06-02 21:13:16 +0000 UTC FieldsV1 {"f:metadata":{"f:generateName":{}},"f:spec":{"f:accessModes":{},"f:resources":{"f:requests":{".":{},"f:storage":{}}},"f:storageClassName":{},"f:volumeMode":{}},"f:status":{"f:phase":{}}}}]},Spec:PersistentVolumeClaimSpec{AccessModes:[ReadWriteOnce],Resources:ResourceRequirements{Limits:ResourceList{},Requests:ResourceList{storage: {{1073741824 0} {<nil>} 1Gi BinarySI},},},VolumeName:,Selector:nil,StorageClassName:*provisioning-9719-e2e-scjtv7r,VolumeMode:*Filesystem,DataSource:nil,},Status:PersistentVolumeClaimStatus{Phase:Pending,AccessModes:[],Capacity:ResourceList{},Conditions:[]PersistentVolumeClaimCondition{},},}
STEP: Deleting pod pod-f803094c-b8bb-4f99-b83e-5766c53afdd5 in namespace provisioning-9719
STEP: checking the created volume is writable on node {Name: Selector:map[] Affinity:nil}
Jun  2 21:13:38.648: INFO: Waiting up to 15m0s for pod "pvc-volume-tester-writer-9n26f" in namespace "provisioning-9719" to be "Succeeded or Failed"
Jun  2 21:13:38.716: INFO: Pod "pvc-volume-tester-writer-9n26f": Phase="Pending", Reason="", readiness=false. Elapsed: 68.159571ms
Jun  2 21:13:40.784: INFO: Pod "pvc-volume-tester-writer-9n26f": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.136011889s
STEP: Saw pod success
Jun  2 21:13:40.784: INFO: Pod "pvc-volume-tester-writer-9n26f" satisfied condition "Succeeded or Failed"
Jun  2 21:13:40.921: INFO: Pod pvc-volume-tester-writer-9n26f has the following logs: 
Jun  2 21:13:40.921: INFO: Deleting pod "pvc-volume-tester-writer-9n26f" in namespace "provisioning-9719"
Jun  2 21:13:41.004: INFO: Wait up to 5m0s for pod "pvc-volume-tester-writer-9n26f" to be fully deleted
STEP: checking the created volume has the correct mount options, is readable and retains data on the same node "ip-172-20-87-181.us-west-2.compute.internal"
Jun  2 21:13:41.271: INFO: Waiting up to 15m0s for pod "pvc-volume-tester-reader-cdv4w" in namespace "provisioning-9719" to be "Succeeded or Failed"
Jun  2 21:13:41.338: INFO: Pod "pvc-volume-tester-reader-cdv4w": Phase="Pending", Reason="", readiness=false. Elapsed: 66.484715ms
Jun  2 21:13:43.404: INFO: Pod "pvc-volume-tester-reader-cdv4w": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.13299989s
STEP: Saw pod success
Jun  2 21:13:43.404: INFO: Pod "pvc-volume-tester-reader-cdv4w" satisfied condition "Succeeded or Failed"
Jun  2 21:13:43.539: INFO: Pod pvc-volume-tester-reader-cdv4w has the following logs: hello world

Jun  2 21:13:43.539: INFO: Deleting pod "pvc-volume-tester-reader-cdv4w" in namespace "provisioning-9719"
Jun  2 21:13:43.611: INFO: Wait up to 5m0s for pod "pvc-volume-tester-reader-cdv4w" to be fully deleted
Jun  2 21:13:43.677: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-f6xmg] to have phase Bound
Jun  2 21:13:43.743: INFO: PersistentVolumeClaim pvc-f6xmg found and phase=Bound (65.861037ms)
... skipping 144 lines ...
Jun  2 21:12:09.946: INFO: Creating resource for dynamic PV
Jun  2 21:12:09.947: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass snapshotting-2213-e2e-scx5nmw
STEP: creating a claim
Jun  2 21:12:10.015: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: [init] starting a pod to use the claim
Jun  2 21:12:10.219: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-qqd5n" in namespace "snapshotting-2213" to be "Succeeded or Failed"
Jun  2 21:12:10.285: INFO: Pod "pvc-snapshottable-tester-qqd5n": Phase="Pending", Reason="", readiness=false. Elapsed: 66.376242ms
Jun  2 21:12:12.352: INFO: Pod "pvc-snapshottable-tester-qqd5n": Phase="Pending", Reason="", readiness=false. Elapsed: 2.132961411s
Jun  2 21:12:14.418: INFO: Pod "pvc-snapshottable-tester-qqd5n": Phase="Pending", Reason="", readiness=false. Elapsed: 4.199367467s
Jun  2 21:12:16.485: INFO: Pod "pvc-snapshottable-tester-qqd5n": Phase="Pending", Reason="", readiness=false. Elapsed: 6.266041692s
Jun  2 21:12:18.555: INFO: Pod "pvc-snapshottable-tester-qqd5n": Phase="Pending", Reason="", readiness=false. Elapsed: 8.33605993s
Jun  2 21:12:20.622: INFO: Pod "pvc-snapshottable-tester-qqd5n": Phase="Pending", Reason="", readiness=false. Elapsed: 10.403435921s
Jun  2 21:12:22.690: INFO: Pod "pvc-snapshottable-tester-qqd5n": Phase="Pending", Reason="", readiness=false. Elapsed: 12.47066793s
Jun  2 21:12:24.757: INFO: Pod "pvc-snapshottable-tester-qqd5n": Phase="Pending", Reason="", readiness=false. Elapsed: 14.537868287s
Jun  2 21:12:26.824: INFO: Pod "pvc-snapshottable-tester-qqd5n": Phase="Pending", Reason="", readiness=false. Elapsed: 16.605399941s
Jun  2 21:12:28.892: INFO: Pod "pvc-snapshottable-tester-qqd5n": Phase="Succeeded", Reason="", readiness=false. Elapsed: 18.673319587s
STEP: Saw pod success
Jun  2 21:12:28.892: INFO: Pod "pvc-snapshottable-tester-qqd5n" satisfied condition "Succeeded or Failed"
Jun  2 21:12:29.036: INFO: Pod pvc-snapshottable-tester-qqd5n has the following logs: 
Jun  2 21:12:29.036: INFO: Deleting pod "pvc-snapshottable-tester-qqd5n" in namespace "snapshotting-2213"
Jun  2 21:12:29.108: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-qqd5n" to be fully deleted
Jun  2 21:12:29.176: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [ebs.csi.aws.comjn5kq] to have phase Bound
Jun  2 21:12:29.242: INFO: PersistentVolumeClaim ebs.csi.aws.comjn5kq found and phase=Bound (65.855871ms)
STEP: [init] checking the claim
... skipping 25 lines ...
[It] should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:245
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
Jun  2 21:13:01.014: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-rt9tc" in namespace "snapshotting-2213" to be "Succeeded or Failed"
Jun  2 21:13:01.080: INFO: Pod "pvc-snapshottable-data-tester-rt9tc": Phase="Pending", Reason="", readiness=false. Elapsed: 66.351517ms
Jun  2 21:13:03.147: INFO: Pod "pvc-snapshottable-data-tester-rt9tc": Phase="Pending", Reason="", readiness=false. Elapsed: 2.133435415s
Jun  2 21:13:05.215: INFO: Pod "pvc-snapshottable-data-tester-rt9tc": Phase="Pending", Reason="", readiness=false. Elapsed: 4.200603008s
Jun  2 21:13:07.282: INFO: Pod "pvc-snapshottable-data-tester-rt9tc": Phase="Pending", Reason="", readiness=false. Elapsed: 6.268491511s
Jun  2 21:13:09.350: INFO: Pod "pvc-snapshottable-data-tester-rt9tc": Phase="Pending", Reason="", readiness=false. Elapsed: 8.33565489s
Jun  2 21:13:11.417: INFO: Pod "pvc-snapshottable-data-tester-rt9tc": Phase="Pending", Reason="", readiness=false. Elapsed: 10.40282848s
Jun  2 21:13:13.483: INFO: Pod "pvc-snapshottable-data-tester-rt9tc": Phase="Pending", Reason="", readiness=false. Elapsed: 12.469442553s
Jun  2 21:13:15.551: INFO: Pod "pvc-snapshottable-data-tester-rt9tc": Phase="Pending", Reason="", readiness=false. Elapsed: 14.536558464s
Jun  2 21:13:17.617: INFO: Pod "pvc-snapshottable-data-tester-rt9tc": Phase="Succeeded", Reason="", readiness=false. Elapsed: 16.603122989s
STEP: Saw pod success
Jun  2 21:13:17.617: INFO: Pod "pvc-snapshottable-data-tester-rt9tc" satisfied condition "Succeeded or Failed"
Jun  2 21:13:17.752: INFO: Pod pvc-snapshottable-data-tester-rt9tc has the following logs: 
Jun  2 21:13:17.752: INFO: Deleting pod "pvc-snapshottable-data-tester-rt9tc" in namespace "snapshotting-2213"
Jun  2 21:13:17.825: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-rt9tc" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
Jun  2 21:13:32.161: INFO: Running '/usr/local/bin/kubectl --server=https://api-test-cluster-26183-k8-8nht0r-1841681954.us-west-2.elb.amazonaws.com --kubeconfig=/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/hack/e2e/csi-test-artifacts/test-cluster-26183.k8s.local.kops.kubeconfig --namespace=snapshotting-2213 exec restored-pvc-tester-h9k2n --namespace=snapshotting-2213 -- cat /mnt/test/data'
... skipping 34 lines ...
Jun  2 21:13:57.865: INFO: volumesnapshotcontents snapcontent-a30493fb-9e85-414b-bde7-bd57778b19d3 has been found and is not deleted
Jun  2 21:13:58.936: INFO: volumesnapshotcontents snapcontent-a30493fb-9e85-414b-bde7-bd57778b19d3 has been found and is not deleted
Jun  2 21:14:00.003: INFO: volumesnapshotcontents snapcontent-a30493fb-9e85-414b-bde7-bd57778b19d3 has been found and is not deleted
Jun  2 21:14:01.071: INFO: volumesnapshotcontents snapcontent-a30493fb-9e85-414b-bde7-bd57778b19d3 has been found and is not deleted
Jun  2 21:14:02.140: INFO: volumesnapshotcontents snapcontent-a30493fb-9e85-414b-bde7-bd57778b19d3 has been found and is not deleted
Jun  2 21:14:03.207: INFO: volumesnapshotcontents snapcontent-a30493fb-9e85-414b-bde7-bd57778b19d3 has been found and is not deleted
Jun  2 21:14:04.207: INFO: WaitUntil failed after reaching the timeout 30s
[AfterEach] volume snapshot controller
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:223
Jun  2 21:14:04.276: INFO: Pod restored-pvc-tester-h9k2n has the following logs: unable to retrieve container logs for containerd://c97c1bb1a34a9435e562db44e65e5e49a1b522060009269018c76d82b835a191
Jun  2 21:14:04.276: INFO: Deleting pod "restored-pvc-tester-h9k2n" in namespace "snapshotting-2213"
Jun  2 21:14:04.344: INFO: Wait up to 5m0s for pod "restored-pvc-tester-h9k2n" to be fully deleted
Jun  2 21:14:12.477: INFO: deleting claim "snapshotting-2213"/"pvc-b6nj9"
... skipping 296 lines ...

    Driver "ebs.csi.aws.com" does not support volume type "PreprovisionedPV" - skipping

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:263
------------------------------
External Storage [Driver: ebs.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/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:297

[BeforeEach] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:185
STEP: Creating a kubernetes client
Jun  2 21:14:22.749: INFO: >>> kubeConfig: /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/hack/e2e/csi-test-artifacts/test-cluster-26183.k8s.local.kops.kubeconfig
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/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:297
Jun  2 21:14:23.075: INFO: Creating resource for dynamic PV
Jun  2 21:14:23.075: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass volumemode-7871-e2e-sc7nswf
STEP: creating a claim
STEP: Creating pod
STEP: Waiting for the pod to fail
Jun  2 21:14:29.539: INFO: Deleting pod "pod-2b17871e-1761-456f-bde9-61baf139206a" in namespace "volumemode-7871"
Jun  2 21:14:29.606: INFO: Wait up to 5m0s for pod "pod-2b17871e-1761-456f-bde9-61baf139206a" to be fully deleted
STEP: Deleting pvc
Jun  2 21:14:33.867: INFO: Deleting PersistentVolumeClaim "ebs.csi.aws.comlz86w"
Jun  2 21:14:33.933: INFO: Waiting up to 5m0s for PersistentVolume pvc-11655384-e504-4ccf-9768-382beeb51b91 to get deleted
Jun  2 21:14:33.998: INFO: PersistentVolume pvc-11655384-e504-4ccf-9768-382beeb51b91 found and phase=Released (65.125177ms)
... skipping 7 lines ...

• [SLOW TEST:16.514 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Dynamic PV (filesystem volmode)] volumeMode
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail to use a volume in a pod with mismatched mode [Slow]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:297
------------------------------
SS
------------------------------
External Storage [Driver: ebs.csi.aws.com] [Testpattern: Dynamic PV (default fs)] subPath 
  should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:278

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:185
STEP: Creating a kubernetes client
Jun  2 21:14:20.775: INFO: >>> kubeConfig: /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/hack/e2e/csi-test-artifacts/test-cluster-26183.k8s.local.kops.kubeconfig
STEP: Building a namespace api object, basename provisioning
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:278
Jun  2 21:14:21.106: INFO: Creating resource for dynamic PV
Jun  2 21:14:21.106: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-3725-e2e-sck9lk8
STEP: creating a claim
Jun  2 21:14:21.173: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: Creating pod pod-subpath-test-dynamicpv-645m
STEP: Checking for subpath error in container status
Jun  2 21:14:35.541: INFO: Deleting pod "pod-subpath-test-dynamicpv-645m" in namespace "provisioning-3725"
Jun  2 21:14:35.608: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-645m" to be fully deleted
STEP: Deleting pod
Jun  2 21:14:43.742: INFO: Deleting pod "pod-subpath-test-dynamicpv-645m" in namespace "provisioning-3725"
STEP: Deleting pvc
Jun  2 21:14:43.941: INFO: Deleting PersistentVolumeClaim "ebs.csi.aws.com2w4st"
... skipping 9 lines ...

• [SLOW TEST:28.575 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:278
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
Jun  2 21:14:49.352: INFO: Driver "ebs.csi.aws.com" does not support volume type "PreprovisionedPV" - skipping
[AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
... skipping 152 lines ...
Jun  2 21:14:04.904: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass volume-5591-e2e-scr9zvn
STEP: creating a claim
Jun  2 21:14:04.972: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: Creating pod exec-volume-test-dynamicpv-q6k2
STEP: Creating a pod to test exec-volume-test
Jun  2 21:14:05.177: INFO: Waiting up to 5m0s for pod "exec-volume-test-dynamicpv-q6k2" in namespace "volume-5591" to be "Succeeded or Failed"
Jun  2 21:14:05.243: INFO: Pod "exec-volume-test-dynamicpv-q6k2": Phase="Pending", Reason="", readiness=false. Elapsed: 65.868846ms
Jun  2 21:14:07.309: INFO: Pod "exec-volume-test-dynamicpv-q6k2": Phase="Pending", Reason="", readiness=false. Elapsed: 2.132478229s
Jun  2 21:14:09.376: INFO: Pod "exec-volume-test-dynamicpv-q6k2": Phase="Pending", Reason="", readiness=false. Elapsed: 4.199685989s
Jun  2 21:14:11.443: INFO: Pod "exec-volume-test-dynamicpv-q6k2": Phase="Pending", Reason="", readiness=false. Elapsed: 6.266429125s
Jun  2 21:14:13.510: INFO: Pod "exec-volume-test-dynamicpv-q6k2": Phase="Pending", Reason="", readiness=false. Elapsed: 8.332884596s
Jun  2 21:14:15.576: INFO: Pod "exec-volume-test-dynamicpv-q6k2": Phase="Succeeded", Reason="", readiness=false. Elapsed: 10.399516046s
STEP: Saw pod success
Jun  2 21:14:15.576: INFO: Pod "exec-volume-test-dynamicpv-q6k2" satisfied condition "Succeeded or Failed"
Jun  2 21:14:15.657: INFO: Trying to get logs from node ip-172-20-87-181.us-west-2.compute.internal pod exec-volume-test-dynamicpv-q6k2 container exec-container-dynamicpv-q6k2: <nil>
STEP: delete the pod
Jun  2 21:14:15.814: INFO: Waiting for pod exec-volume-test-dynamicpv-q6k2 to disappear
Jun  2 21:14:15.880: INFO: Pod exec-volume-test-dynamicpv-q6k2 no longer exists
STEP: Deleting pod exec-volume-test-dynamicpv-q6k2
Jun  2 21:14:15.880: INFO: Deleting pod "exec-volume-test-dynamicpv-q6k2" in namespace "volume-5591"
... skipping 32 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:256

    Driver "ebs.csi.aws.com" does not support volume type "InlineVolume" - skipping

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:263
------------------------------
... skipping 29 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:267

    Driver "ebs.csi.aws.com" does not support volume type "InlineVolume" - skipping

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:263
------------------------------
... skipping 180 lines ...
Jun  2 21:14:39.592: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-9655-e2e-scww72b
STEP: creating a claim
Jun  2 21:14:39.658: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: Creating pod pod-subpath-test-dynamicpv-mvqg
STEP: Creating a pod to test subpath
Jun  2 21:14:39.858: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-mvqg" in namespace "provisioning-9655" to be "Succeeded or Failed"
Jun  2 21:14:39.926: INFO: Pod "pod-subpath-test-dynamicpv-mvqg": Phase="Pending", Reason="", readiness=false. Elapsed: 67.623697ms
Jun  2 21:14:41.991: INFO: Pod "pod-subpath-test-dynamicpv-mvqg": Phase="Pending", Reason="", readiness=false. Elapsed: 2.133337637s
Jun  2 21:14:44.058: INFO: Pod "pod-subpath-test-dynamicpv-mvqg": Phase="Pending", Reason="", readiness=false. Elapsed: 4.199448967s
Jun  2 21:14:46.127: INFO: Pod "pod-subpath-test-dynamicpv-mvqg": Phase="Pending", Reason="", readiness=false. Elapsed: 6.26891342s
Jun  2 21:14:48.193: INFO: Pod "pod-subpath-test-dynamicpv-mvqg": Phase="Pending", Reason="", readiness=false. Elapsed: 8.335287812s
Jun  2 21:14:50.260: INFO: Pod "pod-subpath-test-dynamicpv-mvqg": Phase="Pending", Reason="", readiness=false. Elapsed: 10.401476873s
Jun  2 21:14:52.332: INFO: Pod "pod-subpath-test-dynamicpv-mvqg": Phase="Pending", Reason="", readiness=false. Elapsed: 12.473971572s
Jun  2 21:14:54.403: INFO: Pod "pod-subpath-test-dynamicpv-mvqg": Phase="Pending", Reason="", readiness=false. Elapsed: 14.544410108s
Jun  2 21:14:56.469: INFO: Pod "pod-subpath-test-dynamicpv-mvqg": Phase="Succeeded", Reason="", readiness=false. Elapsed: 16.610350863s
STEP: Saw pod success
Jun  2 21:14:56.469: INFO: Pod "pod-subpath-test-dynamicpv-mvqg" satisfied condition "Succeeded or Failed"
Jun  2 21:14:56.534: INFO: Trying to get logs from node ip-172-20-87-181.us-west-2.compute.internal pod pod-subpath-test-dynamicpv-mvqg container test-container-subpath-dynamicpv-mvqg: <nil>
STEP: delete the pod
Jun  2 21:14:56.674: INFO: Waiting for pod pod-subpath-test-dynamicpv-mvqg to disappear
Jun  2 21:14:56.738: INFO: Pod pod-subpath-test-dynamicpv-mvqg no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-mvqg
Jun  2 21:14:56.738: INFO: Deleting pod "pod-subpath-test-dynamicpv-mvqg" in namespace "provisioning-9655"
... skipping 158 lines ...
Jun  2 21:14:53.245: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-8940-e2e-sczdp2h
STEP: creating a claim
Jun  2 21:14:53.311: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: Creating pod pod-subpath-test-dynamicpv-scnh
STEP: Creating a pod to test subpath
Jun  2 21:14:53.517: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-scnh" in namespace "provisioning-8940" to be "Succeeded or Failed"
Jun  2 21:14:53.583: INFO: Pod "pod-subpath-test-dynamicpv-scnh": Phase="Pending", Reason="", readiness=false. Elapsed: 66.083401ms
Jun  2 21:14:55.650: INFO: Pod "pod-subpath-test-dynamicpv-scnh": Phase="Pending", Reason="", readiness=false. Elapsed: 2.132706013s
Jun  2 21:14:57.717: INFO: Pod "pod-subpath-test-dynamicpv-scnh": Phase="Pending", Reason="", readiness=false. Elapsed: 4.20006654s
Jun  2 21:14:59.786: INFO: Pod "pod-subpath-test-dynamicpv-scnh": Phase="Pending", Reason="", readiness=false. Elapsed: 6.269286629s
Jun  2 21:15:01.855: INFO: Pod "pod-subpath-test-dynamicpv-scnh": Phase="Pending", Reason="", readiness=false. Elapsed: 8.337603854s
Jun  2 21:15:03.923: INFO: Pod "pod-subpath-test-dynamicpv-scnh": Phase="Pending", Reason="", readiness=false. Elapsed: 10.406213887s
Jun  2 21:15:05.991: INFO: Pod "pod-subpath-test-dynamicpv-scnh": Phase="Succeeded", Reason="", readiness=false. Elapsed: 12.474036198s
STEP: Saw pod success
Jun  2 21:15:05.991: INFO: Pod "pod-subpath-test-dynamicpv-scnh" satisfied condition "Succeeded or Failed"
Jun  2 21:15:06.057: INFO: Trying to get logs from node ip-172-20-87-181.us-west-2.compute.internal pod pod-subpath-test-dynamicpv-scnh container test-container-subpath-dynamicpv-scnh: <nil>
STEP: delete the pod
Jun  2 21:15:06.200: INFO: Waiting for pod pod-subpath-test-dynamicpv-scnh to disappear
Jun  2 21:15:06.266: INFO: Pod pod-subpath-test-dynamicpv-scnh no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-scnh
Jun  2 21:15:06.266: INFO: Deleting pod "pod-subpath-test-dynamicpv-scnh" in namespace "provisioning-8940"
STEP: Creating pod pod-subpath-test-dynamicpv-scnh
STEP: Creating a pod to test subpath
Jun  2 21:15:06.399: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-scnh" in namespace "provisioning-8940" to be "Succeeded or Failed"
Jun  2 21:15:06.466: INFO: Pod "pod-subpath-test-dynamicpv-scnh": Phase="Pending", Reason="", readiness=false. Elapsed: 66.523574ms
Jun  2 21:15:08.543: INFO: Pod "pod-subpath-test-dynamicpv-scnh": Phase="Pending", Reason="", readiness=false. Elapsed: 2.143898363s
Jun  2 21:15:10.610: INFO: Pod "pod-subpath-test-dynamicpv-scnh": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.211186931s
STEP: Saw pod success
Jun  2 21:15:10.610: INFO: Pod "pod-subpath-test-dynamicpv-scnh" satisfied condition "Succeeded or Failed"
Jun  2 21:15:10.677: INFO: Trying to get logs from node ip-172-20-87-181.us-west-2.compute.internal pod pod-subpath-test-dynamicpv-scnh container test-container-subpath-dynamicpv-scnh: <nil>
STEP: delete the pod
Jun  2 21:15:10.821: INFO: Waiting for pod pod-subpath-test-dynamicpv-scnh to disappear
Jun  2 21:15:10.888: INFO: Pod pod-subpath-test-dynamicpv-scnh no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-scnh
Jun  2 21:15:10.888: INFO: Deleting pod "pod-subpath-test-dynamicpv-scnh" in namespace "provisioning-8940"
... skipping 39 lines ...
Jun  2 21:14:49.714: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-3713-e2e-scgc6ns
STEP: creating a claim
Jun  2 21:14:49.780: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: Creating pod pod-subpath-test-dynamicpv-59gs
STEP: Creating a pod to test subpath
Jun  2 21:14:49.984: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-59gs" in namespace "provisioning-3713" to be "Succeeded or Failed"
Jun  2 21:14:50.051: INFO: Pod "pod-subpath-test-dynamicpv-59gs": Phase="Pending", Reason="", readiness=false. Elapsed: 66.412284ms
Jun  2 21:14:52.118: INFO: Pod "pod-subpath-test-dynamicpv-59gs": Phase="Pending", Reason="", readiness=false. Elapsed: 2.133667016s
Jun  2 21:14:54.186: INFO: Pod "pod-subpath-test-dynamicpv-59gs": Phase="Pending", Reason="", readiness=false. Elapsed: 4.201296043s
Jun  2 21:14:56.252: INFO: Pod "pod-subpath-test-dynamicpv-59gs": Phase="Pending", Reason="", readiness=false. Elapsed: 6.268246567s
Jun  2 21:14:58.320: INFO: Pod "pod-subpath-test-dynamicpv-59gs": Phase="Pending", Reason="", readiness=false. Elapsed: 8.33619383s
Jun  2 21:15:00.388: INFO: Pod "pod-subpath-test-dynamicpv-59gs": Phase="Pending", Reason="", readiness=false. Elapsed: 10.403553153s
Jun  2 21:15:02.456: INFO: Pod "pod-subpath-test-dynamicpv-59gs": Phase="Pending", Reason="", readiness=false. Elapsed: 12.471604668s
Jun  2 21:15:04.524: INFO: Pod "pod-subpath-test-dynamicpv-59gs": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.540144274s
STEP: Saw pod success
Jun  2 21:15:04.524: INFO: Pod "pod-subpath-test-dynamicpv-59gs" satisfied condition "Succeeded or Failed"
Jun  2 21:15:04.595: INFO: Trying to get logs from node ip-172-20-87-181.us-west-2.compute.internal pod pod-subpath-test-dynamicpv-59gs container test-container-volume-dynamicpv-59gs: <nil>
STEP: delete the pod
Jun  2 21:15:04.734: INFO: Waiting for pod pod-subpath-test-dynamicpv-59gs to disappear
Jun  2 21:15:04.800: INFO: Pod pod-subpath-test-dynamicpv-59gs no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-59gs
Jun  2 21:15:04.800: INFO: Deleting pod "pod-subpath-test-dynamicpv-59gs" in namespace "provisioning-3713"
... skipping 441 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Dynamic PV (ntfs)][Feature:Windows] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail if subpath file is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:256

    Driver ebs.csi.aws.com doesn't support ntfs -- skipping

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:121
------------------------------
... skipping 146 lines ...

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/multivolume.go:635
------------------------------
SSS
------------------------------
External Storage [Driver: ebs.csi.aws.com] [Testpattern: Dynamic PV (default fs)] subPath 
  should fail if subpath directory is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:240

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:185
STEP: Creating a kubernetes client
Jun  2 21:16:29.326: INFO: >>> kubeConfig: /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/hack/e2e/csi-test-artifacts/test-cluster-26183.k8s.local.kops.kubeconfig
STEP: Building a namespace api object, basename provisioning
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail if subpath directory is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:240
Jun  2 21:16:29.664: INFO: Creating resource for dynamic PV
Jun  2 21:16:29.664: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-7846-e2e-scp288l
STEP: creating a claim
Jun  2 21:16:29.741: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: Creating pod pod-subpath-test-dynamicpv-67sj
STEP: Checking for subpath error in container status
Jun  2 21:16:46.087: INFO: Deleting pod "pod-subpath-test-dynamicpv-67sj" in namespace "provisioning-7846"
Jun  2 21:16:46.156: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-67sj" to be fully deleted
STEP: Deleting pod
Jun  2 21:16:52.289: INFO: Deleting pod "pod-subpath-test-dynamicpv-67sj" in namespace "provisioning-7846"
STEP: Deleting pvc
Jun  2 21:16:52.488: INFO: Deleting PersistentVolumeClaim "ebs.csi.aws.comx28h6"
... skipping 11 lines ...

• [SLOW TEST:38.718 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail if subpath directory is outside the volume [Slow][LinuxOnly]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:240
------------------------------
SS
------------------------------
[BeforeEach] [Testpattern: Dynamic PV (ext3)] volumes
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
... skipping 101 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Dynamic PV (ntfs)][Feature:Windows] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:267

    Driver ebs.csi.aws.com doesn't support ntfs -- skipping

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:121
------------------------------
... skipping 219 lines ...

    Driver "ebs.csi.aws.com" does not support volume type "InlineVolume" - skipping

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:263
------------------------------
External Storage [Driver: ebs.csi.aws.com] [Testpattern: Dynamic PV (default fs)] subPath 
  should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:267

[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:185
STEP: Creating a kubernetes client
Jun  2 21:17:08.085: INFO: >>> kubeConfig: /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/hack/e2e/csi-test-artifacts/test-cluster-26183.k8s.local.kops.kubeconfig
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/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:267
Jun  2 21:17:08.416: INFO: Creating resource for dynamic PV
Jun  2 21:17:08.416: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-9805-e2e-sc6pvl8
STEP: creating a claim
Jun  2 21:17:08.483: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: Creating pod pod-subpath-test-dynamicpv-jttl
STEP: Checking for subpath error in container status
Jun  2 21:17:22.823: INFO: Deleting pod "pod-subpath-test-dynamicpv-jttl" in namespace "provisioning-9805"
Jun  2 21:17:22.891: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-jttl" to be fully deleted
STEP: Deleting pod
Jun  2 21:17:33.025: INFO: Deleting pod "pod-subpath-test-dynamicpv-jttl" in namespace "provisioning-9805"
STEP: Deleting pvc
Jun  2 21:17:33.224: INFO: Deleting PersistentVolumeClaim "ebs.csi.aws.comc57w2"
... skipping 10 lines ...

• [SLOW TEST:35.619 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Dynamic PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail if non-existent subpath is outside the volume [Slow][LinuxOnly]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:267
------------------------------
[BeforeEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
Jun  2 21:17:43.706: INFO: Driver "ebs.csi.aws.com" does not support volume type "PreprovisionedPV" - skipping
[AfterEach] [Testpattern: Pre-provisioned PV (default fs)] subPath
... skipping 2 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail if non-existent subpath is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:267

    Driver "ebs.csi.aws.com" does not support volume type "PreprovisionedPV" - skipping

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:263
------------------------------
... skipping 130 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Dynamic PV (ntfs)][Feature:Windows] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail if subpath directory is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:240

    Driver ebs.csi.aws.com doesn't support ntfs -- skipping

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:121
------------------------------
... skipping 249 lines ...
Jun  2 21:18:08.459: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-4172-e2e-scxd6dl
STEP: creating a claim
Jun  2 21:18:08.526: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: Creating pod pod-subpath-test-dynamicpv-r9gv
STEP: Creating a pod to test subpath
Jun  2 21:18:08.729: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-r9gv" in namespace "provisioning-4172" to be "Succeeded or Failed"
Jun  2 21:18:08.795: INFO: Pod "pod-subpath-test-dynamicpv-r9gv": Phase="Pending", Reason="", readiness=false. Elapsed: 65.746508ms
Jun  2 21:18:10.862: INFO: Pod "pod-subpath-test-dynamicpv-r9gv": Phase="Pending", Reason="", readiness=false. Elapsed: 2.132810573s
Jun  2 21:18:12.928: INFO: Pod "pod-subpath-test-dynamicpv-r9gv": Phase="Pending", Reason="", readiness=false. Elapsed: 4.199319538s
Jun  2 21:18:14.995: INFO: Pod "pod-subpath-test-dynamicpv-r9gv": Phase="Pending", Reason="", readiness=false. Elapsed: 6.265971256s
Jun  2 21:18:17.062: INFO: Pod "pod-subpath-test-dynamicpv-r9gv": Phase="Pending", Reason="", readiness=false. Elapsed: 8.332915477s
Jun  2 21:18:19.129: INFO: Pod "pod-subpath-test-dynamicpv-r9gv": Phase="Pending", Reason="", readiness=false. Elapsed: 10.399990604s
Jun  2 21:18:21.196: INFO: Pod "pod-subpath-test-dynamicpv-r9gv": Phase="Pending", Reason="", readiness=false. Elapsed: 12.466783323s
Jun  2 21:18:23.263: INFO: Pod "pod-subpath-test-dynamicpv-r9gv": Phase="Pending", Reason="", readiness=false. Elapsed: 14.534049172s
Jun  2 21:18:25.330: INFO: Pod "pod-subpath-test-dynamicpv-r9gv": Phase="Succeeded", Reason="", readiness=false. Elapsed: 16.601010537s
STEP: Saw pod success
Jun  2 21:18:25.330: INFO: Pod "pod-subpath-test-dynamicpv-r9gv" satisfied condition "Succeeded or Failed"
Jun  2 21:18:25.396: INFO: Trying to get logs from node ip-172-20-87-181.us-west-2.compute.internal pod pod-subpath-test-dynamicpv-r9gv container test-container-subpath-dynamicpv-r9gv: <nil>
STEP: delete the pod
Jun  2 21:18:25.551: INFO: Waiting for pod pod-subpath-test-dynamicpv-r9gv to disappear
Jun  2 21:18:25.617: INFO: Pod pod-subpath-test-dynamicpv-r9gv no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-r9gv
Jun  2 21:18:25.617: INFO: Deleting pod "pod-subpath-test-dynamicpv-r9gv" in namespace "provisioning-4172"
... skipping 41 lines ...
Jun  2 21:18:29.190: INFO: Creating resource for dynamic PV
Jun  2 21:18:29.190: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-8026-e2e-scn4hcs
STEP: creating a claim
Jun  2 21:18:29.256: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: Creating pod to format volume volume-prep-provisioning-8026
Jun  2 21:18:29.457: INFO: Waiting up to 5m0s for pod "volume-prep-provisioning-8026" in namespace "provisioning-8026" to be "Succeeded or Failed"
Jun  2 21:18:29.523: INFO: Pod "volume-prep-provisioning-8026": Phase="Pending", Reason="", readiness=false. Elapsed: 65.820738ms
Jun  2 21:18:31.588: INFO: Pod "volume-prep-provisioning-8026": Phase="Pending", Reason="", readiness=false. Elapsed: 2.131033973s
Jun  2 21:18:33.654: INFO: Pod "volume-prep-provisioning-8026": Phase="Pending", Reason="", readiness=false. Elapsed: 4.196863636s
Jun  2 21:18:35.725: INFO: Pod "volume-prep-provisioning-8026": Phase="Pending", Reason="", readiness=false. Elapsed: 6.268199337s
Jun  2 21:18:37.792: INFO: Pod "volume-prep-provisioning-8026": Phase="Pending", Reason="", readiness=false. Elapsed: 8.335228194s
Jun  2 21:18:39.858: INFO: Pod "volume-prep-provisioning-8026": Phase="Pending", Reason="", readiness=false. Elapsed: 10.401170956s
Jun  2 21:18:41.924: INFO: Pod "volume-prep-provisioning-8026": Phase="Pending", Reason="", readiness=false. Elapsed: 12.466884853s
Jun  2 21:18:43.990: INFO: Pod "volume-prep-provisioning-8026": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.533169371s
STEP: Saw pod success
Jun  2 21:18:43.991: INFO: Pod "volume-prep-provisioning-8026" satisfied condition "Succeeded or Failed"
Jun  2 21:18:43.991: INFO: Deleting pod "volume-prep-provisioning-8026" in namespace "provisioning-8026"
Jun  2 21:18:44.071: INFO: Wait up to 5m0s for pod "volume-prep-provisioning-8026" to be fully deleted
STEP: Creating pod pod-subpath-test-dynamicpv-zbtf
STEP: Checking for subpath error in container status
Jun  2 21:18:54.357: INFO: Deleting pod "pod-subpath-test-dynamicpv-zbtf" in namespace "provisioning-8026"
Jun  2 21:18:54.429: INFO: Wait up to 5m0s for pod "pod-subpath-test-dynamicpv-zbtf" to be fully deleted
STEP: Deleting pod
Jun  2 21:18:54.494: INFO: Deleting pod "pod-subpath-test-dynamicpv-zbtf" in namespace "provisioning-8026"
STEP: Deleting pvc
Jun  2 21:18:54.693: INFO: Deleting PersistentVolumeClaim "ebs.csi.aws.comlp9v7"
... skipping 26 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Inline-volume (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail if subpath directory is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:240

    Driver "ebs.csi.aws.com" does not support volume type "InlineVolume" - skipping

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:263
------------------------------
... skipping 187 lines ...
Jun  2 21:18:38.656: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-5995-e2e-sc8lvl7
STEP: creating a claim
Jun  2 21:18:38.728: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: Creating pod pod-subpath-test-dynamicpv-bkhp
STEP: Creating a pod to test subpath
Jun  2 21:18:38.931: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-bkhp" in namespace "provisioning-5995" to be "Succeeded or Failed"
Jun  2 21:18:39.000: INFO: Pod "pod-subpath-test-dynamicpv-bkhp": Phase="Pending", Reason="", readiness=false. Elapsed: 68.963767ms
Jun  2 21:18:41.067: INFO: Pod "pod-subpath-test-dynamicpv-bkhp": Phase="Pending", Reason="", readiness=false. Elapsed: 2.135194733s
Jun  2 21:18:43.133: INFO: Pod "pod-subpath-test-dynamicpv-bkhp": Phase="Pending", Reason="", readiness=false. Elapsed: 4.202107382s
Jun  2 21:18:45.201: INFO: Pod "pod-subpath-test-dynamicpv-bkhp": Phase="Pending", Reason="", readiness=false. Elapsed: 6.269161042s
Jun  2 21:18:47.268: INFO: Pod "pod-subpath-test-dynamicpv-bkhp": Phase="Pending", Reason="", readiness=false. Elapsed: 8.336705689s
Jun  2 21:18:49.335: INFO: Pod "pod-subpath-test-dynamicpv-bkhp": Phase="Pending", Reason="", readiness=false. Elapsed: 10.403186987s
Jun  2 21:18:51.401: INFO: Pod "pod-subpath-test-dynamicpv-bkhp": Phase="Pending", Reason="", readiness=false. Elapsed: 12.469713768s
Jun  2 21:18:53.467: INFO: Pod "pod-subpath-test-dynamicpv-bkhp": Phase="Pending", Reason="", readiness=false. Elapsed: 14.535185723s
Jun  2 21:18:55.533: INFO: Pod "pod-subpath-test-dynamicpv-bkhp": Phase="Succeeded", Reason="", readiness=false. Elapsed: 16.601562207s
STEP: Saw pod success
Jun  2 21:18:55.533: INFO: Pod "pod-subpath-test-dynamicpv-bkhp" satisfied condition "Succeeded or Failed"
Jun  2 21:18:55.601: INFO: Trying to get logs from node ip-172-20-87-181.us-west-2.compute.internal pod pod-subpath-test-dynamicpv-bkhp container test-container-volume-dynamicpv-bkhp: <nil>
STEP: delete the pod
Jun  2 21:18:55.743: INFO: Waiting for pod pod-subpath-test-dynamicpv-bkhp to disappear
Jun  2 21:18:55.808: INFO: Pod pod-subpath-test-dynamicpv-bkhp no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-bkhp
Jun  2 21:18:55.808: INFO: Deleting pod "pod-subpath-test-dynamicpv-bkhp" in namespace "provisioning-5995"
... skipping 43 lines ...
Jun  2 21:19:10.571: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass volume-1638-e2e-scshvml
STEP: creating a claim
Jun  2 21:19:10.637: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: Creating pod exec-volume-test-dynamicpv-zsmk
STEP: Creating a pod to test exec-volume-test
Jun  2 21:19:10.836: INFO: Waiting up to 5m0s for pod "exec-volume-test-dynamicpv-zsmk" in namespace "volume-1638" to be "Succeeded or Failed"
Jun  2 21:19:10.901: INFO: Pod "exec-volume-test-dynamicpv-zsmk": Phase="Pending", Reason="", readiness=false. Elapsed: 65.125294ms
Jun  2 21:19:12.969: INFO: Pod "exec-volume-test-dynamicpv-zsmk": Phase="Pending", Reason="", readiness=false. Elapsed: 2.132890209s
Jun  2 21:19:15.035: INFO: Pod "exec-volume-test-dynamicpv-zsmk": Phase="Pending", Reason="", readiness=false. Elapsed: 4.199105559s
Jun  2 21:19:17.102: INFO: Pod "exec-volume-test-dynamicpv-zsmk": Phase="Pending", Reason="", readiness=false. Elapsed: 6.266063447s
Jun  2 21:19:19.169: INFO: Pod "exec-volume-test-dynamicpv-zsmk": Phase="Pending", Reason="", readiness=false. Elapsed: 8.33279472s
Jun  2 21:19:21.235: INFO: Pod "exec-volume-test-dynamicpv-zsmk": Phase="Pending", Reason="", readiness=false. Elapsed: 10.398460067s
Jun  2 21:19:23.301: INFO: Pod "exec-volume-test-dynamicpv-zsmk": Phase="Pending", Reason="", readiness=false. Elapsed: 12.464967015s
Jun  2 21:19:25.367: INFO: Pod "exec-volume-test-dynamicpv-zsmk": Phase="Succeeded", Reason="", readiness=false. Elapsed: 14.530708067s
STEP: Saw pod success
Jun  2 21:19:25.367: INFO: Pod "exec-volume-test-dynamicpv-zsmk" satisfied condition "Succeeded or Failed"
Jun  2 21:19:25.433: INFO: Trying to get logs from node ip-172-20-87-181.us-west-2.compute.internal pod exec-volume-test-dynamicpv-zsmk container exec-container-dynamicpv-zsmk: <nil>
STEP: delete the pod
Jun  2 21:19:25.577: INFO: Waiting for pod exec-volume-test-dynamicpv-zsmk to disappear
Jun  2 21:19:25.642: INFO: Pod exec-volume-test-dynamicpv-zsmk no longer exists
STEP: Deleting pod exec-volume-test-dynamicpv-zsmk
Jun  2 21:19:25.642: INFO: Deleting pod "exec-volume-test-dynamicpv-zsmk" in namespace "volume-1638"
... skipping 204 lines ...

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:121
------------------------------
SS
------------------------------
External Storage [Driver: ebs.csi.aws.com] [Testpattern: Dynamic PV (immediate binding)] topology 
  should fail to schedule a pod which has topologies that conflict with AllowedTopologies
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/topology.go:192

[BeforeEach] [Testpattern: Dynamic PV (immediate binding)] topology
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (immediate binding)] topology
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:185
STEP: Creating a kubernetes client
Jun  2 21:19:42.401: INFO: >>> kubeConfig: /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/hack/e2e/csi-test-artifacts/test-cluster-26183.k8s.local.kops.kubeconfig
STEP: Building a namespace api object, basename topology
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail to schedule a pod which has topologies that conflict with AllowedTopologies
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/topology.go:192
Jun  2 21:19:42.792: INFO: found topology map[topology.ebs.csi.aws.com/zone:us-west-2c]
Jun  2 21:19:42.792: INFO: found topology map[topology.ebs.csi.aws.com/zone:us-west-2a]
Jun  2 21:19:42.793: INFO: Creating storage class object and pvc object for driver - sc: &StorageClass{ObjectMeta:{topology-4348-e2e-scbbld2      0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] []  []},Provisioner:ebs.csi.aws.com,Parameters:map[string]string{},ReclaimPolicy:nil,MountOptions:[],AllowVolumeExpansion:nil,VolumeBindingMode:*Immediate,AllowedTopologies:[]TopologySelectorTerm{{[{topology.ebs.csi.aws.com/zone [us-west-2c]}]},},}, pvc: &PersistentVolumeClaim{ObjectMeta:{ pvc- topology-4348    0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] []  []},Spec:PersistentVolumeClaimSpec{AccessModes:[ReadWriteOnce],Resources:ResourceRequirements{Limits:ResourceList{},Requests:ResourceList{storage: {{1073741824 0} {<nil>} 1Gi BinarySI},},},VolumeName:,Selector:nil,StorageClassName:*topology-4348-e2e-scbbld2,VolumeMode:nil,DataSource:nil,},Status:PersistentVolumeClaimStatus{Phase:,AccessModes:[],Capacity:ResourceList{},Conditions:[]PersistentVolumeClaimCondition{},},}
STEP: Creating sc
STEP: Creating pvc
... skipping 23 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:278

    Driver "ebs.csi.aws.com" does not support volume type "PreprovisionedPV" - skipping

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:263
------------------------------
... skipping 120 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail to use a volume in a pod with mismatched mode [Slow] [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:297

    Driver "ebs.csi.aws.com" does not support volume type "PreprovisionedPV" - skipping

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:263
------------------------------
... skipping 114 lines ...
Jun  2 21:18:46.884: INFO: Creating resource for dynamic PV
Jun  2 21:18:46.884: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass snapshotting-6312-e2e-scg2z6p
STEP: creating a claim
Jun  2 21:18:46.951: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: [init] starting a pod to use the claim
Jun  2 21:18:47.160: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-kqdbr" in namespace "snapshotting-6312" to be "Succeeded or Failed"
Jun  2 21:18:47.226: INFO: Pod "pvc-snapshottable-tester-kqdbr": Phase="Pending", Reason="", readiness=false. Elapsed: 66.396739ms
Jun  2 21:18:49.293: INFO: Pod "pvc-snapshottable-tester-kqdbr": Phase="Pending", Reason="", readiness=false. Elapsed: 2.13307683s
Jun  2 21:18:51.360: INFO: Pod "pvc-snapshottable-tester-kqdbr": Phase="Pending", Reason="", readiness=false. Elapsed: 4.199874836s
Jun  2 21:18:53.427: INFO: Pod "pvc-snapshottable-tester-kqdbr": Phase="Pending", Reason="", readiness=false. Elapsed: 6.267438735s
Jun  2 21:18:55.495: INFO: Pod "pvc-snapshottable-tester-kqdbr": Phase="Pending", Reason="", readiness=false. Elapsed: 8.334650362s
Jun  2 21:18:57.562: INFO: Pod "pvc-snapshottable-tester-kqdbr": Phase="Pending", Reason="", readiness=false. Elapsed: 10.401707533s
Jun  2 21:18:59.632: INFO: Pod "pvc-snapshottable-tester-kqdbr": Phase="Pending", Reason="", readiness=false. Elapsed: 12.471819576s
Jun  2 21:19:01.699: INFO: Pod "pvc-snapshottable-tester-kqdbr": Phase="Pending", Reason="", readiness=false. Elapsed: 14.538603352s
Jun  2 21:19:03.766: INFO: Pod "pvc-snapshottable-tester-kqdbr": Phase="Pending", Reason="", readiness=false. Elapsed: 16.606437464s
Jun  2 21:19:05.834: INFO: Pod "pvc-snapshottable-tester-kqdbr": Phase="Pending", Reason="", readiness=false. Elapsed: 18.674119372s
Jun  2 21:19:07.901: INFO: Pod "pvc-snapshottable-tester-kqdbr": Phase="Succeeded", Reason="", readiness=false. Elapsed: 20.740608722s
STEP: Saw pod success
Jun  2 21:19:07.901: INFO: Pod "pvc-snapshottable-tester-kqdbr" satisfied condition "Succeeded or Failed"
Jun  2 21:19:08.034: INFO: Pod pvc-snapshottable-tester-kqdbr has the following logs: 
Jun  2 21:19:08.034: INFO: Deleting pod "pvc-snapshottable-tester-kqdbr" in namespace "snapshotting-6312"
Jun  2 21:19:08.106: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-kqdbr" to be fully deleted
Jun  2 21:19:08.172: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [ebs.csi.aws.comjkfvx] to have phase Bound
Jun  2 21:19:08.238: INFO: PersistentVolumeClaim ebs.csi.aws.comjkfvx found and phase=Bound (66.107952ms)
STEP: [init] checking the claim
... skipping 22 lines ...
[It] should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:245
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
Jun  2 21:19:33.793: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-7kpc7" in namespace "snapshotting-6312" to be "Succeeded or Failed"
Jun  2 21:19:33.859: INFO: Pod "pvc-snapshottable-data-tester-7kpc7": Phase="Pending", Reason="", readiness=false. Elapsed: 66.430832ms
Jun  2 21:19:35.926: INFO: Pod "pvc-snapshottable-data-tester-7kpc7": Phase="Pending", Reason="", readiness=false. Elapsed: 2.133632638s
Jun  2 21:19:37.994: INFO: Pod "pvc-snapshottable-data-tester-7kpc7": Phase="Pending", Reason="", readiness=false. Elapsed: 4.201242613s
Jun  2 21:19:40.063: INFO: Pod "pvc-snapshottable-data-tester-7kpc7": Phase="Pending", Reason="", readiness=false. Elapsed: 6.269878442s
Jun  2 21:19:42.130: INFO: Pod "pvc-snapshottable-data-tester-7kpc7": Phase="Pending", Reason="", readiness=false. Elapsed: 8.337300018s
Jun  2 21:19:44.198: INFO: Pod "pvc-snapshottable-data-tester-7kpc7": Phase="Succeeded", Reason="", readiness=false. Elapsed: 10.404837551s
STEP: Saw pod success
Jun  2 21:19:44.198: INFO: Pod "pvc-snapshottable-data-tester-7kpc7" satisfied condition "Succeeded or Failed"
Jun  2 21:19:44.332: INFO: Pod pvc-snapshottable-data-tester-7kpc7 has the following logs: 
Jun  2 21:19:44.332: INFO: Deleting pod "pvc-snapshottable-data-tester-7kpc7" in namespace "snapshotting-6312"
Jun  2 21:19:44.406: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-7kpc7" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
Jun  2 21:19:56.740: INFO: Running '/usr/local/bin/kubectl --server=https://api-test-cluster-26183-k8-8nht0r-1841681954.us-west-2.elb.amazonaws.com --kubeconfig=/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/hack/e2e/csi-test-artifacts/test-cluster-26183.k8s.local.kops.kubeconfig --namespace=snapshotting-6312 exec restored-pvc-tester-p5dvf --namespace=snapshotting-6312 -- cat /mnt/test/data'
... skipping 76 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Pre-provisioned PV (default fs)] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail if subpath directory is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:240

    Driver "ebs.csi.aws.com" does not support volume type "PreprovisionedPV" - skipping

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:263
------------------------------
... skipping 108 lines ...
Jun  2 21:20:23.673: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass volume-7018-e2e-sc4sn2g
STEP: creating a claim
Jun  2 21:20:23.739: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: Creating pod exec-volume-test-dynamicpv-tnmp
STEP: Creating a pod to test exec-volume-test
Jun  2 21:20:23.939: INFO: Waiting up to 5m0s for pod "exec-volume-test-dynamicpv-tnmp" in namespace "volume-7018" to be "Succeeded or Failed"
Jun  2 21:20:24.005: INFO: Pod "exec-volume-test-dynamicpv-tnmp": Phase="Pending", Reason="", readiness=false. Elapsed: 65.496392ms
Jun  2 21:20:26.072: INFO: Pod "exec-volume-test-dynamicpv-tnmp": Phase="Pending", Reason="", readiness=false. Elapsed: 2.132800645s
Jun  2 21:20:28.139: INFO: Pod "exec-volume-test-dynamicpv-tnmp": Phase="Pending", Reason="", readiness=false. Elapsed: 4.199542233s
Jun  2 21:20:30.207: INFO: Pod "exec-volume-test-dynamicpv-tnmp": Phase="Pending", Reason="", readiness=false. Elapsed: 6.267590007s
Jun  2 21:20:32.273: INFO: Pod "exec-volume-test-dynamicpv-tnmp": Phase="Pending", Reason="", readiness=false. Elapsed: 8.333517429s
Jun  2 21:20:34.339: INFO: Pod "exec-volume-test-dynamicpv-tnmp": Phase="Succeeded", Reason="", readiness=false. Elapsed: 10.399888138s
STEP: Saw pod success
Jun  2 21:20:34.339: INFO: Pod "exec-volume-test-dynamicpv-tnmp" satisfied condition "Succeeded or Failed"
Jun  2 21:20:34.405: INFO: Trying to get logs from node ip-172-20-87-181.us-west-2.compute.internal pod exec-volume-test-dynamicpv-tnmp container exec-container-dynamicpv-tnmp: <nil>
STEP: delete the pod
Jun  2 21:20:34.544: INFO: Waiting for pod exec-volume-test-dynamicpv-tnmp to disappear
Jun  2 21:20:34.609: INFO: Pod exec-volume-test-dynamicpv-tnmp no longer exists
STEP: Deleting pod exec-volume-test-dynamicpv-tnmp
Jun  2 21:20:34.609: INFO: Deleting pod "exec-volume-test-dynamicpv-tnmp" in namespace "volume-7018"
... skipping 231 lines ...
Jun  2 21:20:51.204: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-288-e2e-scjvks9
STEP: creating a claim
Jun  2 21:20:51.270: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: Creating pod pod-subpath-test-dynamicpv-zslg
STEP: Creating a pod to test atomic-volume-subpath
Jun  2 21:20:51.470: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-zslg" in namespace "provisioning-288" to be "Succeeded or Failed"
Jun  2 21:20:51.535: INFO: Pod "pod-subpath-test-dynamicpv-zslg": Phase="Pending", Reason="", readiness=false. Elapsed: 65.500764ms
Jun  2 21:20:53.601: INFO: Pod "pod-subpath-test-dynamicpv-zslg": Phase="Pending", Reason="", readiness=false. Elapsed: 2.130997425s
Jun  2 21:20:55.669: INFO: Pod "pod-subpath-test-dynamicpv-zslg": Phase="Pending", Reason="", readiness=false. Elapsed: 4.199421857s
Jun  2 21:20:57.735: INFO: Pod "pod-subpath-test-dynamicpv-zslg": Phase="Pending", Reason="", readiness=false. Elapsed: 6.265246734s
Jun  2 21:20:59.804: INFO: Pod "pod-subpath-test-dynamicpv-zslg": Phase="Pending", Reason="", readiness=false. Elapsed: 8.33451559s
Jun  2 21:21:01.870: INFO: Pod "pod-subpath-test-dynamicpv-zslg": Phase="Pending", Reason="", readiness=false. Elapsed: 10.400355078s
... skipping 7 lines ...
Jun  2 21:21:18.401: INFO: Pod "pod-subpath-test-dynamicpv-zslg": Phase="Running", Reason="", readiness=true. Elapsed: 26.931504526s
Jun  2 21:21:20.467: INFO: Pod "pod-subpath-test-dynamicpv-zslg": Phase="Running", Reason="", readiness=true. Elapsed: 28.997242517s
Jun  2 21:21:22.535: INFO: Pod "pod-subpath-test-dynamicpv-zslg": Phase="Running", Reason="", readiness=true. Elapsed: 31.064857524s
Jun  2 21:21:24.602: INFO: Pod "pod-subpath-test-dynamicpv-zslg": Phase="Running", Reason="", readiness=true. Elapsed: 33.131978155s
Jun  2 21:21:26.669: INFO: Pod "pod-subpath-test-dynamicpv-zslg": Phase="Succeeded", Reason="", readiness=false. Elapsed: 35.199274972s
STEP: Saw pod success
Jun  2 21:21:26.669: INFO: Pod "pod-subpath-test-dynamicpv-zslg" satisfied condition "Succeeded or Failed"
Jun  2 21:21:26.734: INFO: Trying to get logs from node ip-172-20-87-181.us-west-2.compute.internal pod pod-subpath-test-dynamicpv-zslg container test-container-subpath-dynamicpv-zslg: <nil>
STEP: delete the pod
Jun  2 21:21:26.878: INFO: Waiting for pod pod-subpath-test-dynamicpv-zslg to disappear
Jun  2 21:21:26.943: INFO: Pod pod-subpath-test-dynamicpv-zslg no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-zslg
Jun  2 21:21:26.943: INFO: Deleting pod "pod-subpath-test-dynamicpv-zslg" in namespace "provisioning-288"
... skipping 39 lines ...
Jun  2 21:21:29.641: INFO: Using claimSize:1Gi, test suite supported size:{ 1Gi}, driver(ebs.csi.aws.com) supported size:{ 1Gi} 
STEP: creating a StorageClass volume-expand-2575-e2e-sc6s95m
STEP: creating a claim
Jun  2 21:21:29.708: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: Expanding non-expandable pvc
Jun  2 21:21:29.843: INFO: currentPvcSize {{1073741824 0} {<nil>} 1Gi BinarySI}, newSize {{2147483648 0} {<nil>}  BinarySI}
Jun  2 21:21:29.984: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 5 lines ...
  	},
  	VolumeName:       "",
  	StorageClassName: &"volume-expand-2575-e2e-sc6s95m",
  	... // 2 identical fields
  }

Jun  2 21:21:32.120: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 5 lines ...
  	},
  	VolumeName:       "",
  	StorageClassName: &"volume-expand-2575-e2e-sc6s95m",
  	... // 2 identical fields
  }

Jun  2 21:21:34.120: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 5 lines ...
  	},
  	VolumeName:       "",
  	StorageClassName: &"volume-expand-2575-e2e-sc6s95m",
  	... // 2 identical fields
  }

Jun  2 21:21:36.119: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 5 lines ...
  	},
  	VolumeName:       "",
  	StorageClassName: &"volume-expand-2575-e2e-sc6s95m",
  	... // 2 identical fields
  }

Jun  2 21:21:38.120: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 5 lines ...
  	},
  	VolumeName:       "",
  	StorageClassName: &"volume-expand-2575-e2e-sc6s95m",
  	... // 2 identical fields
  }

Jun  2 21:21:40.119: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 5 lines ...
  	},
  	VolumeName:       "",
  	StorageClassName: &"volume-expand-2575-e2e-sc6s95m",
  	... // 2 identical fields
  }

Jun  2 21:21:42.119: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 5 lines ...
  	},
  	VolumeName:       "",
  	StorageClassName: &"volume-expand-2575-e2e-sc6s95m",
  	... // 2 identical fields
  }

Jun  2 21:21:44.119: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 5 lines ...
  	},
  	VolumeName:       "",
  	StorageClassName: &"volume-expand-2575-e2e-sc6s95m",
  	... // 2 identical fields
  }

Jun  2 21:21:46.119: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 5 lines ...
  	},
  	VolumeName:       "",
  	StorageClassName: &"volume-expand-2575-e2e-sc6s95m",
  	... // 2 identical fields
  }

Jun  2 21:21:48.121: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 5 lines ...
  	},
  	VolumeName:       "",
  	StorageClassName: &"volume-expand-2575-e2e-sc6s95m",
  	... // 2 identical fields
  }

Jun  2 21:21:50.118: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 5 lines ...
  	},
  	VolumeName:       "",
  	StorageClassName: &"volume-expand-2575-e2e-sc6s95m",
  	... // 2 identical fields
  }

Jun  2 21:21:52.122: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 5 lines ...
  	},
  	VolumeName:       "",
  	StorageClassName: &"volume-expand-2575-e2e-sc6s95m",
  	... // 2 identical fields
  }

Jun  2 21:21:54.121: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 5 lines ...
  	},
  	VolumeName:       "",
  	StorageClassName: &"volume-expand-2575-e2e-sc6s95m",
  	... // 2 identical fields
  }

Jun  2 21:21:56.119: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 5 lines ...
  	},
  	VolumeName:       "",
  	StorageClassName: &"volume-expand-2575-e2e-sc6s95m",
  	... // 2 identical fields
  }

Jun  2 21:21:58.122: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 5 lines ...
  	},
  	VolumeName:       "",
  	StorageClassName: &"volume-expand-2575-e2e-sc6s95m",
  	... // 2 identical fields
  }

Jun  2 21:22:00.120: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 5 lines ...
  	},
  	VolumeName:       "",
  	StorageClassName: &"volume-expand-2575-e2e-sc6s95m",
  	... // 2 identical fields
  }

Jun  2 21:22:00.254: INFO: Error updating pvc ebs.csi.aws.comt6lgv: PersistentVolumeClaim "ebs.csi.aws.comt6lgv" is invalid: spec: Forbidden: spec is immutable after creation except resources.requests for bound claims
  core.PersistentVolumeClaimSpec{
  	AccessModes: {"ReadWriteOnce"},
  	Selector:    nil,
  	Resources: core.ResourceRequirements{
  		Limits: nil,
- 		Requests: core.ResourceList{
... skipping 86 lines ...
Jun  2 21:20:43.319: INFO: Creating resource for dynamic PV
Jun  2 21:20:43.319: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass snapshotting-5216-e2e-sckzt5d
STEP: creating a claim
Jun  2 21:20:43.389: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: [init] starting a pod to use the claim
Jun  2 21:20:43.588: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-tester-k98hf" in namespace "snapshotting-5216" to be "Succeeded or Failed"
Jun  2 21:20:43.653: INFO: Pod "pvc-snapshottable-tester-k98hf": Phase="Pending", Reason="", readiness=false. Elapsed: 64.956279ms
Jun  2 21:20:45.719: INFO: Pod "pvc-snapshottable-tester-k98hf": Phase="Pending", Reason="", readiness=false. Elapsed: 2.131073905s
Jun  2 21:20:47.784: INFO: Pod "pvc-snapshottable-tester-k98hf": Phase="Pending", Reason="", readiness=false. Elapsed: 4.196832276s
Jun  2 21:20:49.851: INFO: Pod "pvc-snapshottable-tester-k98hf": Phase="Pending", Reason="", readiness=false. Elapsed: 6.263759373s
Jun  2 21:20:51.918: INFO: Pod "pvc-snapshottable-tester-k98hf": Phase="Pending", Reason="", readiness=false. Elapsed: 8.330746419s
Jun  2 21:20:53.985: INFO: Pod "pvc-snapshottable-tester-k98hf": Phase="Succeeded", Reason="", readiness=false. Elapsed: 10.39702741s
STEP: Saw pod success
Jun  2 21:20:53.985: INFO: Pod "pvc-snapshottable-tester-k98hf" satisfied condition "Succeeded or Failed"
Jun  2 21:20:54.116: INFO: Pod pvc-snapshottable-tester-k98hf has the following logs: 
Jun  2 21:20:54.116: INFO: Deleting pod "pvc-snapshottable-tester-k98hf" in namespace "snapshotting-5216"
Jun  2 21:20:54.190: INFO: Wait up to 5m0s for pod "pvc-snapshottable-tester-k98hf" to be fully deleted
Jun  2 21:20:54.255: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [ebs.csi.aws.comx5dkn] to have phase Bound
Jun  2 21:20:54.320: INFO: PersistentVolumeClaim ebs.csi.aws.comx5dkn found and phase=Bound (64.952628ms)
STEP: [init] checking the claim
... skipping 36 lines ...
[It] should check snapshot fields, check restore correctly works after modifying source data, check deletion
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:245
STEP: checking the snapshot
STEP: checking the SnapshotContent
STEP: Modifying source data test
STEP: modifying the data in the source PVC
Jun  2 21:21:08.110: INFO: Waiting up to 15m0s for pod "pvc-snapshottable-data-tester-5wtcl" in namespace "snapshotting-5216" to be "Succeeded or Failed"
Jun  2 21:21:08.175: INFO: Pod "pvc-snapshottable-data-tester-5wtcl": Phase="Pending", Reason="", readiness=false. Elapsed: 64.997787ms
Jun  2 21:21:10.242: INFO: Pod "pvc-snapshottable-data-tester-5wtcl": Phase="Pending", Reason="", readiness=false. Elapsed: 2.131444141s
Jun  2 21:21:12.308: INFO: Pod "pvc-snapshottable-data-tester-5wtcl": Phase="Pending", Reason="", readiness=false. Elapsed: 4.197662153s
Jun  2 21:21:14.374: INFO: Pod "pvc-snapshottable-data-tester-5wtcl": Phase="Pending", Reason="", readiness=false. Elapsed: 6.263269239s
Jun  2 21:21:16.439: INFO: Pod "pvc-snapshottable-data-tester-5wtcl": Phase="Pending", Reason="", readiness=false. Elapsed: 8.329085184s
Jun  2 21:21:18.505: INFO: Pod "pvc-snapshottable-data-tester-5wtcl": Phase="Succeeded", Reason="", readiness=false. Elapsed: 10.395047271s
STEP: Saw pod success
Jun  2 21:21:18.506: INFO: Pod "pvc-snapshottable-data-tester-5wtcl" satisfied condition "Succeeded or Failed"
Jun  2 21:21:18.640: INFO: Pod pvc-snapshottable-data-tester-5wtcl has the following logs: 
Jun  2 21:21:18.640: INFO: Deleting pod "pvc-snapshottable-data-tester-5wtcl" in namespace "snapshotting-5216"
Jun  2 21:21:18.711: INFO: Wait up to 5m0s for pod "pvc-snapshottable-data-tester-5wtcl" to be fully deleted
STEP: creating a pvc from the snapshot
STEP: starting a pod to use the claim
Jun  2 21:21:33.423: INFO: Running '/usr/local/bin/kubectl --server=https://api-test-cluster-26183-k8-8nht0r-1841681954.us-west-2.elb.amazonaws.com --kubeconfig=/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/hack/e2e/csi-test-artifacts/test-cluster-26183.k8s.local.kops.kubeconfig --namespace=snapshotting-5216 exec restored-pvc-tester-h5n72 --namespace=snapshotting-5216 -- cat /mnt/test/data'
... skipping 34 lines ...
Jun  2 21:21:59.119: INFO: volumesnapshotcontents pre-provisioned-snapcontent-e7933034-7cbf-4086-ab4b-9b562a103c43 has been found and is not deleted
Jun  2 21:22:00.185: INFO: volumesnapshotcontents pre-provisioned-snapcontent-e7933034-7cbf-4086-ab4b-9b562a103c43 has been found and is not deleted
Jun  2 21:22:01.251: INFO: volumesnapshotcontents pre-provisioned-snapcontent-e7933034-7cbf-4086-ab4b-9b562a103c43 has been found and is not deleted
Jun  2 21:22:02.324: INFO: volumesnapshotcontents pre-provisioned-snapcontent-e7933034-7cbf-4086-ab4b-9b562a103c43 has been found and is not deleted
Jun  2 21:22:03.393: INFO: volumesnapshotcontents pre-provisioned-snapcontent-e7933034-7cbf-4086-ab4b-9b562a103c43 has been found and is not deleted
Jun  2 21:22:04.460: INFO: volumesnapshotcontents pre-provisioned-snapcontent-e7933034-7cbf-4086-ab4b-9b562a103c43 has been found and is not deleted
Jun  2 21:22:05.460: INFO: WaitUntil failed after reaching the timeout 30s
[AfterEach] volume snapshot controller
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/snapshottable.go:223
Jun  2 21:22:05.527: INFO: Pod restored-pvc-tester-h5n72 has the following logs: unable to retrieve container logs for containerd://b2a9033c58a1a699fe146e0e4f82e5ef77290e520dcfcab01152cd0aace96514
Jun  2 21:22:05.527: INFO: Deleting pod "restored-pvc-tester-h5n72" in namespace "snapshotting-5216"
Jun  2 21:22:05.592: INFO: Wait up to 5m0s for pod "restored-pvc-tester-h5n72" to be fully deleted
Jun  2 21:22:07.724: INFO: deleting claim "snapshotting-5216"/"pvc-928ds"
... skipping 284 lines ...
Jun  2 21:22:16.202: INFO: Using claimSize:1Gi, test suite supported size:{ 1Mi}, driver(ebs.csi.aws.com) supported size:{ 1Mi} 
STEP: creating a StorageClass provisioning-5933-e2e-scdls2c
STEP: creating a claim
Jun  2 21:22:16.269: INFO: Warning: Making PVC: VolumeMode specified as invalid empty string, treating as nil
STEP: Creating pod pod-subpath-test-dynamicpv-6bhl
STEP: Creating a pod to test subpath
Jun  2 21:22:16.471: INFO: Waiting up to 5m0s for pod "pod-subpath-test-dynamicpv-6bhl" in namespace "provisioning-5933" to be "Succeeded or Failed"
Jun  2 21:22:16.535: INFO: Pod "pod-subpath-test-dynamicpv-6bhl": Phase="Pending", Reason="", readiness=false. Elapsed: 64.648954ms
Jun  2 21:22:18.601: INFO: Pod "pod-subpath-test-dynamicpv-6bhl": Phase="Pending", Reason="", readiness=false. Elapsed: 2.130592603s
Jun  2 21:22:20.668: INFO: Pod "pod-subpath-test-dynamicpv-6bhl": Phase="Pending", Reason="", readiness=false. Elapsed: 4.196846235s
Jun  2 21:22:22.734: INFO: Pod "pod-subpath-test-dynamicpv-6bhl": Phase="Pending", Reason="", readiness=false. Elapsed: 6.262999251s
Jun  2 21:22:24.800: INFO: Pod "pod-subpath-test-dynamicpv-6bhl": Phase="Pending", Reason="", readiness=false. Elapsed: 8.329151822s
Jun  2 21:22:26.866: INFO: Pod "pod-subpath-test-dynamicpv-6bhl": Phase="Pending", Reason="", readiness=false. Elapsed: 10.394925762s
Jun  2 21:22:28.937: INFO: Pod "pod-subpath-test-dynamicpv-6bhl": Phase="Pending", Reason="", readiness=false. Elapsed: 12.46589366s
Jun  2 21:22:31.002: INFO: Pod "pod-subpath-test-dynamicpv-6bhl": Phase="Pending", Reason="", readiness=false. Elapsed: 14.531631506s
Jun  2 21:22:33.069: INFO: Pod "pod-subpath-test-dynamicpv-6bhl": Phase="Pending", Reason="", readiness=false. Elapsed: 16.598689757s
Jun  2 21:22:35.138: INFO: Pod "pod-subpath-test-dynamicpv-6bhl": Phase="Pending", Reason="", readiness=false. Elapsed: 18.667337523s
Jun  2 21:22:37.205: INFO: Pod "pod-subpath-test-dynamicpv-6bhl": Phase="Pending", Reason="", readiness=false. Elapsed: 20.73420565s
Jun  2 21:22:39.272: INFO: Pod "pod-subpath-test-dynamicpv-6bhl": Phase="Succeeded", Reason="", readiness=false. Elapsed: 22.800887657s
STEP: Saw pod success
Jun  2 21:22:39.272: INFO: Pod "pod-subpath-test-dynamicpv-6bhl" satisfied condition "Succeeded or Failed"
Jun  2 21:22:39.344: INFO: Trying to get logs from node ip-172-20-87-181.us-west-2.compute.internal pod pod-subpath-test-dynamicpv-6bhl container test-container-subpath-dynamicpv-6bhl: <nil>
STEP: delete the pod
Jun  2 21:22:39.487: INFO: Waiting for pod pod-subpath-test-dynamicpv-6bhl to disappear
Jun  2 21:22:39.552: INFO: Pod pod-subpath-test-dynamicpv-6bhl no longer exists
STEP: Deleting pod pod-subpath-test-dynamicpv-6bhl
Jun  2 21:22:39.552: INFO: Deleting pod "pod-subpath-test-dynamicpv-6bhl" in namespace "provisioning-5933"
... skipping 261 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Dynamic PV (ntfs)][Feature:Windows] subPath
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail if subpath with backstepping is outside the volume [Slow][LinuxOnly] [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:278

    Driver ebs.csi.aws.com doesn't support ntfs -- skipping

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:121
------------------------------
... skipping 319 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
External Storage [Driver: ebs.csi.aws.com]
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:175
  [Testpattern: Pre-provisioned PV (filesystem volmode)] volumeMode
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:50
    should fail to use a volume in a pod with mismatched mode [Slow] [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/volumemode.go:297

    Driver "ebs.csi.aws.com" does not support volume type "PreprovisionedPV" - skipping

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:263
------------------------------
... skipping 37 lines ...

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:263
------------------------------
S
------------------------------
External Storage [Driver: ebs.csi.aws.com] [Testpattern: Dynamic PV (delayed binding)] topology 
  should fail to schedule a pod which has topologies that conflict with AllowedTopologies
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/topology.go:192

[BeforeEach] [Testpattern: Dynamic PV (delayed binding)] topology
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (delayed binding)] topology
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:185
STEP: Creating a kubernetes client
Jun  2 21:23:39.384: INFO: >>> kubeConfig: /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/hack/e2e/csi-test-artifacts/test-cluster-26183.k8s.local.kops.kubeconfig
STEP: Building a namespace api object, basename topology
STEP: Waiting for a default service account to be provisioned in namespace
[It] should fail to schedule a pod which has topologies that conflict with AllowedTopologies
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/topology.go:192
Jun  2 21:23:39.775: INFO: found topology map[topology.ebs.csi.aws.com/zone:us-west-2c]
Jun  2 21:23:39.775: INFO: found topology map[topology.ebs.csi.aws.com/zone:us-west-2a]
Jun  2 21:23:39.775: INFO: Creating storage class object and pvc object for driver - sc: &StorageClass{ObjectMeta:{topology-8078-e2e-scxxnb7      0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] []  []},Provisioner:ebs.csi.aws.com,Parameters:map[string]string{},ReclaimPolicy:nil,MountOptions:[],AllowVolumeExpansion:nil,VolumeBindingMode:*WaitForFirstConsumer,AllowedTopologies:[]TopologySelectorTerm{{[{topology.ebs.csi.aws.com/zone [us-west-2c]}]},},}, pvc: &PersistentVolumeClaim{ObjectMeta:{ pvc- topology-8078    0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] []  []},Spec:PersistentVolumeClaimSpec{AccessModes:[ReadWriteOnce],Resources:ResourceRequirements{Limits:ResourceList{},Requests:ResourceList{storage: {{1073741824 0} {<nil>} 1Gi BinarySI},},},VolumeName:,Selector:nil,StorageClassName:*topology-8078-e2e-scxxnb7,VolumeMode:nil,DataSource:nil,},Status:PersistentVolumeClaimStatus{Phase:,AccessModes:[],Capacity:ResourceList{},Conditions:[]PersistentVolumeClaimCondition{},},}
STEP: Creating sc
STEP: Creating pvc
... skipping 319 lines ...
    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/testsuites/subpath.go:449

    Driver "ebs.csi.aws.com" does not support volume type "PreprovisionedPV" - skipping

    /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/storage/external/external.go:263
------------------------------
{"component":"entrypoint","file":"k8s.io/test-infra/prow/entrypoint/run.go:169","func":"k8s.io/test-infra/prow/entrypoint.Options.ExecuteProcess","level":"error","msg":"Entrypoint received interrupt: terminated","severity":"error","time":"2022-06-02T21:24:28Z"}