Skip to content

Instantly share code, notes, and snippets.

@leakingtapan

leakingtapan/gist:a268ce078a4c82506082b21f49d81d1f

Last active January 22, 2019 21:41
Show Gist options
  • Save leakingtapan/a268ce078a4c82506082b21f49d81d1f to your computer and use it in GitHub Desktop.
Save leakingtapan/a268ce078a4c82506082b21f49d81d1f to your computer and use it in GitHub Desktop.
Download ZIP
[ebs-csi-e2e] [single-az] Dynamic Provisioning
Raw
gistfile1.txt
[BeforeEach] [ebs-csi-e2e] [single-az] Dynamic Provisioning
/home/ubuntu/ebs-csi/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/dynamic_provisioning.go:40
[It] should create multiple PV objects, bind to PVCs and attach all to a single pod
/home/ubuntu/ebs-csi/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/dynamic_provisioning.go:78
STEP: setting up the StorageClass
STEP: creating a StorageClass
STEP: setting up the PVC and PV
STEP: creating a PVC
Jan 20 22:41:55.957: INFO: Waiting up to 5m0s for PersistentVolumeClaims [pvc-gcs9k] to have phase Bound
Jan 20 22:41:56.035: INFO: PersistentVolumeClaim pvc-gcs9k found but phase is Pending instead of Bound.
Jan 20 22:41:58.114: INFO: PersistentVolumeClaim pvc-gcs9k found but phase is Pending instead of Bound.
Jan 20 22:42:00.193: INFO: PersistentVolumeClaim pvc-gcs9k found but phase is Pending instead of Bound.
Jan 20 22:42:02.272: INFO: PersistentVolumeClaim pvc-gcs9k found but phase is Pending instead of Bound.
Jan 20 22:42:04.350: INFO: PersistentVolumeClaim pvc-gcs9k found and phase=Bound (8.393735667s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: setting up the StorageClass
STEP: creating a StorageClass
STEP: setting up the PVC and PV
STEP: creating a PVC
Jan 20 22:42:04.673: INFO: Waiting up to 5m0s for PersistentVolumeClaims [pvc-m985q] to have phase Bound
Jan 20 22:42:04.763: INFO: PersistentVolumeClaim pvc-m985q found but phase is Pending instead of Bound.
Jan 20 22:42:06.842: INFO: PersistentVolumeClaim pvc-m985q found but phase is Pending instead of Bound.
Jan 20 22:42:08.922: INFO: PersistentVolumeClaim pvc-m985q found but phase is Pending instead of Bound.
Jan 20 22:42:11.002: INFO: PersistentVolumeClaim pvc-m985q found but phase is Pending instead of Bound.
Jan 20 22:42:13.084: INFO: PersistentVolumeClaim pvc-m985q found and phase=Bound (8.411045675s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod and checking that it's command exits with no error
Jan 20 22:42:13.314: INFO: Waiting up to 15m0s for pod "pvc-volume-tester-vtq5p" in namespace "e2e-tests-ebs-s4bsz" to be "success or failure"
Jan 20 22:42:13.390: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 76.485295ms
Jan 20 22:42:15.499: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 2.185081977s
Jan 20 22:42:17.576: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 4.261935025s
Jan 20 22:42:19.660: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 6.34621536s
Jan 20 22:42:21.739: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 8.42529866s
Jan 20 22:42:23.818: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 10.504583798s
Jan 20 22:42:25.898: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 12.583742997s
Jan 20 22:42:27.977: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14.66277322s
Jan 20 22:42:30.058: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 16.744128193s
Jan 20 22:42:32.137: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 18.823556134s
Jan 20 22:42:34.218: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 20.90466707s
Jan 20 22:42:36.298: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 22.983839048s
Jan 20 22:42:38.377: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 25.063096551s
Jan 20 22:42:40.457: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 27.143227792s
Jan 20 22:42:42.536: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 29.222519385s
Jan 20 22:42:44.615: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 31.301306062s
Jan 20 22:42:46.694: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 33.380345554s
Jan 20 22:42:48.786: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 35.471931441s
Jan 20 22:42:50.865: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 37.550877687s
Jan 20 22:42:52.944: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 39.62989727s
Jan 20 22:42:55.023: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 41.708952182s
Jan 20 22:42:57.102: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 43.788353383s
Jan 20 22:42:59.181: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 45.867584832s
Jan 20 22:43:01.266: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 47.951936009s
Jan 20 22:43:03.345: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 50.030811225s
Jan 20 22:43:05.424: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 52.110158633s
Jan 20 22:43:07.515: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 54.201220834s
Jan 20 22:43:09.594: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 56.280643827s
Jan 20 22:43:11.674: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 58.359801913s
Jan 20 22:43:13.753: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m0.439124483s
Jan 20 22:43:15.832: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m2.518277437s
Jan 20 22:43:17.911: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m4.597378205s
Jan 20 22:43:19.990: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m6.676386571s
Jan 20 22:43:22.069: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m8.755558693s
Jan 20 22:43:24.149: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m10.834890632s
Jan 20 22:43:26.228: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m12.91400191s
Jan 20 22:43:28.307: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m14.993105649s
Jan 20 22:43:30.390: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m17.076695134s
Jan 20 22:43:32.474: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m19.160277482s
Jan 20 22:43:34.553: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m21.239258941s
Jan 20 22:43:36.640: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m23.326702375s
Jan 20 22:43:38.719: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m25.405655974s
Jan 20 22:43:40.799: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m27.484824833s
Jan 20 22:43:42.877: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m29.563404317s
Jan 20 22:43:44.974: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m31.65991066s
Jan 20 22:43:47.055: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m33.740949445s
Jan 20 22:43:49.136: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m35.822221371s
Jan 20 22:43:51.213: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m37.898735869s
Jan 20 22:43:53.289: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 1m39.975146651s
...
Jan 20 22:55:15.236: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m1.921800614s
Jan 20 22:55:17.313: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m3.999030596s
Jan 20 22:55:19.390: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m6.075961587s
Jan 20 22:55:21.467: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m8.153293799s
Jan 20 22:55:23.544: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m10.230435177s
Jan 20 22:55:25.621: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m12.307433261s
Jan 20 22:55:27.712: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m14.397990725s
Jan 20 22:55:29.789: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m16.475013858s
Jan 20 22:55:31.899: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m18.585257421s
Jan 20 22:55:33.984: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m20.670425736s
Jan 20 22:55:36.077: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m22.763660986s
Jan 20 22:55:38.154: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m24.840347135s
Jan 20 22:55:40.231: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m26.917186911s
Jan 20 22:55:42.308: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m28.994142704s
Jan 20 22:55:44.385: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m31.070921965s
Jan 20 22:55:46.461: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m33.147592038s
Jan 20 22:55:48.538: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m35.22443031s
Jan 20 22:55:50.616: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m37.301829382s
Jan 20 22:55:52.693: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m39.378729688s
Jan 20 22:55:54.769: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m41.455455837s
Jan 20 22:55:56.846: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m43.532500943s
Jan 20 22:55:58.923: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m45.60959448s
Jan 20 22:56:01.000: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m47.686582955s
Jan 20 22:56:03.077: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m49.763693438s
Jan 20 22:56:05.154: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m51.840537822s
Jan 20 22:56:07.231: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m53.917423639s
Jan 20 22:56:09.310: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m55.99655675s
Jan 20 22:56:11.387: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 13m58.073503517s
Jan 20 22:56:13.468: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m0.154703038s
Jan 20 22:56:15.545: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m2.231369333s
Jan 20 22:56:17.625: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m4.311180876s
Jan 20 22:56:19.702: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m6.388062612s
Jan 20 22:56:21.791: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m8.477523712s
Jan 20 22:56:23.868: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m10.554328608s
Jan 20 22:56:25.945: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m12.631180462s
Jan 20 22:56:28.022: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m14.708011708s
Jan 20 22:56:30.100: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m16.785951276s
Jan 20 22:56:32.176: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m18.862283201s
Jan 20 22:56:34.253: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m20.938838167s
Jan 20 22:56:36.329: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m23.015693173s
Jan 20 22:56:38.415: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m25.101670305s
Jan 20 22:56:40.492: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m27.178320204s
Jan 20 22:56:42.570: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m29.2557841s
Jan 20 22:56:44.646: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m31.332630159s
Jan 20 22:56:46.723: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m33.409565766s
Jan 20 22:56:48.800: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m35.486286124s
Jan 20 22:56:50.877: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m37.563322961s
Jan 20 22:56:52.954: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m39.640499445s
Jan 20 22:56:55.031: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m41.71740012s
Jan 20 22:56:57.108: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m43.794370231s
Jan 20 22:56:59.185: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m45.871129401s
Jan 20 22:57:01.269: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m47.955668845s
Jan 20 22:57:03.346: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m50.032479466s
Jan 20 22:57:05.423: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m52.109348177s
Jan 20 22:57:07.500: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m54.186042153s
Jan 20 22:57:09.580: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m56.265892955s
Jan 20 22:57:11.657: INFO: Pod "pvc-volume-tester-vtq5p": Phase="Pending", Reason="", readiness=false. Elapsed: 14m58.34339507s
Jan 20 22:57:13.657: INFO: Unexpected error occurred: Gave up after waiting 15m0s for pod "pvc-volume-tester-vtq5p" to be "success or failure"
Jan 20 22:57:13.658: INFO: deleting PVC "e2e-tests-ebs-s4bsz"/"pvc-m985q"
Jan 20 22:57:13.658: INFO: Deleting PersistentVolumeClaim "pvc-m985q"
STEP: deleting the claim's PV "pvc-73d23d3c-1d04-11e9-8af7-0aa7dec3d40a"
Jan 20 22:57:13.736: INFO: Waiting up to 10m0s for PersistentVolume pvc-73d23d3c-1d04-11e9-8af7-0aa7dec3d40a to get deleted
Jan 20 22:57:13.841: INFO: PersistentVolume pvc-73d23d3c-1d04-11e9-8af7-0aa7dec3d40a found and phase=Released (105.161577ms)
Jan 20 22:57:18.919: INFO: PersistentVolume pvc-73d23d3c-1d04-11e9-8af7-0aa7dec3d40a was removed
Jan 20 22:57:18.919: INFO: deleting StorageClass e2e-tests-ebs-s4bsz-ebs.csi.aws.com-sc-n72bd
Jan 20 22:57:18.998: INFO: deleting PVC "e2e-tests-ebs-s4bsz"/"pvc-gcs9k"
Jan 20 22:57:18.998: INFO: Deleting PersistentVolumeClaim "pvc-gcs9k"
STEP: deleting the claim's PV "pvc-6e9f09fb-1d04-11e9-8af7-0aa7dec3d40a"
Jan 20 22:57:19.076: INFO: Waiting up to 10m0s for PersistentVolume pvc-6e9f09fb-1d04-11e9-8af7-0aa7dec3d40a to get deleted
Jan 20 22:57:19.152: INFO: PersistentVolume pvc-6e9f09fb-1d04-11e9-8af7-0aa7dec3d40a found and phase=Released (75.896496ms)
Jan 20 22:57:24.229: INFO: PersistentVolume pvc-6e9f09fb-1d04-11e9-8af7-0aa7dec3d40a was removed
Jan 20 22:57:24.229: INFO: deleting StorageClass e2e-tests-ebs-s4bsz-ebs.csi.aws.com-sc-h64zp
[AfterEach] [ebs-csi-e2e] [single-az] Dynamic Provisioning
/home/ubuntu/ebs-csi/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:154
STEP: Collecting events from namespace "e2e-tests-ebs-s4bsz".
STEP: Found 8 events.
Jan 20 22:57:24.384: INFO: At 2019-01-20 22:40:48 +0000 UTC - event for pvc-gcs9k: {persistentvolume-controller } ExternalProvisioning: waiting for a volume to be created, either by external provisioner "ebs.csi.aws.com" or manually created by system administrator
Jan 20 22:57:24.384: INFO: At 2019-01-20 22:40:48 +0000 UTC - event for pvc-gcs9k: {ebs.csi.aws.com_ebs-controller-0_4d70d76f-1d04-11e9-8059-0a5864600313 } Provisioning: External provisioner is provisioning volume for claim "e2e-tests-ebs-s4bsz/pvc-gcs9k"
Jan 20 22:57:24.384: INFO: At 2019-01-20 22:40:54 +0000 UTC - event for pvc-gcs9k: {ebs.csi.aws.com_ebs-controller-0_4d70d76f-1d04-11e9-8059-0a5864600313 } ProvisioningSucceeded: Successfully provisioned volume pvc-6e9f09fb-1d04-11e9-8af7-0aa7dec3d40a
Jan 20 22:57:24.384: INFO: At 2019-01-20 22:40:57 +0000 UTC - event for pvc-m985q: {persistentvolume-controller } ExternalProvisioning: waiting for a volume to be created, either by external provisioner "ebs.csi.aws.com" or manually created by system administrator
Jan 20 22:57:24.384: INFO: At 2019-01-20 22:40:57 +0000 UTC - event for pvc-m985q: {ebs.csi.aws.com_ebs-controller-0_4d70d76f-1d04-11e9-8059-0a5864600313 } Provisioning: External provisioner is provisioning volume for claim "e2e-tests-ebs-s4bsz/pvc-m985q"
Jan 20 22:57:24.384: INFO: At 2019-01-20 22:41:03 +0000 UTC - event for pvc-m985q: {ebs.csi.aws.com_ebs-controller-0_4d70d76f-1d04-11e9-8059-0a5864600313 } ProvisioningSucceeded: Successfully provisioned volume pvc-73d23d3c-1d04-11e9-8af7-0aa7dec3d40a
Jan 20 22:57:24.384: INFO: At 2019-01-20 22:41:05 +0000 UTC - event for pvc-volume-tester-vtq5p: {default-scheduler } FailedScheduling: 0/4 nodes are available: 1 node(s) had taints that the pod didn't tolerate, 3 node(s) had volume node affinity conflict.
Jan 20 22:57:24.384: INFO: At 2019-01-20 22:56:06 +0000 UTC - event for pvc-volume-tester-vtq5p: {default-scheduler } FailedScheduling: persistentvolumeclaim "pvc-m985q" is being deleted
Jan 20 22:57:24.688: INFO: POD NODE PHASE GRACE CONDITIONS
Jan 20 22:57:24.688: INFO: pvc-volume-tester-vtq5p Pending [{PodScheduled False 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:41:05 +0000 UTC Unschedulable persistentvolumeclaim "pvc-gcs9k" not found}]
Jan 20 22:57:24.688: INFO: dns-controller-64db5996cd-grxlk ip-172-20-35-66.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:10:47 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:10:57 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:10:57 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:10:46 +0000 UTC }]
Jan 20 22:57:24.688: INFO: ebs-controller-0 ip-172-20-100-7.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:39:50 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:39:52 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:39:52 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:39:50 +0000 UTC }]
Jan 20 22:57:24.688: INFO: ebs-node-92z49 ip-172-20-75-243.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:39:50 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:39:52 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:39:52 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:39:50 +0000 UTC }]
Jan 20 22:57:24.688: INFO: ebs-node-rfptb ip-172-20-100-7.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:39:50 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:39:52 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:39:52 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:39:50 +0000 UTC }]
Jan 20 22:57:24.688: INFO: ebs-node-xr87t ip-172-20-61-249.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:39:50 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:39:51 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:39:51 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-20 22:39:50 +0000 UTC }]
Jan 20 22:57:24.688: INFO: etcd-manager-events-ip-172-20-35-66.ec2.internal ip-172-20-35-66.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:06:26 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:07:37 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:07:37 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:06:26 +0000 UTC }]
Jan 20 22:57:24.688: INFO: etcd-manager-main-ip-172-20-35-66.ec2.internal ip-172-20-35-66.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:06:26 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:07:12 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:07:12 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:06:26 +0000 UTC }]
Jan 20 22:57:24.688: INFO: kube-apiserver-ip-172-20-35-66.ec2.internal ip-172-20-35-66.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:06:26 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:08:43 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:08:43 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:06:26 +0000 UTC }]
Jan 20 22:57:24.688: INFO: kube-controller-manager-ip-172-20-35-66.ec2.internal ip-172-20-35-66.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:06:27 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:07:22 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:07:22 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:06:27 +0000 UTC }]
Jan 20 22:57:24.688: INFO: kube-dns-859d8bc8bb-2pw8x ip-172-20-61-249.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:11:58 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:12:10 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:12:10 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:11:58 +0000 UTC }]
Jan 20 22:57:24.688: INFO: kube-dns-859d8bc8bb-tnhss ip-172-20-61-249.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:12:03 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:12:17 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:12:17 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:12:03 +0000 UTC }]
Jan 20 22:57:24.688: INFO: kube-dns-autoscaler-779f597664-5mc5r ip-172-20-61-249.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:11:58 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:12:03 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:12:03 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:11:58 +0000 UTC }]
Jan 20 22:57:24.688: INFO: kube-proxy-ip-172-20-100-7.ec2.internal ip-172-20-100-7.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:05:43 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:05:49 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:05:49 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:05:43 +0000 UTC }]
Jan 20 22:57:24.688: INFO: kube-proxy-ip-172-20-35-66.ec2.internal ip-172-20-35-66.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:06:27 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:07:30 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:07:30 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:06:27 +0000 UTC }]
Jan 20 22:57:24.688: INFO: kube-proxy-ip-172-20-61-249.ec2.internal ip-172-20-61-249.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:05:41 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:05:50 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:05:50 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:05:41 +0000 UTC }]
Jan 20 22:57:24.688: INFO: kube-proxy-ip-172-20-75-243.ec2.internal ip-172-20-75-243.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:05:41 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:05:50 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:05:50 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:05:41 +0000 UTC }]
Jan 20 22:57:24.688: INFO: kube-scheduler-ip-172-20-35-66.ec2.internal ip-172-20-35-66.ec2.internal Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:06:28 +0000 UTC } {Ready True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:07:42 +0000 UTC } {ContainersReady True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:07:42 +0000 UTC } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2019-01-19 19:06:28 +0000 UTC }]
Jan 20 22:57:24.688: INFO:
Jan 20 22:57:24.766: INFO:
Logging node info for node ip-172-20-100-7.ec2.internal
Jan 20 22:57:24.842: INFO: Node Info: &Node{ObjectMeta:k8s_io_apimachinery_pkg_apis_meta_v1.ObjectMeta{Name:ip-172-20-100-7.ec2.internal,GenerateName:,Namespace:,SelfLink:/api/v1/nodes/ip-172-20-100-7.ec2.internal,UID:1d6eedb5-1c1e-11e9-8af7-0aa7dec3d40a,ResourceVersion:160321,Generation:0,CreationTimestamp:2019-01-19 19:12:07 +0000 UTC,DeletionTimestamp:<nil>,DeletionGracePeriodSeconds:nil,Labels:map[string]string{beta.kubernetes.io/arch: amd64,beta.kubernetes.io/instance-type: t2.medium,beta.kubernetes.io/os: linux,failure-domain.beta.kubernetes.io/region: us-east-1,failure-domain.beta.kubernetes.io/zone: us-east-1c,kops.k8s.io/instancegroup: nodes,kubernetes.io/hostname: ip-172-20-100-7.ec2.internal,kubernetes.io/role: node,node-role.kubernetes.io/node: ,topology.ebs.csi.aws.com/zone: us-east-1c,},Annotations:map[string]string{csi.volume.kubernetes.io/nodeid: {"ebs.csi.aws.com":"i-0691e4436c3a3f38d"},node.alpha.kubernetes.io/ttl: 0,volumes.kubernetes.io/controller-managed-attach-detach: true,},OwnerReferences:[],Finalizers:[],ClusterName:,Initializers:nil,},Spec:NodeSpec{PodCIDR:100.96.3.0/24,DoNotUse_ExternalID:,ProviderID:aws:///us-east-1c/i-0691e4436c3a3f38d,Unschedulable:false,Taints:[],ConfigSource:nil,},Status:NodeStatus{Capacity:ResourceLis
t{attachable-volumes-aws-ebs: {{39 0} {<nil>} 39 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{128771407872 0} {<nil>} BinarySI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{4146286592 0} {<nil>} 4049108Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{attachable-volumes-aws-ebs: {{39 0} {<nil>} 39 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{115894266893 0} {<nil>} 115894266893 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{4041428992 0} {<nil>} 3946708Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[{NetworkUnavailable False 2019-01-19 19:12:13 +0000 UTC 2019-01-19 19:12:13 +0000 UTC RouteCreated RouteController created a route} {MemoryPressure False 2019-01-20 22:56:14 +0000 UTC 2019-01-19 19:12:07 +0000 UTC KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2019-01-20 22:56:14 +0000 UTC 2019-01-19 19:12:07 +0000 UTC KubeletHasNoDiskPressure kubelet has no disk pressure} {PIDPressure False 2019-01-20 22:56:14 +0000 UTC 2019-01-19 19:12:07 +0000 UTC KubeletHasSufficientPID kubelet has sufficient PID available} {Ready True 2019-01-20 22:56:14 +0000 UTC 2019-01-19 19:12:08 +0000 UTC KubeletReady kube
let is posting ready status}],Addresses:[{InternalIP 172.20.100.7} {ExternalIP 18.207.98.91} {InternalDNS ip-172-20-100-7.ec2.internal} {Hostname ip-172-20-100-7.ec2.internal} {ExternalDNS ec2-18-207-98-91.compute-1.amazonaws.com}],DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:a5c1db2757774415a4552b87ad896bd9,SystemUUID:EC211B21-BA74-FDC3-92DD-AFF3386CC935,BootID:d25f9f06-30b7-4ec7-87fb-486adc1a8a66,KernelVersion:4.9.0-7-amd64,OSImage:Debian GNU/Linux 9 (stretch),ContainerRuntimeVersion:docker://17.3.2,KubeletVersion:v1.13.0,KubeProxyVersion:v1.13.0,OperatingSystem:linux,Architecture:amd64,},Images:[{[amazon/aws-ebs-csi-driver@sha256:40edb443ffe3360f40559986eb053bfa8ad1d295cee4b703b467169f42e36b65 amazon/aws-ebs-csi-driver:latest] 369626671} {[amazon/aws-ebs-csi-driver@sha256:46e39a29c167687d83404d5fedd2ac10a8995408efd7276074f26b52c5146456] 369626671} {[protokube:1.11.0-alpha.1] 282671861} {[centos@sha256:184e5f35598e333bfa7de10d8fb1cebb5ee4df5bc0f970bf2b1e7c7345136426 centos:latest] 201779604} {[k8s.gcr.io/kube-proxy@sha256:6b8dcb3973f2d292a3b19e4a017ed205bc6e81b3bdf6bb8f2d97db2cd11d3439 k8s.gcr.io/kube-proxy:v1.13.0] 80226224} {[quay.io/k8scsi/csi-attacher@sha256:6425af42299ba211
de685a94953a5c4c6fcbfd2494e445437dd9ebd70b28bf8a quay.io/k8scsi/csi-attacher:v1.0.1] 50168619} {[quay.io/k8scsi/csi-provisioner@sha256:7d7d832832b536f32e899669a32d4fb75ab972da20c21a2bd6043eb498cf58e8 quay.io/k8scsi/csi-provisioner:v1.0.1] 47974767} {[quay.io/k8scsi/csi-cluster-driver-registrar@sha256:fafd75ae5442f192cfa8c2e792903aee30d5884b62e802e4464b0a895d21e3ef quay.io/k8scsi/csi-cluster-driver-registrar:v1.0.1] 45874691} {[quay.io/k8scsi/csi-node-driver-registrar@sha256:ffecfbe6ae9f446e5102cbf2c73041d63ccf44bcfd72e2f2a62174a3a185eb69 quay.io/k8scsi/csi-node-driver-registrar:v1.0.2] 20061687} {[busybox@sha256:8ccbac733d19c0dd4d70b4f0c1e12245b5fa3ad24758a11035ee505c629c0796 busybox:1.29] 1154361} {[k8s.gcr.io/pause-amd64@sha256:163ac025575b775d1c0f9bf0bdd0f086883171eb475b5068e7defa4ca9e76516 k8s.gcr.io/pause-amd64:3.0] 746888}],VolumesInUse:[kubernetes.io/csi/ebs.csi.aws.com^vol-045cb708c909b4772 kubernetes.io/csi/ebs.csi.aws.com^vol-094e49a4fb45f9405 kubernetes.io/csi/ebs.csi.aws.com^vol-0cb0473ad04a4229f kubernetes.io/csi/ebs.csi.aws.com^vol-0d3feda8b995cd7f9],VolumesAttached:[],Config:nil,},}
Jan 20 22:57:24.843: INFO:
Logging kubelet events for node ip-172-20-100-7.ec2.internal
Jan 20 22:57:24.921: INFO:
Logging pods the kubelet thinks is on node ip-172-20-100-7.ec2.internal
Jan 20 22:57:25.013: INFO: kube-proxy-ip-172-20-100-7.ec2.internal started at <nil> (0+0 container statuses recorded)
Jan 20 22:57:25.013: INFO: ebs-controller-0 started at 2019-01-20 22:39:50 +0000 UTC (0+4 container statuses recorded)
Jan 20 22:57:25.013: INFO: Container cluster-driver-registrar ready: true, restart count 0
Jan 20 22:57:25.013: INFO: Container csi-attacher ready: true, restart count 0
Jan 20 22:57:25.013: INFO: Container csi-provisioner ready: true, restart count 0
Jan 20 22:57:25.013: INFO: Container ebs-plugin ready: true, restart count 0
Jan 20 22:57:25.013: INFO: ebs-node-rfptb started at 2019-01-20 22:39:50 +0000 UTC (0+2 container statuses recorded)
Jan 20 22:57:25.013: INFO: Container ebs-plugin ready: true, restart count 0
Jan 20 22:57:25.013: INFO: Container node-driver-registrar ready: true, restart count 0
W0120 22:57:25.097855 11162 metrics_grabber.go:81] Master node is not registered. Grabbing metrics from Scheduler, ControllerManager and ClusterAutoscaler is disabled.
Jan 20 22:57:25.305: INFO:
Latency metrics for node ip-172-20-100-7.ec2.internal
Jan 20 22:57:25.305: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.99 Latency:2m3.00887s}
Jan 20 22:57:25.305: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.5 Latency:2m3.00887s}
Jan 20 22:57:25.305: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.9 Latency:2m3.00887s}
Jan 20 22:57:25.305: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.99 Latency:2m3.000749s}
Jan 20 22:57:25.305: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.9 Latency:2m3.000749s}
Jan 20 22:57:25.305: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.5 Latency:2m3.000749s}
Jan 20 22:57:25.305: INFO:
Logging node info for node ip-172-20-35-66.ec2.internal
Jan 20 22:57:25.382: INFO: Node Info: &Node{ObjectMeta:k8s_io_apimachinery_pkg_apis_meta_v1.ObjectMeta{Name:ip-172-20-35-66.ec2.internal,GenerateName:,Namespace:,SelfLink:/api/v1/nodes/ip-172-20-35-66.ec2.internal,UID:ed1b9188-1c1d-11e9-8af7-0aa7dec3d40a,ResourceVersion:160305,Generation:0,CreationTimestamp:2019-01-19 19:10:46 +0000 UTC,DeletionTimestamp:<nil>,DeletionGracePeriodSeconds:nil,Labels:map[string]string{beta.kubernetes.io/arch: amd64,beta.kubernetes.io/instance-type: m3.medium,beta.kubernetes.io/os: linux,failure-domain.beta.kubernetes.io/region: us-east-1,failure-domain.beta.kubernetes.io/zone: us-east-1a,kops.k8s.io/instancegroup: master-us-east-1a,kubernetes.io/hostname: ip-172-20-35-66.ec2.internal,kubernetes.io/role: master,node-role.kubernetes.io/master: ,},Annotations:map[string]string{node.alpha.kubernetes.io/ttl: 0,volumes.kubernetes.io/controller-managed-attach-detach: true,},OwnerReferences:[],Finalizers:[],ClusterName:,Initializers:nil,},Spec:NodeSpec{PodCIDR:100.96.0.0/24,DoNotUse_ExternalID:,ProviderID:aws:///us-east-1a/i-04870eec72e470ed9,Unschedulable:false,Taints:[{node-role.kubernetes.io/master NoSchedule <nil>}],ConfigSource:nil,},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-aws-ebs: {{39 0} {<nil>} 39 De
cimalSI},cpu: {{1 0} {<nil>} 1 DecimalSI},ephemeral-storage: {{64351657984 0} {<nil>} BinarySI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3949547520 0} {<nil>} 3856980Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{attachable-volumes-aws-ebs: {{39 0} {<nil>} 39 DecimalSI},cpu: {{1 0} {<nil>} 1 DecimalSI},ephemeral-storage: {{57916492090 0} {<nil>} 57916492090 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{3844689920 0} {<nil>} 3754580Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[{NetworkUnavailable False 2019-01-19 19:10:53 +0000 UTC 2019-01-19 19:10:53 +0000 UTC RouteCreated RouteController created a route} {MemoryPressure False 2019-01-20 22:56:08 +0000 UTC 2019-01-19 19:10:46 +0000 UTC KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2019-01-20 22:56:08 +0000 UTC 2019-01-19 19:10:46 +0000 UTC KubeletHasNoDiskPressure kubelet has no disk pressure} {PIDPressure False 2019-01-20 22:56:08 +0000 UTC 2019-01-19 19:10:46 +0000 UTC KubeletHasSufficientPID kubelet has sufficient PID available} {Ready True 2019-01-20 22:56:08 +0000 UTC 2019-01-19 19:10:47 +0000 UTC KubeletReady kubelet is posting ready status}],Addresses:[{InternalIP 1
72.20.35.66} {ExternalIP 100.24.36.29} {InternalDNS ip-172-20-35-66.ec2.internal} {Hostname ip-172-20-35-66.ec2.internal} {ExternalDNS ec2-100-24-36-29.compute-1.amazonaws.com}],DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:0a5f5945186c408c9efa9f911c2f6366,SystemUUID:EC2A59AF-538B-AA9C-2AA1-FED11067629C,BootID:e4338642-9485-47f6-b054-c75a45405188,KernelVersion:4.9.0-7-amd64,OSImage:Debian GNU/Linux 9 (stretch),ContainerRuntimeVersion:docker://17.3.2,KubeletVersion:v1.13.0,KubeProxyVersion:v1.13.0,OperatingSystem:linux,Architecture:amd64,},Images:[{[kopeio/etcd-manager@sha256:b371b4a151e272637f3e77a0c7ed32f6ca779e38836aa0a36b6d102272322ee4 kopeio/etcd-manager:1.0.20181001] 555493053} {[protokube:1.11.0-alpha.1] 282671861} {[k8s.gcr.io/kube-apiserver@sha256:a424810eb8862e3e56eea27d5f73f95da255a978e848aa908d1237c2f630e72b k8s.gcr.io/kube-apiserver:v1.13.0] 180893778} {[k8s.gcr.io/kube-controller-manager@sha256:3581475c40dcf68d38cf167f75ee6d34aa8f3a3b73266559b267ce21fced624f k8s.gcr.io/kube-controller-manager:v1.13.0] 146178834} {[kope/dns-controller@sha256:fe90348f06830c9449c13c4cf9dfbf76d00bb710524610a852d78de4c5834400 kope/dns-controller:1.11.0-alpha.1] 122828833} {[k8s.gcr.io/kube-
proxy@sha256:6b8dcb3973f2d292a3b19e4a017ed205bc6e81b3bdf6bb8f2d97db2cd11d3439 k8s.gcr.io/kube-proxy:v1.13.0] 80226224} {[k8s.gcr.io/kube-scheduler@sha256:f3e3ea90717bf4b77d62cee74cd45b51b358d81555db37042ffbe264a16122bd k8s.gcr.io/kube-scheduler:v1.13.0] 79586418} {[k8s.gcr.io/pause-amd64@sha256:163ac025575b775d1c0f9bf0bdd0f086883171eb475b5068e7defa4ca9e76516 k8s.gcr.io/pause-amd64:3.0] 746888}],VolumesInUse:[],VolumesAttached:[],Config:nil,},}
Jan 20 22:57:25.382: INFO:
Logging kubelet events for node ip-172-20-35-66.ec2.internal
Jan 20 22:57:25.461: INFO:
Logging pods the kubelet thinks is on node ip-172-20-35-66.ec2.internal
Jan 20 22:57:25.540: INFO: etcd-manager-main-ip-172-20-35-66.ec2.internal started at <nil> (0+0 container statuses recorded)
Jan 20 22:57:25.541: INFO: kube-apiserver-ip-172-20-35-66.ec2.internal started at <nil> (0+0 container statuses recorded)
Jan 20 22:57:25.541: INFO: kube-controller-manager-ip-172-20-35-66.ec2.internal started at <nil> (0+0 container statuses recorded)
Jan 20 22:57:25.541: INFO: kube-proxy-ip-172-20-35-66.ec2.internal started at <nil> (0+0 container statuses recorded)
Jan 20 22:57:25.541: INFO: kube-scheduler-ip-172-20-35-66.ec2.internal started at <nil> (0+0 container statuses recorded)
Jan 20 22:57:25.541: INFO: dns-controller-64db5996cd-grxlk started at 2019-01-19 19:10:47 +0000 UTC (0+1 container statuses recorded)
Jan 20 22:57:25.541: INFO: Container dns-controller ready: true, restart count 0
Jan 20 22:57:25.541: INFO: etcd-manager-events-ip-172-20-35-66.ec2.internal started at <nil> (0+0 container statuses recorded)
W0120 22:57:25.618164 11162 metrics_grabber.go:81] Master node is not registered. Grabbing metrics from Scheduler, ControllerManager and ClusterAutoscaler is disabled.
Jan 20 22:57:25.795: INFO:
Latency metrics for node ip-172-20-35-66.ec2.internal
Jan 20 22:57:25.795: INFO:
Logging node info for node ip-172-20-61-249.ec2.internal
Jan 20 22:57:25.872: INFO: Node Info: &Node{ObjectMeta:k8s_io_apimachinery_pkg_apis_meta_v1.ObjectMeta{Name:ip-172-20-61-249.ec2.internal,GenerateName:,Namespace:,SelfLink:/api/v1/nodes/ip-172-20-61-249.ec2.internal,UID:17afe3ea-1c1e-11e9-8af7-0aa7dec3d40a,ResourceVersion:160326,Generation:0,CreationTimestamp:2019-01-19 19:11:58 +0000 UTC,DeletionTimestamp:<nil>,DeletionGracePeriodSeconds:nil,Labels:map[string]string{beta.kubernetes.io/arch: amd64,beta.kubernetes.io/instance-type: t2.medium,beta.kubernetes.io/os: linux,failure-domain.beta.kubernetes.io/region: us-east-1,failure-domain.beta.kubernetes.io/zone: us-east-1a,kops.k8s.io/instancegroup: nodes,kubernetes.io/hostname: ip-172-20-61-249.ec2.internal,kubernetes.io/role: node,node-role.kubernetes.io/node: ,topology.ebs.csi.aws.com/zone: us-east-1a,},Annotations:map[string]string{csi.volume.kubernetes.io/nodeid: {"ebs.csi.aws.com":"i-0db0d2931144e28a4"},node.alpha.kubernetes.io/ttl: 0,volumes.kubernetes.io/controller-managed-attach-detach: true,},OwnerReferences:[],Finalizers:[],ClusterName:,Initializers:nil,},Spec:NodeSpec{PodCIDR:100.96.1.0/24,DoNotUse_ExternalID:,ProviderID:aws:///us-east-1a/i-0db0d2931144e28a4,Unschedulable:false,Taints:[],ConfigSource:nil,},Status:NodeStatus{Capacity:Resource
List{attachable-volumes-aws-ebs: {{39 0} {<nil>} 39 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{128771407872 0} {<nil>} BinarySI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{4146278400 0} {<nil>} 4049100Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{attachable-volumes-aws-ebs: {{39 0} {<nil>} 39 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{115894266893 0} {<nil>} 115894266893 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{4041420800 0} {<nil>} 3946700Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[{NetworkUnavailable False 2019-01-19 19:12:03 +0000 UTC 2019-01-19 19:12:03 +0000 UTC RouteCreated RouteController created a route} {MemoryPressure False 2019-01-20 22:56:18 +0000 UTC 2019-01-19 19:11:58 +0000 UTC KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2019-01-20 22:56:18 +0000 UTC 2019-01-19 19:11:58 +0000 UTC KubeletHasNoDiskPressure kubelet has no disk pressure} {PIDPressure False 2019-01-20 22:56:18 +0000 UTC 2019-01-19 19:11:58 +0000 UTC KubeletHasSufficientPID kubelet has sufficient PID available} {Ready True 2019-01-20 22:56:18 +0000 UTC 2019-01-19 19:11:58 +0000 UTC KubeletReady k
ubelet is posting ready status}],Addresses:[{InternalIP 172.20.61.249} {ExternalIP 3.89.47.40} {InternalDNS ip-172-20-61-249.ec2.internal} {Hostname ip-172-20-61-249.ec2.internal} {ExternalDNS ec2-3-89-47-40.compute-1.amazonaws.com}],DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:0471c04f5f894c90b85473073d188c8e,SystemUUID:EC22D493-7133-4AA0-FB37-C68056827453,BootID:9b4068c8-509f-4547-8b6c-bd74967f5a8a,KernelVersion:4.9.0-7-amd64,OSImage:Debian GNU/Linux 9 (stretch),ContainerRuntimeVersion:docker://17.3.2,KubeletVersion:v1.13.0,KubeProxyVersion:v1.13.0,OperatingSystem:linux,Architecture:amd64,},Images:[{[amazon/aws-ebs-csi-driver@sha256:40edb443ffe3360f40559986eb053bfa8ad1d295cee4b703b467169f42e36b65 amazon/aws-ebs-csi-driver:latest] 369626671} {[amazon/aws-ebs-csi-driver@sha256:46e39a29c167687d83404d5fedd2ac10a8995408efd7276074f26b52c5146456] 369626671} {[protokube:1.11.0-alpha.1] 282671861} {[k8s.gcr.io/kube-proxy@sha256:6b8dcb3973f2d292a3b19e4a017ed205bc6e81b3bdf6bb8f2d97db2cd11d3439 k8s.gcr.io/kube-proxy:v1.13.0] 80226224} {[k8s.gcr.io/cluster-proportional-autoscaler-amd64@sha256:003f98d9f411ddfa6ff6d539196355e03ddd69fa4ed38c7ffb8fec6f729afe2d k8s.gcr.io/cluster-proportional-aut
oscaler-amd64:1.1.2-r2] 49648481} {[k8s.gcr.io/k8s-dns-kube-dns-amd64@sha256:b99fc3eee2a9f052f7eb4cc00f15eb12fc405fa41019baa2d6b79847ae7284a8 k8s.gcr.io/k8s-dns-kube-dns-amd64:1.14.10] 49549457} {[k8s.gcr.io/k8s-dns-sidecar-amd64@sha256:4f1ab957f87b94a5ec1edc26fae50da2175461f00afecf68940c4aa079bd08a4 k8s.gcr.io/k8s-dns-sidecar-amd64:1.14.10] 41635309} {[k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64@sha256:bbb2a290a568125b3b996028958eb773f33b5b87a6b37bf38a28f8b62dddb3c8 k8s.gcr.io/k8s-dns-dnsmasq-nanny-amd64:1.14.10] 40372149} {[quay.io/k8scsi/csi-node-driver-registrar@sha256:ffecfbe6ae9f446e5102cbf2c73041d63ccf44bcfd72e2f2a62174a3a185eb69 quay.io/k8scsi/csi-node-driver-registrar:v1.0.2] 20061687} {[busybox@sha256:8ccbac733d19c0dd4d70b4f0c1e12245b5fa3ad24758a11035ee505c629c0796 busybox:1.29] 1154361} {[k8s.gcr.io/pause-amd64@sha256:163ac025575b775d1c0f9bf0bdd0f086883171eb475b5068e7defa4ca9e76516 k8s.gcr.io/pause-amd64:3.0] 746888}],VolumesInUse:[kubernetes.io/csi/ebs.csi.aws.com^vol-02e759c773211f03a kubernetes.io/csi/ebs.csi.aws.com^vol-0628b8f7a05911dc5 kubernetes.io/csi/ebs.csi.aws.com^vol-0e02fd7fb05a3048c kubernetes.io/csi/ebs.csi.aws.com^vol-0e7c58805f6fcf99c kubernetes.io/csi/ebs.csi.aws.com^vol-0f4f97cdb71d2e7a8],VolumesAttached:[],Config:nil,},}
Jan 20 22:57:25.872: INFO:
Logging kubelet events for node ip-172-20-61-249.ec2.internal
Jan 20 22:57:25.949: INFO:
Logging pods the kubelet thinks is on node ip-172-20-61-249.ec2.internal
Jan 20 22:57:26.037: INFO: kube-proxy-ip-172-20-61-249.ec2.internal started at <nil> (0+0 container statuses recorded)
Jan 20 22:57:26.037: INFO: kube-dns-autoscaler-779f597664-5mc5r started at 2019-01-19 19:11:58 +0000 UTC (0+1 container statuses recorded)
Jan 20 22:57:26.037: INFO: Container autoscaler ready: true, restart count 0
Jan 20 22:57:26.037: INFO: kube-dns-859d8bc8bb-2pw8x started at 2019-01-19 19:11:58 +0000 UTC (0+3 container statuses recorded)
Jan 20 22:57:26.037: INFO: Container dnsmasq ready: true, restart count 0
Jan 20 22:57:26.037: INFO: Container kubedns ready: true, restart count 0
Jan 20 22:57:26.037: INFO: Container sidecar ready: true, restart count 0
Jan 20 22:57:26.037: INFO: kube-dns-859d8bc8bb-tnhss started at 2019-01-19 19:12:03 +0000 UTC (0+3 container statuses recorded)
Jan 20 22:57:26.037: INFO: Container dnsmasq ready: true, restart count 0
Jan 20 22:57:26.037: INFO: Container kubedns ready: true, restart count 0
Jan 20 22:57:26.037: INFO: Container sidecar ready: true, restart count 0
Jan 20 22:57:26.037: INFO: ebs-node-xr87t started at 2019-01-20 22:39:50 +0000 UTC (0+2 container statuses recorded)
Jan 20 22:57:26.037: INFO: Container ebs-plugin ready: true, restart count 0
Jan 20 22:57:26.037: INFO: Container node-driver-registrar ready: true, restart count 0
W0120 22:57:26.114533 11162 metrics_grabber.go:81] Master node is not registered. Grabbing metrics from Scheduler, ControllerManager and ClusterAutoscaler is disabled.
Jan 20 22:57:26.280: INFO:
Latency metrics for node ip-172-20-61-249.ec2.internal
Jan 20 22:57:26.280: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.99 Latency:2m3.000526s}
Jan 20 22:57:26.280: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.5 Latency:2m3.000526s}
Jan 20 22:57:26.280: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.9 Latency:2m3.000526s}
Jan 20 22:57:26.280: INFO:
Logging node info for node ip-172-20-75-243.ec2.internal
Jan 20 22:57:26.357: INFO: Node Info: &Node{ObjectMeta:k8s_io_apimachinery_pkg_apis_meta_v1.ObjectMeta{Name:ip-172-20-75-243.ec2.internal,GenerateName:,Namespace:,SelfLink:/api/v1/nodes/ip-172-20-75-243.ec2.internal,UID:1bbb8b84-1c1e-11e9-8af7-0aa7dec3d40a,ResourceVersion:160319,Generation:0,CreationTimestamp:2019-01-19 19:12:05 +0000 UTC,DeletionTimestamp:<nil>,DeletionGracePeriodSeconds:nil,Labels:map[string]string{beta.kubernetes.io/arch: amd64,beta.kubernetes.io/instance-type: t2.medium,beta.kubernetes.io/os: linux,failure-domain.beta.kubernetes.io/region: us-east-1,failure-domain.beta.kubernetes.io/zone: us-east-1b,kops.k8s.io/instancegroup: nodes,kubernetes.io/hostname: ip-172-20-75-243.ec2.internal,kubernetes.io/role: node,node-role.kubernetes.io/node: ,topology.ebs.csi.aws.com/zone: us-east-1b,},Annotations:map[string]string{csi.volume.kubernetes.io/nodeid: {"ebs.csi.aws.com":"i-05dfd3477c24b3cc2"},node.alpha.kubernetes.io/ttl: 0,volumes.kubernetes.io/controller-managed-attach-detach: true,},OwnerReferences:[],Finalizers:[],ClusterName:,Initializers:nil,},Spec:NodeSpec{PodCIDR:100.96.2.0/24,DoNotUse_ExternalID:,ProviderID:aws:///us-east-1b/i-05dfd3477c24b3cc2,Unschedulable:false,Taints:[],ConfigSource:nil,},Status:NodeStatus{Capacity:Resource
List{attachable-volumes-aws-ebs: {{39 0} {<nil>} 39 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{128771407872 0} {<nil>} BinarySI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{4146278400 0} {<nil>} 4049100Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{attachable-volumes-aws-ebs: {{39 0} {<nil>} 39 DecimalSI},cpu: {{2 0} {<nil>} 2 DecimalSI},ephemeral-storage: {{115894266893 0} {<nil>} 115894266893 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{4041420800 0} {<nil>} 3946700Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[{NetworkUnavailable False 2019-01-19 19:12:13 +0000 UTC 2019-01-19 19:12:13 +0000 UTC RouteCreated RouteController created a route} {MemoryPressure False 2019-01-20 22:56:14 +0000 UTC 2019-01-19 19:12:05 +0000 UTC KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2019-01-20 22:56:14 +0000 UTC 2019-01-19 19:12:05 +0000 UTC KubeletHasNoDiskPressure kubelet has no disk pressure} {PIDPressure False 2019-01-20 22:56:14 +0000 UTC 2019-01-19 19:12:05 +0000 UTC KubeletHasSufficientPID kubelet has sufficient PID available} {Ready True 2019-01-20 22:56:14 +0000 UTC 2019-01-19 19:12:05 +0000 UTC KubeletReady k
ubelet is posting ready status}],Addresses:[{InternalIP 172.20.75.243} {ExternalIP 3.81.212.4} {InternalDNS ip-172-20-75-243.ec2.internal} {Hostname ip-172-20-75-243.ec2.internal} {ExternalDNS ec2-3-81-212-4.compute-1.amazonaws.com}],DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:61972e6d8c4e403385c295c2bcbb41d6,SystemUUID:EC2AFBFB-DED2-0E60-AF56-D18A4C7C3A59,BootID:cf8c3577-f1dd-47c2-b2d5-c71bdaaa537b,KernelVersion:4.9.0-7-amd64,OSImage:Debian GNU/Linux 9 (stretch),ContainerRuntimeVersion:docker://17.3.2,KubeletVersion:v1.13.0,KubeProxyVersion:v1.13.0,OperatingSystem:linux,Architecture:amd64,},Images:[{[amazon/aws-ebs-csi-driver@sha256:40edb443ffe3360f40559986eb053bfa8ad1d295cee4b703b467169f42e36b65 amazon/aws-ebs-csi-driver:latest] 369626671} {[amazon/aws-ebs-csi-driver@sha256:46e39a29c167687d83404d5fedd2ac10a8995408efd7276074f26b52c5146456] 369626671} {[protokube:1.11.0-alpha.1] 282671861} {[centos@sha256:184e5f35598e333bfa7de10d8fb1cebb5ee4df5bc0f970bf2b1e7c7345136426 centos:latest] 201779604} {[k8s.gcr.io/kube-proxy@sha256:6b8dcb3973f2d292a3b19e4a017ed205bc6e81b3bdf6bb8f2d97db2cd11d3439 k8s.gcr.io/kube-proxy:v1.13.0] 80226224} {[quay.io/k8scsi/csi-attacher@sha256:6425af42299ba2
11de685a94953a5c4c6fcbfd2494e445437dd9ebd70b28bf8a quay.io/k8scsi/csi-attacher:v1.0.1] 50168619} {[quay.io/k8scsi/csi-provisioner@sha256:7d7d832832b536f32e899669a32d4fb75ab972da20c21a2bd6043eb498cf58e8 quay.io/k8scsi/csi-provisioner:v1.0.1] 47974767} {[quay.io/k8scsi/csi-cluster-driver-registrar@sha256:fafd75ae5442f192cfa8c2e792903aee30d5884b62e802e4464b0a895d21e3ef quay.io/k8scsi/csi-cluster-driver-registrar:v1.0.1] 45874691} {[quay.io/k8scsi/csi-node-driver-registrar@sha256:ffecfbe6ae9f446e5102cbf2c73041d63ccf44bcfd72e2f2a62174a3a185eb69 quay.io/k8scsi/csi-node-driver-registrar:v1.0.2] 20061687} {[busybox@sha256:8ccbac733d19c0dd4d70b4f0c1e12245b5fa3ad24758a11035ee505c629c0796 busybox:1.29] 1154361} {[k8s.gcr.io/pause-amd64@sha256:163ac025575b775d1c0f9bf0bdd0f086883171eb475b5068e7defa4ca9e76516 k8s.gcr.io/pause-amd64:3.0] 746888}],VolumesInUse:[kubernetes.io/csi/ebs.csi.aws.com^vol-035c0f365125b336d kubernetes.io/csi/ebs.csi.aws.com^vol-06d0f92bd9d5d98dc],VolumesAttached:[],Config:nil,},}
Jan 20 22:57:26.357: INFO:
Logging kubelet events for node ip-172-20-75-243.ec2.internal
Jan 20 22:57:26.434: INFO:
Logging pods the kubelet thinks is on node ip-172-20-75-243.ec2.internal
Jan 20 22:57:26.514: INFO: ebs-node-92z49 started at 2019-01-20 22:39:50 +0000 UTC (0+2 container statuses recorded)
Jan 20 22:57:26.514: INFO: Container ebs-plugin ready: true, restart count 0
Jan 20 22:57:26.514: INFO: Container node-driver-registrar ready: true, restart count 0
Jan 20 22:57:26.514: INFO: kube-proxy-ip-172-20-75-243.ec2.internal started at <nil> (0+0 container statuses recorded)
W0120 22:57:26.591703 11162 metrics_grabber.go:81] Master node is not registered. Grabbing metrics from Scheduler, ControllerManager and ClusterAutoscaler is disabled.
Jan 20 22:57:26.760: INFO:
Latency metrics for node ip-172-20-75-243.ec2.internal
Jan 20 22:57:26.760: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.99 Latency:6m48.973128s}
Jan 20 22:57:26.760: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.9 Latency:6m48.973128s}
Jan 20 22:57:26.760: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.5 Latency:6m48.973128s}
Jan 20 22:57:26.760: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.9 Latency:2m3.000722s}
Jan 20 22:57:26.760: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.5 Latency:2m3.000722s}
Jan 20 22:57:26.760: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.99 Latency:2m3.000722s}
Jan 20 22:57:26.760: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-ebs-s4bsz" for this suite.
Jan 20 22:57:49.067: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Jan 20 22:57:49.602: INFO: namespace: e2e-tests-ebs-s4bsz, resource: bindings, ignored listing per whitelist
Jan 20 22:57:51.910: INFO: namespace e2e-tests-ebs-s4bsz deletion completed in 25.072828472s
• Failure [959.109 seconds]
[ebs-csi-e2e] [single-az] Dynamic Provisioning
/home/ubuntu/ebs-csi/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/dynamic_provisioning.go:31
should create multiple PV objects, bind to PVCs and attach all to a single pod [It]
/home/ubuntu/ebs-csi/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/dynamic_provisioning.go:78
Expected error:
<*errors.errorString | 0xc00051ae40>: {
s: "Gave up after waiting 15m0s for pod \"pvc-volume-tester-vtq5p\" to be \"success or failure\"",
}
Gave up after waiting 15m0s for pod "pvc-volume-tester-vtq5p" to be "success or failure"
not to have occurred
/home/ubuntu/ebs-csi/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/testsuites/testsuites.go:199
------------------------------
Summarizing 1 Failure:
[Fail] [ebs-csi-e2e] [single-az] Dynamic Provisioning [It] should create multiple PV objects, bind to PVCs and attach all to a single pod
/home/ubuntu/ebs-csi/src/github.com/kubernetes-sigs/aws-ebs-csi-driver/tests/e2e/testsuites/testsuites.go:199
Ran 14 of 14 Specs in 959.169 seconds
FAIL! -- 13 Passed | 1 Failed | 0 Pending | 0 Skipped
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment