This job view page is being replaced by Spyglass soon. Check out the new job view.
PRwongma7: Run upstream e2e test suites with migration
ResultFAILURE
Tests 1 failed / 34 succeeded
Started2019-08-23 01:44
Elapsed42m48s
Revision3c8b4649efabbec741c83b6a7451f9df23105854
Refs 341

Test Failures


AWS EBS CSI Driver End-to-End Tests [ebs-csi-e2e] [single-az] Dynamic Provisioning should create a raw block volume on demand 25m34s

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\]\sDynamic\sProvisioning\sshould\screate\sa\sraw\sblock\svolume\son\sdemand$'
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/dynamic_provisioning.go:209
Expected error:
    <*errors.errorString | 0xc00001b2e0>: {
        s: "Gave up after waiting 15m0s for pod \"ebs-volume-tester-jn56l\" to be \"success or failure\"",
    }
    Gave up after waiting 15m0s for pod "ebs-volume-tester-jn56l" to be "success or failure"
not to have occurred
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/testsuites/testsuites.go:512
				
				Click to see stdout/stderrfrom junit_10.xml

Find ebs-volume-tester-jn56l mentions in log files | View test history on testgrid


Show 34 Passed Tests

Show 2 Skipped Tests

Error lines from build-log.txt

... skipping 1833 lines ...

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

Validating cluster test-cluster-24850.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-24850-k8-vsisot-1517093989.us-west-2.elb.amazonaws.com/api/v1/nodes: dial tcp: lookup api-test-cluster-24850-k8-vsisot-1517093989.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-24850.k8s.local

Validating cluster test-cluster-24850.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-24850-k8-vsisot-1517093989.us-west-2.elb.amazonaws.com/api/v1/nodes: dial tcp: lookup api-test-cluster-24850-k8-vsisot-1517093989.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-24850.k8s.local

Validating cluster test-cluster-24850.k8s.local


unexpected error during validation: error listing nodes: Get https://api-test-cluster-24850-k8-vsisot-1517093989.us-west-2.elb.amazonaws.com/api/v1/nodes: dial tcp: lookup api-test-cluster-24850-k8-vsisot-1517093989.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-24850.k8s.local

Validating cluster test-cluster-24850.k8s.local


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

Validating cluster test-cluster-24850.k8s.local


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

Validating cluster test-cluster-24850.k8s.local


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

Validating cluster test-cluster-24850.k8s.local


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

Validating cluster test-cluster-24850.k8s.local


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

Validating cluster test-cluster-24850.k8s.local


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

Validating cluster test-cluster-24850.k8s.local


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

Validating cluster test-cluster-24850.k8s.local

INSTANCE GROUPS
... skipping 11 lines ...
Machine	i-0734517437aa4b8d6					machine "i-0734517437aa4b8d6" has not yet joined cluster
Machine	i-0bbca3f18fb3a3c94					machine "i-0bbca3f18fb3a3c94" has not yet joined cluster
Pod	kube-system/dns-controller-56455df565-zg2b4		kube-system pod "dns-controller-56455df565-zg2b4" is pending
Pod	kube-system/kube-dns-66b6848cf6-8fvh9			kube-system pod "kube-dns-66b6848cf6-8fvh9" is pending
Pod	kube-system/kube-dns-autoscaler-577b4774b5-6w6mt	kube-system pod "kube-dns-autoscaler-577b4774b5-6w6mt" is pending

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

Validating cluster test-cluster-24850.k8s.local

INSTANCE GROUPS
... skipping 15 lines ...
Node	ip-172-20-56-84.us-west-2.compute.internal				node "ip-172-20-56-84.us-west-2.compute.internal" is not ready
Pod	kube-system/kube-dns-66b6848cf6-8fvh9					kube-system pod "kube-dns-66b6848cf6-8fvh9" is pending
Pod	kube-system/kube-dns-autoscaler-577b4774b5-6w6mt			kube-system pod "kube-dns-autoscaler-577b4774b5-6w6mt" is pending
Pod	kube-system/kube-proxy-ip-172-20-35-88.us-west-2.compute.internal	kube-system pod "kube-proxy-ip-172-20-35-88.us-west-2.compute.internal" is pending
Pod	kube-system/kube-proxy-ip-172-20-50-193.us-west-2.compute.internal	kube-system pod "kube-proxy-ip-172-20-50-193.us-west-2.compute.internal" is pending

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

Validating cluster test-cluster-24850.k8s.local

INSTANCE GROUPS
... skipping 888 lines ...
Aug 23 02:00:31.487: INFO: Waiting up to 5m0s for PersistentVolumeClaims [pvc-j854m] to have phase Bound
Aug 23 02:00:31.543: INFO: PersistentVolumeClaim pvc-j854m found and phase=Bound (56.624146ms)
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 23 02:00:31.712: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-nm9m9" in namespace "ebs-5493" to be "Error status code"
Aug 23 02:00:31.780: INFO: Pod "ebs-volume-tester-nm9m9": Phase="Pending", Reason="", readiness=false. Elapsed: 67.67607ms
Aug 23 02:00:33.835: INFO: Pod "ebs-volume-tester-nm9m9": Phase="Pending", Reason="", readiness=false. Elapsed: 2.123458477s
Aug 23 02:00:35.891: INFO: Pod "ebs-volume-tester-nm9m9": Phase="Pending", Reason="", readiness=false. Elapsed: 4.179562896s
Aug 23 02:00:37.949: INFO: Pod "ebs-volume-tester-nm9m9": Phase="Pending", Reason="", readiness=false. Elapsed: 6.236784098s
Aug 23 02:00:40.006: INFO: Pod "ebs-volume-tester-nm9m9": Phase="Pending", Reason="", readiness=false. Elapsed: 8.294499239s
Aug 23 02:00:42.066: INFO: Pod "ebs-volume-tester-nm9m9": Phase="Pending", Reason="", readiness=false. Elapsed: 10.354322673s
Aug 23 02:00:44.123: INFO: Pod "ebs-volume-tester-nm9m9": Phase="Pending", Reason="", readiness=false. Elapsed: 12.410668906s
Aug 23 02:00:46.178: INFO: Pod "ebs-volume-tester-nm9m9": Phase="Pending", Reason="", readiness=false. Elapsed: 14.466322312s
Aug 23 02:00:48.234: INFO: Pod "ebs-volume-tester-nm9m9": Phase="Failed", Reason="", readiness=false. Elapsed: 16.521695009s
STEP: Saw pod failure
Aug 23 02:00:48.234: INFO: Pod "ebs-volume-tester-nm9m9" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Aug 23 02:00:48.299: INFO: deleting Pod "ebs-5493"/"ebs-volume-tester-nm9m9"
Aug 23 02:00:48.360: INFO: Pod ebs-volume-tester-nm9m9 has the following logs: /bin/sh: can't create /mnt/test-1/data: Read-only file system

STEP: Deleting pod ebs-volume-tester-nm9m9 in namespace ebs-5493
Aug 23 02:00:48.421: INFO: deleting PVC "ebs-5493"/"pvc-j854m"
... skipping 55 lines ...
Aug 23 02:00:37.872: INFO: PersistentVolumeClaim pvc-gf4k9 found but phase is Pending instead of Bound.
Aug 23 02:00:39.972: INFO: PersistentVolumeClaim pvc-gf4k9 found and phase=Bound (14.508232031s)
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 23 02:00:40.282: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-s8bpn" in namespace "ebs-9225" to be "success or failure"
Aug 23 02:00:40.396: INFO: Pod "ebs-volume-tester-s8bpn": Phase="Pending", Reason="", readiness=false. Elapsed: 114.313046ms
Aug 23 02:00:42.453: INFO: Pod "ebs-volume-tester-s8bpn": Phase="Pending", Reason="", readiness=false. Elapsed: 2.170480467s
Aug 23 02:00:44.509: INFO: Pod "ebs-volume-tester-s8bpn": Phase="Pending", Reason="", readiness=false. Elapsed: 4.226813842s
Aug 23 02:00:46.566: INFO: Pod "ebs-volume-tester-s8bpn": Phase="Pending", Reason="", readiness=false. Elapsed: 6.283629167s
Aug 23 02:00:48.624: INFO: Pod "ebs-volume-tester-s8bpn": Phase="Pending", Reason="", readiness=false. Elapsed: 8.341384436s
... skipping 59 lines ...
Aug 23 02:00:34.420: INFO: PersistentVolumeClaim pvc-w44lk found but phase is Pending instead of Bound.
Aug 23 02:00:36.478: INFO: PersistentVolumeClaim pvc-w44lk found and phase=Bound (12.505211473s)
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 23 02:00:36.665: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-sfqgt" in namespace "ebs-6827" to be "success or failure"
Aug 23 02:00:36.755: INFO: Pod "ebs-volume-tester-sfqgt": Phase="Pending", Reason="", readiness=false. Elapsed: 89.222349ms
Aug 23 02:00:38.835: INFO: Pod "ebs-volume-tester-sfqgt": Phase="Pending", Reason="", readiness=false. Elapsed: 2.16967177s
Aug 23 02:00:40.908: INFO: Pod "ebs-volume-tester-sfqgt": Phase="Pending", Reason="", readiness=false. Elapsed: 4.242309204s
Aug 23 02:00:42.965: INFO: Pod "ebs-volume-tester-sfqgt": Phase="Pending", Reason="", readiness=false. Elapsed: 6.299259674s
Aug 23 02:00:45.030: INFO: Pod "ebs-volume-tester-sfqgt": Phase="Pending", Reason="", readiness=false. Elapsed: 8.364992884s
... skipping 66 lines ...
Aug 23 02:00:41.079: INFO: PersistentVolumeClaim pvc-97r57 found but phase is Pending instead of Bound.
Aug 23 02:00:43.134: INFO: PersistentVolumeClaim pvc-97r57 found and phase=Bound (16.545137404s)
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 23 02:00:43.301: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-s9fdj" in namespace "ebs-1410" to be "success or failure"
Aug 23 02:00:43.390: INFO: Pod "ebs-volume-tester-s9fdj": Phase="Pending", Reason="", readiness=false. Elapsed: 89.714798ms
Aug 23 02:00:45.448: INFO: Pod "ebs-volume-tester-s9fdj": Phase="Pending", Reason="", readiness=false. Elapsed: 2.147251073s
Aug 23 02:00:47.517: INFO: Pod "ebs-volume-tester-s9fdj": Phase="Pending", Reason="", readiness=false. Elapsed: 4.216792846s
Aug 23 02:00:49.574: INFO: Pod "ebs-volume-tester-s9fdj": Phase="Pending", Reason="", readiness=false. Elapsed: 6.272994586s
Aug 23 02:00:51.685: INFO: Pod "ebs-volume-tester-s9fdj": Phase="Pending", Reason="", readiness=false. Elapsed: 8.384626886s
... skipping 64 lines ...
Aug 23 02:00:40.342: INFO: PersistentVolumeClaim pvc-vxnm8 found but phase is Pending instead of Bound.
Aug 23 02:00:42.399: INFO: PersistentVolumeClaim pvc-vxnm8 found and phase=Bound (16.560684873s)
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 23 02:00:42.587: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-h4ksh" in namespace "ebs-2741" to be "success or failure"
Aug 23 02:00:42.671: INFO: Pod "ebs-volume-tester-h4ksh": Phase="Pending", Reason="", readiness=false. Elapsed: 84.829598ms
Aug 23 02:00:44.734: INFO: Pod "ebs-volume-tester-h4ksh": Phase="Pending", Reason="", readiness=false. Elapsed: 2.147250393s
Aug 23 02:00:46.794: INFO: Pod "ebs-volume-tester-h4ksh": Phase="Pending", Reason="", readiness=false. Elapsed: 4.206978198s
Aug 23 02:00:48.851: INFO: Pod "ebs-volume-tester-h4ksh": Phase="Pending", Reason="", readiness=false. Elapsed: 6.264211407s
Aug 23 02:00:50.928: INFO: Pod "ebs-volume-tester-h4ksh": Phase="Pending", Reason="", readiness=false. Elapsed: 8.340962623s
... skipping 61 lines ...
Aug 23 02:00:57.143: INFO: PersistentVolumeClaim pvc-x7h6h found but phase is Pending instead of Bound.
Aug 23 02:00:59.202: INFO: PersistentVolumeClaim pvc-x7h6h found and phase=Bound (8.301033746s)
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 23 02:00:59.375: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-hmtch" in namespace "ebs-3934" to be "success or failure"
Aug 23 02:00:59.433: INFO: Pod "ebs-volume-tester-hmtch": Phase="Pending", Reason="", readiness=false. Elapsed: 58.176886ms
Aug 23 02:01:01.492: INFO: Pod "ebs-volume-tester-hmtch": Phase="Pending", Reason="", readiness=false. Elapsed: 2.116565127s
Aug 23 02:01:03.550: INFO: Pod "ebs-volume-tester-hmtch": Phase="Pending", Reason="", readiness=false. Elapsed: 4.174680596s
Aug 23 02:01:05.608: INFO: Pod "ebs-volume-tester-hmtch": Phase="Pending", Reason="", readiness=false. Elapsed: 6.233180503s
Aug 23 02:01:07.665: INFO: Pod "ebs-volume-tester-hmtch": Phase="Pending", Reason="", readiness=false. Elapsed: 8.290139452s
... skipping 64 lines ...
Aug 23 02:00:41.084: INFO: PersistentVolumeClaim pvc-f2kd2 found but phase is Pending instead of Bound.
Aug 23 02:00:43.138: INFO: PersistentVolumeClaim pvc-f2kd2 found and phase=Bound (16.497684214s)
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 23 02:00:43.317: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-w6mzh" in namespace "ebs-8943" to be "Error status code"
Aug 23 02:00:43.436: INFO: Pod "ebs-volume-tester-w6mzh": Phase="Pending", Reason="", readiness=false. Elapsed: 118.848761ms
Aug 23 02:00:45.655: INFO: Pod "ebs-volume-tester-w6mzh": Phase="Pending", Reason="", readiness=false. Elapsed: 2.337917172s
Aug 23 02:00:47.711: INFO: Pod "ebs-volume-tester-w6mzh": Phase="Pending", Reason="", readiness=false. Elapsed: 4.394473216s
Aug 23 02:00:49.767: INFO: Pod "ebs-volume-tester-w6mzh": Phase="Pending", Reason="", readiness=false. Elapsed: 6.450401s
Aug 23 02:00:51.822: INFO: Pod "ebs-volume-tester-w6mzh": Phase="Pending", Reason="", readiness=false. Elapsed: 8.505654393s
Aug 23 02:00:53.878: INFO: Pod "ebs-volume-tester-w6mzh": Phase="Pending", Reason="", readiness=false. Elapsed: 10.560874026s
... skipping 6 lines ...
Aug 23 02:01:08.275: INFO: Pod "ebs-volume-tester-w6mzh": Phase="Pending", Reason="", readiness=false. Elapsed: 24.95820247s
Aug 23 02:01:10.331: INFO: Pod "ebs-volume-tester-w6mzh": Phase="Pending", Reason="", readiness=false. Elapsed: 27.013814448s
Aug 23 02:01:12.387: INFO: Pod "ebs-volume-tester-w6mzh": Phase="Pending", Reason="", readiness=false. Elapsed: 29.070153269s
Aug 23 02:01:14.446: INFO: Pod "ebs-volume-tester-w6mzh": Phase="Pending", Reason="", readiness=false. Elapsed: 31.128786581s
Aug 23 02:01:16.538: INFO: Pod "ebs-volume-tester-w6mzh": Phase="Pending", Reason="", readiness=false. Elapsed: 33.22084142s
Aug 23 02:01:18.593: INFO: Pod "ebs-volume-tester-w6mzh": Phase="Pending", Reason="", readiness=false. Elapsed: 35.276487592s
Aug 23 02:01:20.651: INFO: Pod "ebs-volume-tester-w6mzh": Phase="Failed", Reason="", readiness=false. Elapsed: 37.334029056s
STEP: Saw pod failure
Aug 23 02:01:20.651: INFO: Pod "ebs-volume-tester-w6mzh" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Aug 23 02:01:20.715: INFO: deleting Pod "ebs-8943"/"ebs-volume-tester-w6mzh"
Aug 23 02:01:20.848: INFO: Pod ebs-volume-tester-w6mzh has the following logs: touch: /mnt/test-1/data: Read-only file system

STEP: Deleting pod ebs-volume-tester-w6mzh in namespace ebs-8943
Aug 23 02:01:20.930: INFO: deleting PVC "ebs-8943"/"pvc-f2kd2"
... skipping 49 lines ...
Aug 23 02:00:34.457: INFO: PersistentVolumeClaim pvc-ldm72 found but phase is Pending instead of Bound.
Aug 23 02:00:36.513: INFO: PersistentVolumeClaim pvc-ldm72 found and phase=Bound (12.49338195s)
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 23 02:00:36.698: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-jjqrk" in namespace "ebs-4694" to be "success or failure"
Aug 23 02:00:36.808: INFO: Pod "ebs-volume-tester-jjqrk": Phase="Pending", Reason="", readiness=false. Elapsed: 110.21331ms
Aug 23 02:00:38.876: INFO: Pod "ebs-volume-tester-jjqrk": Phase="Pending", Reason="", readiness=false. Elapsed: 2.17812709s
Aug 23 02:00:40.937: INFO: Pod "ebs-volume-tester-jjqrk": Phase="Pending", Reason="", readiness=false. Elapsed: 4.239828869s
Aug 23 02:00:42.994: INFO: Pod "ebs-volume-tester-jjqrk": Phase="Pending", Reason="", readiness=false. Elapsed: 6.296361365s
Aug 23 02:00:45.051: INFO: Pod "ebs-volume-tester-jjqrk": Phase="Pending", Reason="", readiness=false. Elapsed: 8.353404793s
... skipping 76 lines ...
Aug 23 02:00:32.006: INFO: PersistentVolumeClaim pvc-t2znz found but phase is Pending instead of Bound.
Aug 23 02:00:34.063: INFO: PersistentVolumeClaim pvc-t2znz found and phase=Bound (10.393756267s)
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 23 02:00:34.245: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-pqzbd" in namespace "ebs-2018" to be "success or failure"
Aug 23 02:00:34.311: INFO: Pod "ebs-volume-tester-pqzbd": Phase="Pending", Reason="", readiness=false. Elapsed: 65.903077ms
Aug 23 02:00:36.371: INFO: Pod "ebs-volume-tester-pqzbd": Phase="Pending", Reason="", readiness=false. Elapsed: 2.12591436s
Aug 23 02:00:38.427: INFO: Pod "ebs-volume-tester-pqzbd": Phase="Pending", Reason="", readiness=false. Elapsed: 4.181920038s
Aug 23 02:00:40.495: INFO: Pod "ebs-volume-tester-pqzbd": Phase="Pending", Reason="", readiness=false. Elapsed: 6.249841803s
Aug 23 02:00:42.551: INFO: Pod "ebs-volume-tester-pqzbd": Phase="Pending", Reason="", readiness=false. Elapsed: 8.306285075s
... skipping 94 lines ...
Aug 23 02:00:49.801: INFO: PersistentVolumeClaim pvc-pcqft found but phase is Pending instead of Bound.
Aug 23 02:00:51.862: INFO: PersistentVolumeClaim pvc-pcqft found and phase=Bound (8.470238592s)
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 23 02:00:52.053: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-9jcvz" in namespace "ebs-9037" to be "success or failure"
Aug 23 02:00:52.143: INFO: Pod "ebs-volume-tester-9jcvz": Phase="Pending", Reason="", readiness=false. Elapsed: 90.353849ms
Aug 23 02:00:54.204: INFO: Pod "ebs-volume-tester-9jcvz": Phase="Pending", Reason="", readiness=false. Elapsed: 2.151290341s
Aug 23 02:00:56.267: INFO: Pod "ebs-volume-tester-9jcvz": Phase="Pending", Reason="", readiness=false. Elapsed: 4.21357206s
Aug 23 02:00:58.327: INFO: Pod "ebs-volume-tester-9jcvz": Phase="Pending", Reason="", readiness=false. Elapsed: 6.27381134s
Aug 23 02:01:00.388: INFO: Pod "ebs-volume-tester-9jcvz": Phase="Pending", Reason="", readiness=false. Elapsed: 8.334547307s
... skipping 74 lines ...
Aug 23 02:00:31.883: INFO: Waiting up to 5m0s for PersistentVolumeClaims [pvc-fdhqf] to have phase Bound
Aug 23 02:00:31.937: INFO: PersistentVolumeClaim pvc-fdhqf found and phase=Bound (54.870685ms)
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 23 02:00:32.119: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-qm4wp" in namespace "ebs-3659" to be "success or failure"
Aug 23 02:00:32.195: INFO: Pod "ebs-volume-tester-qm4wp": Phase="Pending", Reason="", readiness=false. Elapsed: 76.174253ms
Aug 23 02:00:34.267: INFO: Pod "ebs-volume-tester-qm4wp": Phase="Pending", Reason="", readiness=false. Elapsed: 2.148647326s
Aug 23 02:00:36.327: INFO: Pod "ebs-volume-tester-qm4wp": Phase="Pending", Reason="", readiness=false. Elapsed: 4.208096671s
Aug 23 02:00:38.395: INFO: Pod "ebs-volume-tester-qm4wp": Phase="Pending", Reason="", readiness=false. Elapsed: 6.276731546s
Aug 23 02:00:40.479: INFO: Pod "ebs-volume-tester-qm4wp": Phase="Pending", Reason="", readiness=false. Elapsed: 8.36024624s
... skipping 84 lines ...
Aug 23 02:00:34.916: INFO: PersistentVolumeClaim pvc-vxvvd found but phase is Pending instead of Bound.
Aug 23 02:00:36.973: INFO: PersistentVolumeClaim pvc-vxvvd found and phase=Bound (12.527071468s)
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 23 02:00:37.217: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-jvlsm" in namespace "ebs-2971" to be "success or failure"
Aug 23 02:00:37.298: INFO: Pod "ebs-volume-tester-jvlsm": Phase="Pending", Reason="", readiness=false. Elapsed: 80.572782ms
Aug 23 02:00:39.360: INFO: Pod "ebs-volume-tester-jvlsm": Phase="Pending", Reason="", readiness=false. Elapsed: 2.142480108s
Aug 23 02:00:41.427: INFO: Pod "ebs-volume-tester-jvlsm": Phase="Pending", Reason="", readiness=false. Elapsed: 4.210240181s
Aug 23 02:00:43.484: INFO: Pod "ebs-volume-tester-jvlsm": Phase="Pending", Reason="", readiness=false. Elapsed: 6.266334851s
Aug 23 02:00:45.664: INFO: Pod "ebs-volume-tester-jvlsm": Phase="Pending", Reason="", readiness=false. Elapsed: 8.446492683s
... skipping 80 lines ...
Aug 23 02:00:35.076: INFO: PersistentVolumeClaim pvc-5bgf4 found but phase is Pending instead of Bound.
Aug 23 02:00:37.146: INFO: PersistentVolumeClaim pvc-5bgf4 found and phase=Bound (12.427927184s)
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 23 02:00:37.392: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-lch7k" in namespace "ebs-5867" to be "success or failure"
Aug 23 02:00:37.471: INFO: Pod "ebs-volume-tester-lch7k": Phase="Pending", Reason="", readiness=false. Elapsed: 79.454057ms
Aug 23 02:00:39.531: INFO: Pod "ebs-volume-tester-lch7k": Phase="Pending", Reason="", readiness=false. Elapsed: 2.139960901s
Aug 23 02:00:41.589: INFO: Pod "ebs-volume-tester-lch7k": Phase="Pending", Reason="", readiness=false. Elapsed: 4.197855023s
Aug 23 02:00:43.656: INFO: Pod "ebs-volume-tester-lch7k": Phase="Pending", Reason="", readiness=false. Elapsed: 6.264929822s
Aug 23 02:00:45.748: INFO: Pod "ebs-volume-tester-lch7k": Phase="Pending", Reason="", readiness=false. Elapsed: 8.356193154s
... skipping 82 lines ...
Aug 23 02:00:34.703: INFO: PersistentVolumeClaim pvc-c7x89 found but phase is Pending instead of Bound.
Aug 23 02:00:36.807: INFO: PersistentVolumeClaim pvc-c7x89 found and phase=Bound (12.481034137s)
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 23 02:00:36.983: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-55q4x" in namespace "ebs-6888" to be "success or failure"
Aug 23 02:00:37.054: INFO: Pod "ebs-volume-tester-55q4x": Phase="Pending", Reason="", readiness=false. Elapsed: 70.763248ms
Aug 23 02:00:39.110: INFO: Pod "ebs-volume-tester-55q4x": Phase="Pending", Reason="", readiness=false. Elapsed: 2.126590613s
Aug 23 02:00:41.165: INFO: Pod "ebs-volume-tester-55q4x": Phase="Pending", Reason="", readiness=false. Elapsed: 4.181756928s
Aug 23 02:00:43.221: INFO: Pod "ebs-volume-tester-55q4x": Phase="Pending", Reason="", readiness=false. Elapsed: 6.237564664s
Aug 23 02:00:45.279: INFO: Pod "ebs-volume-tester-55q4x": Phase="Pending", Reason="", readiness=false. Elapsed: 8.295809638s
... skipping 82 lines ...
Aug 23 02:00:38.126: INFO: PersistentVolumeClaim pvc-qvv7b found but phase is Pending instead of Bound.
Aug 23 02:00:40.281: INFO: PersistentVolumeClaim pvc-qvv7b found and phase=Bound (14.566098361s)
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 23 02:00:40.573: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-plzbr" in namespace "ebs-526" to be "success or failure"
Aug 23 02:00:40.628: INFO: Pod "ebs-volume-tester-plzbr": Phase="Pending", Reason="", readiness=false. Elapsed: 54.655695ms
Aug 23 02:00:42.689: INFO: Pod "ebs-volume-tester-plzbr": Phase="Pending", Reason="", readiness=false. Elapsed: 2.115335229s
Aug 23 02:00:44.744: INFO: Pod "ebs-volume-tester-plzbr": Phase="Pending", Reason="", readiness=false. Elapsed: 4.171215601s
Aug 23 02:00:46.812: INFO: Pod "ebs-volume-tester-plzbr": Phase="Pending", Reason="", readiness=false. Elapsed: 6.238664416s
Aug 23 02:00:48.867: INFO: Pod "ebs-volume-tester-plzbr": Phase="Pending", Reason="", readiness=false. Elapsed: 8.293955224s
... skipping 83 lines ...
Aug 23 02:00:40.622: INFO: PersistentVolumeClaim pvc-k8w2d found but phase is Pending instead of Bound.
Aug 23 02:00:42.689: INFO: PersistentVolumeClaim pvc-k8w2d found and phase=Bound (16.599862162s)
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 23 02:00:42.864: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-smjhq" in namespace "ebs-5075" to be "success or failure"
Aug 23 02:00:42.921: INFO: Pod "ebs-volume-tester-smjhq": Phase="Pending", Reason="", readiness=false. Elapsed: 57.494859ms
Aug 23 02:00:44.979: INFO: Pod "ebs-volume-tester-smjhq": Phase="Pending", Reason="", readiness=false. Elapsed: 2.114685435s
Aug 23 02:00:47.038: INFO: Pod "ebs-volume-tester-smjhq": Phase="Pending", Reason="", readiness=false. Elapsed: 4.174323515s
Aug 23 02:00:49.095: INFO: Pod "ebs-volume-tester-smjhq": Phase="Pending", Reason="", readiness=false. Elapsed: 6.230940552s
Aug 23 02:00:51.156: INFO: Pod "ebs-volume-tester-smjhq": Phase="Pending", Reason="", readiness=false. Elapsed: 8.291807592s
... skipping 82 lines ...
Aug 23 02:00:40.705: INFO: PersistentVolumeClaim pvc-c8b96 found but phase is Pending instead of Bound.
Aug 23 02:00:42.764: INFO: PersistentVolumeClaim pvc-c8b96 found and phase=Bound (16.593706444s)
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 23 02:00:42.951: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-j8df4" in namespace "ebs-4496" to be "success or failure"
Aug 23 02:00:43.009: INFO: Pod "ebs-volume-tester-j8df4": Phase="Pending", Reason="", readiness=false. Elapsed: 58.431529ms
Aug 23 02:00:45.068: INFO: Pod "ebs-volume-tester-j8df4": Phase="Pending", Reason="", readiness=false. Elapsed: 2.117052995s
Aug 23 02:00:47.140: INFO: Pod "ebs-volume-tester-j8df4": Phase="Pending", Reason="", readiness=false. Elapsed: 4.189144219s
Aug 23 02:00:49.199: INFO: Pod "ebs-volume-tester-j8df4": Phase="Pending", Reason="", readiness=false. Elapsed: 6.248129869s
Aug 23 02:00:51.263: INFO: Pod "ebs-volume-tester-j8df4": Phase="Pending", Reason="", readiness=false. Elapsed: 8.312007449s
... skipping 80 lines ...
Aug 23 02:00:35.031: INFO: PersistentVolumeClaim pvc-dbzvp found but phase is Pending instead of Bound.
Aug 23 02:00:37.105: INFO: PersistentVolumeClaim pvc-dbzvp found and phase=Bound (12.448051155s)
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 23 02:00:37.349: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-xjlp2" in namespace "ebs-9162" to be "success or failure"
Aug 23 02:00:37.432: INFO: Pod "ebs-volume-tester-xjlp2": Phase="Pending", Reason="", readiness=false. Elapsed: 82.988337ms
Aug 23 02:00:39.488: INFO: Pod "ebs-volume-tester-xjlp2": Phase="Pending", Reason="", readiness=false. Elapsed: 2.138986103s
Aug 23 02:00:41.545: INFO: Pod "ebs-volume-tester-xjlp2": Phase="Pending", Reason="", readiness=false. Elapsed: 4.196073303s
Aug 23 02:00:43.606: INFO: Pod "ebs-volume-tester-xjlp2": Phase="Pending", Reason="", readiness=false. Elapsed: 6.257523448s
Aug 23 02:00:45.683: INFO: Pod "ebs-volume-tester-xjlp2": Phase="Pending", Reason="", readiness=false. Elapsed: 8.334490962s
... skipping 82 lines ...
Aug 23 02:00:56.469: INFO: PersistentVolumeClaim pvc-4ftmq found but phase is Pending instead of Bound.
Aug 23 02:00:58.525: INFO: PersistentVolumeClaim pvc-4ftmq found and phase=Bound (8.283376523s)
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 23 02:00:58.697: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-tz49p" in namespace "ebs-2356" to be "success or failure"
Aug 23 02:00:58.755: INFO: Pod "ebs-volume-tester-tz49p": Phase="Pending", Reason="", readiness=false. Elapsed: 57.726837ms
Aug 23 02:01:00.820: INFO: Pod "ebs-volume-tester-tz49p": Phase="Pending", Reason="", readiness=false. Elapsed: 2.122674053s
Aug 23 02:01:02.877: INFO: Pod "ebs-volume-tester-tz49p": Phase="Pending", Reason="", readiness=false. Elapsed: 4.179715911s
Aug 23 02:01:04.937: INFO: Pod "ebs-volume-tester-tz49p": Phase="Pending", Reason="", readiness=false. Elapsed: 6.239567112s
Aug 23 02:01:06.993: INFO: Pod "ebs-volume-tester-tz49p": Phase="Pending", Reason="", readiness=false. Elapsed: 8.295471958s
... skipping 92 lines ...
Aug 23 02:00:49.801: INFO: PersistentVolumeClaim pvc-cbtrx found but phase is Pending instead of Bound.
Aug 23 02:00:51.862: INFO: PersistentVolumeClaim pvc-cbtrx found and phase=Bound (8.341748942s)
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 23 02:00:52.062: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-l2h8r" in namespace "ebs-2550" to be "success or failure"
Aug 23 02:00:52.142: INFO: Pod "ebs-volume-tester-l2h8r": Phase="Pending", Reason="", readiness=false. Elapsed: 80.482324ms
Aug 23 02:00:54.203: INFO: Pod "ebs-volume-tester-l2h8r": Phase="Pending", Reason="", readiness=false. Elapsed: 2.140992499s
Aug 23 02:00:56.264: INFO: Pod "ebs-volume-tester-l2h8r": Phase="Pending", Reason="", readiness=false. Elapsed: 4.202311442s
Aug 23 02:00:58.325: INFO: Pod "ebs-volume-tester-l2h8r": Phase="Pending", Reason="", readiness=false. Elapsed: 6.263425834s
Aug 23 02:01:00.387: INFO: Pod "ebs-volume-tester-l2h8r": Phase="Pending", Reason="", readiness=false. Elapsed: 8.325278555s
... skipping 163 lines ...
Aug 23 02:00:37.555: INFO: PersistentVolumeClaim pvc-fggvk found but phase is Pending instead of Bound.
Aug 23 02:00:39.613: INFO: PersistentVolumeClaim pvc-fggvk found and phase=Bound (14.530486846s)
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 23 02:00:39.789: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-hwxxx" in namespace "ebs-1203" to be "success or failure"
Aug 23 02:00:39.861: INFO: Pod "ebs-volume-tester-hwxxx": Phase="Pending", Reason="", readiness=false. Elapsed: 71.378496ms
Aug 23 02:00:41.918: INFO: Pod "ebs-volume-tester-hwxxx": Phase="Pending", Reason="", readiness=false. Elapsed: 2.128420218s
Aug 23 02:00:43.980: INFO: Pod "ebs-volume-tester-hwxxx": Phase="Pending", Reason="", readiness=false. Elapsed: 4.190184979s
Aug 23 02:00:46.037: INFO: Pod "ebs-volume-tester-hwxxx": Phase="Pending", Reason="", readiness=false. Elapsed: 6.248056588s
Aug 23 02:00:48.095: INFO: Pod "ebs-volume-tester-hwxxx": Phase="Pending", Reason="", readiness=false. Elapsed: 8.305625148s
... skipping 107 lines ...
Aug 23 02:00:37.643: INFO: PersistentVolumeClaim pvc-5fv5g found but phase is Pending instead of Bound.
Aug 23 02:00:39.698: INFO: PersistentVolumeClaim pvc-5fv5g found and phase=Bound (14.523931015s)
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 23 02:00:39.924: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-kr2lh" in namespace "ebs-2065" to be "success or failure"
Aug 23 02:00:39.991: INFO: Pod "ebs-volume-tester-kr2lh": Phase="Pending", Reason="", readiness=false. Elapsed: 67.249851ms
Aug 23 02:00:42.047: INFO: Pod "ebs-volume-tester-kr2lh": Phase="Pending", Reason="", readiness=false. Elapsed: 2.123053924s
Aug 23 02:00:44.104: INFO: Pod "ebs-volume-tester-kr2lh": Phase="Pending", Reason="", readiness=false. Elapsed: 4.180007816s
Aug 23 02:00:46.159: INFO: Pod "ebs-volume-tester-kr2lh": Phase="Pending", Reason="", readiness=false. Elapsed: 6.235454381s
Aug 23 02:00:48.215: INFO: Pod "ebs-volume-tester-kr2lh": Phase="Pending", Reason="", readiness=false. Elapsed: 8.291080009s
... skipping 29 lines ...
Aug 23 02:01:31.624: INFO: PersistentVolumeClaim pvc-dzxqg found but phase is Pending instead of Bound.
Aug 23 02:01:33.681: INFO: PersistentVolumeClaim pvc-dzxqg found and phase=Bound (8.299920935s)
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 23 02:01:33.849: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-x6sbw" in namespace "ebs-2065" to be "success or failure"
Aug 23 02:01:33.906: INFO: Pod "ebs-volume-tester-x6sbw": Phase="Pending", Reason="", readiness=false. Elapsed: 57.072792ms
Aug 23 02:01:35.963: INFO: Pod "ebs-volume-tester-x6sbw": Phase="Pending", Reason="", readiness=false. Elapsed: 2.113670988s
Aug 23 02:01:38.032: INFO: Pod "ebs-volume-tester-x6sbw": Phase="Pending", Reason="", readiness=false. Elapsed: 4.183028083s
Aug 23 02:01:40.091: INFO: Pod "ebs-volume-tester-x6sbw": Phase="Pending", Reason="", readiness=false. Elapsed: 6.241666415s
Aug 23 02:01:42.180: INFO: Pod "ebs-volume-tester-x6sbw": Phase="Pending", Reason="", readiness=false. Elapsed: 8.330403035s
... skipping 95 lines ...
Aug 23 02:00:35.345: INFO: PersistentVolumeClaim pvc-b64w2 found but phase is Pending instead of Bound.
Aug 23 02:00:37.433: INFO: PersistentVolumeClaim pvc-b64w2 found and phase=Bound (12.530256363s)
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 23 02:00:37.643: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-kb4rj" in namespace "ebs-7059" to be "success or failure"
Aug 23 02:00:37.726: INFO: Pod "ebs-volume-tester-kb4rj": Phase="Pending", Reason="", readiness=false. Elapsed: 82.641248ms
Aug 23 02:00:39.783: INFO: Pod "ebs-volume-tester-kb4rj": Phase="Pending", Reason="", readiness=false. Elapsed: 2.139368695s
Aug 23 02:00:41.842: INFO: Pod "ebs-volume-tester-kb4rj": Phase="Pending", Reason="", readiness=false. Elapsed: 4.199221033s
Aug 23 02:00:43.900: INFO: Pod "ebs-volume-tester-kb4rj": Phase="Pending", Reason="", readiness=false. Elapsed: 6.25678861s
Aug 23 02:00:45.959: INFO: Pod "ebs-volume-tester-kb4rj": Phase="Pending", Reason="", readiness=false. Elapsed: 8.315522544s
... skipping 18 lines ...
Aug 23 02:02:41.652: INFO: PersistentVolumeClaim pvc-6dt4b found but phase is Pending instead of Bound.
Aug 23 02:02:43.716: INFO: PersistentVolumeClaim pvc-6dt4b found and phase=Bound (8.29079305s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying a second pod with a volume restored from the snapshot
STEP: checking that the pods command exits with no error
Aug 23 02:02:43.887: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-znd57" in namespace "ebs-7059" to be "success or failure"
Aug 23 02:02:43.945: INFO: Pod "ebs-volume-tester-znd57": Phase="Pending", Reason="", readiness=false. Elapsed: 57.408639ms
Aug 23 02:02:46.002: INFO: Pod "ebs-volume-tester-znd57": Phase="Pending", Reason="", readiness=false. Elapsed: 2.114906859s
Aug 23 02:02:48.074: INFO: Pod "ebs-volume-tester-znd57": Phase="Pending", Reason="", readiness=false. Elapsed: 4.186948399s
Aug 23 02:02:50.132: INFO: Pod "ebs-volume-tester-znd57": Phase="Pending", Reason="", readiness=false. Elapsed: 6.244296127s
Aug 23 02:02:52.190: INFO: Pod "ebs-volume-tester-znd57": Phase="Pending", Reason="", readiness=false. Elapsed: 8.302296703s
... skipping 181 lines ...
Aug 23 02:00:37.727: INFO: PersistentVolumeClaim pvc-77qwc found but phase is Pending instead of Bound.
Aug 23 02:00:39.784: INFO: PersistentVolumeClaim pvc-77qwc found and phase=Bound (14.531620816s)
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 23 02:00:40.028: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-mlhw5" in namespace "ebs-7140" to be "success or failure"
Aug 23 02:00:40.128: INFO: Pod "ebs-volume-tester-mlhw5": Phase="Pending", Reason="", readiness=false. Elapsed: 99.598647ms
Aug 23 02:00:42.185: INFO: Pod "ebs-volume-tester-mlhw5": Phase="Pending", Reason="", readiness=false. Elapsed: 2.156914771s
Aug 23 02:00:44.243: INFO: Pod "ebs-volume-tester-mlhw5": Phase="Pending", Reason="", readiness=false. Elapsed: 4.214573867s
Aug 23 02:00:46.300: INFO: Pod "ebs-volume-tester-mlhw5": Phase="Pending", Reason="", readiness=false. Elapsed: 6.271697918s
Aug 23 02:00:48.358: INFO: Pod "ebs-volume-tester-mlhw5": Phase="Pending", Reason="", readiness=false. Elapsed: 8.32942745s
... skipping 151 lines ...
Aug 23 02:00:40.280: INFO: PersistentVolumeClaim pvc-xllvq found but phase is Pending instead of Bound.
Aug 23 02:00:42.335: INFO: PersistentVolumeClaim pvc-xllvq found and phase=Bound (16.594679281s)
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 23 02:00:42.505: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-cwqhn" in namespace "ebs-6448" to be "success or failure"
Aug 23 02:00:42.566: INFO: Pod "ebs-volume-tester-cwqhn": Phase="Pending", Reason="", readiness=false. Elapsed: 60.929631ms
Aug 23 02:00:44.623: INFO: Pod "ebs-volume-tester-cwqhn": Phase="Pending", Reason="", readiness=false. Elapsed: 2.117820085s
Aug 23 02:00:46.682: INFO: Pod "ebs-volume-tester-cwqhn": Phase="Pending", Reason="", readiness=false. Elapsed: 4.177189444s
Aug 23 02:00:48.738: INFO: Pod "ebs-volume-tester-cwqhn": Phase="Pending", Reason="", readiness=false. Elapsed: 6.232931539s
Aug 23 02:00:50.796: INFO: Pod "ebs-volume-tester-cwqhn": Phase="Pending", Reason="", readiness=false. Elapsed: 8.291457055s
... skipping 157 lines ...
Aug 23 02:00:37.829: INFO: PersistentVolumeClaim pvc-459j9 found but phase is Pending instead of Bound.
Aug 23 02:00:39.892: INFO: PersistentVolumeClaim pvc-459j9 found and phase=Bound (14.493647196s)
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 23 02:00:40.126: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-6zl65" in namespace "ebs-2950" to be "success or failure"
Aug 23 02:00:40.281: INFO: Pod "ebs-volume-tester-6zl65": Phase="Pending", Reason="", readiness=false. Elapsed: 155.208784ms
Aug 23 02:00:42.337: INFO: Pod "ebs-volume-tester-6zl65": Phase="Pending", Reason="", readiness=false. Elapsed: 2.210567436s
Aug 23 02:00:44.392: INFO: Pod "ebs-volume-tester-6zl65": Phase="Pending", Reason="", readiness=false. Elapsed: 4.26614662s
Aug 23 02:00:46.449: INFO: Pod "ebs-volume-tester-6zl65": Phase="Pending", Reason="", readiness=false. Elapsed: 6.322562031s
Aug 23 02:00:48.506: INFO: Pod "ebs-volume-tester-6zl65": Phase="Pending", Reason="", readiness=false. Elapsed: 8.379275862s
... skipping 246 lines ...
Aug 23 02:00:34.804: INFO: PersistentVolumeClaim pvc-qk2h4 found but phase is Pending instead of Bound.
Aug 23 02:00:36.869: INFO: PersistentVolumeClaim pvc-qk2h4 found and phase=Bound (12.444609713s)
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 23 02:00:37.103: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-r29qm" in namespace "ebs-3393" to be "success or failure"
Aug 23 02:00:37.183: INFO: Pod "ebs-volume-tester-r29qm": Phase="Pending", Reason="", readiness=false. Elapsed: 80.492845ms
Aug 23 02:00:39.240: INFO: Pod "ebs-volume-tester-r29qm": Phase="Pending", Reason="", readiness=false. Elapsed: 2.136520848s
Aug 23 02:00:41.298: INFO: Pod "ebs-volume-tester-r29qm": Phase="Pending", Reason="", readiness=false. Elapsed: 4.194749429s
Aug 23 02:00:43.392: INFO: Pod "ebs-volume-tester-r29qm": Phase="Pending", Reason="", readiness=false. Elapsed: 6.28849993s
Aug 23 02:00:45.450: INFO: Pod "ebs-volume-tester-r29qm": Phase="Pending", Reason="", readiness=false. Elapsed: 8.346533802s
... skipping 298 lines ...
Aug 23 02:00:32.290: INFO: PersistentVolumeClaim pvc-tl48v found but phase is Pending instead of Bound.
Aug 23 02:00:34.368: INFO: PersistentVolumeClaim pvc-tl48v found and phase=Bound (10.511844234s)
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 23 02:00:34.551: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-kn6b4" in namespace "ebs-4441" to be "success or failure"
Aug 23 02:00:34.607: INFO: Pod "ebs-volume-tester-kn6b4": Phase="Pending", Reason="", readiness=false. Elapsed: 55.962686ms
Aug 23 02:00:36.669: INFO: Pod "ebs-volume-tester-kn6b4": Phase="Pending", Reason="", readiness=false. Elapsed: 2.117528787s
Aug 23 02:00:38.732: INFO: Pod "ebs-volume-tester-kn6b4": Phase="Pending", Reason="", readiness=false. Elapsed: 4.180181959s
Aug 23 02:00:40.788: INFO: Pod "ebs-volume-tester-kn6b4": Phase="Pending", Reason="", readiness=false. Elapsed: 6.236689547s
Aug 23 02:00:42.850: INFO: Pod "ebs-volume-tester-kn6b4": Phase="Pending", Reason="", readiness=false. Elapsed: 8.298044667s
... skipping 359 lines ...
Aug 23 02:00:41.093: INFO: PersistentVolumeClaim pvc-q77ms found but phase is Pending instead of Bound.
Aug 23 02:00:43.154: INFO: PersistentVolumeClaim pvc-q77ms found and phase=Bound (16.538615075s)
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 23 02:00:43.358: INFO: Waiting up to 15m0s for pod "ebs-volume-tester-jn56l" in namespace "ebs-4573" to be "success or failure"
Aug 23 02:00:43.440: INFO: Pod "ebs-volume-tester-jn56l": Phase="Pending", Reason="", readiness=false. Elapsed: 81.128185ms
Aug 23 02:00:45.667: INFO: Pod "ebs-volume-tester-jn56l": Phase="Pending", Reason="", readiness=false. Elapsed: 2.308771761s
Aug 23 02:00:47.741: INFO: Pod "ebs-volume-tester-jn56l": Phase="Pending", Reason="", readiness=false. Elapsed: 4.38248507s
Aug 23 02:00:49.803: INFO: Pod "ebs-volume-tester-jn56l": Phase="Pending", Reason="", readiness=false. Elapsed: 6.444054559s
Aug 23 02:00:51.880: INFO: Pod "ebs-volume-tester-jn56l": Phase="Pending", Reason="", readiness=false. Elapsed: 8.521969805s
... skipping 426 lines ...
Aug 23 02:15:32.685: INFO: Pod "ebs-volume-tester-jn56l": Phase="Pending", Reason="", readiness=false. Elapsed: 14m49.32623563s
Aug 23 02:15:34.741: INFO: Pod "ebs-volume-tester-jn56l": Phase="Pending", Reason="", readiness=false. Elapsed: 14m51.382183967s
Aug 23 02:15:36.796: INFO: Pod "ebs-volume-tester-jn56l": Phase="Pending", Reason="", readiness=false. Elapsed: 14m53.437951225s
Aug 23 02:15:38.859: INFO: Pod "ebs-volume-tester-jn56l": Phase="Pending", Reason="", readiness=false. Elapsed: 14m55.50095366s
Aug 23 02:15:40.916: INFO: Pod "ebs-volume-tester-jn56l": Phase="Pending", Reason="", readiness=false. Elapsed: 14m57.557379323s
Aug 23 02:15:42.972: INFO: Pod "ebs-volume-tester-jn56l": Phase="Pending", Reason="", readiness=false. Elapsed: 14m59.613644589s
Aug 23 02:15:44.973: INFO: Unexpected error occurred: Gave up after waiting 15m0s for pod "ebs-volume-tester-jn56l" to be "success or failure"
Aug 23 02:15:44.974: INFO: deleting Pod "ebs-4573"/"ebs-volume-tester-jn56l"
Aug 23 02:15:45.032: INFO: Error getting logs for pod ebs-volume-tester-jn56l: the server rejected our request for an unknown reason (get pods ebs-volume-tester-jn56l)
STEP: Deleting pod ebs-volume-tester-jn56l in namespace ebs-4573
Aug 23 02:15:45.091: INFO: deleting PVC "ebs-4573"/"pvc-q77ms"
Aug 23 02:15:45.091: INFO: Deleting PersistentVolumeClaim "pvc-q77ms"
STEP: waiting for claim's PV "pvc-778b98fa-5960-4747-9b7b-f96e0850f5e0" to be deleted
Aug 23 02:15:45.151: INFO: Waiting up to 10m0s for PersistentVolume pvc-778b98fa-5960-4747-9b7b-f96e0850f5e0 to get deleted
Aug 23 02:15:45.210: INFO: PersistentVolume pvc-778b98fa-5960-4747-9b7b-f96e0850f5e0 found and phase=Bound (59.75492ms)
... skipping 112 lines ...
Aug 23 02:25:17.091: INFO: PersistentVolume pvc-778b98fa-5960-4747-9b7b-f96e0850f5e0 found and phase=Released (9m31.940673235s)
Aug 23 02:25:22.148: INFO: PersistentVolume pvc-778b98fa-5960-4747-9b7b-f96e0850f5e0 found and phase=Released (9m36.997293841s)
Aug 23 02:25:27.206: INFO: PersistentVolume pvc-778b98fa-5960-4747-9b7b-f96e0850f5e0 found and phase=Released (9m42.054934696s)
Aug 23 02:25:32.263: INFO: PersistentVolume pvc-778b98fa-5960-4747-9b7b-f96e0850f5e0 found and phase=Released (9m47.111841238s)
Aug 23 02:25:37.320: INFO: PersistentVolume pvc-778b98fa-5960-4747-9b7b-f96e0850f5e0 found and phase=Released (9m52.169617428s)
Aug 23 02:25:42.379: INFO: PersistentVolume pvc-778b98fa-5960-4747-9b7b-f96e0850f5e0 found and phase=Released (9m57.228546425s)
Aug 23 02:25:47.380: INFO: Unexpected error occurred: PersistentVolume pvc-778b98fa-5960-4747-9b7b-f96e0850f5e0 still exists within 10m0s
Aug 23 02:25:47.381: INFO: deleting StorageClass ebs-4573-ebs.csi.aws.com-dynamic-sc-nsvd2
[AfterEach] [ebs-csi-e2e] [single-az] Dynamic Provisioning
  /home/prow/go/pkg/mod/k8s.io/kubernetes@v1.15.2/test/e2e/framework/framework.go:151
STEP: Collecting events from namespace "ebs-4573".
STEP: Found 7 events.
Aug 23 02:25:47.503: INFO: At 2019-08-23 02:00:26 +0000 UTC - event for pvc-q77ms: {persistentvolume-controller } ExternalProvisioning: waiting for a volume to be created, either by external provisioner "ebs.csi.aws.com" or manually created by system administrator
Aug 23 02:25:47.503: INFO: At 2019-08-23 02:00:30 +0000 UTC - event for pvc-q77ms: {ebs.csi.aws.com_ebs-csi-controller-0_760cc385-c549-11e9-be2e-f2ee5a795260 } Provisioning: External provisioner is provisioning volume for claim "ebs-4573/pvc-q77ms"
Aug 23 02:25:47.503: INFO: At 2019-08-23 02:00:43 +0000 UTC - event for ebs-volume-tester-jn56l: {default-scheduler } Scheduled: Successfully assigned ebs-4573/ebs-volume-tester-jn56l to ip-172-20-56-84.us-west-2.compute.internal
Aug 23 02:25:47.503: INFO: At 2019-08-23 02:00:50 +0000 UTC - event for ebs-volume-tester-jn56l: {attachdetach-controller } SuccessfulAttachVolume: AttachVolume.Attach succeeded for volume "pvc-778b98fa-5960-4747-9b7b-f96e0850f5e0" 
Aug 23 02:25:47.503: INFO: At 2019-08-23 02:00:51 +0000 UTC - event for ebs-volume-tester-jn56l: {kubelet ip-172-20-56-84.us-west-2.compute.internal} FailedMapVolume: MapVolume.SetUp failed for volume "pvc-778b98fa-5960-4747-9b7b-f96e0850f5e0" : rpc error: code = Internal desc = Failed to find device path /dev/xvdbk. nvme path "/dev/disk/by-id/nvme-Amazon_Elastic_Block_Store_vol0e172b260f9491af4" not found
Aug 23 02:25:47.503: INFO: At 2019-08-23 02:02:46 +0000 UTC - event for ebs-volume-tester-jn56l: {kubelet ip-172-20-56-84.us-west-2.compute.internal} FailedMount: Unable to mount volumes for pod "ebs-volume-tester-jn56l_ebs-4573(e492fd07-4943-4fe2-b014-758ac98b4dfb)": timeout expired waiting for volumes to attach or mount for pod "ebs-4573"/"ebs-volume-tester-jn56l". list of unmounted volumes=[test-block-volume-1]. list of unattached volumes=[test-block-volume-1 default-token-m479s]
Aug 23 02:25:47.503: INFO: At 2019-08-23 02:16:30 +0000 UTC - event for ebs-volume-tester-jn56l: {kubelet ip-172-20-56-84.us-west-2.compute.internal} FailedMount: Unable to mount volumes for pod "ebs-volume-tester-jn56l_ebs-4573(e492fd07-4943-4fe2-b014-758ac98b4dfb)": timeout expired waiting for volumes to attach or mount for pod "ebs-4573"/"ebs-volume-tester-jn56l". list of unmounted volumes=[test-block-volume-1 default-token-m479s]. list of unattached volumes=[test-block-volume-1 default-token-m479s]
Aug 23 02:25:47.558: INFO: POD  NODE  PHASE  GRACE  CONDITIONS
Aug 23 02:25:47.559: INFO: 
Aug 23 02:25:47.730: INFO: 
Logging node info for node ip-172-20-35-88.us-west-2.compute.internal
... skipping 91 lines ...
• Failure [1534.370 seconds]
[ebs-csi-e2e] [single-az] Dynamic Provisioning
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/dynamic_provisioning.go:40
  should create a raw block volume on demand [It]
  /home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/dynamic_provisioning.go:209

  Expected error:
      <*errors.errorString | 0xc00001b2e0>: {
          s: "Gave up after waiting 15m0s for pod \"ebs-volume-tester-jn56l\" to be \"success or failure\"",
      }
      Gave up after waiting 15m0s for pod "ebs-volume-tester-jn56l" to be "success or failure"
  not to have occurred

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


Summarizing 1 Failure:

[Fail] [ebs-csi-e2e] [single-az] Dynamic Provisioning [It] should create a raw block volume on demand 
/home/prow/go/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/testsuites/testsuites.go:512

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


Ginkgo ran 1 suite in 27m5.077717411s
Test Suite Failed
Removing driver
release "aws-ebs-csi-driver" deleted
Deleting cluster test-cluster-24850
TYPE			NAME											ID
autoscaling-config	master-us-west-2a.masters.test-cluster-24850.k8s.local-20190823015057			master-us-west-2a.masters.test-cluster-24850.k8s.local-20190823015057
autoscaling-config	nodes.test-cluster-24850.k8s.local-20190823015057					nodes.test-cluster-24850.k8s.local-20190823015057
... skipping 106 lines ...
route-table:rtb-0f6fc8b9eace9200e	ok
vpc:vpc-06a43bdadd767cb65	ok
dhcp-options:dopt-06b30041ceaac2b27	ok
Deleted kubectl config for test-cluster-24850.k8s.local

Deleted cluster: "test-cluster-24850.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/23 02:26:57 Cleaning up Docker data root...
[Barnacle] 2019/08/23 02:26:57 Removing all containers.
... skipping 25 lines ...