This job view page is being replaced by Spyglass soon. Check out the new job view.
PRleakingtapan: Update CHANGELOG for 0.4 release
ResultFAILURE
Tests 1 failed / 34 succeeded
Started2019-08-15 00:29
Elapsed32m41s
Revisiond96c37096f845978c118e9d9c30d3126126d670b
Refs 343

Test Failures


AWS EBS CSI Driver End-to-End Tests [ebs-csi-e2e] [single-az] Snapshot should create a pod, write and read to it, take a volume snapshot, and create another pod from the snapshot 8m30s

go run hack/e2e.go -v --test --test_args='--ginkgo.focus=AWS\sEBS\sCSI\sDriver\sEnd\-to\-End\sTests\s\[ebs\-csi\-e2e\]\s\[single\-az\]\sSnapshot\sshould\screate\sa\spod\,\swrite\sand\sread\sto\sit\,\stake\sa\svolume\ssnapshot\,\sand\screate\sanother\spod\sfrom\sthe\ssnapshot$'
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/dynamic_provisioning.go:428
Expected error:
    <*errors.errorString | 0xc000274110>: {
        s: "timed out waiting for the condition",
    }
    timed out waiting for the condition
not to have occurred
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/testsuites/testsuites.go:132
				
				Click to see stdout/stderrfrom junit_13.xml

Filter through log files | View test history on testgrid


Show 34 Passed Tests

Show 2 Skipped Tests

Error lines from build-log.txt

... skipping 1635 lines ...

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

Validating cluster test-cluster-12612.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-12612-k8-190lod-2047714421.us-west-2.elb.amazonaws.com/api/v1/nodes: dial tcp: lookup api-test-cluster-12612-k8-190lod-2047714421.us-west-2.elb.amazonaws.com on 10.63.240.10:53: no such host
Waiting cluster to be created
Using cluster from kubectl context: test-cluster-12612.k8s.local

Validating cluster test-cluster-12612.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-12612-k8-190lod-2047714421.us-west-2.elb.amazonaws.com/api/v1/nodes: dial tcp: lookup api-test-cluster-12612-k8-190lod-2047714421.us-west-2.elb.amazonaws.com on 10.63.240.10:53: no such host
Waiting cluster to be created
Using cluster from kubectl context: test-cluster-12612.k8s.local

Validating cluster test-cluster-12612.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-12612-k8-190lod-2047714421.us-west-2.elb.amazonaws.com/api/v1/nodes: dial tcp: lookup api-test-cluster-12612-k8-190lod-2047714421.us-west-2.elb.amazonaws.com on 10.63.240.10:53: no such host
Waiting cluster to be created
Using cluster from kubectl context: test-cluster-12612.k8s.local

Validating cluster test-cluster-12612.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-12612-k8-190lod-2047714421.us-west-2.elb.amazonaws.com/api/v1/nodes: EOF
Waiting cluster to be created
Using cluster from kubectl context: test-cluster-12612.k8s.local

Validating cluster test-cluster-12612.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-12612-k8-190lod-2047714421.us-west-2.elb.amazonaws.com/api/v1/nodes: EOF
Waiting cluster to be created
Using cluster from kubectl context: test-cluster-12612.k8s.local

Validating cluster test-cluster-12612.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-12612-k8-190lod-2047714421.us-west-2.elb.amazonaws.com/api/v1/nodes: EOF
Waiting cluster to be created
Using cluster from kubectl context: test-cluster-12612.k8s.local

Validating cluster test-cluster-12612.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-12612-k8-190lod-2047714421.us-west-2.elb.amazonaws.com/api/v1/nodes: EOF
Waiting cluster to be created
Using cluster from kubectl context: test-cluster-12612.k8s.local

Validating cluster test-cluster-12612.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-12612-k8-190lod-2047714421.us-west-2.elb.amazonaws.com/api/v1/nodes: EOF
Waiting cluster to be created
Using cluster from kubectl context: test-cluster-12612.k8s.local

Validating cluster test-cluster-12612.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-12612-k8-190lod-2047714421.us-west-2.elb.amazonaws.com/api/v1/nodes: EOF
Waiting cluster to be created
Using cluster from kubectl context: test-cluster-12612.k8s.local

Validating cluster test-cluster-12612.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-12612-k8-190lod-2047714421.us-west-2.elb.amazonaws.com/api/v1/nodes: EOF
Waiting cluster to be created
Using cluster from kubectl context: test-cluster-12612.k8s.local

Validating cluster test-cluster-12612.k8s.local

INSTANCE GROUPS
... skipping 10 lines ...
Machine	i-04f3ae1689ebd83f5					machine "i-04f3ae1689ebd83f5" has not yet joined cluster
Machine	i-0720796858e589770					machine "i-0720796858e589770" has not yet joined cluster
Machine	i-0af3f00a690f652db					machine "i-0af3f00a690f652db" has not yet joined cluster
Pod	kube-system/kube-dns-autoscaler-6567f59ccb-gwjzj	kube-system pod "kube-dns-autoscaler-6567f59ccb-gwjzj" is pending
Pod	kube-system/kube-dns-bdf859cdc-jvqmd			kube-system pod "kube-dns-bdf859cdc-jvqmd" is pending

Validation Failed
Waiting cluster to be created
Using cluster from kubectl context: test-cluster-12612.k8s.local

Validating cluster test-cluster-12612.k8s.local

INSTANCE GROUPS
... skipping 26 lines ...

Tiller (the Helm server-side component) has been installed into your Kubernetes Cluster.

Please note: by default, Tiller is deployed with an insecure 'allow unauthenticated users' policy.
To prevent this, run `helm init` with the --tiller-tls-verify flag.
For more information on securing your installation see: https://docs.helm.sh/using_helm/#securing-your-helm-installation
error: timed out waiting for the condition on deployments/tiller-deploy
NAME                                                                  READY   STATUS    RESTARTS   AGE
dns-controller-67d566c9b4-7scrk                                       1/1     Running   0          11m
etcd-manager-events-ip-172-20-53-225.us-west-2.compute.internal       1/1     Running   0          11m
etcd-manager-main-ip-172-20-53-225.us-west-2.compute.internal         1/1     Running   0          10m
kube-apiserver-ip-172-20-53-225.us-west-2.compute.internal            1/1     Running   3          11m
kube-controller-manager-ip-172-20-53-225.us-west-2.compute.internal   1/1     Running   0          11m
... skipping 668 lines ...
Aug 15 00:52:27.459: INFO: PersistentVolumeClaim pvc-4x9b9 found but phase is Pending instead of Bound.
Aug 15 00:52:29.527: INFO: PersistentVolumeClaim pvc-4x9b9 found and phase=Bound (10.423128687s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:29.694: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-mrlbx" in namespace "e2e-tests-ebs-4lth2" to be "success or failure"
Aug 15 00:52:29.749: INFO: Pod "ebs-volume-tester-mrlbx": Phase="Pending", Reason="", readiness=false. Elapsed: 54.893708ms
Aug 15 00:52:31.818: INFO: Pod "ebs-volume-tester-mrlbx": Phase="Pending", Reason="", readiness=false. Elapsed: 2.123434493s
Aug 15 00:52:33.882: INFO: Pod "ebs-volume-tester-mrlbx": Phase="Pending", Reason="", readiness=false. Elapsed: 4.187501895s
Aug 15 00:52:35.938: INFO: Pod "ebs-volume-tester-mrlbx": Phase="Pending", Reason="", readiness=false. Elapsed: 6.243543455s
Aug 15 00:52:37.993: INFO: Pod "ebs-volume-tester-mrlbx": Phase="Pending", Reason="", readiness=false. Elapsed: 8.298510263s
... skipping 55 lines ...
Aug 15 00:52:26.652: INFO: Waiting up to 5m0s for PersistentVolumeClaims [pvc-9wsh5] to have phase Bound
Aug 15 00:52:26.706: INFO: PersistentVolumeClaim pvc-9wsh5 found and phase=Bound (54.120015ms)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:26.872: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-nwr65" in namespace "e2e-tests-ebs-whh2c" to be "success or failure"
Aug 15 00:52:26.945: INFO: Pod "ebs-volume-tester-nwr65": Phase="Pending", Reason="", readiness=false. Elapsed: 72.595265ms
Aug 15 00:52:29.000: INFO: Pod "ebs-volume-tester-nwr65": Phase="Pending", Reason="", readiness=false. Elapsed: 2.127615879s
Aug 15 00:52:31.055: INFO: Pod "ebs-volume-tester-nwr65": Phase="Pending", Reason="", readiness=false. Elapsed: 4.182768422s
Aug 15 00:52:33.120: INFO: Pod "ebs-volume-tester-nwr65": Phase="Pending", Reason="", readiness=false. Elapsed: 6.247419266s
Aug 15 00:52:35.174: INFO: Pod "ebs-volume-tester-nwr65": Phase="Pending", Reason="", readiness=false. Elapsed: 8.302166708s
... skipping 66 lines ...
Aug 15 00:52:29.900: INFO: PersistentVolumeClaim pvc-cgd5p found but phase is Pending instead of Bound.
Aug 15 00:52:31.958: INFO: PersistentVolumeClaim pvc-cgd5p found and phase=Bound (12.437163995s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:32.138: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-8h26t" in namespace "e2e-tests-ebs-kgv9x" to be "success or failure"
Aug 15 00:52:32.207: INFO: Pod "ebs-volume-tester-8h26t": Phase="Pending", Reason="", readiness=false. Elapsed: 69.100738ms
Aug 15 00:52:34.270: INFO: Pod "ebs-volume-tester-8h26t": Phase="Pending", Reason="", readiness=false. Elapsed: 2.131507775s
Aug 15 00:52:36.331: INFO: Pod "ebs-volume-tester-8h26t": Phase="Pending", Reason="", readiness=false. Elapsed: 4.192703736s
Aug 15 00:52:38.402: INFO: Pod "ebs-volume-tester-8h26t": Phase="Pending", Reason="", readiness=false. Elapsed: 6.264198924s
Aug 15 00:52:40.463: INFO: Pod "ebs-volume-tester-8h26t": Phase="Pending", Reason="", readiness=false. Elapsed: 8.32469086s
... skipping 65 lines ...
Aug 15 00:52:35.017: INFO: PersistentVolumeClaim pvc-gz8mv found but phase is Pending instead of Bound.
Aug 15 00:52:37.078: INFO: PersistentVolumeClaim pvc-gz8mv found and phase=Bound (16.522093992s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:37.260: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-v22c5" in namespace "e2e-tests-ebs-lfvf8" to be "success or failure"
Aug 15 00:52:37.319: INFO: Pod "ebs-volume-tester-v22c5": Phase="Pending", Reason="", readiness=false. Elapsed: 59.17068ms
Aug 15 00:52:39.377: INFO: Pod "ebs-volume-tester-v22c5": Phase="Pending", Reason="", readiness=false. Elapsed: 2.117282111s
Aug 15 00:52:41.442: INFO: Pod "ebs-volume-tester-v22c5": Phase="Pending", Reason="", readiness=false. Elapsed: 4.182225672s
Aug 15 00:52:43.506: INFO: Pod "ebs-volume-tester-v22c5": Phase="Pending", Reason="", readiness=false. Elapsed: 6.246508198s
Aug 15 00:52:45.564: INFO: Pod "ebs-volume-tester-v22c5": Phase="Pending", Reason="", readiness=false. Elapsed: 8.304910563s
... skipping 59 lines ...
Aug 15 00:52:26.972: INFO: PersistentVolumeClaim pvc-gzgmw found but phase is Pending instead of Bound.
Aug 15 00:52:29.027: INFO: PersistentVolumeClaim pvc-gzgmw found and phase=Bound (10.352619022s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:29.198: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-fx6v9" in namespace "e2e-tests-ebs-7gq4p" to be "success or failure"
Aug 15 00:52:29.263: INFO: Pod "ebs-volume-tester-fx6v9": Phase="Pending", Reason="", readiness=false. Elapsed: 65.114459ms
Aug 15 00:52:31.318: INFO: Pod "ebs-volume-tester-fx6v9": Phase="Pending", Reason="", readiness=false. Elapsed: 2.120049596s
Aug 15 00:52:33.375: INFO: Pod "ebs-volume-tester-fx6v9": Phase="Pending", Reason="", readiness=false. Elapsed: 4.176622056s
Aug 15 00:52:35.434: INFO: Pod "ebs-volume-tester-fx6v9": Phase="Pending", Reason="", readiness=false. Elapsed: 6.235738619s
Aug 15 00:52:37.489: INFO: Pod "ebs-volume-tester-fx6v9": Phase="Pending", Reason="", readiness=false. Elapsed: 8.290870503s
... skipping 74 lines ...
Aug 15 00:52:44.395: INFO: PersistentVolumeClaim pvc-rwtmm found but phase is Pending instead of Bound.
Aug 15 00:52:46.448: INFO: PersistentVolumeClaim pvc-rwtmm found and phase=Bound (28.901598065s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:46.623: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-cc7pb" in namespace "e2e-tests-ebs-c7k4w" to be "success or failure"
Aug 15 00:52:46.707: INFO: Pod "ebs-volume-tester-cc7pb": Phase="Pending", Reason="", readiness=false. Elapsed: 84.205985ms
Aug 15 00:52:48.771: INFO: Pod "ebs-volume-tester-cc7pb": Phase="Pending", Reason="", readiness=false. Elapsed: 2.148044599s
Aug 15 00:52:50.825: INFO: Pod "ebs-volume-tester-cc7pb": Phase="Pending", Reason="", readiness=false. Elapsed: 4.202028323s
Aug 15 00:52:52.879: INFO: Pod "ebs-volume-tester-cc7pb": Phase="Pending", Reason="", readiness=false. Elapsed: 6.256085859s
Aug 15 00:52:54.943: INFO: Pod "ebs-volume-tester-cc7pb": Phase="Pending", Reason="", readiness=false. Elapsed: 8.31974765s
... skipping 58 lines ...
Aug 15 00:52:24.025: INFO: PersistentVolumeClaim pvc-qvt2j found but phase is Pending instead of Bound.
Aug 15 00:52:26.081: INFO: PersistentVolumeClaim pvc-qvt2j found and phase=Bound (8.389989857s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:26.262: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-ds48p" in namespace "e2e-tests-ebs-fc4q5" to be "success or failure"
Aug 15 00:52:26.350: INFO: Pod "ebs-volume-tester-ds48p": Phase="Pending", Reason="", readiness=false. Elapsed: 87.840251ms
Aug 15 00:52:28.407: INFO: Pod "ebs-volume-tester-ds48p": Phase="Pending", Reason="", readiness=false. Elapsed: 2.144828021s
Aug 15 00:52:30.466: INFO: Pod "ebs-volume-tester-ds48p": Phase="Pending", Reason="", readiness=false. Elapsed: 4.20437054s
Aug 15 00:52:32.522: INFO: Pod "ebs-volume-tester-ds48p": Phase="Pending", Reason="", readiness=false. Elapsed: 6.259866084s
Aug 15 00:52:34.577: INFO: Pod "ebs-volume-tester-ds48p": Phase="Pending", Reason="", readiness=false. Elapsed: 8.315425278s
... skipping 65 lines ...
Aug 15 00:52:24.850: INFO: Waiting up to 5m0s for PersistentVolumeClaims [pvc-x89x6] to have phase Bound
Aug 15 00:52:24.903: INFO: PersistentVolumeClaim pvc-x89x6 found and phase=Bound (53.500095ms)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with an error
Aug 15 00:52:25.069: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-kk7br" in namespace "e2e-tests-ebs-2l2x8" to be "Error status code"
Aug 15 00:52:25.122: INFO: Pod "ebs-volume-tester-kk7br": Phase="Pending", Reason="", readiness=false. Elapsed: 53.567977ms
Aug 15 00:52:27.177: INFO: Pod "ebs-volume-tester-kk7br": Phase="Pending", Reason="", readiness=false. Elapsed: 2.108578774s
Aug 15 00:52:29.232: INFO: Pod "ebs-volume-tester-kk7br": Phase="Pending", Reason="", readiness=false. Elapsed: 4.163311238s
Aug 15 00:52:31.290: INFO: Pod "ebs-volume-tester-kk7br": Phase="Pending", Reason="", readiness=false. Elapsed: 6.221508813s
Aug 15 00:52:33.346: INFO: Pod "ebs-volume-tester-kk7br": Phase="Pending", Reason="", readiness=false. Elapsed: 8.277335646s
Aug 15 00:52:35.401: INFO: Pod "ebs-volume-tester-kk7br": Phase="Pending", Reason="", readiness=false. Elapsed: 10.331909241s
Aug 15 00:52:37.457: INFO: Pod "ebs-volume-tester-kk7br": Phase="Pending", Reason="", readiness=false. Elapsed: 12.388406118s
Aug 15 00:52:39.513: INFO: Pod "ebs-volume-tester-kk7br": Phase="Pending", Reason="", readiness=false. Elapsed: 14.444062569s
Aug 15 00:52:41.571: INFO: Pod "ebs-volume-tester-kk7br": Phase="Pending", Reason="", readiness=false. Elapsed: 16.502177857s
Aug 15 00:52:43.641: INFO: Pod "ebs-volume-tester-kk7br": Phase="Failed", Reason="", readiness=false. Elapsed: 18.572252661s
STEP: Saw pod failure
Aug 15 00:52:43.641: INFO: Pod "ebs-volume-tester-kk7br" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Aug 15 00:52:43.719: INFO: deleting Pod "e2e-tests-ebs-2l2x8"/"ebs-volume-tester-kk7br"
Aug 15 00:52:43.787: INFO: Pod ebs-volume-tester-kk7br has the following logs: /bin/sh: can't create /mnt/test-1/data: Read-only file system

STEP: Deleting pod ebs-volume-tester-kk7br in namespace e2e-tests-ebs-2l2x8
Aug 15 00:52:43.848: INFO: deleting PVC "e2e-tests-ebs-2l2x8"/"pvc-x89x6"
... skipping 56 lines ...
Aug 15 00:52:24.085: INFO: PersistentVolumeClaim pvc-kh88r found but phase is Pending instead of Bound.
Aug 15 00:52:26.151: INFO: PersistentVolumeClaim pvc-kh88r found and phase=Bound (8.328342235s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:26.373: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-8lqt8" in namespace "e2e-tests-ebs-b6bjh" to be "success or failure"
Aug 15 00:52:26.445: INFO: Pod "ebs-volume-tester-8lqt8": Phase="Pending", Reason="", readiness=false. Elapsed: 72.755988ms
Aug 15 00:52:28.507: INFO: Pod "ebs-volume-tester-8lqt8": Phase="Pending", Reason="", readiness=false. Elapsed: 2.13411204s
Aug 15 00:52:30.566: INFO: Pod "ebs-volume-tester-8lqt8": Phase="Pending", Reason="", readiness=false. Elapsed: 4.193592954s
Aug 15 00:52:32.629: INFO: Pod "ebs-volume-tester-8lqt8": Phase="Pending", Reason="", readiness=false. Elapsed: 6.255870009s
Aug 15 00:52:34.698: INFO: Pod "ebs-volume-tester-8lqt8": Phase="Pending", Reason="", readiness=false. Elapsed: 8.325139126s
... skipping 68 lines ...
Aug 15 00:52:27.179: INFO: PersistentVolumeClaim pvc-cktnm found but phase is Pending instead of Bound.
Aug 15 00:52:29.236: INFO: PersistentVolumeClaim pvc-cktnm found and phase=Bound (10.376601023s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:29.434: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-fgwrw" in namespace "e2e-tests-ebs-rrvzt" to be "success or failure"
Aug 15 00:52:29.507: INFO: Pod "ebs-volume-tester-fgwrw": Phase="Pending", Reason="", readiness=false. Elapsed: 72.255705ms
Aug 15 00:52:31.564: INFO: Pod "ebs-volume-tester-fgwrw": Phase="Pending", Reason="", readiness=false. Elapsed: 2.129845323s
Aug 15 00:52:33.622: INFO: Pod "ebs-volume-tester-fgwrw": Phase="Pending", Reason="", readiness=false. Elapsed: 4.187414833s
Aug 15 00:52:35.683: INFO: Pod "ebs-volume-tester-fgwrw": Phase="Pending", Reason="", readiness=false. Elapsed: 6.248719214s
Aug 15 00:52:37.740: INFO: Pod "ebs-volume-tester-fgwrw": Phase="Pending", Reason="", readiness=false. Elapsed: 8.305655357s
... skipping 66 lines ...
Aug 15 00:52:24.755: INFO: PersistentVolumeClaim pvc-wlg4d found but phase is Pending instead of Bound.
Aug 15 00:52:26.815: INFO: PersistentVolumeClaim pvc-wlg4d found and phase=Bound (8.292937767s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:27.007: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-vsvvr" in namespace "e2e-tests-ebs-dg5v7" to be "success or failure"
Aug 15 00:52:27.067: INFO: Pod "ebs-volume-tester-vsvvr": Phase="Pending", Reason="", readiness=false. Elapsed: 60.033097ms
Aug 15 00:52:29.123: INFO: Pod "ebs-volume-tester-vsvvr": Phase="Pending", Reason="", readiness=false. Elapsed: 2.115387733s
Aug 15 00:52:31.180: INFO: Pod "ebs-volume-tester-vsvvr": Phase="Pending", Reason="", readiness=false. Elapsed: 4.172852056s
Aug 15 00:52:33.240: INFO: Pod "ebs-volume-tester-vsvvr": Phase="Pending", Reason="", readiness=false. Elapsed: 6.232831837s
Aug 15 00:52:35.305: INFO: Pod "ebs-volume-tester-vsvvr": Phase="Pending", Reason="", readiness=false. Elapsed: 8.29747403s
... skipping 76 lines ...
Aug 15 00:52:32.759: INFO: PersistentVolumeClaim pvc-tlvqh found but phase is Pending instead of Bound.
Aug 15 00:52:34.819: INFO: PersistentVolumeClaim pvc-tlvqh found and phase=Bound (14.49021553s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:35.017: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-dhpkk" in namespace "e2e-tests-ebs-ghpqv" to be "success or failure"
Aug 15 00:52:35.076: INFO: Pod "ebs-volume-tester-dhpkk": Phase="Pending", Reason="", readiness=false. Elapsed: 59.560065ms
Aug 15 00:52:37.137: INFO: Pod "ebs-volume-tester-dhpkk": Phase="Pending", Reason="", readiness=false. Elapsed: 2.120378632s
Aug 15 00:52:39.197: INFO: Pod "ebs-volume-tester-dhpkk": Phase="Pending", Reason="", readiness=false. Elapsed: 4.180587328s
Aug 15 00:52:41.298: INFO: Pod "ebs-volume-tester-dhpkk": Phase="Pending", Reason="", readiness=false. Elapsed: 6.28108309s
Aug 15 00:52:43.357: INFO: Pod "ebs-volume-tester-dhpkk": Phase="Pending", Reason="", readiness=false. Elapsed: 8.340521341s
... skipping 72 lines ...
Aug 15 00:52:32.864: INFO: PersistentVolumeClaim pvc-29grz found but phase is Pending instead of Bound.
Aug 15 00:52:34.922: INFO: PersistentVolumeClaim pvc-29grz found and phase=Bound (14.500460513s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:35.107: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-ddrcz" in namespace "e2e-tests-ebs-w4fpc" to be "success or failure"
Aug 15 00:52:35.166: INFO: Pod "ebs-volume-tester-ddrcz": Phase="Pending", Reason="", readiness=false. Elapsed: 58.942124ms
Aug 15 00:52:37.224: INFO: Pod "ebs-volume-tester-ddrcz": Phase="Pending", Reason="", readiness=false. Elapsed: 2.116731404s
Aug 15 00:52:39.282: INFO: Pod "ebs-volume-tester-ddrcz": Phase="Pending", Reason="", readiness=false. Elapsed: 4.175041259s
Aug 15 00:52:41.361: INFO: Pod "ebs-volume-tester-ddrcz": Phase="Pending", Reason="", readiness=false. Elapsed: 6.253396287s
Aug 15 00:52:43.425: INFO: Pod "ebs-volume-tester-ddrcz": Phase="Pending", Reason="", readiness=false. Elapsed: 8.317711757s
... skipping 67 lines ...
Aug 15 00:52:30.011: INFO: PersistentVolumeClaim pvc-hrfsd found but phase is Pending instead of Bound.
Aug 15 00:52:32.065: INFO: PersistentVolumeClaim pvc-hrfsd found and phase=Bound (12.391945949s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:32.253: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-58mz9" in namespace "e2e-tests-ebs-grkpr" to be "success or failure"
Aug 15 00:52:32.329: INFO: Pod "ebs-volume-tester-58mz9": Phase="Pending", Reason="", readiness=false. Elapsed: 75.48224ms
Aug 15 00:52:34.383: INFO: Pod "ebs-volume-tester-58mz9": Phase="Pending", Reason="", readiness=false. Elapsed: 2.130055059s
Aug 15 00:52:36.438: INFO: Pod "ebs-volume-tester-58mz9": Phase="Pending", Reason="", readiness=false. Elapsed: 4.184830296s
Aug 15 00:52:38.510: INFO: Pod "ebs-volume-tester-58mz9": Phase="Pending", Reason="", readiness=false. Elapsed: 6.256642203s
Aug 15 00:52:40.566: INFO: Pod "ebs-volume-tester-58mz9": Phase="Pending", Reason="", readiness=false. Elapsed: 8.312751513s
... skipping 75 lines ...
Aug 15 00:52:41.165: INFO: PersistentVolumeClaim pvc-p9bpr found but phase is Pending instead of Bound.
Aug 15 00:52:43.224: INFO: PersistentVolumeClaim pvc-p9bpr found and phase=Bound (25.029957209s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:43.427: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-qrnc2" in namespace "e2e-tests-ebs-8qrrj" to be "success or failure"
Aug 15 00:52:43.497: INFO: Pod "ebs-volume-tester-qrnc2": Phase="Pending", Reason="", readiness=false. Elapsed: 70.762752ms
Aug 15 00:52:45.556: INFO: Pod "ebs-volume-tester-qrnc2": Phase="Pending", Reason="", readiness=false. Elapsed: 2.129753578s
Aug 15 00:52:47.615: INFO: Pod "ebs-volume-tester-qrnc2": Phase="Pending", Reason="", readiness=false. Elapsed: 4.188684168s
Aug 15 00:52:49.674: INFO: Pod "ebs-volume-tester-qrnc2": Phase="Pending", Reason="", readiness=false. Elapsed: 6.247518191s
Aug 15 00:52:51.733: INFO: Pod "ebs-volume-tester-qrnc2": Phase="Pending", Reason="", readiness=false. Elapsed: 8.306571537s
... skipping 75 lines ...
Aug 15 00:52:43.335: INFO: PersistentVolumeClaim pvc-rth2d found but phase is Pending instead of Bound.
Aug 15 00:52:45.393: INFO: PersistentVolumeClaim pvc-rth2d found and phase=Bound (26.96535779s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with an error
Aug 15 00:52:45.569: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-7vlr2" in namespace "e2e-tests-ebs-n7cm9" to be "Error status code"
Aug 15 00:52:45.643: INFO: Pod "ebs-volume-tester-7vlr2": Phase="Pending", Reason="", readiness=false. Elapsed: 73.946025ms
Aug 15 00:52:47.701: INFO: Pod "ebs-volume-tester-7vlr2": Phase="Pending", Reason="", readiness=false. Elapsed: 2.131444531s
Aug 15 00:52:49.761: INFO: Pod "ebs-volume-tester-7vlr2": Phase="Pending", Reason="", readiness=false. Elapsed: 4.192130046s
Aug 15 00:52:51.819: INFO: Pod "ebs-volume-tester-7vlr2": Phase="Pending", Reason="", readiness=false. Elapsed: 6.249992738s
Aug 15 00:52:53.878: INFO: Pod "ebs-volume-tester-7vlr2": Phase="Pending", Reason="", readiness=false. Elapsed: 8.308858756s
Aug 15 00:52:55.936: INFO: Pod "ebs-volume-tester-7vlr2": Phase="Pending", Reason="", readiness=false. Elapsed: 10.366765028s
Aug 15 00:52:58.006: INFO: Pod "ebs-volume-tester-7vlr2": Phase="Pending", Reason="", readiness=false. Elapsed: 12.437010967s
Aug 15 00:53:00.065: INFO: Pod "ebs-volume-tester-7vlr2": Phase="Pending", Reason="", readiness=false. Elapsed: 14.495860521s
Aug 15 00:53:02.123: INFO: Pod "ebs-volume-tester-7vlr2": Phase="Pending", Reason="", readiness=false. Elapsed: 16.553871181s
Aug 15 00:53:04.181: INFO: Pod "ebs-volume-tester-7vlr2": Phase="Pending", Reason="", readiness=false. Elapsed: 18.612005011s
Aug 15 00:53:06.241: INFO: Pod "ebs-volume-tester-7vlr2": Phase="Pending", Reason="", readiness=false. Elapsed: 20.671941885s
Aug 15 00:53:08.337: INFO: Pod "ebs-volume-tester-7vlr2": Phase="Pending", Reason="", readiness=false. Elapsed: 22.768241629s
Aug 15 00:53:10.407: INFO: Pod "ebs-volume-tester-7vlr2": Phase="Failed", Reason="", readiness=false. Elapsed: 24.83821572s
STEP: Saw pod failure
Aug 15 00:53:10.407: INFO: Pod "ebs-volume-tester-7vlr2" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Aug 15 00:53:10.475: INFO: deleting Pod "e2e-tests-ebs-n7cm9"/"ebs-volume-tester-7vlr2"
Aug 15 00:53:10.555: INFO: Pod ebs-volume-tester-7vlr2 has the following logs: touch: /mnt/test-1/data: Read-only file system

STEP: Deleting pod ebs-volume-tester-7vlr2 in namespace e2e-tests-ebs-n7cm9
Aug 15 00:53:10.645: INFO: deleting PVC "e2e-tests-ebs-n7cm9"/"pvc-rth2d"
... skipping 51 lines ...
Aug 15 00:52:27.030: INFO: PersistentVolumeClaim pvc-7d27l found but phase is Pending instead of Bound.
Aug 15 00:52:29.084: INFO: PersistentVolumeClaim pvc-7d27l found and phase=Bound (10.357599576s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:29.261: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-lc9b7" in namespace "e2e-tests-ebs-pbpst" to be "success or failure"
Aug 15 00:52:29.324: INFO: Pod "ebs-volume-tester-lc9b7": Phase="Pending", Reason="", readiness=false. Elapsed: 62.562715ms
Aug 15 00:52:31.379: INFO: Pod "ebs-volume-tester-lc9b7": Phase="Pending", Reason="", readiness=false. Elapsed: 2.118081273s
Aug 15 00:52:33.443: INFO: Pod "ebs-volume-tester-lc9b7": Phase="Pending", Reason="", readiness=false. Elapsed: 4.181900909s
Aug 15 00:52:35.498: INFO: Pod "ebs-volume-tester-lc9b7": Phase="Pending", Reason="", readiness=false. Elapsed: 6.236747852s
Aug 15 00:52:37.552: INFO: Pod "ebs-volume-tester-lc9b7": Phase="Pending", Reason="", readiness=false. Elapsed: 8.291047588s
... skipping 81 lines ...
Aug 15 00:52:43.280: INFO: PersistentVolumeClaim pvc-lvzfh found but phase is Pending instead of Bound.
Aug 15 00:52:45.337: INFO: PersistentVolumeClaim pvc-lvzfh found and phase=Bound (27.008658025s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:45.514: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-njzh7" in namespace "e2e-tests-ebs-49bwk" to be "success or failure"
Aug 15 00:52:45.571: INFO: Pod "ebs-volume-tester-njzh7": Phase="Pending", Reason="", readiness=false. Elapsed: 57.72015ms
Aug 15 00:52:47.628: INFO: Pod "ebs-volume-tester-njzh7": Phase="Pending", Reason="", readiness=false. Elapsed: 2.114361741s
Aug 15 00:52:49.685: INFO: Pod "ebs-volume-tester-njzh7": Phase="Pending", Reason="", readiness=false. Elapsed: 4.170908916s
Aug 15 00:52:51.741: INFO: Pod "ebs-volume-tester-njzh7": Phase="Pending", Reason="", readiness=false. Elapsed: 6.227460155s
Aug 15 00:52:53.798: INFO: Pod "ebs-volume-tester-njzh7": Phase="Pending", Reason="", readiness=false. Elapsed: 8.284341521s
... skipping 70 lines ...
Aug 15 00:52:29.539: INFO: PersistentVolumeClaim pvc-q22bz found but phase is Pending instead of Bound.
Aug 15 00:52:31.599: INFO: PersistentVolumeClaim pvc-q22bz found and phase=Bound (12.474792947s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:31.790: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-gk8fb" in namespace "e2e-tests-ebs-bh47v" to be "success or failure"
Aug 15 00:52:31.858: INFO: Pod "ebs-volume-tester-gk8fb": Phase="Pending", Reason="", readiness=false. Elapsed: 67.048624ms
Aug 15 00:52:33.917: INFO: Pod "ebs-volume-tester-gk8fb": Phase="Pending", Reason="", readiness=false. Elapsed: 2.126193358s
Aug 15 00:52:35.975: INFO: Pod "ebs-volume-tester-gk8fb": Phase="Pending", Reason="", readiness=false. Elapsed: 4.184652416s
Aug 15 00:52:38.034: INFO: Pod "ebs-volume-tester-gk8fb": Phase="Pending", Reason="", readiness=false. Elapsed: 6.243334583s
Aug 15 00:52:40.093: INFO: Pod "ebs-volume-tester-gk8fb": Phase="Pending", Reason="", readiness=false. Elapsed: 8.302854107s
... skipping 81 lines ...
Aug 15 00:52:43.355: INFO: PersistentVolumeClaim pvc-bdvtz found but phase is Pending instead of Bound.
Aug 15 00:52:45.412: INFO: PersistentVolumeClaim pvc-bdvtz found and phase=Bound (24.819625756s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:45.604: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-rxgzr" in namespace "e2e-tests-ebs-5nh4k" to be "success or failure"
Aug 15 00:52:45.661: INFO: Pod "ebs-volume-tester-rxgzr": Phase="Pending", Reason="", readiness=false. Elapsed: 56.382045ms
Aug 15 00:52:47.718: INFO: Pod "ebs-volume-tester-rxgzr": Phase="Pending", Reason="", readiness=false. Elapsed: 2.113774796s
Aug 15 00:52:49.778: INFO: Pod "ebs-volume-tester-rxgzr": Phase="Pending", Reason="", readiness=false. Elapsed: 4.173971119s
Aug 15 00:52:51.836: INFO: Pod "ebs-volume-tester-rxgzr": Phase="Pending", Reason="", readiness=false. Elapsed: 6.231661793s
Aug 15 00:52:53.894: INFO: Pod "ebs-volume-tester-rxgzr": Phase="Pending", Reason="", readiness=false. Elapsed: 8.289430992s
... skipping 87 lines ...
Aug 15 00:52:38.511: INFO: PersistentVolumeClaim pvc-xggjb found but phase is Pending instead of Bound.
Aug 15 00:52:40.565: INFO: PersistentVolumeClaim pvc-xggjb found and phase=Bound (14.455901638s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:40.731: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-wt52f" in namespace "e2e-tests-ebs-fd5fg" to be "success or failure"
Aug 15 00:52:40.832: INFO: Pod "ebs-volume-tester-wt52f": Phase="Pending", Reason="", readiness=false. Elapsed: 100.655247ms
Aug 15 00:52:42.891: INFO: Pod "ebs-volume-tester-wt52f": Phase="Pending", Reason="", readiness=false. Elapsed: 2.159774409s
Aug 15 00:52:44.945: INFO: Pod "ebs-volume-tester-wt52f": Phase="Pending", Reason="", readiness=false. Elapsed: 4.21398156s
Aug 15 00:52:47.014: INFO: Pod "ebs-volume-tester-wt52f": Phase="Pending", Reason="", readiness=false. Elapsed: 6.282974429s
Aug 15 00:52:49.071: INFO: Pod "ebs-volume-tester-wt52f": Phase="Pending", Reason="", readiness=false. Elapsed: 8.34011028s
... skipping 99 lines ...
Aug 15 00:52:55.740: INFO: PersistentVolumeClaim pvc-6plg2 found but phase is Pending instead of Bound.
Aug 15 00:52:57.793: INFO: PersistentVolumeClaim pvc-6plg2 found and phase=Bound (10.328606835s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:57.960: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-pxwjb" in namespace "e2e-tests-ebs-nbzdf" to be "success or failure"
Aug 15 00:52:58.014: INFO: Pod "ebs-volume-tester-pxwjb": Phase="Pending", Reason="", readiness=false. Elapsed: 53.741049ms
Aug 15 00:53:00.069: INFO: Pod "ebs-volume-tester-pxwjb": Phase="Pending", Reason="", readiness=false. Elapsed: 2.108675375s
Aug 15 00:53:02.125: INFO: Pod "ebs-volume-tester-pxwjb": Phase="Pending", Reason="", readiness=false. Elapsed: 4.16486848s
Aug 15 00:53:04.180: INFO: Pod "ebs-volume-tester-pxwjb": Phase="Pending", Reason="", readiness=false. Elapsed: 6.219634918s
Aug 15 00:53:06.237: INFO: Pod "ebs-volume-tester-pxwjb": Phase="Pending", Reason="", readiness=false. Elapsed: 8.277130432s
... skipping 85 lines ...
Aug 15 00:52:52.593: INFO: PersistentVolumeClaim pvc-jhp2h found but phase is Pending instead of Bound.
Aug 15 00:52:54.647: INFO: PersistentVolumeClaim pvc-jhp2h found and phase=Bound (10.352496376s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:54.812: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-wp8jb" in namespace "e2e-tests-ebs-d9kkm" to be "success or failure"
Aug 15 00:52:54.907: INFO: Pod "ebs-volume-tester-wp8jb": Phase="Pending", Reason="", readiness=false. Elapsed: 94.930909ms
Aug 15 00:52:56.971: INFO: Pod "ebs-volume-tester-wp8jb": Phase="Pending", Reason="", readiness=false. Elapsed: 2.158918077s
Aug 15 00:52:59.025: INFO: Pod "ebs-volume-tester-wp8jb": Phase="Pending", Reason="", readiness=false. Elapsed: 4.21264166s
Aug 15 00:53:01.085: INFO: Pod "ebs-volume-tester-wp8jb": Phase="Pending", Reason="", readiness=false. Elapsed: 6.272726674s
Aug 15 00:53:03.139: INFO: Pod "ebs-volume-tester-wp8jb": Phase="Pending", Reason="", readiness=false. Elapsed: 8.327020127s
... skipping 84 lines ...
Aug 15 00:52:42.724: INFO: PersistentVolumeClaim pvc-b8hd6 found but phase is Pending instead of Bound.
Aug 15 00:52:44.781: INFO: PersistentVolumeClaim pvc-b8hd6 found and phase=Bound (26.877806407s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:44.957: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-xqvzn" in namespace "e2e-tests-ebs-btbcv" to be "success or failure"
Aug 15 00:52:45.040: INFO: Pod "ebs-volume-tester-xqvzn": Phase="Pending", Reason="", readiness=false. Elapsed: 82.78258ms
Aug 15 00:52:47.099: INFO: Pod "ebs-volume-tester-xqvzn": Phase="Pending", Reason="", readiness=false. Elapsed: 2.141562083s
Aug 15 00:52:49.156: INFO: Pod "ebs-volume-tester-xqvzn": Phase="Pending", Reason="", readiness=false. Elapsed: 4.198870827s
Aug 15 00:52:51.221: INFO: Pod "ebs-volume-tester-xqvzn": Phase="Pending", Reason="", readiness=false. Elapsed: 6.263801954s
Aug 15 00:52:53.317: INFO: Pod "ebs-volume-tester-xqvzn": Phase="Pending", Reason="", readiness=false. Elapsed: 8.360130481s
... skipping 17 lines ...
Aug 15 00:53:12.068: INFO: PersistentVolumeClaim pvc-79g2w found but phase is Pending instead of Bound.
Aug 15 00:53:14.134: INFO: PersistentVolumeClaim pvc-79g2w found and phase=Bound (8.327590082s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:53:14.319: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-266tf" in namespace "e2e-tests-ebs-btbcv" to be "success or failure"
Aug 15 00:53:14.379: INFO: Pod "ebs-volume-tester-266tf": Phase="Pending", Reason="", readiness=false. Elapsed: 59.884357ms
Aug 15 00:53:16.442: INFO: Pod "ebs-volume-tester-266tf": Phase="Pending", Reason="", readiness=false. Elapsed: 2.122963186s
Aug 15 00:53:18.507: INFO: Pod "ebs-volume-tester-266tf": Phase="Pending", Reason="", readiness=false. Elapsed: 4.188463234s
Aug 15 00:53:20.568: INFO: Pod "ebs-volume-tester-266tf": Phase="Pending", Reason="", readiness=false. Elapsed: 6.249084369s
Aug 15 00:53:22.626: INFO: Pod "ebs-volume-tester-266tf": Phase="Pending", Reason="", readiness=false. Elapsed: 8.30689404s
... skipping 289 lines ...
Aug 15 00:52:24.283: INFO: PersistentVolumeClaim pvc-kzggs found but phase is Pending instead of Bound.
Aug 15 00:52:26.354: INFO: PersistentVolumeClaim pvc-kzggs found and phase=Bound (8.317897416s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:26.546: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-8rz2s" in namespace "e2e-tests-ebs-7ls9j" to be "success or failure"
Aug 15 00:52:26.609: INFO: Pod "ebs-volume-tester-8rz2s": Phase="Pending", Reason="", readiness=false. Elapsed: 63.313571ms
Aug 15 00:52:28.666: INFO: Pod "ebs-volume-tester-8rz2s": Phase="Pending", Reason="", readiness=false. Elapsed: 2.120191283s
Aug 15 00:52:30.723: INFO: Pod "ebs-volume-tester-8rz2s": Phase="Pending", Reason="", readiness=false. Elapsed: 4.177210443s
Aug 15 00:52:32.781: INFO: Pod "ebs-volume-tester-8rz2s": Phase="Pending", Reason="", readiness=false. Elapsed: 6.234965404s
Aug 15 00:52:34.841: INFO: Pod "ebs-volume-tester-8rz2s": Phase="Pending", Reason="", readiness=false. Elapsed: 8.294821187s
... skipping 197 lines ...
Aug 15 00:52:43.011: INFO: PersistentVolumeClaim pvc-f2znj found but phase is Pending instead of Bound.
Aug 15 00:52:45.079: INFO: PersistentVolumeClaim pvc-f2znj found and phase=Bound (26.97483755s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:45.249: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-c4qzf" in namespace "e2e-tests-ebs-v8hd4" to be "success or failure"
Aug 15 00:52:45.322: INFO: Pod "ebs-volume-tester-c4qzf": Phase="Pending", Reason="", readiness=false. Elapsed: 73.233698ms
Aug 15 00:52:47.378: INFO: Pod "ebs-volume-tester-c4qzf": Phase="Pending", Reason="", readiness=false. Elapsed: 2.128840595s
Aug 15 00:52:49.435: INFO: Pod "ebs-volume-tester-c4qzf": Phase="Pending", Reason="", readiness=false. Elapsed: 4.185963464s
Aug 15 00:52:51.513: INFO: Pod "ebs-volume-tester-c4qzf": Phase="Pending", Reason="", readiness=false. Elapsed: 6.264376126s
Aug 15 00:52:53.569: INFO: Pod "ebs-volume-tester-c4qzf": Phase="Pending", Reason="", readiness=false. Elapsed: 8.319961136s
... skipping 189 lines ...
Aug 15 00:52:32.331: INFO: PersistentVolumeClaim pvc-9ls9k found but phase is Pending instead of Bound.
Aug 15 00:52:34.386: INFO: PersistentVolumeClaim pvc-9ls9k found and phase=Bound (14.463710157s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:34.557: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-f6wwh" in namespace "e2e-tests-ebs-mwxht" to be "success or failure"
Aug 15 00:52:34.616: INFO: Pod "ebs-volume-tester-f6wwh": Phase="Pending", Reason="", readiness=false. Elapsed: 59.231583ms
Aug 15 00:52:36.671: INFO: Pod "ebs-volume-tester-f6wwh": Phase="Pending", Reason="", readiness=false. Elapsed: 2.114215367s
Aug 15 00:52:38.745: INFO: Pod "ebs-volume-tester-f6wwh": Phase="Pending", Reason="", readiness=false. Elapsed: 4.187688852s
Aug 15 00:52:40.878: INFO: Pod "ebs-volume-tester-f6wwh": Phase="Pending", Reason="", readiness=false. Elapsed: 6.320831664s
Aug 15 00:52:42.933: INFO: Pod "ebs-volume-tester-f6wwh": Phase="Pending", Reason="", readiness=false. Elapsed: 8.376093317s
... skipping 253 lines ...
Aug 15 00:52:54.894: INFO: PersistentVolumeClaim pvc-cmrtq found but phase is Pending instead of Bound.
Aug 15 00:52:56.949: INFO: PersistentVolumeClaim pvc-cmrtq found and phase=Bound (10.360976127s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:57.117: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-5m7l5" in namespace "e2e-tests-ebs-vg69k" to be "success or failure"
Aug 15 00:52:57.171: INFO: Pod "ebs-volume-tester-5m7l5": Phase="Pending", Reason="", readiness=false. Elapsed: 54.505628ms
Aug 15 00:52:59.242: INFO: Pod "ebs-volume-tester-5m7l5": Phase="Pending", Reason="", readiness=false. Elapsed: 2.125244704s
Aug 15 00:53:01.301: INFO: Pod "ebs-volume-tester-5m7l5": Phase="Pending", Reason="", readiness=false. Elapsed: 4.184091812s
Aug 15 00:53:03.356: INFO: Pod "ebs-volume-tester-5m7l5": Phase="Pending", Reason="", readiness=false. Elapsed: 6.239195826s
Aug 15 00:53:05.411: INFO: Pod "ebs-volume-tester-5m7l5": Phase="Pending", Reason="", readiness=false. Elapsed: 8.294584253s
... skipping 256 lines ...
Aug 15 00:52:29.953: INFO: PersistentVolumeClaim pvc-8hjsb found but phase is Pending instead of Bound.
Aug 15 00:52:32.008: INFO: PersistentVolumeClaim pvc-8hjsb found and phase=Bound (12.387809382s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Aug 15 00:52:32.179: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-rq6vm" in namespace "e2e-tests-ebs-qrrpw" to be "success or failure"
Aug 15 00:52:32.236: INFO: Pod "ebs-volume-tester-rq6vm": Phase="Pending", Reason="", readiness=false. Elapsed: 56.8121ms
Aug 15 00:52:34.306: INFO: Pod "ebs-volume-tester-rq6vm": Phase="Pending", Reason="", readiness=false. Elapsed: 2.127009755s
Aug 15 00:52:36.363: INFO: Pod "ebs-volume-tester-rq6vm": Phase="Pending", Reason="", readiness=false. Elapsed: 4.183741801s
Aug 15 00:52:38.429: INFO: Pod "ebs-volume-tester-rq6vm": Phase="Pending", Reason="", readiness=false. Elapsed: 6.250073321s
Aug 15 00:52:40.484: INFO: Pod "ebs-volume-tester-rq6vm": Phase="Pending", Reason="", readiness=false. Elapsed: 8.304865039s
... skipping 83 lines ...
Aug 15 00:55:29.368: INFO: Pod "ebs-volume-tester-rq6vm" satisfied condition "success or failure"
STEP: taking snapshots
STEP: setting up the VolumeSnapshotClass
STEP: creating a VolumeSnapshotClass
STEP: creating a VolumeSnapshot for pvc-8hjsb
STEP: waiting for VolumeSnapshot to be ready to use - volume-snapshot-tbqkx
Aug 15 01:00:29.608: INFO: Unexpected error occurred: timed out waiting for the condition
STEP: deleting a VolumeSnapshot volume-snapshot-tbqkx
Aug 15 01:00:29.669: INFO: deleting VolumeSnapshotClass e2e-tests-ebs-qrrpw-ebs.csi.aws.com-dynamic-sc-kns9k
Aug 15 01:00:29.728: INFO: deleting Pod "e2e-tests-ebs-qrrpw"/"ebs-volume-tester-rq6vm"
Aug 15 01:00:29.791: INFO: Pod ebs-volume-tester-rq6vm has the following logs: hello world

STEP: Deleting pod ebs-volume-tester-rq6vm in namespace e2e-tests-ebs-qrrpw
... skipping 14 lines ...
Aug 15 01:00:35.220: INFO: At 2019-08-15 00:52:21 +0000 UTC - event for pvc-8hjsb: {ebs.csi.aws.com_ebs-csi-controller-0_ab93b54d-bef6-11e9-a61c-eafd51f7a2ec } Provisioning: External provisioner is provisioning volume for claim "e2e-tests-ebs-qrrpw/pvc-8hjsb"
Aug 15 01:00:35.220: INFO: At 2019-08-15 00:52:32 +0000 UTC - event for ebs-volume-tester-rq6vm: {default-scheduler } Scheduled: Successfully assigned e2e-tests-ebs-qrrpw/ebs-volume-tester-rq6vm to ip-172-20-49-126.us-west-2.compute.internal
Aug 15 01:00:35.220: INFO: At 2019-08-15 00:52:36 +0000 UTC - event for ebs-volume-tester-rq6vm: {attachdetach-controller } SuccessfulAttachVolume: AttachVolume.Attach succeeded for volume "pvc-ef341eb8-bef6-11e9-bb66-0266f4b0eab6" 
Aug 15 01:00:35.220: INFO: At 2019-08-15 00:52:58 +0000 UTC - event for ebs-volume-tester-rq6vm: {kubelet ip-172-20-49-126.us-west-2.compute.internal} Pulled: Container image "docker.io/library/busybox:1.29" already present on machine
Aug 15 01:00:35.220: INFO: At 2019-08-15 00:53:00 +0000 UTC - event for ebs-volume-tester-rq6vm: {kubelet ip-172-20-49-126.us-west-2.compute.internal} Created: Created container volume-tester
Aug 15 01:00:35.221: INFO: At 2019-08-15 00:53:02 +0000 UTC - event for ebs-volume-tester-rq6vm: {kubelet ip-172-20-49-126.us-west-2.compute.internal} Started: Started container volume-tester
Aug 15 01:00:35.221: INFO: At 2019-08-15 00:55:30 +0000 UTC - event for volume-snapshot-tbqkx: {csi-snapshotter ebs.csi.aws.com } GetSnapshotClassFailed: Failed to get snapshot class with error failed to retrieve snapshot class e2e-tests-ebs-qrrpw-ebs.csi.aws.com-dynamic-sc-kns9k from the informer: "volumesnapshotclass.snapshot.storage.k8s.io \"e2e-tests-ebs-qrrpw-ebs.csi.aws.com-dynamic-sc-kns9k\" not found"
Aug 15 01:00:35.450: INFO: POD                                                                  NODE                                         PHASE    GRACE  CONDITIONS
Aug 15 01:00:35.450: INFO: dns-controller-67d566c9b4-7scrk                                      ip-172-20-53-225.us-west-2.compute.internal  Running         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:38:15 +0000 UTC  } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:38:28 +0000 UTC  } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:38:28 +0000 UTC  } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:38:15 +0000 UTC  }]
Aug 15 01:00:35.450: INFO: ebs-csi-controller-0                                                 ip-172-20-35-92.us-west-2.compute.internal   Running         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:50:08 +0000 UTC  } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:50:40 +0000 UTC  } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:50:40 +0000 UTC  } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:50:08 +0000 UTC  }]
Aug 15 01:00:35.450: INFO: ebs-csi-node-8qhb4                                                   ip-172-20-35-92.us-west-2.compute.internal   Running         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:50:08 +0000 UTC  } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:50:29 +0000 UTC  } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:50:29 +0000 UTC  } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:50:08 +0000 UTC  }]
Aug 15 01:00:35.450: INFO: ebs-csi-node-xsnbg                                                   ip-172-20-49-213.us-west-2.compute.internal  Running         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:50:08 +0000 UTC  } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:50:25 +0000 UTC  } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:50:25 +0000 UTC  } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:50:08 +0000 UTC  }]
Aug 15 01:00:35.450: INFO: ebs-csi-node-zshbs                                                   ip-172-20-49-126.us-west-2.compute.internal  Running         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:50:08 +0000 UTC  } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:50:25 +0000 UTC  } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:50:25 +0000 UTC  } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-08-15 00:50:08 +0000 UTC  }]
... skipping 125 lines ...
• Failure [510.701 seconds]
[ebs-csi-e2e] [single-az] Snapshot
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/dynamic_provisioning.go:407
  should create a pod, write and read to it, take a volume snapshot, and create another pod from the snapshot [It]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/dynamic_provisioning.go:428

  Expected error:
      <*errors.errorString | 0xc000274110>: {
          s: "timed out waiting for the condition",
      }
      timed out waiting for the condition
  not to have occurred

  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/testsuites/testsuites.go:132
------------------------------


Summarizing 1 Failure:

[Fail] [ebs-csi-e2e] [single-az] Snapshot [It] should create a pod, write and read to it, take a volume snapshot, and create another pod from the snapshot 
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/testsuites/testsuites.go:132

Ran 35 of 37 Specs in 511.282 seconds
FAIL! -- 34 Passed | 1 Failed | 0 Pending | 2 Skipped


Ginkgo ran 1 suite in 10m4.117652592s
Test Suite Failed
Removing driver
release "aws-ebs-csi-driver" deleted
Deleting cluster test-cluster-12612
TYPE			NAME											ID
autoscaling-config	master-us-west-2a.masters.test-cluster-12612.k8s.local-20190815003301			master-us-west-2a.masters.test-cluster-12612.k8s.local-20190815003301
autoscaling-config	nodes.test-cluster-12612.k8s.local-20190815003301					nodes.test-cluster-12612.k8s.local-20190815003301
... skipping 106 lines ...
route-table:rtb-07774630dd6f4b0c5	ok
vpc:vpc-0c13403eda2d85993	ok
dhcp-options:dopt-0dc8783d3e7ea50b3	ok
Deleted kubectl config for test-cluster-12612.k8s.local

Deleted cluster: "test-cluster-12612.k8s.local"
Makefile:49: recipe for target 'test-e2e-single-az' failed
make: *** [test-e2e-single-az] Error 1
+ EXIT_VALUE=2
+ set +o xtrace
Cleaning up after docker in docker.
================================================================================
[Barnacle] 2019/08/15 01:01:45 Cleaning up Docker data root...
[Barnacle] 2019/08/15 01:01:45 Removing all containers.
... skipping 25 lines ...