ci-kubernetes-kind-e2e-json-logging-1-25 #1622394211728887808 Results

Test Failures

Kubernetes e2e suite [It] [sig-storage] CSI Volumes [Driver: csi-hostpath] [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand should resize volume when PVC is edited while pod is using it 31m9s

go run hack/e2e.go -v --test --test_args='--ginkgo.focus=Kubernetes\se2e\ssuite\s\[It\]\s\[sig\-storage\]\sCSI\sVolumes\s\[Driver\:\scsi\-hostpath\]\s\[Testpattern\:\sDynamic\sPV\s\(block\svolmode\)\(allowExpansion\)\]\svolume\-expand\sshould\sresize\svolume\swhen\sPVC\sis\sedited\swhile\spod\sis\susing\sit$'

test/e2e/storage/testsuites/volume_expand.go:294
k8s.io/kubernetes/test/e2e/storage/testsuites.(*volumeExpandTestSuite).DefineTests.func5()
	test/e2e/storage/testsuites/volume_expand.go:294 +0x9c9
				[BeforeEach] [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand
  test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand
  test/e2e/framework/framework.go:186
STEP: Creating a kubernetes client 02/06/23 00:57:51.298
Feb  6 00:57:51.298: INFO: >>> kubeConfig: /root/.kube/kind-test-config
STEP: Building a namespace api object, basename volume-expand 02/06/23 00:57:51.3
STEP: Waiting for a default service account to be provisioned in namespace 02/06/23 00:57:51.528
STEP: Waiting for kube-root-ca.crt to be provisioned in namespace 02/06/23 00:57:51.536
[It] should resize volume when PVC is edited while pod is using it
  test/e2e/storage/testsuites/volume_expand.go:252
STEP: Building a driver namespace object, basename volume-expand-6065 02/06/23 00:57:51.54
STEP: Waiting for a default service account to be provisioned in namespace 02/06/23 00:57:51.888
STEP: deploying csi-hostpath driver 02/06/23 00:57:51.9
Feb  6 00:57:51.966: INFO: creating *v1.ServiceAccount: volume-expand-6065-2835/csi-attacher
Feb  6 00:57:51.978: INFO: creating *v1.ClusterRole: external-attacher-runner-volume-expand-6065
Feb  6 00:57:51.978: INFO: Define cluster role external-attacher-runner-volume-expand-6065
Feb  6 00:57:51.984: INFO: creating *v1.ClusterRoleBinding: csi-attacher-role-volume-expand-6065
Feb  6 00:57:51.992: INFO: creating *v1.Role: volume-expand-6065-2835/external-attacher-cfg-volume-expand-6065
Feb  6 00:57:51.997: INFO: creating *v1.RoleBinding: volume-expand-6065-2835/csi-attacher-role-cfg
Feb  6 00:57:52.001: INFO: creating *v1.ServiceAccount: volume-expand-6065-2835/csi-provisioner
Feb  6 00:57:52.004: INFO: creating *v1.ClusterRole: external-provisioner-runner-volume-expand-6065
Feb  6 00:57:52.004: INFO: Define cluster role external-provisioner-runner-volume-expand-6065
Feb  6 00:57:52.014: INFO: creating *v1.ClusterRoleBinding: csi-provisioner-role-volume-expand-6065
Feb  6 00:57:52.023: INFO: creating *v1.Role: volume-expand-6065-2835/external-provisioner-cfg-volume-expand-6065
Feb  6 00:57:52.033: INFO: creating *v1.RoleBinding: volume-expand-6065-2835/csi-provisioner-role-cfg
Feb  6 00:57:52.039: INFO: creating *v1.ServiceAccount: volume-expand-6065-2835/csi-snapshotter
Feb  6 00:57:52.044: INFO: creating *v1.ClusterRole: external-snapshotter-runner-volume-expand-6065
Feb  6 00:57:52.044: INFO: Define cluster role external-snapshotter-runner-volume-expand-6065
Feb  6 00:57:52.051: INFO: creating *v1.ClusterRoleBinding: csi-snapshotter-role-volume-expand-6065
Feb  6 00:57:52.058: INFO: creating *v1.Role: volume-expand-6065-2835/external-snapshotter-leaderelection-volume-expand-6065
Feb  6 00:57:52.062: INFO: creating *v1.RoleBinding: volume-expand-6065-2835/external-snapshotter-leaderelection
Feb  6 00:57:52.067: INFO: creating *v1.ServiceAccount: volume-expand-6065-2835/csi-external-health-monitor-controller
Feb  6 00:57:52.074: INFO: creating *v1.ClusterRole: external-health-monitor-controller-runner-volume-expand-6065
Feb  6 00:57:52.074: INFO: Define cluster role external-health-monitor-controller-runner-volume-expand-6065
Feb  6 00:57:52.081: INFO: creating *v1.ClusterRoleBinding: csi-external-health-monitor-controller-role-volume-expand-6065
Feb  6 00:57:52.087: INFO: creating *v1.Role: volume-expand-6065-2835/external-health-monitor-controller-cfg-volume-expand-6065
Feb  6 00:57:52.091: INFO: creating *v1.RoleBinding: volume-expand-6065-2835/csi-external-health-monitor-controller-role-cfg
Feb  6 00:57:52.097: INFO: creating *v1.ServiceAccount: volume-expand-6065-2835/csi-resizer
Feb  6 00:57:52.101: INFO: creating *v1.ClusterRole: external-resizer-runner-volume-expand-6065
Feb  6 00:57:52.101: INFO: Define cluster role external-resizer-runner-volume-expand-6065
Feb  6 00:57:52.106: INFO: creating *v1.ClusterRoleBinding: csi-resizer-role-volume-expand-6065
Feb  6 00:57:52.113: INFO: creating *v1.Role: volume-expand-6065-2835/external-resizer-cfg-volume-expand-6065
Feb  6 00:57:52.116: INFO: creating *v1.RoleBinding: volume-expand-6065-2835/csi-resizer-role-cfg
Feb  6 00:57:52.120: INFO: creating *v1.CSIDriver: csi-hostpath-volume-expand-6065
Feb  6 00:57:52.123: INFO: creating *v1.ServiceAccount: volume-expand-6065-2835/csi-hostpathplugin-sa
Feb  6 00:57:52.126: INFO: creating *v1.ClusterRoleBinding: csi-hostpathplugin-attacher-cluster-role-volume-expand-6065
Feb  6 00:57:52.130: INFO: creating *v1.ClusterRoleBinding: csi-hostpathplugin-health-monitor-controller-cluster-role-volume-expand-6065
Feb  6 00:57:52.135: INFO: creating *v1.ClusterRoleBinding: csi-hostpathplugin-provisioner-cluster-role-volume-expand-6065
Feb  6 00:57:52.144: INFO: creating *v1.ClusterRoleBinding: csi-hostpathplugin-resizer-cluster-role-volume-expand-6065
Feb  6 00:57:52.149: INFO: creating *v1.ClusterRoleBinding: csi-hostpathplugin-snapshotter-cluster-role-volume-expand-6065
Feb  6 00:57:52.159: INFO: creating *v1.RoleBinding: volume-expand-6065-2835/csi-hostpathplugin-attacher-role
Feb  6 00:57:52.162: INFO: creating *v1.RoleBinding: volume-expand-6065-2835/csi-hostpathplugin-health-monitor-controller-role
Feb  6 00:57:52.166: INFO: creating *v1.RoleBinding: volume-expand-6065-2835/csi-hostpathplugin-provisioner-role
Feb  6 00:57:52.170: INFO: creating *v1.RoleBinding: volume-expand-6065-2835/csi-hostpathplugin-resizer-role
Feb  6 00:57:52.175: INFO: creating *v1.RoleBinding: volume-expand-6065-2835/csi-hostpathplugin-snapshotter-role
Feb  6 00:57:52.182: INFO: creating *v1.StatefulSet: volume-expand-6065-2835/csi-hostpathplugin
Feb  6 00:57:52.192: INFO: creating *v1.ClusterRoleBinding: psp-csi-hostpath-role-volume-expand-6065
Feb  6 00:57:52.198: INFO: Creating resource for dynamic PV
Feb  6 00:57:52.198: INFO: Using claimSize:1Gi, test suite supported size:{ 1Gi}, driver(csi-hostpath) supported size:{ 1Gi} 
STEP: creating a StorageClass volume-expand-60657nbwg 02/06/23 00:57:52.198
STEP: creating a claim 02/06/23 00:57:52.208
Feb  6 00:57:52.215: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [csi-hostpathpzpxv] to have phase Bound
Feb  6 00:57:52.220: INFO: PersistentVolumeClaim csi-hostpathpzpxv found but phase is Pending instead of Bound.
Feb  6 00:57:54.235: INFO: PersistentVolumeClaim csi-hostpathpzpxv found but phase is Pending instead of Bound.
Feb  6 00:57:56.239: INFO: PersistentVolumeClaim csi-hostpathpzpxv found but phase is Pending instead of Bound.
Feb  6 00:57:58.261: INFO: PersistentVolumeClaim csi-hostpathpzpxv found but phase is Pending instead of Bound.
Feb  6 00:58:00.265: INFO: PersistentVolumeClaim csi-hostpathpzpxv found but phase is Pending instead of Bound.
Feb  6 00:58:02.269: INFO: PersistentVolumeClaim csi-hostpathpzpxv found but phase is Pending instead of Bound.
Feb  6 00:58:04.273: INFO: PersistentVolumeClaim csi-hostpathpzpxv found but phase is Pending instead of Bound.
Feb  6 00:58:06.277: INFO: PersistentVolumeClaim csi-hostpathpzpxv found but phase is Pending instead of Bound.
Feb  6 00:58:08.423: INFO: PersistentVolumeClaim csi-hostpathpzpxv found but phase is Pending instead of Bound.
Feb  6 00:58:10.433: INFO: PersistentVolumeClaim csi-hostpathpzpxv found but phase is Pending instead of Bound.
Feb  6 00:58:12.438: INFO: PersistentVolumeClaim csi-hostpathpzpxv found but phase is Pending instead of Bound.
Feb  6 00:58:14.441: INFO: PersistentVolumeClaim csi-hostpathpzpxv found but phase is Pending instead of Bound.
Feb  6 00:58:16.445: INFO: PersistentVolumeClaim csi-hostpathpzpxv found and phase=Bound (24.229676784s)
STEP: Creating a pod with dynamically provisioned volume 02/06/23 00:58:16.453
Feb  6 00:58:16.459: INFO: Waiting up to 5m0s for pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1" in namespace "volume-expand-6065" to be "running"
Feb  6 00:58:16.464: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 5.074831ms
Feb  6 00:58:18.468: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 2.009853141s
Feb  6 00:58:20.468: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 4.008984328s
Feb  6 00:58:22.468: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 6.00907594s
Feb  6 00:58:24.467: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 8.008344075s
Feb  6 00:58:26.467: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 10.008889074s
Feb  6 00:58:28.467: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 12.008809057s
Feb  6 00:58:30.468: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 14.009454111s
Feb  6 00:58:32.467: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 16.008664168s
Feb  6 00:58:34.469: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 18.010238461s
Feb  6 00:58:36.468: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 20.009409807s
Feb  6 00:58:38.467: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 22.008879607s
Feb  6 00:58:40.476: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 24.01772688s
Feb  6 00:58:42.499: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 26.040929238s
Feb  6 00:58:44.501: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 28.042100234s
Feb  6 00:58:46.473: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 30.014744097s
Feb  6 00:58:48.467: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 32.008517831s
Feb  6 00:58:50.468: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 34.009061459s
Feb  6 00:58:52.467: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Pending", Reason="", readiness=false. Elapsed: 36.008275426s
Feb  6 00:58:54.476: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1": Phase="Running", Reason="", readiness=true. Elapsed: 38.016960841s
Feb  6 00:58:54.476: INFO: Pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1" satisfied condition "running"
STEP: Expanding current pvc 02/06/23 00:58:54.482
Feb  6 00:58:54.482: INFO: currentPvcSize {{1073741824 0} {<nil>} 1Gi BinarySI}, newSize {{2147483648 0} {<nil>}  BinarySI}
STEP: Waiting for cloudprovider resize to finish 02/06/23 00:58:54.506
Feb  6 01:08:54.520: INFO: Unexpected error: While waiting for pvc resize to finish: 
    <*errors.errorString | 0xc0004eedc0>: {
        s: "error while waiting for controller resize to finish: timed out waiting for the condition",
    }
Feb  6 01:08:54.520: FAIL: While waiting for pvc resize to finish: error while waiting for controller resize to finish: timed out waiting for the condition

Full Stack Trace
k8s.io/kubernetes/test/e2e/storage/testsuites.(*volumeExpandTestSuite).DefineTests.func5()
	test/e2e/storage/testsuites/volume_expand.go:294 +0x9c9
Feb  6 01:08:54.520: INFO: Deleting pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1" in namespace "volume-expand-6065"
Feb  6 01:08:54.525: INFO: Wait up to 5m0s for pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1" to be fully deleted
Feb  6 01:13:54.534: INFO: Unexpected error: while cleaning up pod already deleted in resize test: 
    <*errors.errorString | 0xc0012fab50>: {
        s: "pod \"pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1\" was not deleted: timed out while waiting for pod volume-expand-6065/pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1 to be Not Found",
    }
Feb  6 01:13:54.534: FAIL: while cleaning up pod already deleted in resize test: pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1" was not deleted: timed out while waiting for pod volume-expand-6065/pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1 to be Not Found

Full Stack Trace
k8s.io/kubernetes/test/e2e/storage/testsuites.(*volumeExpandTestSuite).DefineTests.func5.1()
	test/e2e/storage/testsuites/volume_expand.go:272 +0xae
panic({0x6ea5bc0, 0xc001a2a440})
	/usr/local/go/src/runtime/panic.go:884 +0x212
k8s.io/kubernetes/test/e2e/framework/ginkgowrapper.Fail.func1()
	test/e2e/framework/ginkgowrapper/wrapper.go:73 +0x7d
panic({0x6ea7de0, 0xc00077ca80})
	/usr/local/go/src/runtime/panic.go:884 +0x212
k8s.io/kubernetes/test/e2e/framework/ginkgowrapper.Fail({0xc001e40140, 0x95}, {0xc001977a88?, 0x735f76c?, 0xc001977aa8?})
	test/e2e/framework/ginkgowrapper/wrapper.go:77 +0x197
k8s.io/kubernetes/test/e2e/framework.Fail({0xc0012f2080, 0x80}, {0xc001977b20?, 0xc001d4c480?, 0xc001977b48?})
	test/e2e/framework/log.go:63 +0x145
k8s.io/kubernetes/test/e2e/framework.ExpectNoErrorWithOffset(0x1, {0x7c2e5a0, 0xc0004eedc0}, {0xc0004eede0?, 0x0?, 0x0?})
	test/e2e/framework/expect.go:76 +0x267
k8s.io/kubernetes/test/e2e/framework.ExpectNoError(...)
	test/e2e/framework/expect.go:43
k8s.io/kubernetes/test/e2e/storage/testsuites.(*volumeExpandTestSuite).DefineTests.func5()
	test/e2e/storage/testsuites/volume_expand.go:294 +0x9c9
STEP: Deleting pod 02/06/23 01:13:54.535
Feb  6 01:13:54.535: INFO: Deleting pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1" in namespace "volume-expand-6065"
Feb  6 01:13:54.537: INFO: Wait up to 5m0s for pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1" to be fully deleted
STEP: Deleting pvc 02/06/23 01:18:54.545
Feb  6 01:18:54.545: INFO: Deleting PersistentVolumeClaim "csi-hostpathpzpxv"
Feb  6 01:18:54.549: INFO: Waiting up to 5m0s for PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee to get deleted
Feb  6 01:18:54.552: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (2.825945ms)
Feb  6 01:18:59.555: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (5.006441238s)
Feb  6 01:19:04.559: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (10.010099171s)
Feb  6 01:19:09.562: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (15.013275013s)
Feb  6 01:19:14.565: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (20.016480337s)
Feb  6 01:19:19.568: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (25.019529889s)
Feb  6 01:19:24.572: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (30.023315287s)
Feb  6 01:19:29.576: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (35.026885699s)
Feb  6 01:19:34.579: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (40.030408917s)
Feb  6 01:19:39.582: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (45.033504425s)
Feb  6 01:19:44.585: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (50.036191531s)
Feb  6 01:19:49.588: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (55.039459241s)
Feb  6 01:19:54.592: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (1m0.043397289s)
Feb  6 01:19:59.596: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (1m5.047129345s)
Feb  6 01:20:04.600: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (1m10.050932779s)
Feb  6 01:20:09.603: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (1m15.05443848s)
Feb  6 01:20:14.606: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (1m20.057521108s)
Feb  6 01:20:19.610: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (1m25.060925466s)
Feb  6 01:20:24.613: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (1m30.064602649s)
Feb  6 01:20:29.617: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (1m35.068443855s)
Feb  6 01:20:34.621: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (1m40.072402862s)
Feb  6 01:20:39.624: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (1m45.075500782s)
Feb  6 01:20:44.628: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (1m50.078979804s)
Feb  6 01:20:49.632: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (1m55.082795244s)
Feb  6 01:20:54.635: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (2m0.086543477s)
Feb  6 01:20:59.638: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (2m5.089619967s)
Feb  6 01:21:04.641: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (2m10.092372384s)
Feb  6 01:21:09.644: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (2m15.095203433s)
Feb  6 01:21:14.647: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (2m20.098137825s)
Feb  6 01:21:19.651: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (2m25.102194019s)
Feb  6 01:21:24.654: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (2m30.105706704s)
Feb  6 01:21:29.658: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (2m35.109684716s)
Feb  6 01:21:34.662: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (2m40.113582905s)
Feb  6 01:21:39.666: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (2m45.117332263s)
Feb  6 01:21:44.670: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (2m50.120809796s)
Feb  6 01:21:49.673: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (2m55.124311354s)
Feb  6 01:21:54.677: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (3m0.128349401s)
Feb  6 01:21:59.681: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (3m5.131826496s)
Feb  6 01:22:04.684: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (3m10.13492513s)
Feb  6 01:22:09.688: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (3m15.13876705s)
Feb  6 01:22:14.691: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (3m20.142035677s)
Feb  6 01:22:19.694: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (3m25.145598251s)
Feb  6 01:22:24.698: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (3m30.148781905s)
Feb  6 01:22:29.701: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (3m35.152057086s)
Feb  6 01:22:34.704: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (3m40.155043479s)
Feb  6 01:22:39.707: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (3m45.158668969s)
Feb  6 01:22:44.710: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (3m50.161483962s)
Feb  6 01:22:49.714: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (3m55.165336118s)
Feb  6 01:22:54.718: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (4m0.16894789s)
Feb  6 01:22:59.721: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (4m5.171806349s)
Feb  6 01:23:04.724: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (4m10.175320216s)
Feb  6 01:23:09.728: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (4m15.179172667s)
Feb  6 01:23:14.731: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (4m20.182696329s)
Feb  6 01:23:19.735: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (4m25.185785841s)
Feb  6 01:23:24.738: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (4m30.189065952s)
Feb  6 01:23:29.741: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (4m35.192494602s)
Feb  6 01:23:34.744: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (4m40.195603925s)
Feb  6 01:23:39.747: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (4m45.198570107s)
Feb  6 01:23:44.750: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (4m50.201492038s)
Feb  6 01:23:49.754: INFO: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee found and phase=Bound (4m55.204967807s)
STEP: Deleting sc 02/06/23 01:23:54.755
STEP: deleting the test namespace: volume-expand-6065 02/06/23 01:23:54.76
STEP: Waiting for namespaces [volume-expand-6065] to vanish 02/06/23 01:23:54.763
Feb  6 01:28:54.770: INFO: error deleting namespace volume-expand-6065: timed out waiting for the condition
STEP: uninstalling csi csi-hostpath driver 02/06/23 01:28:54.77
Feb  6 01:28:54.771: INFO: deleting *v1.ServiceAccount: volume-expand-6065-2835/csi-attacher
Feb  6 01:28:54.774: INFO: deleting *v1.ClusterRole: external-attacher-runner-volume-expand-6065
Feb  6 01:28:54.778: INFO: deleting *v1.ClusterRoleBinding: csi-attacher-role-volume-expand-6065
Feb  6 01:28:54.782: INFO: deleting *v1.Role: volume-expand-6065-2835/external-attacher-cfg-volume-expand-6065
Feb  6 01:28:54.785: INFO: deleting *v1.RoleBinding: volume-expand-6065-2835/csi-attacher-role-cfg
Feb  6 01:28:54.788: INFO: deleting *v1.ServiceAccount: volume-expand-6065-2835/csi-provisioner
Feb  6 01:28:54.792: INFO: deleting *v1.ClusterRole: external-provisioner-runner-volume-expand-6065
Feb  6 01:28:54.795: INFO: deleting *v1.ClusterRoleBinding: csi-provisioner-role-volume-expand-6065
Feb  6 01:28:54.799: INFO: deleting *v1.Role: volume-expand-6065-2835/external-provisioner-cfg-volume-expand-6065
Feb  6 01:28:54.802: INFO: deleting *v1.RoleBinding: volume-expand-6065-2835/csi-provisioner-role-cfg
Feb  6 01:28:54.805: INFO: deleting *v1.ServiceAccount: volume-expand-6065-2835/csi-snapshotter
Feb  6 01:28:54.809: INFO: deleting *v1.ClusterRole: external-snapshotter-runner-volume-expand-6065
Feb  6 01:28:54.813: INFO: deleting *v1.ClusterRoleBinding: csi-snapshotter-role-volume-expand-6065
Feb  6 01:28:54.817: INFO: deleting *v1.Role: volume-expand-6065-2835/external-snapshotter-leaderelection-volume-expand-6065
Feb  6 01:28:54.821: INFO: deleting *v1.RoleBinding: volume-expand-6065-2835/external-snapshotter-leaderelection
Feb  6 01:28:54.824: INFO: deleting *v1.ServiceAccount: volume-expand-6065-2835/csi-external-health-monitor-controller
Feb  6 01:28:54.828: INFO: deleting *v1.ClusterRole: external-health-monitor-controller-runner-volume-expand-6065
Feb  6 01:28:54.832: INFO: deleting *v1.ClusterRoleBinding: csi-external-health-monitor-controller-role-volume-expand-6065
Feb  6 01:28:54.835: INFO: deleting *v1.Role: volume-expand-6065-2835/external-health-monitor-controller-cfg-volume-expand-6065
Feb  6 01:28:54.839: INFO: deleting *v1.RoleBinding: volume-expand-6065-2835/csi-external-health-monitor-controller-role-cfg
Feb  6 01:28:54.843: INFO: deleting *v1.ServiceAccount: volume-expand-6065-2835/csi-resizer
Feb  6 01:28:54.846: INFO: deleting *v1.ClusterRole: external-resizer-runner-volume-expand-6065
Feb  6 01:28:54.850: INFO: deleting *v1.ClusterRoleBinding: csi-resizer-role-volume-expand-6065
Feb  6 01:28:54.854: INFO: deleting *v1.Role: volume-expand-6065-2835/external-resizer-cfg-volume-expand-6065
Feb  6 01:28:54.858: INFO: deleting *v1.RoleBinding: volume-expand-6065-2835/csi-resizer-role-cfg
Feb  6 01:28:54.861: INFO: deleting *v1.CSIDriver: csi-hostpath-volume-expand-6065
Feb  6 01:28:54.865: INFO: deleting *v1.ServiceAccount: volume-expand-6065-2835/csi-hostpathplugin-sa
Feb  6 01:28:54.869: INFO: deleting *v1.ClusterRoleBinding: csi-hostpathplugin-attacher-cluster-role-volume-expand-6065
Feb  6 01:28:54.872: INFO: deleting *v1.ClusterRoleBinding: csi-hostpathplugin-health-monitor-controller-cluster-role-volume-expand-6065
Feb  6 01:28:54.876: INFO: deleting *v1.ClusterRoleBinding: csi-hostpathplugin-provisioner-cluster-role-volume-expand-6065
Feb  6 01:28:54.879: INFO: deleting *v1.ClusterRoleBinding: csi-hostpathplugin-resizer-cluster-role-volume-expand-6065
Feb  6 01:28:54.882: INFO: deleting *v1.ClusterRoleBinding: csi-hostpathplugin-snapshotter-cluster-role-volume-expand-6065
Feb  6 01:28:54.886: INFO: deleting *v1.RoleBinding: volume-expand-6065-2835/csi-hostpathplugin-attacher-role
Feb  6 01:28:54.889: INFO: deleting *v1.RoleBinding: volume-expand-6065-2835/csi-hostpathplugin-health-monitor-controller-role
Feb  6 01:28:54.893: INFO: deleting *v1.RoleBinding: volume-expand-6065-2835/csi-hostpathplugin-provisioner-role
Feb  6 01:28:54.897: INFO: deleting *v1.RoleBinding: volume-expand-6065-2835/csi-hostpathplugin-resizer-role
Feb  6 01:28:54.900: INFO: deleting *v1.RoleBinding: volume-expand-6065-2835/csi-hostpathplugin-snapshotter-role
Feb  6 01:28:54.903: INFO: deleting *v1.StatefulSet: volume-expand-6065-2835/csi-hostpathplugin
Feb  6 01:28:54.907: INFO: deleting *v1.ClusterRoleBinding: psp-csi-hostpath-role-volume-expand-6065
STEP: deleting the driver namespace: volume-expand-6065-2835 02/06/23 01:28:54.912
STEP: Waiting for namespaces [volume-expand-6065-2835] to vanish 02/06/23 01:28:54.919
Feb  6 01:29:00.923: INFO: Unexpected error: while cleaning up resource: 
    <errors.aggregate | len:2, cap:2>: [
        <*errors.errorString | 0xc0012fbe50>{
            s: "pod \"pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1\" was not deleted: timed out while waiting for pod volume-expand-6065/pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1 to be Not Found",
        },
        <errors.aggregate | len:1, cap:1>[
            <*fmt.wrapError | 0xc001a0a100>{
                msg: "persistent Volume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee not deleted by dynamic provisioner: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee still exists within 5m0s",
                err: <*errors.errorString | 0xc0010fe670>{
                    s: "PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee still exists within 5m0s",
                },
            },
        ],
    ]
Feb  6 01:29:00.923: FAIL: while cleaning up resource: [pod "pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1" was not deleted: timed out while waiting for pod volume-expand-6065/pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1 to be Not Found, persistent Volume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee not deleted by dynamic provisioner: PersistentVolume pvc-95f5c1f3-b1ed-473c-ae09-cd1859c5a1ee still exists within 5m0s]

Full Stack Trace
k8s.io/kubernetes/test/e2e/storage/testsuites.(*volumeExpandTestSuite).DefineTests.func2()
	test/e2e/storage/testsuites/volume_expand.go:154 +0x49a
panic({0x6ea5bc0, 0xc0034d8f00})
	/usr/local/go/src/runtime/panic.go:884 +0x212
k8s.io/kubernetes/test/e2e/framework/ginkgowrapper.Fail.func1()
	test/e2e/framework/ginkgowrapper/wrapper.go:73 +0x7d
panic({0x6ea7de0, 0xc000255810})
	/usr/local/go/src/runtime/panic.go:884 +0x212
k8s.io/kubernetes/test/e2e/framework/ginkgowrapper.Fail({0xc00343a000, 0xf6}, {0xc00312d418?, 0x735f76c?, 0xc00312d438?})
	test/e2e/framework/ginkgowrapper/wrapper.go:77 +0x197
k8s.io/kubernetes/test/e2e/framework.Fail({0xc0006605a0, 0xe1}, {0xc00312d4b0?, 0xc0018b4000?, 0xc00312d4d8?})
	test/e2e/framework/log.go:63 +0x145
k8s.io/kubernetes/test/e2e/framework.ExpectNoErrorWithOffset(0x1, {0x7c2e5a0, 0xc0012fab50}, {0xc0012fab60?, 0x2725c0c?, 0x0?})
	test/e2e/framework/expect.go:76 +0x267
k8s.io/kubernetes/test/e2e/framework.ExpectNoError(...)
	test/e2e/framework/expect.go:43
k8s.io/kubernetes/test/e2e/storage/testsuites.(*volumeExpandTestSuite).DefineTests.func5.1()
	test/e2e/storage/testsuites/volume_expand.go:272 +0xae
panic({0x6ea5bc0, 0xc001a2a440})
	/usr/local/go/src/runtime/panic.go:884 +0x212
k8s.io/kubernetes/test/e2e/framework/ginkgowrapper.Fail.func1()
	test/e2e/framework/ginkgowrapper/wrapper.go:73 +0x7d
panic({0x6ea7de0, 0xc00077ca80})
	/usr/local/go/src/runtime/panic.go:884 +0x212
k8s.io/kubernetes/test/e2e/framework/ginkgowrapper.Fail({0xc001e40140, 0x95}, {0xc001977a88?, 0x735f76c?, 0xc001977aa8?})
	test/e2e/framework/ginkgowrapper/wrapper.go:77 +0x197
k8s.io/kubernetes/test/e2e/framework.Fail({0xc0012f2080, 0x80}, {0xc001977b20?, 0xc001d4c480?, 0xc001977b48?})
	test/e2e/framework/log.go:63 +0x145
k8s.io/kubernetes/test/e2e/framework.ExpectNoErrorWithOffset(0x1, {0x7c2e5a0, 0xc0004eedc0}, {0xc0004eede0?, 0x0?, 0x0?})
	test/e2e/framework/expect.go:76 +0x267
k8s.io/kubernetes/test/e2e/framework.ExpectNoError(...)
	test/e2e/framework/expect.go:43
k8s.io/kubernetes/test/e2e/storage/testsuites.(*volumeExpandTestSuite).DefineTests.func5()
	test/e2e/storage/testsuites/volume_expand.go:294 +0x9c9
[AfterEach] [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand
  test/e2e/framework/framework.go:187
STEP: Collecting events from namespace "volume-expand-6065". 02/06/23 01:29:00.923
STEP: Found 0 events. 02/06/23 01:29:00.926
Feb  6 01:29:00.928: INFO: POD                                       NODE          PHASE      GRACE  CONDITIONS
Feb  6 01:29:00.928: INFO: pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1  kind-worker2  Succeeded  30s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2023-02-06 00:58:16 +0000 UTC PodCompleted } {Ready False 0001-01-01 00:00:00 +0000 UTC 2023-02-06 01:08:56 +0000 UTC PodCompleted } {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2023-02-06 01:08:56 +0000 UTC PodCompleted } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2023-02-06 00:58:16 +0000 UTC  }]
Feb  6 01:29:00.928: INFO: 
Feb  6 01:29:00.945: INFO: 
Logging node info for node kind-control-plane
Feb  6 01:29:00.947: INFO: Node Info: &Node{ObjectMeta:{kind-control-plane    b1cb49e6-75a7-41b8-ba69-7767e3f199b8 27785 0 2023-02-06 00:56:10 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/os:linux kubernetes.io/arch:amd64 kubernetes.io/hostname:kind-control-plane kubernetes.io/os:linux node-role.kubernetes.io/control-plane: node.kubernetes.io/exclude-from-external-load-balancers:] map[kubeadm.alpha.kubernetes.io/cri-socket:unix:///run/containerd/containerd.sock node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] [] [{kubelet Update v1 2023-02-06 00:56:10 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{".":{},"f:volumes.kubernetes.io/controller-managed-attach-detach":{}},"f:labels":{".":{},"f:beta.kubernetes.io/arch":{},"f:beta.kubernetes.io/os":{},"f:kubernetes.io/arch":{},"f:kubernetes.io/hostname":{},"f:kubernetes.io/os":{}}},"f:spec":{"f:providerID":{}}} } {kubeadm Update v1 2023-02-06 00:56:13 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:kubeadm.alpha.kubernetes.io/cri-socket":{}},"f:labels":{"f:node-role.kubernetes.io/control-plane":{},"f:node.kubernetes.io/exclude-from-external-load-balancers":{}}}} } {kube-controller-manager Update v1 2023-02-06 00:56:33 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:node.alpha.kubernetes.io/ttl":{}}},"f:spec":{"f:podCIDR":{},"f:podCIDRs":{".":{},"v:\"10.244.0.0/24\"":{}},"f:taints":{}}} } {kubelet Update v1 2023-02-06 01:27:10 +0000 UTC FieldsV1 {"f:status":{"f:conditions":{"k:{\"type\":\"DiskPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"MemoryPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"PIDPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"Ready\"}":{"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{}}}}} status}]},Spec:NodeSpec{PodCIDR:10.244.0.0/24,DoNotUseExternalID:,ProviderID:kind://docker/kind/kind-control-plane,Unschedulable:false,Taints:[]Taint{Taint{Key:node-role.kubernetes.io/control-plane,Value:,Effect:NoSchedule,TimeAdded:<nil>,},},ConfigSource:nil,PodCIDRs:[10.244.0.0/24],},Status:NodeStatus{Capacity:ResourceList{cpu: {{8 0} {<nil>} 8 DecimalSI},ephemeral-storage: {{791251738624 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{54757810176 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{cpu: {{8 0} {<nil>} 8 DecimalSI},ephemeral-storage: {{791251738624 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{54757810176 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2023-02-06 01:27:10 +0000 UTC,LastTransitionTime:2023-02-06 00:56:08 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2023-02-06 01:27:10 +0000 UTC,LastTransitionTime:2023-02-06 00:56:08 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2023-02-06 01:27:10 +0000 UTC,LastTransitionTime:2023-02-06 00:56:08 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2023-02-06 01:27:10 +0000 UTC,LastTransitionTime:2023-02-06 00:56:33 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:172.18.0.2,},NodeAddress{Type:Hostname,Address:kind-control-plane,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:e328ce60ab914277863a031f417c80ce,SystemUUID:5870377a-0780-4b03-b020-1e88dc0a9381,BootID:1091d151-f16c-4fdc-899a-0bff3500ee70,KernelVersion:5.4.0-1086-gke,OSImage:Ubuntu 22.04.1 LTS,ContainerRuntimeVersion:containerd://1.6.14,KubeletVersion:v1.25.7-rc.0.16+74817504841bde,KubeProxyVersion:v1.25.7-rc.0.16+74817504841bde,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:57e1263cc818c8c5dc39e96fd84b43e749372f7d8e0194473db182c02d736f7a registry.k8s.io/kube-apiserver:v1.25.7-rc.0.16_74817504841bde],SizeBytes:129108435,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:1e63ddf4e3d1ee17eb9088ac421b83265235b72a43aa83f480cff1a72d879e4d registry.k8s.io/kube-controller-manager:v1.25.7-rc.0.16_74817504841bde],SizeBytes:118450487,},ContainerImage{Names:[registry.k8s.io/etcd:3.5.6-0],SizeBytes:102542580,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:e84585d19592e86a87b740345f7251ea2fe5752bd3c944759025c5a8382436f2 registry.k8s.io/kube-scheduler:v1.25.7-rc.0.16_74817504841bde],SizeBytes:51931448,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:7006371f635ca3e22c794b167237c78cdecc5689d6b60bbb5b6257ec2a32463a registry.k8s.io/kube-proxy:v1.25.7-rc.0.16_74817504841bde],SizeBytes:48821978,},ContainerImage{Names:[docker.io/kindest/kindnetd:v20221004-44d545d1],SizeBytes:25830582,},ContainerImage{Names:[docker.io/kindest/local-path-provisioner:v0.0.22-kind.0],SizeBytes:17375346,},ContainerImage{Names:[registry.k8s.io/coredns/coredns:v1.9.3],SizeBytes:14837849,},ContainerImage{Names:[docker.io/kindest/local-path-helper:v20220607-9a4d8d2a],SizeBytes:2859509,},ContainerImage{Names:[registry.k8s.io/pause:3.7],SizeBytes:311278,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
Feb  6 01:29:00.947: INFO: 
Logging kubelet events for node kind-control-plane
Feb  6 01:29:00.951: INFO: 
Logging pods the kubelet thinks is on node kind-control-plane
Feb  6 01:29:00.967: INFO: kube-controller-manager-kind-control-plane started at 2023-02-06 00:56:14 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:29:00.967: INFO: 	Container kube-controller-manager ready: true, restart count 0
Feb  6 01:29:00.967: INFO: kube-apiserver-kind-control-plane started at 2023-02-06 00:56:13 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:29:00.967: INFO: 	Container kube-apiserver ready: true, restart count 0
Feb  6 01:29:00.967: INFO: coredns-565d847f94-4f782 started at 2023-02-06 00:56:33 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:29:00.967: INFO: 	Container coredns ready: true, restart count 0
Feb  6 01:29:00.967: INFO: kube-proxy-4smqz started at 2023-02-06 00:56:49 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:29:00.967: INFO: 	Container kube-proxy ready: true, restart count 0
Feb  6 01:29:00.967: INFO: kube-scheduler-kind-control-plane started at 2023-02-06 00:56:14 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:29:00.967: INFO: 	Container kube-scheduler ready: true, restart count 0
Feb  6 01:29:00.967: INFO: etcd-kind-control-plane started at 2023-02-06 00:56:14 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:29:00.967: INFO: 	Container etcd ready: true, restart count 0
Feb  6 01:29:00.967: INFO: kindnet-57djt started at 2023-02-06 00:56:29 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:29:00.967: INFO: 	Container kindnet-cni ready: true, restart count 0
Feb  6 01:29:00.967: INFO: coredns-565d847f94-jwtds started at 2023-02-06 00:56:33 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:29:00.967: INFO: 	Container coredns ready: true, restart count 0
Feb  6 01:29:00.967: INFO: local-path-provisioner-684f458cdd-c4fcg started at 2023-02-06 00:56:33 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:29:00.967: INFO: 	Container local-path-provisioner ready: true, restart count 0
Feb  6 01:29:01.012: INFO: 
Latency metrics for node kind-control-plane
Feb  6 01:29:01.012: INFO: 
Logging node info for node kind-worker
Feb  6 01:29:01.015: INFO: Node Info: &Node{ObjectMeta:{kind-worker    97dfb9be-7347-4918-a3ea-1d0304aae063 27606 0 2023-02-06 00:56:48 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/os:linux kubernetes.io/arch:amd64 kubernetes.io/hostname:kind-worker kubernetes.io/os:linux topology.hostpath.csi/node:kind-worker] map[kubeadm.alpha.kubernetes.io/cri-socket:unix:///run/containerd/containerd.sock node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] [] [{kubeadm Update v1 2023-02-06 00:56:48 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:kubeadm.alpha.kubernetes.io/cri-socket":{}}}} } {kubelet Update v1 2023-02-06 00:56:48 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{".":{},"f:volumes.kubernetes.io/controller-managed-attach-detach":{}},"f:labels":{".":{},"f:beta.kubernetes.io/arch":{},"f:beta.kubernetes.io/os":{},"f:kubernetes.io/arch":{},"f:kubernetes.io/hostname":{},"f:kubernetes.io/os":{}}},"f:spec":{"f:providerID":{}}} } {kube-controller-manager Update v1 2023-02-06 00:57:18 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:node.alpha.kubernetes.io/ttl":{}}},"f:spec":{"f:podCIDR":{},"f:podCIDRs":{".":{},"v:\"10.244.1.0/24\"":{}}}} } {kubelet Update v1 2023-02-06 01:25:03 +0000 UTC FieldsV1 {"f:metadata":{"f:labels":{"f:topology.hostpath.csi/node":{}}},"f:status":{"f:conditions":{"k:{\"type\":\"DiskPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"MemoryPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"PIDPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"Ready\"}":{"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{}}},"f:images":{}}} status}]},Spec:NodeSpec{PodCIDR:10.244.1.0/24,DoNotUseExternalID:,ProviderID:kind://docker/kind/kind-worker,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[10.244.1.0/24],},Status:NodeStatus{Capacity:ResourceList{cpu: {{8 0} {<nil>} 8 DecimalSI},ephemeral-storage: {{791251738624 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{54757810176 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{cpu: {{8 0} {<nil>} 8 DecimalSI},ephemeral-storage: {{791251738624 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{54757810176 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2023-02-06 01:25:03 +0000 UTC,LastTransitionTime:2023-02-06 00:56:48 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2023-02-06 01:25:03 +0000 UTC,LastTransitionTime:2023-02-06 00:56:48 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2023-02-06 01:25:03 +0000 UTC,LastTransitionTime:2023-02-06 00:56:48 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2023-02-06 01:25:03 +0000 UTC,LastTransitionTime:2023-02-06 00:57:18 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:172.18.0.3,},NodeAddress{Type:Hostname,Address:kind-worker,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:172373c86857450b92ebd910b3e64bfe,SystemUUID:35d425c3-1b1c-4f22-884d-edbfd8946a2a,BootID:1091d151-f16c-4fdc-899a-0bff3500ee70,KernelVersion:5.4.0-1086-gke,OSImage:Ubuntu 22.04.1 LTS,ContainerRuntimeVersion:containerd://1.6.14,KubeletVersion:v1.25.7-rc.0.16+74817504841bde,KubeProxyVersion:v1.25.7-rc.0.16+74817504841bde,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:57e1263cc818c8c5dc39e96fd84b43e749372f7d8e0194473db182c02d736f7a registry.k8s.io/kube-apiserver:v1.25.7-rc.0.16_74817504841bde],SizeBytes:129108435,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:1e63ddf4e3d1ee17eb9088ac421b83265235b72a43aa83f480cff1a72d879e4d registry.k8s.io/kube-controller-manager:v1.25.7-rc.0.16_74817504841bde],SizeBytes:118450487,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/jessie-dnsutils@sha256:11e6a66017ba4e4b938c1612b7a54a3befcefd354796c04e1dba76873a13518e registry.k8s.io/e2e-test-images/jessie-dnsutils:1.5],SizeBytes:112030526,},ContainerImage{Names:[registry.k8s.io/etcd:3.5.6-0],SizeBytes:102542580,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:e84585d19592e86a87b740345f7251ea2fe5752bd3c944759025c5a8382436f2 registry.k8s.io/kube-scheduler:v1.25.7-rc.0.16_74817504841bde],SizeBytes:51931448,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/agnhost@sha256:af7e3857d87770ddb40f5ea4f89b5a2709504ab1ee31f9ea4ab5823c045f2146 registry.k8s.io/e2e-test-images/agnhost:2.40],SizeBytes:51155161,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/nautilus@sha256:99c0d6f1ad24a1aa1905d9c6534d193f268f7b23f9add2ae6bb41f31094bdd5c registry.k8s.io/e2e-test-images/nautilus:1.5],SizeBytes:49642095,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:7006371f635ca3e22c794b167237c78cdecc5689d6b60bbb5b6257ec2a32463a registry.k8s.io/kube-proxy:v1.25.7-rc.0.16_74817504841bde],SizeBytes:48821978,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/httpd@sha256:20f25f275d46aa728f7615a1ccc19c78b2ed89435bf943a44b339f70f45508e6 registry.k8s.io/e2e-test-images/httpd:2.4.39-2],SizeBytes:41902010,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/httpd@sha256:1b9d1b2f36cb2dbee1960e82a9344aeb11bd4c4c03abf5e1853e0559c23855e3 registry.k8s.io/e2e-test-images/httpd:2.4.38-2],SizeBytes:40764680,},ContainerImage{Names:[docker.io/kindest/kindnetd:v20221004-44d545d1],SizeBytes:25830582,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/sample-apiserver@sha256:f9c93b92b6ff750b41a93c4e4fe0bfe384597aeb841e2539d5444815c55b2d8f registry.k8s.io/e2e-test-images/sample-apiserver:1.17.5],SizeBytes:24316368,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-provisioner@sha256:122bfb8c1edabb3c0edd63f06523e6940d958d19b3957dc7b1d6f81e9f1f6119 registry.k8s.io/sig-storage/csi-provisioner:v3.1.0],SizeBytes:23345856,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-resizer@sha256:9ebbf9f023e7b41ccee3d52afe39a89e3ddacdbb69269d583abfc25847cfd9e4 registry.k8s.io/sig-storage/csi-resizer:v1.4.0],SizeBytes:22381475,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-snapshotter@sha256:89e900a160a986a1a7a4eba7f5259e510398fa87ca9b8a729e7dec59e04c7709 registry.k8s.io/sig-storage/csi-snapshotter:v5.0.1],SizeBytes:22163966,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-attacher@sha256:8b9c313c05f54fb04f8d430896f5f5904b6cb157df261501b29adc04d2b2dc7b registry.k8s.io/sig-storage/csi-attacher:v3.4.0],SizeBytes:22085298,},ContainerImage{Names:[docker.io/kindest/local-path-provisioner:v0.0.22-kind.0],SizeBytes:17375346,},ContainerImage{Names:[registry.k8s.io/sig-storage/hostpathplugin@sha256:6029c252dae6178c99b580de72d7776158edbc81be0de15cedc4152a3acfed18 registry.k8s.io/sig-storage/hostpathplugin:v1.7.3],SizeBytes:15224494,},ContainerImage{Names:[registry.k8s.io/coredns/coredns:v1.9.3],SizeBytes:14837849,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-node-driver-registrar@sha256:4fd21f36075b44d1a423dfb262ad79202ce54e95f5cbc4622a6c1c38ab287ad6 registry.k8s.io/sig-storage/csi-node-driver-registrar:v2.5.0],SizeBytes:9132637,},ContainerImage{Names:[registry.k8s.io/sig-storage/livenessprobe@sha256:406f59599991916d2942d8d02f076d957ed71b541ee19f09fc01723a6e6f5932 registry.k8s.io/sig-storage/livenessprobe:v2.6.0],SizeBytes:8240918,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/nginx@sha256:13616070e3f29de4417eee434a8ef472221c9e51b3d037b5a6b46cef08eb7443 registry.k8s.io/e2e-test-images/nginx:1.14-2],SizeBytes:6979041,},ContainerImage{Names:[docker.io/kindest/local-path-helper:v20220607-9a4d8d2a],SizeBytes:2859509,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/busybox@sha256:c318242786b139d18676b1c09a0ad7f15fc17f8f16a5b2e625cd0dc8c9703daf registry.k8s.io/e2e-test-images/busybox:1.29-2],SizeBytes:732424,},ContainerImage{Names:[registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d registry.k8s.io/pause:3.8],SizeBytes:311286,},ContainerImage{Names:[registry.k8s.io/pause:3.7],SizeBytes:311278,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
Feb  6 01:29:01.015: INFO: 
Logging kubelet events for node kind-worker
Feb  6 01:29:01.018: INFO: 
Logging pods the kubelet thinks is on node kind-worker
Feb  6 01:29:01.034: INFO: kindnet-c28mq started at 2023-02-06 00:56:53 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:29:01.034: INFO: 	Container kindnet-cni ready: true, restart count 0
Feb  6 01:29:01.034: INFO: kube-proxy-gfjzk started at 2023-02-06 00:56:53 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:29:01.034: INFO: 	Container kube-proxy ready: true, restart count 0
Feb  6 01:29:01.102: INFO: 
Latency metrics for node kind-worker
Feb  6 01:29:01.102: INFO: 
Logging node info for node kind-worker2
Feb  6 01:29:01.105: INFO: Node Info: &Node{ObjectMeta:{kind-worker2    fd792665-70e8-4b27-859a-cd25172161db 27977 0 2023-02-06 00:56:48 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/os:linux kubernetes.io/arch:amd64 kubernetes.io/hostname:kind-worker2 kubernetes.io/os:linux topology.hostpath.csi/node:kind-worker2] map[kubeadm.alpha.kubernetes.io/cri-socket:unix:///run/containerd/containerd.sock node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] [] [{kubeadm Update v1 2023-02-06 00:56:48 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:kubeadm.alpha.kubernetes.io/cri-socket":{}}}} } {kubelet Update v1 2023-02-06 00:56:48 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{".":{},"f:volumes.kubernetes.io/controller-managed-attach-detach":{}},"f:labels":{".":{},"f:beta.kubernetes.io/arch":{},"f:beta.kubernetes.io/os":{},"f:kubernetes.io/arch":{},"f:kubernetes.io/hostname":{},"f:kubernetes.io/os":{}}},"f:spec":{"f:providerID":{}}} } {kube-controller-manager Update v1 2023-02-06 00:57:08 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:node.alpha.kubernetes.io/ttl":{}}},"f:spec":{"f:podCIDR":{},"f:podCIDRs":{".":{},"v:\"10.244.2.0/24\"":{}}}} } {kube-controller-manager Update v1 2023-02-06 01:14:10 +0000 UTC FieldsV1 {"f:status":{"f:volumesAttached":{}}} status} {kubelet Update v1 2023-02-06 01:24:23 +0000 UTC FieldsV1 {"f:metadata":{"f:labels":{"f:topology.hostpath.csi/node":{}}},"f:status":{"f:conditions":{"k:{\"type\":\"DiskPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"MemoryPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"PIDPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"Ready\"}":{"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{}}},"f:images":{},"f:volumesInUse":{}}} status}]},Spec:NodeSpec{PodCIDR:10.244.2.0/24,DoNotUseExternalID:,ProviderID:kind://docker/kind/kind-worker2,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[10.244.2.0/24],},Status:NodeStatus{Capacity:ResourceList{cpu: {{8 0} {<nil>} 8 DecimalSI},ephemeral-storage: {{791251738624 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{54757810176 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{cpu: {{8 0} {<nil>} 8 DecimalSI},ephemeral-storage: {{791251738624 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{54757810176 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2023-02-06 01:24:23 +0000 UTC,LastTransitionTime:2023-02-06 00:56:47 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2023-02-06 01:24:23 +0000 UTC,LastTransitionTime:2023-02-06 00:56:47 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2023-02-06 01:24:23 +0000 UTC,LastTransitionTime:2023-02-06 00:56:47 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2023-02-06 01:24:23 +0000 UTC,LastTransitionTime:2023-02-06 00:57:08 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:172.18.0.4,},NodeAddress{Type:Hostname,Address:kind-worker2,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:de9b0ce4c4194dd295a948bc056e313c,SystemUUID:89fd3899-adba-4b64-a6ab-cac0ef1fa3b4,BootID:1091d151-f16c-4fdc-899a-0bff3500ee70,KernelVersion:5.4.0-1086-gke,OSImage:Ubuntu 22.04.1 LTS,ContainerRuntimeVersion:containerd://1.6.14,KubeletVersion:v1.25.7-rc.0.16+74817504841bde,KubeProxyVersion:v1.25.7-rc.0.16+74817504841bde,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:57e1263cc818c8c5dc39e96fd84b43e749372f7d8e0194473db182c02d736f7a registry.k8s.io/kube-apiserver:v1.25.7-rc.0.16_74817504841bde],SizeBytes:129108435,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:1e63ddf4e3d1ee17eb9088ac421b83265235b72a43aa83f480cff1a72d879e4d registry.k8s.io/kube-controller-manager:v1.25.7-rc.0.16_74817504841bde],SizeBytes:118450487,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/jessie-dnsutils@sha256:11e6a66017ba4e4b938c1612b7a54a3befcefd354796c04e1dba76873a13518e registry.k8s.io/e2e-test-images/jessie-dnsutils:1.5],SizeBytes:112030526,},ContainerImage{Names:[registry.k8s.io/etcd:3.5.6-0],SizeBytes:102542580,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:e84585d19592e86a87b740345f7251ea2fe5752bd3c944759025c5a8382436f2 registry.k8s.io/kube-scheduler:v1.25.7-rc.0.16_74817504841bde],SizeBytes:51931448,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/agnhost@sha256:af7e3857d87770ddb40f5ea4f89b5a2709504ab1ee31f9ea4ab5823c045f2146 registry.k8s.io/e2e-test-images/agnhost:2.40],SizeBytes:51155161,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/nautilus@sha256:99c0d6f1ad24a1aa1905d9c6534d193f268f7b23f9add2ae6bb41f31094bdd5c registry.k8s.io/e2e-test-images/nautilus:1.5],SizeBytes:49642095,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:7006371f635ca3e22c794b167237c78cdecc5689d6b60bbb5b6257ec2a32463a registry.k8s.io/kube-proxy:v1.25.7-rc.0.16_74817504841bde],SizeBytes:48821978,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/httpd@sha256:20f25f275d46aa728f7615a1ccc19c78b2ed89435bf943a44b339f70f45508e6 registry.k8s.io/e2e-test-images/httpd:2.4.39-2],SizeBytes:41902010,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/httpd@sha256:1b9d1b2f36cb2dbee1960e82a9344aeb11bd4c4c03abf5e1853e0559c23855e3 registry.k8s.io/e2e-test-images/httpd:2.4.38-2],SizeBytes:40764680,},ContainerImage{Names:[docker.io/kindest/kindnetd:v20221004-44d545d1],SizeBytes:25830582,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-provisioner@sha256:122bfb8c1edabb3c0edd63f06523e6940d958d19b3957dc7b1d6f81e9f1f6119 registry.k8s.io/sig-storage/csi-provisioner:v3.1.0],SizeBytes:23345856,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-resizer@sha256:9ebbf9f023e7b41ccee3d52afe39a89e3ddacdbb69269d583abfc25847cfd9e4 registry.k8s.io/sig-storage/csi-resizer:v1.4.0],SizeBytes:22381475,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-snapshotter@sha256:89e900a160a986a1a7a4eba7f5259e510398fa87ca9b8a729e7dec59e04c7709 registry.k8s.io/sig-storage/csi-snapshotter:v5.0.1],SizeBytes:22163966,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-attacher@sha256:8b9c313c05f54fb04f8d430896f5f5904b6cb157df261501b29adc04d2b2dc7b registry.k8s.io/sig-storage/csi-attacher:v3.4.0],SizeBytes:22085298,},ContainerImage{Names:[docker.io/kindest/local-path-provisioner:v0.0.22-kind.0],SizeBytes:17375346,},ContainerImage{Names:[registry.k8s.io/sig-storage/hostpathplugin@sha256:6029c252dae6178c99b580de72d7776158edbc81be0de15cedc4152a3acfed18 registry.k8s.io/sig-storage/hostpathplugin:v1.7.3],SizeBytes:15224494,},ContainerImage{Names:[registry.k8s.io/coredns/coredns:v1.9.3],SizeBytes:14837849,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-node-driver-registrar@sha256:4fd21f36075b44d1a423dfb262ad79202ce54e95f5cbc4622a6c1c38ab287ad6 registry.k8s.io/sig-storage/csi-node-driver-registrar:v2.5.0],SizeBytes:9132637,},ContainerImage{Names:[registry.k8s.io/sig-storage/livenessprobe@sha256:406f59599991916d2942d8d02f076d957ed71b541ee19f09fc01723a6e6f5932 registry.k8s.io/sig-storage/livenessprobe:v2.6.0],SizeBytes:8240918,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/nginx@sha256:13616070e3f29de4417eee434a8ef472221c9e51b3d037b5a6b46cef08eb7443 registry.k8s.io/e2e-test-images/nginx:1.14-2],SizeBytes:6979041,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/nonewprivs@sha256:8ac1264691820febacf3aea5d152cbde6d10685731ec14966a9401c6f47a68ac registry.k8s.io/e2e-test-images/nonewprivs:1.3],SizeBytes:3263463,},ContainerImage{Names:[docker.io/kindest/local-path-helper:v20220607-9a4d8d2a],SizeBytes:2859509,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/busybox@sha256:c318242786b139d18676b1c09a0ad7f15fc17f8f16a5b2e625cd0dc8c9703daf registry.k8s.io/e2e-test-images/busybox:1.29-2],SizeBytes:732424,},ContainerImage{Names:[registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d registry.k8s.io/pause:3.8],SizeBytes:311286,},ContainerImage{Names:[registry.k8s.io/pause:3.7],SizeBytes:311278,},},VolumesInUse:[kubernetes.io/csi/csi-hostpath-ephemeral-1313^56aab5ed-a5b9-11ed-842e-9e5b967d35a7 kubernetes.io/csi/csi-hostpath-volume-expand-6065^568dffcc-a5b9-11ed-9853-1e18a871a9f1],VolumesAttached:[]AttachedVolume{AttachedVolume{Name:kubernetes.io/csi/csi-hostpath-volume-expand-6065^568dffcc-a5b9-11ed-9853-1e18a871a9f1,DevicePath:,},AttachedVolume{Name:kubernetes.io/csi/csi-hostpath-ephemeral-1313^56aab5ed-a5b9-11ed-842e-9e5b967d35a7,DevicePath:,},},Config:nil,},}
Feb  6 01:29:01.106: INFO: 
Logging kubelet events for node kind-worker2
Feb  6 01:29:01.109: INFO: 
Logging pods the kubelet thinks is on node kind-worker2
Feb  6 01:29:01.115: INFO: inline-volume-tester-txq4j started at 2023-02-06 00:58:16 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:29:01.115: INFO: 	Container csi-volume-tester ready: false, restart count 0
Feb  6 01:29:01.115: INFO: pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1 started at 2023-02-06 00:58:16 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:29:01.115: INFO: 	Container write-pod ready: false, restart count 0
Feb  6 01:29:01.115: INFO: kube-proxy-68p4n started at 2023-02-06 00:56:56 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:29:01.115: INFO: 	Container kube-proxy ready: true, restart count 0
Feb  6 01:29:01.115: INFO: kindnet-4xswj started at 2023-02-06 00:56:56 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:29:01.115: INFO: 	Container kindnet-cni ready: true, restart count 0
Feb  6 01:29:01.192: INFO: 
Latency metrics for node kind-worker2
Feb  6 01:29:01.192: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "volume-expand-6065" for this suite. 02/06/23 01:29:01.195

				Click to see stdout/stderrfrom junit_01.xml

Filter through log files | View test history on testgrid

Kubernetes e2e suite [It] [sig-storage] CSI Volumes [Driver: csi-hostpath] [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral should support expansion of pvcs created for ephemeral pvcs 16m25s

go run hack/e2e.go -v --test --test_args='--ginkgo.focus=Kubernetes\se2e\ssuite\s\[It\]\s\[sig\-storage\]\sCSI\sVolumes\s\[Driver\:\scsi\-hostpath\]\s\[Testpattern\:\sGeneric\sEphemeral\-volume\s\(default\sfs\)\s\(immediate\-binding\)\]\sephemeral\sshould\ssupport\sexpansion\sof\spvcs\screated\sfor\sephemeral\spvcs$'

test/e2e/storage/testsuites/ephemeral.go:416
k8s.io/kubernetes/test/e2e/storage/testsuites.EphemeralTest.TestEphemeral({{0x7ca6438, 0xc000a2d800}, 0xc000f48780, {0xc000c0a2c0, 0xe}, {0x0, 0x0}, 0xc0027b05a0, {{0xc002838e80, 0xc}, ...}, ...})
	test/e2e/storage/testsuites/ephemeral.go:416 +0x5f0
k8s.io/kubernetes/test/e2e/storage/testsuites.(*ephemeralTestSuite).DefineTests.func5()
	test/e2e/storage/testsuites/ephemeral.go:277 +0x205
				[BeforeEach] [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral
  test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral
  test/e2e/framework/framework.go:186
STEP: Creating a kubernetes client 02/06/23 00:57:51.286
Feb  6 00:57:51.286: INFO: >>> kubeConfig: /root/.kube/kind-test-config
STEP: Building a namespace api object, basename ephemeral 02/06/23 00:57:51.288
STEP: Waiting for a default service account to be provisioned in namespace 02/06/23 00:57:51.543
STEP: Waiting for kube-root-ca.crt to be provisioned in namespace 02/06/23 00:57:51.554
[It] should support expansion of pvcs created for ephemeral pvcs
  test/e2e/storage/testsuites/ephemeral.go:220
Feb  6 00:57:51.593: INFO: Pod inline-volume-hvkg9 has the following logs: 
Feb  6 00:57:51.600: INFO: Deleting pod "inline-volume-hvkg9" in namespace "ephemeral-7578"
Feb  6 00:57:51.625: INFO: Wait up to 5m0s for pod "inline-volume-hvkg9" to be fully deleted
STEP: Building a driver namespace object, basename ephemeral-7578 02/06/23 00:57:53.638
STEP: Waiting for a default service account to be provisioned in namespace 02/06/23 00:57:53.655
STEP: deploying csi-hostpath driver 02/06/23 00:57:53.659
Feb  6 00:57:53.686: INFO: creating *v1.ServiceAccount: ephemeral-7578-7697/csi-attacher
Feb  6 00:57:53.693: INFO: creating *v1.ClusterRole: external-attacher-runner-ephemeral-7578
Feb  6 00:57:53.693: INFO: Define cluster role external-attacher-runner-ephemeral-7578
Feb  6 00:57:53.700: INFO: creating *v1.ClusterRoleBinding: csi-attacher-role-ephemeral-7578
Feb  6 00:57:53.706: INFO: creating *v1.Role: ephemeral-7578-7697/external-attacher-cfg-ephemeral-7578
Feb  6 00:57:53.712: INFO: creating *v1.RoleBinding: ephemeral-7578-7697/csi-attacher-role-cfg
Feb  6 00:57:53.716: INFO: creating *v1.ServiceAccount: ephemeral-7578-7697/csi-provisioner
Feb  6 00:57:53.720: INFO: creating *v1.ClusterRole: external-provisioner-runner-ephemeral-7578
Feb  6 00:57:53.720: INFO: Define cluster role external-provisioner-runner-ephemeral-7578
Feb  6 00:57:53.725: INFO: creating *v1.ClusterRoleBinding: csi-provisioner-role-ephemeral-7578
Feb  6 00:57:53.730: INFO: creating *v1.Role: ephemeral-7578-7697/external-provisioner-cfg-ephemeral-7578
Feb  6 00:57:53.734: INFO: creating *v1.RoleBinding: ephemeral-7578-7697/csi-provisioner-role-cfg
Feb  6 00:57:53.738: INFO: creating *v1.ServiceAccount: ephemeral-7578-7697/csi-snapshotter
Feb  6 00:57:53.741: INFO: creating *v1.ClusterRole: external-snapshotter-runner-ephemeral-7578
Feb  6 00:57:53.741: INFO: Define cluster role external-snapshotter-runner-ephemeral-7578
Feb  6 00:57:53.745: INFO: creating *v1.ClusterRoleBinding: csi-snapshotter-role-ephemeral-7578
Feb  6 00:57:53.756: INFO: creating *v1.Role: ephemeral-7578-7697/external-snapshotter-leaderelection-ephemeral-7578
Feb  6 00:57:53.763: INFO: creating *v1.RoleBinding: ephemeral-7578-7697/external-snapshotter-leaderelection
Feb  6 00:57:53.773: INFO: creating *v1.ServiceAccount: ephemeral-7578-7697/csi-external-health-monitor-controller
Feb  6 00:57:53.776: INFO: creating *v1.ClusterRole: external-health-monitor-controller-runner-ephemeral-7578
Feb  6 00:57:53.776: INFO: Define cluster role external-health-monitor-controller-runner-ephemeral-7578
Feb  6 00:57:53.781: INFO: creating *v1.ClusterRoleBinding: csi-external-health-monitor-controller-role-ephemeral-7578
Feb  6 00:57:53.786: INFO: creating *v1.Role: ephemeral-7578-7697/external-health-monitor-controller-cfg-ephemeral-7578
Feb  6 00:57:53.802: INFO: creating *v1.RoleBinding: ephemeral-7578-7697/csi-external-health-monitor-controller-role-cfg
Feb  6 00:57:53.847: INFO: creating *v1.ServiceAccount: ephemeral-7578-7697/csi-resizer
Feb  6 00:57:53.862: INFO: creating *v1.ClusterRole: external-resizer-runner-ephemeral-7578
Feb  6 00:57:53.862: INFO: Define cluster role external-resizer-runner-ephemeral-7578
Feb  6 00:57:53.879: INFO: creating *v1.ClusterRoleBinding: csi-resizer-role-ephemeral-7578
Feb  6 00:57:53.896: INFO: creating *v1.Role: ephemeral-7578-7697/external-resizer-cfg-ephemeral-7578
Feb  6 00:57:53.905: INFO: creating *v1.RoleBinding: ephemeral-7578-7697/csi-resizer-role-cfg
Feb  6 00:57:53.910: INFO: creating *v1.CSIDriver: csi-hostpath-ephemeral-7578
Feb  6 00:57:53.921: INFO: creating *v1.ServiceAccount: ephemeral-7578-7697/csi-hostpathplugin-sa
Feb  6 00:57:53.932: INFO: creating *v1.ClusterRoleBinding: csi-hostpathplugin-attacher-cluster-role-ephemeral-7578
Feb  6 00:57:53.938: INFO: creating *v1.ClusterRoleBinding: csi-hostpathplugin-health-monitor-controller-cluster-role-ephemeral-7578
Feb  6 00:57:53.949: INFO: creating *v1.ClusterRoleBinding: csi-hostpathplugin-provisioner-cluster-role-ephemeral-7578
Feb  6 00:57:53.967: INFO: creating *v1.ClusterRoleBinding: csi-hostpathplugin-resizer-cluster-role-ephemeral-7578
Feb  6 00:57:53.995: INFO: creating *v1.ClusterRoleBinding: csi-hostpathplugin-snapshotter-cluster-role-ephemeral-7578
Feb  6 00:57:54.006: INFO: creating *v1.RoleBinding: ephemeral-7578-7697/csi-hostpathplugin-attacher-role
Feb  6 00:57:54.011: INFO: creating *v1.RoleBinding: ephemeral-7578-7697/csi-hostpathplugin-health-monitor-controller-role
Feb  6 00:57:54.022: INFO: creating *v1.RoleBinding: ephemeral-7578-7697/csi-hostpathplugin-provisioner-role
Feb  6 00:57:54.052: INFO: creating *v1.RoleBinding: ephemeral-7578-7697/csi-hostpathplugin-resizer-role
Feb  6 00:57:54.071: INFO: creating *v1.RoleBinding: ephemeral-7578-7697/csi-hostpathplugin-snapshotter-role
Feb  6 00:57:54.087: INFO: creating *v1.StatefulSet: ephemeral-7578-7697/csi-hostpathplugin
Feb  6 00:57:54.121: INFO: creating *v1.ClusterRoleBinding: psp-csi-hostpath-role-ephemeral-7578
Feb  6 00:57:54.143: INFO: Creating resource for dynamic PV
Feb  6 00:57:54.143: INFO: Using claimSize:1Mi, test suite supported size:{ }, driver(csi-hostpath) supported size:{ } 
STEP: creating a StorageClass ephemeral-7578bqhv8 02/06/23 00:57:54.143
STEP: checking the requested inline volume exists in the pod running on node {Name:kind-worker2 Selector:map[] Affinity:nil} 02/06/23 00:57:54.158
Feb  6 00:57:54.165: INFO: Waiting up to 15m0s for pod "inline-volume-tester-g62vf" in namespace "ephemeral-7578" to be "running"
Feb  6 00:57:54.169: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3.644418ms
Feb  6 00:57:56.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2.007320679s
Feb  6 00:57:58.194: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4.028383948s
Feb  6 00:58:00.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6.007884048s
Feb  6 00:58:02.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8.009636026s
Feb  6 00:58:04.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10.008342068s
Feb  6 00:58:06.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12.008212825s
Feb  6 00:58:08.422: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14.256226804s
Feb  6 00:58:10.178: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 16.012112249s
Feb  6 00:58:12.176: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 18.01026596s
Feb  6 00:58:14.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 20.008034472s
Feb  6 00:58:16.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 22.008977778s
Feb  6 00:58:18.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 24.008133284s
Feb  6 00:58:20.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 26.007561431s
Feb  6 00:58:22.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 28.008354326s
Feb  6 00:58:24.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 30.006933057s
Feb  6 00:58:26.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 32.006919676s
Feb  6 00:58:28.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 34.007473157s
Feb  6 00:58:30.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 36.007438447s
Feb  6 00:58:32.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 38.006920577s
Feb  6 00:58:34.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 40.007562125s
Feb  6 00:58:36.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 42.007564565s
Feb  6 00:58:38.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 44.007525868s
Feb  6 00:58:40.182: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 46.016946735s
Feb  6 00:58:42.183: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 48.017173531s
Feb  6 00:58:44.177: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 50.01102125s
Feb  6 00:58:46.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 52.009415639s
Feb  6 00:58:48.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 54.009938947s
Feb  6 00:58:50.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 56.007217462s
Feb  6 00:58:52.176: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 58.011001803s
Feb  6 00:58:54.190: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m0.024065593s
Feb  6 00:58:56.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m2.007788648s
Feb  6 00:58:58.178: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m4.012897158s
Feb  6 00:59:00.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m6.008466953s
Feb  6 00:59:02.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m8.007776554s
Feb  6 00:59:04.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m10.008942095s
Feb  6 00:59:06.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m12.008003479s
Feb  6 00:59:08.180: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m14.014812466s
Feb  6 00:59:10.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m16.006410078s
Feb  6 00:59:12.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m18.007248526s
Feb  6 00:59:14.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m20.007495461s
Feb  6 00:59:16.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m22.007977027s
Feb  6 00:59:18.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m24.00792514s
Feb  6 00:59:20.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m26.009884825s
Feb  6 00:59:22.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m28.006357016s
Feb  6 00:59:24.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m30.008748243s
Feb  6 00:59:26.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m32.006526822s
Feb  6 00:59:28.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m34.006728959s
Feb  6 00:59:30.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m36.008660887s
Feb  6 00:59:32.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m38.0082145s
Feb  6 00:59:34.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m40.006957872s
Feb  6 00:59:36.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m42.007032876s
Feb  6 00:59:38.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m44.007280225s
Feb  6 00:59:40.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m46.007580545s
Feb  6 00:59:42.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m48.008485139s
Feb  6 00:59:44.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m50.008990501s
Feb  6 00:59:46.176: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m52.010253054s
Feb  6 00:59:48.187: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m54.02115641s
Feb  6 00:59:50.184: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m56.018404052s
Feb  6 00:59:52.180: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 1m58.014279801s
Feb  6 00:59:54.179: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m0.013586957s
Feb  6 00:59:56.195: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m2.029623821s
Feb  6 00:59:58.198: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m4.03259317s
Feb  6 01:00:00.242: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m6.076280239s
Feb  6 01:00:02.177: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m8.011045697s
Feb  6 01:00:04.188: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m10.022743785s
Feb  6 01:00:06.177: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m12.011053376s
Feb  6 01:00:08.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m14.008086043s
Feb  6 01:00:10.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m16.00980218s
Feb  6 01:00:12.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m18.006502024s
Feb  6 01:00:14.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m20.006139566s
Feb  6 01:00:16.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m22.006940679s
Feb  6 01:00:18.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m24.009053445s
Feb  6 01:00:20.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m26.008201764s
Feb  6 01:00:22.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m28.008667961s
Feb  6 01:00:24.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m30.008802736s
Feb  6 01:00:26.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m32.007450739s
Feb  6 01:00:28.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m34.006288265s
Feb  6 01:00:30.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m36.007300513s
Feb  6 01:00:32.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m38.007153693s
Feb  6 01:00:34.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m40.006732888s
Feb  6 01:00:36.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m42.007660792s
Feb  6 01:00:38.182: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m44.016366094s
Feb  6 01:00:40.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m46.00716338s
Feb  6 01:00:42.196: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m48.030753956s
Feb  6 01:00:44.184: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m50.018308413s
Feb  6 01:00:46.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m52.00704107s
Feb  6 01:00:48.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m54.007129138s
Feb  6 01:00:50.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m56.008051073s
Feb  6 01:00:52.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 2m58.007420417s
Feb  6 01:00:54.176: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m0.010452168s
Feb  6 01:00:56.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m2.006978678s
Feb  6 01:00:58.179: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m4.013782957s
Feb  6 01:01:00.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m6.008289227s
Feb  6 01:01:02.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m8.007518674s
Feb  6 01:01:04.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m10.009748039s
Feb  6 01:01:06.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m12.00870387s
Feb  6 01:01:08.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m14.008625705s
Feb  6 01:01:10.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m16.008074551s
Feb  6 01:01:12.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m18.009331424s
Feb  6 01:01:14.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m20.008364772s
Feb  6 01:01:16.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m22.007773675s
Feb  6 01:01:18.176: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m24.010593994s
Feb  6 01:01:20.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m26.007653362s
Feb  6 01:01:22.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m28.008500625s
Feb  6 01:01:24.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m30.008351714s
Feb  6 01:01:26.180: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m32.014044203s
Feb  6 01:01:28.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m34.007375727s
Feb  6 01:01:30.185: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m36.019051262s
Feb  6 01:01:32.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m38.006587082s
Feb  6 01:01:34.177: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m40.011630206s
Feb  6 01:01:36.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m42.008547937s
Feb  6 01:01:38.176: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m44.010176555s
Feb  6 01:01:40.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m46.008727715s
Feb  6 01:01:42.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m48.007621785s
Feb  6 01:01:44.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m50.008239221s
Feb  6 01:01:46.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m52.007682872s
Feb  6 01:01:48.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m54.007611323s
Feb  6 01:01:50.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m56.008697274s
Feb  6 01:01:52.177: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 3m58.011026557s
Feb  6 01:01:54.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m0.006985244s
Feb  6 01:01:56.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m2.007427475s
Feb  6 01:01:58.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m4.006713595s
Feb  6 01:02:00.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m6.007247359s
Feb  6 01:02:02.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m8.007486553s
Feb  6 01:02:04.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m10.007215062s
Feb  6 01:02:06.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m12.008444306s
Feb  6 01:02:08.179: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m14.013086852s
Feb  6 01:02:10.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m16.009135866s
Feb  6 01:02:12.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m18.007757223s
Feb  6 01:02:14.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m20.00760668s
Feb  6 01:02:16.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m22.007261023s
Feb  6 01:02:18.184: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m24.01874836s
Feb  6 01:02:20.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m26.007565615s
Feb  6 01:02:22.179: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m28.013497965s
Feb  6 01:02:24.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m30.009022197s
Feb  6 01:02:26.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m32.008636289s
Feb  6 01:02:28.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m34.009929069s
Feb  6 01:02:30.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m36.007725257s
Feb  6 01:02:32.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m38.007565242s
Feb  6 01:02:34.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m40.008714268s
Feb  6 01:02:36.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m42.008793528s
Feb  6 01:02:38.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m44.00750436s
Feb  6 01:02:40.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m46.009936383s
Feb  6 01:02:42.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m48.009920053s
Feb  6 01:02:44.178: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m50.012108696s
Feb  6 01:02:46.187: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m52.021380151s
Feb  6 01:02:48.176: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m54.010417683s
Feb  6 01:02:50.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m56.009800043s
Feb  6 01:02:52.176: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 4m58.010165356s
Feb  6 01:02:54.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m0.008615377s
Feb  6 01:02:56.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m2.006720381s
Feb  6 01:02:58.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m4.007492099s
Feb  6 01:03:00.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m6.008628618s
Feb  6 01:03:02.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m8.008037541s
Feb  6 01:03:04.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m10.007604316s
Feb  6 01:03:06.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m12.007914116s
Feb  6 01:03:08.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m14.007544234s
Feb  6 01:03:10.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m16.00798816s
Feb  6 01:03:12.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m18.009968854s
Feb  6 01:03:14.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m20.007346009s
Feb  6 01:03:16.177: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m22.011140985s
Feb  6 01:03:18.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m24.007451299s
Feb  6 01:03:20.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m26.008662883s
Feb  6 01:03:22.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m28.008229394s
Feb  6 01:03:24.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m30.008447135s
Feb  6 01:03:26.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m32.008350905s
Feb  6 01:03:28.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m34.00774116s
Feb  6 01:03:30.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m36.007350894s
Feb  6 01:03:32.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m38.008806462s
Feb  6 01:03:34.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m40.009118217s
Feb  6 01:03:36.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m42.00860734s
Feb  6 01:03:38.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m44.007632642s
Feb  6 01:03:40.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m46.007999111s
Feb  6 01:03:42.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m48.008480911s
Feb  6 01:03:44.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m50.007776031s
Feb  6 01:03:46.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m52.008184176s
Feb  6 01:03:48.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m54.00733646s
Feb  6 01:03:50.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m56.007683934s
Feb  6 01:03:52.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 5m58.008852349s
Feb  6 01:03:54.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m0.008489893s
Feb  6 01:03:56.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m2.008189401s
Feb  6 01:03:58.177: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m4.011774878s
Feb  6 01:04:00.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m6.008299776s
Feb  6 01:04:02.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m8.00793208s
Feb  6 01:04:04.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m10.008697776s
Feb  6 01:04:06.176: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m12.010830638s
Feb  6 01:04:08.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m14.007627227s
Feb  6 01:04:10.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m16.00808445s
Feb  6 01:04:12.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m18.009758903s
Feb  6 01:04:14.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m20.007110232s
Feb  6 01:04:16.179: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m22.013097914s
Feb  6 01:04:18.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m24.007812536s
Feb  6 01:04:20.177: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m26.011609244s
Feb  6 01:04:22.179: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m28.013257844s
Feb  6 01:04:24.184: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m30.018481113s
Feb  6 01:04:26.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m32.00763863s
Feb  6 01:04:28.182: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m34.01621086s
Feb  6 01:04:30.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m36.008314523s
Feb  6 01:04:32.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m38.007625238s
Feb  6 01:04:34.178: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m40.012545484s
Feb  6 01:04:36.176: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m42.01005059s
Feb  6 01:04:38.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m44.007956534s
Feb  6 01:04:40.180: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m46.01495657s
Feb  6 01:04:42.226: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m48.060719553s
Feb  6 01:04:44.186: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m50.02038579s
Feb  6 01:04:46.182: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m52.016037829s
Feb  6 01:04:48.182: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m54.016648516s
Feb  6 01:04:50.184: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m56.018315491s
Feb  6 01:04:52.199: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 6m58.033956195s
Feb  6 01:04:54.180: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m0.014507662s
Feb  6 01:04:56.192: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m2.026481324s
Feb  6 01:04:58.239: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m4.073750151s
Feb  6 01:05:00.180: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m6.015000754s
Feb  6 01:05:02.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m8.008189282s
Feb  6 01:05:04.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m10.008253236s
Feb  6 01:05:06.183: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m12.017073166s
Feb  6 01:05:08.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m14.00786773s
Feb  6 01:05:10.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m16.007800494s
Feb  6 01:05:12.180: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m18.014098276s
Feb  6 01:05:14.186: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m20.020878334s
Feb  6 01:05:16.178: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m22.012030853s
Feb  6 01:05:18.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m24.009481458s
Feb  6 01:05:20.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m26.007818535s
Feb  6 01:05:22.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m28.008715903s
Feb  6 01:05:24.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m30.008599461s
Feb  6 01:05:26.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m32.008541502s
Feb  6 01:05:28.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m34.009379071s
Feb  6 01:05:30.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m36.008222328s
Feb  6 01:05:32.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m38.009078874s
Feb  6 01:05:34.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m40.008466375s
Feb  6 01:05:36.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m42.008167542s
Feb  6 01:05:38.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m44.007591354s
Feb  6 01:05:40.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m46.007347967s
Feb  6 01:05:42.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m48.00805319s
Feb  6 01:05:44.186: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m50.020472139s
Feb  6 01:05:46.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m52.007214056s
Feb  6 01:05:48.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m54.008272389s
Feb  6 01:05:50.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m56.00780505s
Feb  6 01:05:52.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 7m58.008048109s
Feb  6 01:05:54.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m0.008326473s
Feb  6 01:05:56.176: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m2.01029973s
Feb  6 01:05:58.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m4.00796133s
Feb  6 01:06:00.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m6.0083402s
Feb  6 01:06:02.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m8.008376505s
Feb  6 01:06:04.176: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m10.010382911s
Feb  6 01:06:06.177: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m12.01161525s
Feb  6 01:06:08.182: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m14.016303976s
Feb  6 01:06:10.178: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m16.012619173s
Feb  6 01:06:12.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m18.0071442s
Feb  6 01:06:14.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m20.006416634s
Feb  6 01:06:16.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m22.007277457s
Feb  6 01:06:18.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m24.008402455s
Feb  6 01:06:20.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m26.006721839s
Feb  6 01:06:22.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m28.007575771s
Feb  6 01:06:24.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m30.008373434s
Feb  6 01:06:26.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m32.006635778s
Feb  6 01:06:28.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m34.007572371s
Feb  6 01:06:30.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m36.007819739s
Feb  6 01:06:32.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m38.007622677s
Feb  6 01:06:34.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m40.007288827s
Feb  6 01:06:36.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m42.007113578s
Feb  6 01:06:38.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m44.007094289s
Feb  6 01:06:40.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m46.006698658s
Feb  6 01:06:42.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m48.007368953s
Feb  6 01:06:44.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m50.00714255s
Feb  6 01:06:46.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m52.007466553s
Feb  6 01:06:48.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m54.007152882s
Feb  6 01:06:50.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m56.007465136s
Feb  6 01:06:52.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 8m58.006986066s
Feb  6 01:06:54.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m0.006491122s
Feb  6 01:06:56.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m2.007259279s
Feb  6 01:06:58.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m4.00775066s
Feb  6 01:07:00.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m6.00752384s
Feb  6 01:07:02.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m8.006544409s
Feb  6 01:07:04.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m10.007074926s
Feb  6 01:07:06.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m12.007439752s
Feb  6 01:07:08.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m14.007420054s
Feb  6 01:07:10.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m16.007590246s
Feb  6 01:07:12.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m18.00661959s
Feb  6 01:07:14.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m20.007289981s
Feb  6 01:07:16.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m22.007745229s
Feb  6 01:07:18.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m24.007775144s
Feb  6 01:07:20.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m26.007186392s
Feb  6 01:07:22.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m28.007487447s
Feb  6 01:07:24.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m30.007390696s
Feb  6 01:07:26.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m32.006993481s
Feb  6 01:07:28.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m34.007289672s
Feb  6 01:07:30.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m36.007608339s
Feb  6 01:07:32.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m38.007371354s
Feb  6 01:07:34.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m40.007445261s
Feb  6 01:07:36.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m42.007055685s
Feb  6 01:07:38.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m44.006936105s
Feb  6 01:07:40.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m46.007887621s
Feb  6 01:07:42.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m48.007899576s
Feb  6 01:07:44.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m50.007695433s
Feb  6 01:07:46.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m52.007540006s
Feb  6 01:07:48.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m54.008339708s
Feb  6 01:07:50.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m56.007028877s
Feb  6 01:07:52.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 9m58.007141697s
Feb  6 01:07:54.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m0.007150797s
Feb  6 01:07:56.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m2.007719575s
Feb  6 01:07:58.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m4.007307032s
Feb  6 01:08:00.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m6.007374289s
Feb  6 01:08:02.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m8.00783525s
Feb  6 01:08:04.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m10.006908537s
Feb  6 01:08:06.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m12.007607596s
Feb  6 01:08:08.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m14.007766478s
Feb  6 01:08:10.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m16.00751958s
Feb  6 01:08:12.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m18.00704781s
Feb  6 01:08:14.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m20.007777235s
Feb  6 01:08:16.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m22.006882391s
Feb  6 01:08:18.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m24.007863325s
Feb  6 01:08:20.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m26.007766013s
Feb  6 01:08:22.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m28.007147934s
Feb  6 01:08:24.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m30.007735597s
Feb  6 01:08:26.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m32.007227633s
Feb  6 01:08:28.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m34.007550807s
Feb  6 01:08:30.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m36.007849144s
Feb  6 01:08:32.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m38.007200427s
Feb  6 01:08:34.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m40.006951426s
Feb  6 01:08:36.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m42.006912795s
Feb  6 01:08:38.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m44.007285944s
Feb  6 01:08:40.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m46.008351528s
Feb  6 01:08:42.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m48.007743058s
Feb  6 01:08:44.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m50.007272286s
Feb  6 01:08:46.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m52.008091821s
Feb  6 01:08:48.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m54.008094795s
Feb  6 01:08:50.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m56.007438894s
Feb  6 01:08:52.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 10m58.007826354s
Feb  6 01:08:54.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m0.007445015s
Feb  6 01:08:56.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m2.007677387s
Feb  6 01:08:58.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m4.007462194s
Feb  6 01:09:00.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m6.008805877s
Feb  6 01:09:02.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m8.007623914s
Feb  6 01:09:04.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m10.007295447s
Feb  6 01:09:06.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m12.007819182s
Feb  6 01:09:08.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m14.008035864s
Feb  6 01:09:10.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m16.008112575s
Feb  6 01:09:12.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m18.007326319s
Feb  6 01:09:14.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m20.007279729s
Feb  6 01:09:16.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m22.008047053s
Feb  6 01:09:18.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m24.007349619s
Feb  6 01:09:20.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m26.00797994s
Feb  6 01:09:22.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m28.007185966s
Feb  6 01:09:24.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m30.008246551s
Feb  6 01:09:26.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m32.007978485s
Feb  6 01:09:28.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m34.007040251s
Feb  6 01:09:30.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m36.007957539s
Feb  6 01:09:32.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m38.007800675s
Feb  6 01:09:34.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m40.007441208s
Feb  6 01:09:36.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m42.00776302s
Feb  6 01:09:38.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m44.007412404s
Feb  6 01:09:40.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m46.007719619s
Feb  6 01:09:42.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m48.007518814s
Feb  6 01:09:44.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m50.007300503s
Feb  6 01:09:46.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m52.007388022s
Feb  6 01:09:48.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m54.00728771s
Feb  6 01:09:50.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m56.00793599s
Feb  6 01:09:52.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 11m58.008170706s
Feb  6 01:09:54.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m0.007462917s
Feb  6 01:09:56.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m2.007713661s
Feb  6 01:09:58.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m4.007743122s
Feb  6 01:10:00.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m6.008317803s
Feb  6 01:10:02.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m8.007534771s
Feb  6 01:10:04.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m10.006940342s
Feb  6 01:10:06.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m12.007672229s
Feb  6 01:10:08.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m14.007714521s
Feb  6 01:10:10.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m16.007040774s
Feb  6 01:10:12.174: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m18.008138075s
Feb  6 01:10:14.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m20.007256349s
Feb  6 01:10:16.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m22.007667484s
Feb  6 01:10:18.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m24.007828695s
Feb  6 01:10:20.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m26.007847124s
Feb  6 01:10:22.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m28.007304452s
Feb  6 01:10:24.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m30.00724481s
Feb  6 01:10:26.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m32.006941595s
Feb  6 01:10:28.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m34.007004301s
Feb  6 01:10:30.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m36.006684748s
Feb  6 01:10:32.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m38.006770524s
Feb  6 01:10:34.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m40.007238726s
Feb  6 01:10:36.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m42.006865151s
Feb  6 01:10:38.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m44.0076448s
Feb  6 01:10:40.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m46.007809838s
Feb  6 01:10:42.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m48.006865087s
Feb  6 01:10:44.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m50.007435053s
Feb  6 01:10:46.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m52.007334508s
Feb  6 01:10:48.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m54.007644754s
Feb  6 01:10:50.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m56.006792717s
Feb  6 01:10:52.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 12m58.006891413s
Feb  6 01:10:54.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m0.007675859s
Feb  6 01:10:56.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m2.007786212s
Feb  6 01:10:58.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m4.007544111s
Feb  6 01:11:00.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m6.007723492s
Feb  6 01:11:02.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m8.006790822s
Feb  6 01:11:04.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m10.007416161s
Feb  6 01:11:06.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m12.007597085s
Feb  6 01:11:08.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m14.007701427s
Feb  6 01:11:10.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m16.006922104s
Feb  6 01:11:12.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m18.006926161s
Feb  6 01:11:14.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m20.006961686s
Feb  6 01:11:16.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m22.006896775s
Feb  6 01:11:18.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m24.007016698s
Feb  6 01:11:20.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m26.007855334s
Feb  6 01:11:22.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m28.007231157s
Feb  6 01:11:24.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m30.007730509s
Feb  6 01:11:26.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m32.006978832s
Feb  6 01:11:28.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m34.00685382s
Feb  6 01:11:30.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m36.007652829s
Feb  6 01:11:32.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m38.007076617s
Feb  6 01:11:34.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m40.006705939s
Feb  6 01:11:36.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m42.006617149s
Feb  6 01:11:38.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m44.007716123s
Feb  6 01:11:40.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m46.006238531s
Feb  6 01:11:42.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m48.007350741s
Feb  6 01:11:44.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m50.006785391s
Feb  6 01:11:46.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m52.006927369s
Feb  6 01:11:48.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m54.007143393s
Feb  6 01:11:50.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m56.006502536s
Feb  6 01:11:52.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 13m58.006679212s
Feb  6 01:11:54.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m0.007543402s
Feb  6 01:11:56.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m2.007029388s
Feb  6 01:11:58.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m4.007981364s
Feb  6 01:12:00.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m6.006798655s
Feb  6 01:12:02.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m8.007088617s
Feb  6 01:12:04.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m10.006737013s
Feb  6 01:12:06.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m12.007850363s
Feb  6 01:12:08.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m14.007510668s
Feb  6 01:12:10.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m16.006846943s
Feb  6 01:12:12.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m18.006625994s
Feb  6 01:12:14.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m20.0074273s
Feb  6 01:12:16.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m22.00743266s
Feb  6 01:12:18.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m24.006947676s
Feb  6 01:12:20.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m26.00689406s
Feb  6 01:12:22.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m28.007400019s
Feb  6 01:12:24.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m30.006770841s
Feb  6 01:12:26.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m32.007258105s
Feb  6 01:12:28.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m34.007534493s
Feb  6 01:12:30.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m36.006767509s
Feb  6 01:12:32.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m38.007315585s
Feb  6 01:12:34.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m40.006873821s
Feb  6 01:12:36.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m42.006938332s
Feb  6 01:12:38.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m44.007495195s
Feb  6 01:12:40.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m46.007164885s
Feb  6 01:12:42.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m48.006283873s
Feb  6 01:12:44.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m50.007052656s
Feb  6 01:12:46.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m52.007017555s
Feb  6 01:12:48.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m54.007258827s
Feb  6 01:12:50.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m56.007396507s
Feb  6 01:12:52.172: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 14m58.006518811s
Feb  6 01:12:54.173: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 15m0.007301114s
Feb  6 01:12:54.175: INFO: Pod "inline-volume-tester-g62vf": Phase="Pending", Reason="", readiness=false. Elapsed: 15m0.009553108s
Feb  6 01:12:54.176: INFO: Unexpected error: waiting for pod with inline volume: 
    <*pod.timeoutError | 0xc003d066f0>: {
        msg: "timed out while waiting for pod ephemeral-7578/inline-volume-tester-g62vf to be running",
        observedObjects: [
            <*v1.Pod | 0xc001768400>{
                TypeMeta: {Kind: "", APIVersion: ""},
                ObjectMeta: {
                    Name: "inline-volume-tester-g62vf",
                    GenerateName: "inline-volume-tester-",
                    Namespace: "ephemeral-7578",
                    SelfLink: "",
                    UID: "a277662d-a502-42e4-a312-ae6d2c712aa4",
                    ResourceVersion: "2943",
                    Generation: 0,
                    CreationTimestamp: {
                        Time: {
                            wall: 0,
                            ext: 63811241874,
                            loc: {
                                name: "Local",
                                zone: [
                                    {name: "UTC", offset: 0, isDST: false},
                                ],
                                tx: [
                                    {
                                        when: -9223372036854775808,
                                        index: 0,
                                        isstd: false,
                                        isutc: false,
                                    },
                                ],
                                extend: "UTC0",
                                cacheStart: 9223372036854775807,
                                cacheEnd: 9223372036854775807,
                                cacheZone: {name: "UTC", offset: 0, isDST: false},
                            },
                        },
                    },
                    DeletionTimestamp: nil,
                    DeletionGracePeriodSeconds: nil,
                    Labels: {
                        "app": "inline-volume-tester",
                    },
                    Annotations: nil,
                    OwnerReferences: nil,
                    Finalizers: nil,
                    ManagedFields: [
                        {
                            Manager: "e2e.test",
                            Operation: "Update",
                            APIVersion: "v1",
                            Time: {
                                Time: {
                                    wall: 0,
                                    ext: 63811241874,
                                    loc: {
                                        name: "Local",
                                        zone: [...],
                                        tx: [...],
                                        extend: "UTC0",
                                        cacheStart: 9223372036854775807,
                                        cacheEnd: 9223372036854775807,
                                        cacheZone: {name: ..., offset: ..., isDST: ...},
                                    },
                                },
                            },
                            FieldsType: "FieldsV1",
                            FieldsV1: {
                                Raw: "{\"f:metadata\":{\"f:generateName\":{},\"f:labels\":{\".\":{},\"f:app\":{}}},\"f:spec\":{\"f:affinity\":{\".\":{},\"f:nodeAffinity\":{\".\":{},\"f:requiredDuringSchedulingIgnoredDuringExecution\":{}}},\"f:containers\":{\"k:{\\\"name\\\":\\\"csi-volume-tester\\\"}\":{\".\":{},\"f:command\":{},\"f:image\":{},\"f:imagePullPolicy\":{},\"f:name\":{},\"f:resources\":{},\"f:terminationMessagePath\":{},\"f:terminationMessagePolicy\":{},\"f:volumeMounts\":{\".\":{},\"k:{\\\"mountPath\\\":\\\"/mnt/test-0\\\"}\":{\".\":{},\"f:mountPath\":{},\"f:name\":{}}}}},\"f:dnsPolicy\":{},\"f:enableServiceLinks\":{},\"f:restartPolicy\":{},\"f:schedulerName\":{},\"f:securityContext\":{},\"f:terminationGracePeriodSeconds\":{},\"f:volumes\":{\".\":{},\"k:{\\\"name\\\":\\\"my-volume-0\\\"}\":{\".\":{},\"f:ephemeral\":{\".\":{},\"f:volumeClaimTemplate\":{\".\":{},\"f:metadata\":{\"....

Gomega truncated this representation as it exceeds 'format.MaxLength'.
Consider having the object provide a custom 'GomegaStringer' representation
or adjust the parameters in Gomega's 'format' package.

Learn more here: https://onsi.github.io/gomega/#adjusting-output

Feb  6 01:12:54.176: FAIL: waiting for pod with inline volume: timed out while waiting for pod ephemeral-7578/inline-volume-tester-g62vf to be running

Full Stack Trace
k8s.io/kubernetes/test/e2e/storage/testsuites.EphemeralTest.TestEphemeral({{0x7ca6438, 0xc000a2d800}, 0xc000f48780, {0xc000c0a2c0, 0xe}, {0x0, 0x0}, 0xc0027b05a0, {{0xc002838e80, 0xc}, ...}, ...})
	test/e2e/storage/testsuites/ephemeral.go:416 +0x5f0
k8s.io/kubernetes/test/e2e/storage/testsuites.(*ephemeralTestSuite).DefineTests.func5()
	test/e2e/storage/testsuites/ephemeral.go:277 +0x205
Feb  6 01:12:54.192: INFO: Error getting logs for pod inline-volume-tester-g62vf: the server rejected our request for an unknown reason (get pods inline-volume-tester-g62vf)
Feb  6 01:12:54.196: INFO: Deleting pod "inline-volume-tester-g62vf" in namespace "ephemeral-7578"
Feb  6 01:12:54.200: INFO: Wait up to 5m0s for pod "inline-volume-tester-g62vf" to be fully deleted
Feb  6 01:14:04.208: INFO: Wait up to 5m0s for pod PV pvc-2787122a-43a5-4eb2-9f4b-3809ee27258b to be fully deleted
Feb  6 01:14:04.208: INFO: Waiting up to 5m0s for PersistentVolume pvc-2787122a-43a5-4eb2-9f4b-3809ee27258b to get deleted
Feb  6 01:14:04.210: INFO: PersistentVolume pvc-2787122a-43a5-4eb2-9f4b-3809ee27258b was removed
STEP: Deleting sc 02/06/23 01:14:04.21
STEP: deleting the test namespace: ephemeral-7578 02/06/23 01:14:04.214
STEP: Waiting for namespaces [ephemeral-7578] to vanish 02/06/23 01:14:04.218
STEP: uninstalling csi csi-hostpath driver 02/06/23 01:14:10.222
Feb  6 01:14:10.222: INFO: deleting *v1.ServiceAccount: ephemeral-7578-7697/csi-attacher
Feb  6 01:14:10.226: INFO: deleting *v1.ClusterRole: external-attacher-runner-ephemeral-7578
Feb  6 01:14:10.230: INFO: deleting *v1.ClusterRoleBinding: csi-attacher-role-ephemeral-7578
Feb  6 01:14:10.234: INFO: deleting *v1.Role: ephemeral-7578-7697/external-attacher-cfg-ephemeral-7578
Feb  6 01:14:10.237: INFO: deleting *v1.RoleBinding: ephemeral-7578-7697/csi-attacher-role-cfg
Feb  6 01:14:10.241: INFO: deleting *v1.ServiceAccount: ephemeral-7578-7697/csi-provisioner
Feb  6 01:14:10.244: INFO: deleting *v1.ClusterRole: external-provisioner-runner-ephemeral-7578
Feb  6 01:14:10.248: INFO: deleting *v1.ClusterRoleBinding: csi-provisioner-role-ephemeral-7578
Feb  6 01:14:10.252: INFO: deleting *v1.Role: ephemeral-7578-7697/external-provisioner-cfg-ephemeral-7578
Feb  6 01:14:10.255: INFO: deleting *v1.RoleBinding: ephemeral-7578-7697/csi-provisioner-role-cfg
Feb  6 01:14:10.258: INFO: deleting *v1.ServiceAccount: ephemeral-7578-7697/csi-snapshotter
Feb  6 01:14:10.262: INFO: deleting *v1.ClusterRole: external-snapshotter-runner-ephemeral-7578
Feb  6 01:14:10.265: INFO: deleting *v1.ClusterRoleBinding: csi-snapshotter-role-ephemeral-7578
Feb  6 01:14:10.268: INFO: deleting *v1.Role: ephemeral-7578-7697/external-snapshotter-leaderelection-ephemeral-7578
Feb  6 01:14:10.272: INFO: deleting *v1.RoleBinding: ephemeral-7578-7697/external-snapshotter-leaderelection
Feb  6 01:14:10.275: INFO: deleting *v1.ServiceAccount: ephemeral-7578-7697/csi-external-health-monitor-controller
Feb  6 01:14:10.278: INFO: deleting *v1.ClusterRole: external-health-monitor-controller-runner-ephemeral-7578
Feb  6 01:14:10.282: INFO: deleting *v1.ClusterRoleBinding: csi-external-health-monitor-controller-role-ephemeral-7578
Feb  6 01:14:10.285: INFO: deleting *v1.Role: ephemeral-7578-7697/external-health-monitor-controller-cfg-ephemeral-7578
Feb  6 01:14:10.289: INFO: deleting *v1.RoleBinding: ephemeral-7578-7697/csi-external-health-monitor-controller-role-cfg
Feb  6 01:14:10.292: INFO: deleting *v1.ServiceAccount: ephemeral-7578-7697/csi-resizer
Feb  6 01:14:10.295: INFO: deleting *v1.ClusterRole: external-resizer-runner-ephemeral-7578
Feb  6 01:14:10.298: INFO: deleting *v1.ClusterRoleBinding: csi-resizer-role-ephemeral-7578
Feb  6 01:14:10.302: INFO: deleting *v1.Role: ephemeral-7578-7697/external-resizer-cfg-ephemeral-7578
Feb  6 01:14:10.305: INFO: deleting *v1.RoleBinding: ephemeral-7578-7697/csi-resizer-role-cfg
Feb  6 01:14:10.308: INFO: deleting *v1.CSIDriver: csi-hostpath-ephemeral-7578
Feb  6 01:14:10.312: INFO: deleting *v1.ServiceAccount: ephemeral-7578-7697/csi-hostpathplugin-sa
Feb  6 01:14:10.315: INFO: deleting *v1.ClusterRoleBinding: csi-hostpathplugin-attacher-cluster-role-ephemeral-7578
Feb  6 01:14:10.318: INFO: deleting *v1.ClusterRoleBinding: csi-hostpathplugin-health-monitor-controller-cluster-role-ephemeral-7578
Feb  6 01:14:10.322: INFO: deleting *v1.ClusterRoleBinding: csi-hostpathplugin-provisioner-cluster-role-ephemeral-7578
Feb  6 01:14:10.325: INFO: deleting *v1.ClusterRoleBinding: csi-hostpathplugin-resizer-cluster-role-ephemeral-7578
Feb  6 01:14:10.329: INFO: deleting *v1.ClusterRoleBinding: csi-hostpathplugin-snapshotter-cluster-role-ephemeral-7578
Feb  6 01:14:10.332: INFO: deleting *v1.RoleBinding: ephemeral-7578-7697/csi-hostpathplugin-attacher-role
Feb  6 01:14:10.335: INFO: deleting *v1.RoleBinding: ephemeral-7578-7697/csi-hostpathplugin-health-monitor-controller-role
Feb  6 01:14:10.339: INFO: deleting *v1.RoleBinding: ephemeral-7578-7697/csi-hostpathplugin-provisioner-role
Feb  6 01:14:10.342: INFO: deleting *v1.RoleBinding: ephemeral-7578-7697/csi-hostpathplugin-resizer-role
Feb  6 01:14:10.346: INFO: deleting *v1.RoleBinding: ephemeral-7578-7697/csi-hostpathplugin-snapshotter-role
Feb  6 01:14:10.349: INFO: deleting *v1.StatefulSet: ephemeral-7578-7697/csi-hostpathplugin
Feb  6 01:14:10.353: INFO: deleting *v1.ClusterRoleBinding: psp-csi-hostpath-role-ephemeral-7578
STEP: deleting the driver namespace: ephemeral-7578-7697 02/06/23 01:14:10.358
STEP: Waiting for namespaces [ephemeral-7578-7697] to vanish 02/06/23 01:14:10.363
[AfterEach] [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral
  test/e2e/framework/framework.go:187
Feb  6 01:14:16.367: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready

				Click to see stdout/stderrfrom junit_01.xml

Filter through log files | View test history on testgrid

Kubernetes e2e suite [It] [sig-storage] CSI Volumes [Driver: csi-hostpath] [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral should support two pods which have the same volume definition 17m30s

go run hack/e2e.go -v --test --test_args='--ginkgo.focus=Kubernetes\se2e\ssuite\s\[It\]\s\[sig\-storage\]\sCSI\sVolumes\s\[Driver\:\scsi\-hostpath\]\s\[Testpattern\:\sGeneric\sEphemeral\-volume\s\(default\sfs\)\s\(late\-binding\)\]\sephemeral\sshould\ssupport\stwo\spods\swhich\shave\sthe\ssame\svolume\sdefinition$'

test/e2e/storage/testsuites/ephemeral.go:434
k8s.io/kubernetes/test/e2e/storage/testsuites.EphemeralTest.TestEphemeral({{0x7ca6438, 0xc000a6a000}, 0xc000ceee80, {0xc002d24bb0, 0xe}, {0x0, 0x0}, 0xc0014d3680, {{0xc0035cf2a0, 0xc}, ...}, ...})
	test/e2e/storage/testsuites/ephemeral.go:434 +0x8ac
k8s.io/kubernetes/test/e2e/storage/testsuites.(*ephemeralTestSuite).DefineTests.func6()
	test/e2e/storage/testsuites/ephemeral.go:316 +0x205
				[BeforeEach] [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral
  test/e2e/storage/framework/testsuite.go:51
[BeforeEach] [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral
  test/e2e/framework/framework.go:186
STEP: Creating a kubernetes client 02/06/23 00:57:51.333
Feb  6 00:57:51.333: INFO: >>> kubeConfig: /root/.kube/kind-test-config
STEP: Building a namespace api object, basename ephemeral 02/06/23 00:57:51.335
STEP: Waiting for a default service account to be provisioned in namespace 02/06/23 00:57:51.762
STEP: Waiting for kube-root-ca.crt to be provisioned in namespace 02/06/23 00:57:51.766
[It] should support two pods which have the same volume definition
  test/e2e/storage/testsuites/ephemeral.go:281
Feb  6 00:57:51.791: INFO: Pod inline-volume-jvlzt has the following logs: 
Feb  6 00:57:51.794: INFO: Deleting pod "inline-volume-jvlzt" in namespace "ephemeral-1313"
Feb  6 00:57:51.811: INFO: Wait up to 5m0s for pod "inline-volume-jvlzt" to be fully deleted
STEP: Building a driver namespace object, basename ephemeral-1313 02/06/23 00:57:53.844
STEP: Waiting for a default service account to be provisioned in namespace 02/06/23 00:57:53.874
STEP: deploying csi-hostpath driver 02/06/23 00:57:53.889
Feb  6 00:57:53.939: INFO: creating *v1.ServiceAccount: ephemeral-1313-3655/csi-attacher
Feb  6 00:57:53.948: INFO: creating *v1.ClusterRole: external-attacher-runner-ephemeral-1313
Feb  6 00:57:53.948: INFO: Define cluster role external-attacher-runner-ephemeral-1313
Feb  6 00:57:53.963: INFO: creating *v1.ClusterRoleBinding: csi-attacher-role-ephemeral-1313
Feb  6 00:57:53.972: INFO: creating *v1.Role: ephemeral-1313-3655/external-attacher-cfg-ephemeral-1313
Feb  6 00:57:53.994: INFO: creating *v1.RoleBinding: ephemeral-1313-3655/csi-attacher-role-cfg
Feb  6 00:57:54.006: INFO: creating *v1.ServiceAccount: ephemeral-1313-3655/csi-provisioner
Feb  6 00:57:54.011: INFO: creating *v1.ClusterRole: external-provisioner-runner-ephemeral-1313
Feb  6 00:57:54.011: INFO: Define cluster role external-provisioner-runner-ephemeral-1313
Feb  6 00:57:54.023: INFO: creating *v1.ClusterRoleBinding: csi-provisioner-role-ephemeral-1313
Feb  6 00:57:54.027: INFO: creating *v1.Role: ephemeral-1313-3655/external-provisioner-cfg-ephemeral-1313
Feb  6 00:57:54.035: INFO: creating *v1.RoleBinding: ephemeral-1313-3655/csi-provisioner-role-cfg
Feb  6 00:57:54.073: INFO: creating *v1.ServiceAccount: ephemeral-1313-3655/csi-snapshotter
Feb  6 00:57:54.105: INFO: creating *v1.ClusterRole: external-snapshotter-runner-ephemeral-1313
Feb  6 00:57:54.105: INFO: Define cluster role external-snapshotter-runner-ephemeral-1313
Feb  6 00:57:54.134: INFO: creating *v1.ClusterRoleBinding: csi-snapshotter-role-ephemeral-1313
Feb  6 00:57:54.153: INFO: creating *v1.Role: ephemeral-1313-3655/external-snapshotter-leaderelection-ephemeral-1313
Feb  6 00:57:54.162: INFO: creating *v1.RoleBinding: ephemeral-1313-3655/external-snapshotter-leaderelection
Feb  6 00:57:54.181: INFO: creating *v1.ServiceAccount: ephemeral-1313-3655/csi-external-health-monitor-controller
Feb  6 00:57:54.188: INFO: creating *v1.ClusterRole: external-health-monitor-controller-runner-ephemeral-1313
Feb  6 00:57:54.188: INFO: Define cluster role external-health-monitor-controller-runner-ephemeral-1313
Feb  6 00:57:54.202: INFO: creating *v1.ClusterRoleBinding: csi-external-health-monitor-controller-role-ephemeral-1313
Feb  6 00:57:54.211: INFO: creating *v1.Role: ephemeral-1313-3655/external-health-monitor-controller-cfg-ephemeral-1313
Feb  6 00:57:54.229: INFO: creating *v1.RoleBinding: ephemeral-1313-3655/csi-external-health-monitor-controller-role-cfg
Feb  6 00:57:54.242: INFO: creating *v1.ServiceAccount: ephemeral-1313-3655/csi-resizer
Feb  6 00:57:54.250: INFO: creating *v1.ClusterRole: external-resizer-runner-ephemeral-1313
Feb  6 00:57:54.250: INFO: Define cluster role external-resizer-runner-ephemeral-1313
Feb  6 00:57:54.254: INFO: creating *v1.ClusterRoleBinding: csi-resizer-role-ephemeral-1313
Feb  6 00:57:54.258: INFO: creating *v1.Role: ephemeral-1313-3655/external-resizer-cfg-ephemeral-1313
Feb  6 00:57:54.269: INFO: creating *v1.RoleBinding: ephemeral-1313-3655/csi-resizer-role-cfg
Feb  6 00:57:54.276: INFO: creating *v1.CSIDriver: csi-hostpath-ephemeral-1313
Feb  6 00:57:54.281: INFO: creating *v1.ServiceAccount: ephemeral-1313-3655/csi-hostpathplugin-sa
Feb  6 00:57:54.285: INFO: creating *v1.ClusterRoleBinding: csi-hostpathplugin-attacher-cluster-role-ephemeral-1313
Feb  6 00:57:54.306: INFO: creating *v1.ClusterRoleBinding: csi-hostpathplugin-health-monitor-controller-cluster-role-ephemeral-1313
Feb  6 00:57:54.312: INFO: creating *v1.ClusterRoleBinding: csi-hostpathplugin-provisioner-cluster-role-ephemeral-1313
Feb  6 00:57:54.318: INFO: creating *v1.ClusterRoleBinding: csi-hostpathplugin-resizer-cluster-role-ephemeral-1313
Feb  6 00:57:54.334: INFO: creating *v1.ClusterRoleBinding: csi-hostpathplugin-snapshotter-cluster-role-ephemeral-1313
Feb  6 00:57:54.338: INFO: creating *v1.RoleBinding: ephemeral-1313-3655/csi-hostpathplugin-attacher-role
Feb  6 00:57:54.342: INFO: creating *v1.RoleBinding: ephemeral-1313-3655/csi-hostpathplugin-health-monitor-controller-role
Feb  6 00:57:54.359: INFO: creating *v1.RoleBinding: ephemeral-1313-3655/csi-hostpathplugin-provisioner-role
Feb  6 00:57:54.363: INFO: creating *v1.RoleBinding: ephemeral-1313-3655/csi-hostpathplugin-resizer-role
Feb  6 00:57:54.367: INFO: creating *v1.RoleBinding: ephemeral-1313-3655/csi-hostpathplugin-snapshotter-role
Feb  6 00:57:54.371: INFO: creating *v1.StatefulSet: ephemeral-1313-3655/csi-hostpathplugin
Feb  6 00:57:54.378: INFO: creating *v1.ClusterRoleBinding: psp-csi-hostpath-role-ephemeral-1313
Feb  6 00:57:54.384: INFO: Creating resource for dynamic PV
Feb  6 00:57:54.384: INFO: Using claimSize:1Mi, test suite supported size:{ }, driver(csi-hostpath) supported size:{ } 
STEP: creating a StorageClass ephemeral-13135lclv 02/06/23 00:57:54.384
STEP: checking the requested inline volume exists in the pod running on node {Name:kind-worker2 Selector:map[] Affinity:nil} 02/06/23 00:57:54.394
Feb  6 00:57:54.399: INFO: Waiting up to 15m0s for pod "inline-volume-tester-txq4j" in namespace "ephemeral-1313" to be "running"
Feb  6 00:57:54.404: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 5.037646ms
Feb  6 00:57:56.408: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 2.009366341s
Feb  6 00:57:58.420: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 4.020803924s
Feb  6 00:58:00.409: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 6.010275494s
Feb  6 00:58:02.408: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 8.009362113s
Feb  6 00:58:04.414: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 10.015336725s
Feb  6 00:58:06.413: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 12.014292391s
Feb  6 00:58:08.422: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 14.023240712s
Feb  6 00:58:10.409: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 16.010526551s
Feb  6 00:58:12.432: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 18.033546858s
Feb  6 00:58:14.408: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 20.008794683s
Feb  6 00:58:16.408: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 22.008631475s
Feb  6 00:58:18.409: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 24.009823657s
Feb  6 00:58:20.408: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 26.009486415s
Feb  6 00:58:22.408: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 28.009288s
Feb  6 00:58:24.408: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 30.008923957s
Feb  6 00:58:26.407: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 32.008546478s
Feb  6 00:58:28.408: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 34.008856403s
Feb  6 00:58:30.409: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 36.01044866s
Feb  6 00:58:32.407: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 38.008404923s
Feb  6 00:58:34.407: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 40.008384879s
Feb  6 00:58:36.414: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 42.014945348s
Feb  6 00:58:38.407: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 44.008086552s
Feb  6 00:58:40.417: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 46.017843807s
Feb  6 00:58:42.412: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 48.012663057s
Feb  6 00:58:44.411: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 50.011894078s
Feb  6 00:58:46.408: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 52.009112636s
Feb  6 00:58:48.408: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 54.009179781s
Feb  6 00:58:50.407: INFO: Pod "inline-volume-tester-txq4j": Phase="Pending", Reason="", readiness=false. Elapsed: 56.008381188s
Feb  6 00:58:52.407: INFO: Pod "inline-volume-tester-txq4j": Phase="Running", Reason="", readiness=true. Elapsed: 58.008348757s
Feb  6 00:58:52.407: INFO: Pod "inline-volume-tester-txq4j" satisfied condition "running"
Feb  6 00:58:52.421: INFO: Waiting up to 15m0s for pod "inline-volume-tester2-mdpkz" in namespace "ephemeral-1313" to be "running"
Feb  6 00:58:52.441: INFO: Pod "inline-volume-tester2-mdpkz": Phase="Pending", Reason="", readiness=false. Elapsed: 20.526033ms
Feb  6 00:58:54.444: INFO: Pod "inline-volume-tester2-mdpkz": Phase="Pending", Reason="", readiness=false. Elapsed: 2.023200795s
Feb  6 00:58:56.446: INFO: Pod "inline-volume-tester2-mdpkz": Phase="Pending", Reason="", readiness=false. Elapsed: 4.024812087s
Feb  6 00:58:58.446: INFO: Pod "inline-volume-tester2-mdpkz": Phase="Pending", Reason="", readiness=false. Elapsed: 6.024883956s
Feb  6 00:59:00.446: INFO: Pod "inline-volume-tester2-mdpkz": Phase="Pending", Reason="", readiness=false. Elapsed: 8.024767567s
Feb  6 00:59:02.445: INFO: Pod "inline-volume-tester2-mdpkz": Phase="Pending", Reason="", readiness=false. Elapsed: 10.024434377s
Feb  6 00:59:04.445: INFO: Pod "inline-volume-tester2-mdpkz": Phase="Pending", Reason="", readiness=false. Elapsed: 12.024218758s
Feb  6 00:59:06.445: INFO: Pod "inline-volume-tester2-mdpkz": Phase="Pending", Reason="", readiness=false. Elapsed: 14.023910343s
Feb  6 00:59:08.446: INFO: Pod "inline-volume-tester2-mdpkz": Phase="Pending", Reason="", readiness=false. Elapsed: 16.025706341s
Feb  6 00:59:10.477: INFO: Pod "inline-volume-tester2-mdpkz": Phase="Pending", Reason="", readiness=false. Elapsed: 18.056162908s
Feb  6 00:59:12.445: INFO: Pod "inline-volume-tester2-mdpkz": Phase="Pending", Reason="", readiness=false. Elapsed: 20.024428016s
Feb  6 00:59:14.454: INFO: Pod "inline-volume-tester2-mdpkz": Phase="Running", Reason="", readiness=true. Elapsed: 22.033486952s
Feb  6 00:59:14.454: INFO: Pod "inline-volume-tester2-mdpkz" satisfied condition "running"
STEP: writing data in one pod and checking the second does not see it (it should get its own volume) 02/06/23 00:59:14.454
Feb  6 00:59:14.454: INFO: ExecWithOptions {Command:[/bin/sh -c touch /mnt/test-0/hello-world] Namespace:ephemeral-1313 PodName:inline-volume-tester-txq4j ContainerName:csi-volume-tester Stdin:<nil> CaptureStdout:true CaptureStderr:true PreserveWhitespace:false Quiet:false}
Feb  6 00:59:14.454: INFO: >>> kubeConfig: /root/.kube/kind-test-config
Feb  6 00:59:14.455: INFO: ExecWithOptions: Clientset creation
Feb  6 00:59:14.455: INFO: ExecWithOptions: execute(POST https://127.0.0.1:46447/api/v1/namespaces/ephemeral-1313/pods/inline-volume-tester-txq4j/exec?command=%2Fbin%2Fsh&command=-c&command=touch+%2Fmnt%2Ftest-0%2Fhello-world&container=csi-volume-tester&container=csi-volume-tester&stderr=true&stdout=true)
Feb  6 00:59:14.609: INFO: ExecWithOptions {Command:[/bin/sh -c [ ! -f /mnt/test-0/hello-world ]] Namespace:ephemeral-1313 PodName:inline-volume-tester2-mdpkz ContainerName:csi-volume-tester Stdin:<nil> CaptureStdout:true CaptureStderr:true PreserveWhitespace:false Quiet:false}
Feb  6 00:59:14.609: INFO: >>> kubeConfig: /root/.kube/kind-test-config
Feb  6 00:59:14.610: INFO: ExecWithOptions: Clientset creation
Feb  6 00:59:14.610: INFO: ExecWithOptions: execute(POST https://127.0.0.1:46447/api/v1/namespaces/ephemeral-1313/pods/inline-volume-tester2-mdpkz/exec?command=%2Fbin%2Fsh&command=-c&command=%5B+%21+-f+%2Fmnt%2Ftest-0%2Fhello-world+%5D&container=csi-volume-tester&container=csi-volume-tester&stderr=true&stdout=true)
Feb  6 00:59:14.707: INFO: Pod inline-volume-tester2-mdpkz has the following logs: 
Feb  6 00:59:14.716: INFO: Deleting pod "inline-volume-tester2-mdpkz" in namespace "ephemeral-1313"
Feb  6 00:59:14.720: INFO: Wait up to 5m0s for pod "inline-volume-tester2-mdpkz" to be fully deleted
Feb  6 01:00:14.733: INFO: Wait up to 5m0s for pod PV pvc-87583ded-ec29-40c0-a4a0-8fca6cfb3b0b to be fully deleted
Feb  6 01:00:14.733: INFO: Waiting up to 5m0s for PersistentVolume pvc-87583ded-ec29-40c0-a4a0-8fca6cfb3b0b to get deleted
Feb  6 01:00:14.735: INFO: PersistentVolume pvc-87583ded-ec29-40c0-a4a0-8fca6cfb3b0b was removed
Feb  6 01:00:14.740: INFO: Pod inline-volume-tester-txq4j has the following logs: 
Feb  6 01:00:14.745: INFO: Deleting pod "inline-volume-tester-txq4j" in namespace "ephemeral-1313"
Feb  6 01:00:14.751: INFO: Wait up to 5m0s for pod "inline-volume-tester-txq4j" to be fully deleted
Feb  6 01:05:14.766: INFO: Wait up to 5m0s for pod PV pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e to be fully deleted
Feb  6 01:05:14.766: INFO: Waiting up to 5m0s for PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e to get deleted
Feb  6 01:05:14.769: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (3.466015ms)
Feb  6 01:05:19.774: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (5.007927934s)
Feb  6 01:05:24.777: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (10.01116139s)
Feb  6 01:05:29.785: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (15.019476044s)
Feb  6 01:05:34.790: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (20.023538937s)
Feb  6 01:05:39.793: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (25.027271919s)
Feb  6 01:05:44.797: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (30.030587023s)
Feb  6 01:05:49.801: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (35.034737699s)
Feb  6 01:05:54.810: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (40.043845252s)
Feb  6 01:05:59.814: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (45.048337006s)
Feb  6 01:06:04.819: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (50.053194737s)
Feb  6 01:06:09.822: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (55.055904745s)
Feb  6 01:06:14.825: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (1m0.059411548s)
Feb  6 01:06:19.828: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (1m5.061997251s)
Feb  6 01:06:24.831: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (1m10.065242537s)
Feb  6 01:06:29.835: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (1m15.068718042s)
Feb  6 01:06:34.839: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (1m20.072644036s)
Feb  6 01:06:39.842: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (1m25.076139445s)
Feb  6 01:06:44.845: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (1m30.079110949s)
Feb  6 01:06:49.849: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (1m35.082521551s)
Feb  6 01:06:54.852: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (1m40.086371636s)
Feb  6 01:06:59.856: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (1m45.089525155s)
Feb  6 01:07:04.859: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (1m50.093034536s)
Feb  6 01:07:09.862: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (1m55.096383025s)
Feb  6 01:07:14.866: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (2m0.099801859s)
Feb  6 01:07:19.870: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (2m5.10352825s)
Feb  6 01:07:24.873: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (2m10.106689568s)
Feb  6 01:07:29.876: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (2m15.109832668s)
Feb  6 01:07:34.880: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (2m20.113553094s)
Feb  6 01:07:39.883: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (2m25.117438124s)
Feb  6 01:07:44.887: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (2m30.121134343s)
Feb  6 01:07:49.890: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (2m35.124054485s)
Feb  6 01:07:54.893: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (2m40.127328781s)
Feb  6 01:07:59.896: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (2m45.130422748s)
Feb  6 01:08:04.899: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (2m50.133474107s)
Feb  6 01:08:09.902: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (2m55.136493686s)
Feb  6 01:08:14.906: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (3m0.140323666s)
Feb  6 01:08:19.910: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (3m5.143920498s)
Feb  6 01:08:24.914: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (3m10.147523791s)
Feb  6 01:08:29.917: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (3m15.150584186s)
Feb  6 01:08:34.921: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (3m20.154911774s)
Feb  6 01:08:39.925: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (3m25.158716006s)
Feb  6 01:08:44.930: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (3m30.163516547s)
Feb  6 01:08:49.933: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (3m35.167389082s)
Feb  6 01:08:54.937: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (3m40.170951488s)
Feb  6 01:08:59.941: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (3m45.174947876s)
Feb  6 01:09:04.945: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (3m50.178885697s)
Feb  6 01:09:09.948: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (3m55.182279946s)
Feb  6 01:09:14.952: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (4m0.185883916s)
Feb  6 01:09:19.956: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (4m5.18966012s)
Feb  6 01:09:24.959: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (4m10.193299963s)
Feb  6 01:09:29.963: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (4m15.196636374s)
Feb  6 01:09:34.966: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (4m20.19980969s)
Feb  6 01:09:39.969: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (4m25.203502992s)
Feb  6 01:09:44.974: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (4m30.207693953s)
Feb  6 01:09:49.978: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (4m35.211603188s)
Feb  6 01:09:54.981: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (4m40.215344462s)
Feb  6 01:09:59.984: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (4m45.218418402s)
Feb  6 01:10:04.989: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (4m50.22261699s)
Feb  6 01:10:09.992: INFO: PersistentVolume pvc-f02d6eef-f791-47b1-a7c2-10954da4e74e found and phase=Bound (4m55.226038113s)
Feb  6 01:10:14.996: FAIL: no dangling PVCs
Expected
    <[]v1.PersistentVolumeClaim | len:1, cap:1>: [
        {
            TypeMeta: {Kind: "", APIVersion: ""},
            ObjectMeta: {
                Name: "inline-volume-tester-txq4j-my-volume-0",
                GenerateName: "",
                Namespace: "ephemeral-1313",
                SelfLink: "",
                UID: "f02d6eef-f791-47b1-a7c2-10954da4e74e",
                ResourceVersion: "7988",
                Generation: 0,
                CreationTimestamp: {
                    Time: 2023-02-06T00:57:54Z,
                },
                DeletionTimestamp: {
                    Time: 2023-02-06T01:00:14Z,
                },
                DeletionGracePeriodSeconds: 0,
                Labels: nil,
                Annotations: {
                    "volume.beta.kubernetes.io/storage-provisioner": "csi-hostpath-ephemeral-1313",
                    "volume.kubernetes.io/selected-node": "kind-worker2",
                    "volume.kubernetes.io/storage-provisioner": "csi-hostpath-ephemeral-1313",
                    "pv.kubernetes.io/bind-completed": "yes",
                    "pv.kubernetes.io/bound-by-controller": "yes",
                },
                OwnerReferences: [
                    {
                        APIVersion: "v1",
                        Kind: "Pod",
                        Name: "inline-volume-tester-txq4j",
                        UID: "f8834d78-d64d-41d7-9eae-49879897836c",
                        Controller: true,
                        BlockOwnerDeletion: true,
                    },
                ],
                Finalizers: [
                    "kubernetes.io/pvc-protection",
                ],
                ManagedFields: [
                    {
                        Manager: "kube-scheduler",
                        Operation: "Update",
                        APIVersion: "v1",
                        Time: {
                            Time: 2023-02-06T00:57:55Z,
                        },
                        FieldsType: "FieldsV1",
                        FieldsV1: {
                            Raw: "{\"f:metadata\":{\"f:annotations\":{\".\":{},\"f:volume.kubernetes.io/selected-node\":{}}}}",
                        },
                        Subresource: "",
                    },
                    {
                        Manager: "kube-controller-manager",
                        Operation: "Update",
                        APIVersion: "v1",
                        Time: {
                            Time: 2023-02-06T00:58:16Z,
                        },
                        FieldsType: "FieldsV1",
                        FieldsV1: {
                            Raw: "{\"f:metadata\":{\"f:annotations\":{\"f:pv.kubernetes.io/bind-completed\":{},\"f:pv.kubernetes.io/bound-by-controller\":{},\"f:volume.beta.kubernetes.io/storage-provisioner\":{},\"f:volume.kubernetes.io/storage-provisioner\":{}},\"f:ownerReferences\":{\".\":{},\"k:{\\\"uid\\\":\\\"f8834d78-d64d-41d7-9eae-49879897836c\\\"}\":{}}},\"f:spec\":{\"f:accessModes\":{},\"f:resources\":{\"f:requests\":{\".\":{},\"f:storage\":{}}},\"f:storageClassName\":{},\"f:volumeMode\":{},\"f:volumeName\":{}}}",
                        },
                        Subresource: "",
                    },
                    {
                        Manager: "kube-controller-manager",
                        Operation: "Update",
                        APIVersion: "v1",
                        Time: {
                            Time: 2023-02-06T00:58:16Z,
                        },
                        FieldsType: "FieldsV1",
                        FieldsV1: {
                            Raw: "{\"f:status\":{\"f:accessModes\":{},\"f:capacity\":{\".\":{},\"f:storage\":{}},\"f:phase\":{}}}",
                        },
                        Subresource: "status",
                    },
                ],
            },
            Spec: {
                AccessModes: ["ReadWriteOnce"],
                Selector: nil,
                Resources: {
                ...

Gomega truncated this representation as it exceeds 'format.MaxLength'.
Consider having the object provide a custom 'GomegaStringer' representation
or adjust the parameters in Gomega's 'format' package.

Learn more here: https://onsi.github.io/gomega/#adjusting-output

to be empty

Full Stack Trace
k8s.io/kubernetes/test/e2e/storage/testsuites.EphemeralTest.TestEphemeral({{0x7ca6438, 0xc000a6a000}, 0xc000ceee80, {0xc002d24bb0, 0xe}, {0x0, 0x0}, 0xc0014d3680, {{0xc0035cf2a0, 0xc}, ...}, ...})
	test/e2e/storage/testsuites/ephemeral.go:434 +0x8ac
k8s.io/kubernetes/test/e2e/storage/testsuites.(*ephemeralTestSuite).DefineTests.func6()
	test/e2e/storage/testsuites/ephemeral.go:316 +0x205
STEP: Deleting sc 02/06/23 01:10:14.997
STEP: deleting the test namespace: ephemeral-1313 02/06/23 01:10:15.001
STEP: Waiting for namespaces [ephemeral-1313] to vanish 02/06/23 01:10:15.006
Feb  6 01:15:15.013: INFO: error deleting namespace ephemeral-1313: timed out waiting for the condition
STEP: uninstalling csi csi-hostpath driver 02/06/23 01:15:15.013
Feb  6 01:15:15.013: INFO: deleting *v1.ServiceAccount: ephemeral-1313-3655/csi-attacher
Feb  6 01:15:15.017: INFO: deleting *v1.ClusterRole: external-attacher-runner-ephemeral-1313
Feb  6 01:15:15.020: INFO: deleting *v1.ClusterRoleBinding: csi-attacher-role-ephemeral-1313
Feb  6 01:15:15.024: INFO: deleting *v1.Role: ephemeral-1313-3655/external-attacher-cfg-ephemeral-1313
Feb  6 01:15:15.027: INFO: deleting *v1.RoleBinding: ephemeral-1313-3655/csi-attacher-role-cfg
Feb  6 01:15:15.031: INFO: deleting *v1.ServiceAccount: ephemeral-1313-3655/csi-provisioner
Feb  6 01:15:15.034: INFO: deleting *v1.ClusterRole: external-provisioner-runner-ephemeral-1313
Feb  6 01:15:15.038: INFO: deleting *v1.ClusterRoleBinding: csi-provisioner-role-ephemeral-1313
Feb  6 01:15:15.041: INFO: deleting *v1.Role: ephemeral-1313-3655/external-provisioner-cfg-ephemeral-1313
Feb  6 01:15:15.045: INFO: deleting *v1.RoleBinding: ephemeral-1313-3655/csi-provisioner-role-cfg
Feb  6 01:15:15.050: INFO: deleting *v1.ServiceAccount: ephemeral-1313-3655/csi-snapshotter
Feb  6 01:15:15.054: INFO: deleting *v1.ClusterRole: external-snapshotter-runner-ephemeral-1313
Feb  6 01:15:15.057: INFO: deleting *v1.ClusterRoleBinding: csi-snapshotter-role-ephemeral-1313
Feb  6 01:15:15.061: INFO: deleting *v1.Role: ephemeral-1313-3655/external-snapshotter-leaderelection-ephemeral-1313
Feb  6 01:15:15.064: INFO: deleting *v1.RoleBinding: ephemeral-1313-3655/external-snapshotter-leaderelection
Feb  6 01:15:15.068: INFO: deleting *v1.ServiceAccount: ephemeral-1313-3655/csi-external-health-monitor-controller
Feb  6 01:15:15.071: INFO: deleting *v1.ClusterRole: external-health-monitor-controller-runner-ephemeral-1313
Feb  6 01:15:15.074: INFO: deleting *v1.ClusterRoleBinding: csi-external-health-monitor-controller-role-ephemeral-1313
Feb  6 01:15:15.078: INFO: deleting *v1.Role: ephemeral-1313-3655/external-health-monitor-controller-cfg-ephemeral-1313
Feb  6 01:15:15.081: INFO: deleting *v1.RoleBinding: ephemeral-1313-3655/csi-external-health-monitor-controller-role-cfg
Feb  6 01:15:15.084: INFO: deleting *v1.ServiceAccount: ephemeral-1313-3655/csi-resizer
Feb  6 01:15:15.088: INFO: deleting *v1.ClusterRole: external-resizer-runner-ephemeral-1313
Feb  6 01:15:15.091: INFO: deleting *v1.ClusterRoleBinding: csi-resizer-role-ephemeral-1313
Feb  6 01:15:15.094: INFO: deleting *v1.Role: ephemeral-1313-3655/external-resizer-cfg-ephemeral-1313
Feb  6 01:15:15.098: INFO: deleting *v1.RoleBinding: ephemeral-1313-3655/csi-resizer-role-cfg
Feb  6 01:15:15.102: INFO: deleting *v1.CSIDriver: csi-hostpath-ephemeral-1313
Feb  6 01:15:15.106: INFO: deleting *v1.ServiceAccount: ephemeral-1313-3655/csi-hostpathplugin-sa
Feb  6 01:15:15.109: INFO: deleting *v1.ClusterRoleBinding: csi-hostpathplugin-attacher-cluster-role-ephemeral-1313
Feb  6 01:15:15.112: INFO: deleting *v1.ClusterRoleBinding: csi-hostpathplugin-health-monitor-controller-cluster-role-ephemeral-1313
Feb  6 01:15:15.116: INFO: deleting *v1.ClusterRoleBinding: csi-hostpathplugin-provisioner-cluster-role-ephemeral-1313
Feb  6 01:15:15.119: INFO: deleting *v1.ClusterRoleBinding: csi-hostpathplugin-resizer-cluster-role-ephemeral-1313
Feb  6 01:15:15.122: INFO: deleting *v1.ClusterRoleBinding: csi-hostpathplugin-snapshotter-cluster-role-ephemeral-1313
Feb  6 01:15:15.126: INFO: deleting *v1.RoleBinding: ephemeral-1313-3655/csi-hostpathplugin-attacher-role
Feb  6 01:15:15.129: INFO: deleting *v1.RoleBinding: ephemeral-1313-3655/csi-hostpathplugin-health-monitor-controller-role
Feb  6 01:15:15.132: INFO: deleting *v1.RoleBinding: ephemeral-1313-3655/csi-hostpathplugin-provisioner-role
Feb  6 01:15:15.135: INFO: deleting *v1.RoleBinding: ephemeral-1313-3655/csi-hostpathplugin-resizer-role
Feb  6 01:15:15.138: INFO: deleting *v1.RoleBinding: ephemeral-1313-3655/csi-hostpathplugin-snapshotter-role
Feb  6 01:15:15.142: INFO: deleting *v1.StatefulSet: ephemeral-1313-3655/csi-hostpathplugin
Feb  6 01:15:15.145: INFO: deleting *v1.ClusterRoleBinding: psp-csi-hostpath-role-ephemeral-1313
STEP: deleting the driver namespace: ephemeral-1313-3655 02/06/23 01:15:15.149
STEP: Waiting for namespaces [ephemeral-1313-3655] to vanish 02/06/23 01:15:15.157
[AfterEach] [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral
  test/e2e/framework/framework.go:187
STEP: Collecting events from namespace "ephemeral-1313". 02/06/23 01:15:21.162
STEP: Found 0 events. 02/06/23 01:15:21.164
Feb  6 01:15:21.167: INFO: POD                         NODE          PHASE   GRACE  CONDITIONS
Feb  6 01:15:21.167: INFO: inline-volume-tester-txq4j  kind-worker2  Failed  30s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2023-02-06 00:58:16 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2023-02-06 01:00:45 +0000 UTC PodFailed } {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2023-02-06 01:00:45 +0000 UTC PodFailed } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2023-02-06 00:58:16 +0000 UTC  }]
Feb  6 01:15:21.167: INFO: 
Feb  6 01:15:21.186: INFO: 
Logging node info for node kind-control-plane
Feb  6 01:15:21.188: INFO: Node Info: &Node{ObjectMeta:{kind-control-plane    b1cb49e6-75a7-41b8-ba69-7767e3f199b8 26286 0 2023-02-06 00:56:10 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/os:linux kubernetes.io/arch:amd64 kubernetes.io/hostname:kind-control-plane kubernetes.io/os:linux node-role.kubernetes.io/control-plane: node.kubernetes.io/exclude-from-external-load-balancers:] map[kubeadm.alpha.kubernetes.io/cri-socket:unix:///run/containerd/containerd.sock node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] [] [{kubelet Update v1 2023-02-06 00:56:10 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{".":{},"f:volumes.kubernetes.io/controller-managed-attach-detach":{}},"f:labels":{".":{},"f:beta.kubernetes.io/arch":{},"f:beta.kubernetes.io/os":{},"f:kubernetes.io/arch":{},"f:kubernetes.io/hostname":{},"f:kubernetes.io/os":{}}},"f:spec":{"f:providerID":{}}} } {kubeadm Update v1 2023-02-06 00:56:13 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:kubeadm.alpha.kubernetes.io/cri-socket":{}},"f:labels":{"f:node-role.kubernetes.io/control-plane":{},"f:node.kubernetes.io/exclude-from-external-load-balancers":{}}}} } {kube-controller-manager Update v1 2023-02-06 00:56:33 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:node.alpha.kubernetes.io/ttl":{}}},"f:spec":{"f:podCIDR":{},"f:podCIDRs":{".":{},"v:\"10.244.0.0/24\"":{}},"f:taints":{}}} } {kubelet Update v1 2023-02-06 01:11:52 +0000 UTC FieldsV1 {"f:status":{"f:conditions":{"k:{\"type\":\"DiskPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"MemoryPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"PIDPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"Ready\"}":{"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{}}}}} status}]},Spec:NodeSpec{PodCIDR:10.244.0.0/24,DoNotUseExternalID:,ProviderID:kind://docker/kind/kind-control-plane,Unschedulable:false,Taints:[]Taint{Taint{Key:node-role.kubernetes.io/control-plane,Value:,Effect:NoSchedule,TimeAdded:<nil>,},},ConfigSource:nil,PodCIDRs:[10.244.0.0/24],},Status:NodeStatus{Capacity:ResourceList{cpu: {{8 0} {<nil>} 8 DecimalSI},ephemeral-storage: {{791251738624 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{54757810176 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{cpu: {{8 0} {<nil>} 8 DecimalSI},ephemeral-storage: {{791251738624 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{54757810176 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2023-02-06 01:11:52 +0000 UTC,LastTransitionTime:2023-02-06 00:56:08 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2023-02-06 01:11:52 +0000 UTC,LastTransitionTime:2023-02-06 00:56:08 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2023-02-06 01:11:52 +0000 UTC,LastTransitionTime:2023-02-06 00:56:08 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2023-02-06 01:11:52 +0000 UTC,LastTransitionTime:2023-02-06 00:56:33 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:172.18.0.2,},NodeAddress{Type:Hostname,Address:kind-control-plane,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:e328ce60ab914277863a031f417c80ce,SystemUUID:5870377a-0780-4b03-b020-1e88dc0a9381,BootID:1091d151-f16c-4fdc-899a-0bff3500ee70,KernelVersion:5.4.0-1086-gke,OSImage:Ubuntu 22.04.1 LTS,ContainerRuntimeVersion:containerd://1.6.14,KubeletVersion:v1.25.7-rc.0.16+74817504841bde,KubeProxyVersion:v1.25.7-rc.0.16+74817504841bde,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:57e1263cc818c8c5dc39e96fd84b43e749372f7d8e0194473db182c02d736f7a registry.k8s.io/kube-apiserver:v1.25.7-rc.0.16_74817504841bde],SizeBytes:129108435,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:1e63ddf4e3d1ee17eb9088ac421b83265235b72a43aa83f480cff1a72d879e4d registry.k8s.io/kube-controller-manager:v1.25.7-rc.0.16_74817504841bde],SizeBytes:118450487,},ContainerImage{Names:[registry.k8s.io/etcd:3.5.6-0],SizeBytes:102542580,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:e84585d19592e86a87b740345f7251ea2fe5752bd3c944759025c5a8382436f2 registry.k8s.io/kube-scheduler:v1.25.7-rc.0.16_74817504841bde],SizeBytes:51931448,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:7006371f635ca3e22c794b167237c78cdecc5689d6b60bbb5b6257ec2a32463a registry.k8s.io/kube-proxy:v1.25.7-rc.0.16_74817504841bde],SizeBytes:48821978,},ContainerImage{Names:[docker.io/kindest/kindnetd:v20221004-44d545d1],SizeBytes:25830582,},ContainerImage{Names:[docker.io/kindest/local-path-provisioner:v0.0.22-kind.0],SizeBytes:17375346,},ContainerImage{Names:[registry.k8s.io/coredns/coredns:v1.9.3],SizeBytes:14837849,},ContainerImage{Names:[docker.io/kindest/local-path-helper:v20220607-9a4d8d2a],SizeBytes:2859509,},ContainerImage{Names:[registry.k8s.io/pause:3.7],SizeBytes:311278,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
Feb  6 01:15:21.189: INFO: 
Logging kubelet events for node kind-control-plane
Feb  6 01:15:21.193: INFO: 
Logging pods the kubelet thinks is on node kind-control-plane
Feb  6 01:15:21.211: INFO: coredns-565d847f94-jwtds started at 2023-02-06 00:56:33 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:15:21.211: INFO: 	Container coredns ready: true, restart count 0
Feb  6 01:15:21.211: INFO: local-path-provisioner-684f458cdd-c4fcg started at 2023-02-06 00:56:33 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:15:21.211: INFO: 	Container local-path-provisioner ready: true, restart count 0
Feb  6 01:15:21.211: INFO: kube-scheduler-kind-control-plane started at 2023-02-06 00:56:14 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:15:21.211: INFO: 	Container kube-scheduler ready: true, restart count 0
Feb  6 01:15:21.211: INFO: etcd-kind-control-plane started at 2023-02-06 00:56:14 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:15:21.211: INFO: 	Container etcd ready: true, restart count 0
Feb  6 01:15:21.211: INFO: kindnet-57djt started at 2023-02-06 00:56:29 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:15:21.211: INFO: 	Container kindnet-cni ready: true, restart count 0
Feb  6 01:15:21.211: INFO: kube-controller-manager-kind-control-plane started at 2023-02-06 00:56:14 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:15:21.211: INFO: 	Container kube-controller-manager ready: true, restart count 0
Feb  6 01:15:21.211: INFO: kube-apiserver-kind-control-plane started at 2023-02-06 00:56:13 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:15:21.211: INFO: 	Container kube-apiserver ready: true, restart count 0
Feb  6 01:15:21.211: INFO: coredns-565d847f94-4f782 started at 2023-02-06 00:56:33 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:15:21.211: INFO: 	Container coredns ready: true, restart count 0
Feb  6 01:15:21.211: INFO: kube-proxy-4smqz started at 2023-02-06 00:56:49 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:15:21.211: INFO: 	Container kube-proxy ready: true, restart count 0
Feb  6 01:15:21.255: INFO: 
Latency metrics for node kind-control-plane
Feb  6 01:15:21.255: INFO: 
Logging node info for node kind-worker
Feb  6 01:15:21.259: INFO: Node Info: &Node{ObjectMeta:{kind-worker    97dfb9be-7347-4918-a3ea-1d0304aae063 26645 0 2023-02-06 00:56:48 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/os:linux kubernetes.io/arch:amd64 kubernetes.io/hostname:kind-worker kubernetes.io/os:linux topology.hostpath.csi/node:kind-worker] map[kubeadm.alpha.kubernetes.io/cri-socket:unix:///run/containerd/containerd.sock node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] [] [{kubeadm Update v1 2023-02-06 00:56:48 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:kubeadm.alpha.kubernetes.io/cri-socket":{}}}} } {kubelet Update v1 2023-02-06 00:56:48 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{".":{},"f:volumes.kubernetes.io/controller-managed-attach-detach":{}},"f:labels":{".":{},"f:beta.kubernetes.io/arch":{},"f:beta.kubernetes.io/os":{},"f:kubernetes.io/arch":{},"f:kubernetes.io/hostname":{},"f:kubernetes.io/os":{}}},"f:spec":{"f:providerID":{}}} } {kube-controller-manager Update v1 2023-02-06 00:57:18 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:node.alpha.kubernetes.io/ttl":{}}},"f:spec":{"f:podCIDR":{},"f:podCIDRs":{".":{},"v:\"10.244.1.0/24\"":{}}}} } {kubelet Update v1 2023-02-06 01:14:51 +0000 UTC FieldsV1 {"f:metadata":{"f:labels":{"f:topology.hostpath.csi/node":{}}},"f:status":{"f:conditions":{"k:{\"type\":\"DiskPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"MemoryPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"PIDPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"Ready\"}":{"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{}}},"f:images":{}}} status}]},Spec:NodeSpec{PodCIDR:10.244.1.0/24,DoNotUseExternalID:,ProviderID:kind://docker/kind/kind-worker,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[10.244.1.0/24],},Status:NodeStatus{Capacity:ResourceList{cpu: {{8 0} {<nil>} 8 DecimalSI},ephemeral-storage: {{791251738624 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{54757810176 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{cpu: {{8 0} {<nil>} 8 DecimalSI},ephemeral-storage: {{791251738624 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{54757810176 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2023-02-06 01:14:51 +0000 UTC,LastTransitionTime:2023-02-06 00:56:48 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2023-02-06 01:14:51 +0000 UTC,LastTransitionTime:2023-02-06 00:56:48 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2023-02-06 01:14:51 +0000 UTC,LastTransitionTime:2023-02-06 00:56:48 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2023-02-06 01:14:51 +0000 UTC,LastTransitionTime:2023-02-06 00:57:18 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:172.18.0.3,},NodeAddress{Type:Hostname,Address:kind-worker,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:172373c86857450b92ebd910b3e64bfe,SystemUUID:35d425c3-1b1c-4f22-884d-edbfd8946a2a,BootID:1091d151-f16c-4fdc-899a-0bff3500ee70,KernelVersion:5.4.0-1086-gke,OSImage:Ubuntu 22.04.1 LTS,ContainerRuntimeVersion:containerd://1.6.14,KubeletVersion:v1.25.7-rc.0.16+74817504841bde,KubeProxyVersion:v1.25.7-rc.0.16+74817504841bde,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:57e1263cc818c8c5dc39e96fd84b43e749372f7d8e0194473db182c02d736f7a registry.k8s.io/kube-apiserver:v1.25.7-rc.0.16_74817504841bde],SizeBytes:129108435,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:1e63ddf4e3d1ee17eb9088ac421b83265235b72a43aa83f480cff1a72d879e4d registry.k8s.io/kube-controller-manager:v1.25.7-rc.0.16_74817504841bde],SizeBytes:118450487,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/jessie-dnsutils@sha256:11e6a66017ba4e4b938c1612b7a54a3befcefd354796c04e1dba76873a13518e registry.k8s.io/e2e-test-images/jessie-dnsutils:1.5],SizeBytes:112030526,},ContainerImage{Names:[registry.k8s.io/etcd:3.5.6-0],SizeBytes:102542580,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:e84585d19592e86a87b740345f7251ea2fe5752bd3c944759025c5a8382436f2 registry.k8s.io/kube-scheduler:v1.25.7-rc.0.16_74817504841bde],SizeBytes:51931448,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/agnhost@sha256:af7e3857d87770ddb40f5ea4f89b5a2709504ab1ee31f9ea4ab5823c045f2146 registry.k8s.io/e2e-test-images/agnhost:2.40],SizeBytes:51155161,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/nautilus@sha256:99c0d6f1ad24a1aa1905d9c6534d193f268f7b23f9add2ae6bb41f31094bdd5c registry.k8s.io/e2e-test-images/nautilus:1.5],SizeBytes:49642095,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:7006371f635ca3e22c794b167237c78cdecc5689d6b60bbb5b6257ec2a32463a registry.k8s.io/kube-proxy:v1.25.7-rc.0.16_74817504841bde],SizeBytes:48821978,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/httpd@sha256:20f25f275d46aa728f7615a1ccc19c78b2ed89435bf943a44b339f70f45508e6 registry.k8s.io/e2e-test-images/httpd:2.4.39-2],SizeBytes:41902010,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/httpd@sha256:1b9d1b2f36cb2dbee1960e82a9344aeb11bd4c4c03abf5e1853e0559c23855e3 registry.k8s.io/e2e-test-images/httpd:2.4.38-2],SizeBytes:40764680,},ContainerImage{Names:[docker.io/kindest/kindnetd:v20221004-44d545d1],SizeBytes:25830582,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/sample-apiserver@sha256:f9c93b92b6ff750b41a93c4e4fe0bfe384597aeb841e2539d5444815c55b2d8f registry.k8s.io/e2e-test-images/sample-apiserver:1.17.5],SizeBytes:24316368,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-provisioner@sha256:122bfb8c1edabb3c0edd63f06523e6940d958d19b3957dc7b1d6f81e9f1f6119 registry.k8s.io/sig-storage/csi-provisioner:v3.1.0],SizeBytes:23345856,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-resizer@sha256:9ebbf9f023e7b41ccee3d52afe39a89e3ddacdbb69269d583abfc25847cfd9e4 registry.k8s.io/sig-storage/csi-resizer:v1.4.0],SizeBytes:22381475,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-snapshotter@sha256:89e900a160a986a1a7a4eba7f5259e510398fa87ca9b8a729e7dec59e04c7709 registry.k8s.io/sig-storage/csi-snapshotter:v5.0.1],SizeBytes:22163966,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-attacher@sha256:8b9c313c05f54fb04f8d430896f5f5904b6cb157df261501b29adc04d2b2dc7b registry.k8s.io/sig-storage/csi-attacher:v3.4.0],SizeBytes:22085298,},ContainerImage{Names:[docker.io/kindest/local-path-provisioner:v0.0.22-kind.0],SizeBytes:17375346,},ContainerImage{Names:[registry.k8s.io/sig-storage/hostpathplugin@sha256:6029c252dae6178c99b580de72d7776158edbc81be0de15cedc4152a3acfed18 registry.k8s.io/sig-storage/hostpathplugin:v1.7.3],SizeBytes:15224494,},ContainerImage{Names:[registry.k8s.io/coredns/coredns:v1.9.3],SizeBytes:14837849,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-node-driver-registrar@sha256:4fd21f36075b44d1a423dfb262ad79202ce54e95f5cbc4622a6c1c38ab287ad6 registry.k8s.io/sig-storage/csi-node-driver-registrar:v2.5.0],SizeBytes:9132637,},ContainerImage{Names:[registry.k8s.io/sig-storage/livenessprobe@sha256:406f59599991916d2942d8d02f076d957ed71b541ee19f09fc01723a6e6f5932 registry.k8s.io/sig-storage/livenessprobe:v2.6.0],SizeBytes:8240918,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/nginx@sha256:13616070e3f29de4417eee434a8ef472221c9e51b3d037b5a6b46cef08eb7443 registry.k8s.io/e2e-test-images/nginx:1.14-2],SizeBytes:6979041,},ContainerImage{Names:[docker.io/kindest/local-path-helper:v20220607-9a4d8d2a],SizeBytes:2859509,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/busybox@sha256:c318242786b139d18676b1c09a0ad7f15fc17f8f16a5b2e625cd0dc8c9703daf registry.k8s.io/e2e-test-images/busybox:1.29-2],SizeBytes:732424,},ContainerImage{Names:[registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d registry.k8s.io/pause:3.8],SizeBytes:311286,},ContainerImage{Names:[registry.k8s.io/pause:3.7],SizeBytes:311278,},},VolumesInUse:[],VolumesAttached:[]AttachedVolume{},Config:nil,},}
Feb  6 01:15:21.259: INFO: 
Logging kubelet events for node kind-worker
Feb  6 01:15:21.263: INFO: 
Logging pods the kubelet thinks is on node kind-worker
Feb  6 01:15:21.279: INFO: kindnet-c28mq started at 2023-02-06 00:56:53 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:15:21.279: INFO: 	Container kindnet-cni ready: true, restart count 0
Feb  6 01:15:21.279: INFO: kube-proxy-gfjzk started at 2023-02-06 00:56:53 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:15:21.279: INFO: 	Container kube-proxy ready: true, restart count 0
Feb  6 01:15:21.346: INFO: 
Latency metrics for node kind-worker
Feb  6 01:15:21.346: INFO: 
Logging node info for node kind-worker2
Feb  6 01:15:21.353: INFO: Node Info: &Node{ObjectMeta:{kind-worker2    fd792665-70e8-4b27-859a-cd25172161db 26724 0 2023-02-06 00:56:48 +0000 UTC <nil> <nil> map[beta.kubernetes.io/arch:amd64 beta.kubernetes.io/os:linux kubernetes.io/arch:amd64 kubernetes.io/hostname:kind-worker2 kubernetes.io/os:linux topology.hostpath.csi/node:kind-worker2] map[csi.volume.kubernetes.io/nodeid:{"csi-hostpath-volume-expand-6065":"kind-worker2"} kubeadm.alpha.kubernetes.io/cri-socket:unix:///run/containerd/containerd.sock node.alpha.kubernetes.io/ttl:0 volumes.kubernetes.io/controller-managed-attach-detach:true] [] [] [{kubeadm Update v1 2023-02-06 00:56:48 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:kubeadm.alpha.kubernetes.io/cri-socket":{}}}} } {kubelet Update v1 2023-02-06 00:56:48 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{".":{},"f:volumes.kubernetes.io/controller-managed-attach-detach":{}},"f:labels":{".":{},"f:beta.kubernetes.io/arch":{},"f:beta.kubernetes.io/os":{},"f:kubernetes.io/arch":{},"f:kubernetes.io/hostname":{},"f:kubernetes.io/os":{}}},"f:spec":{"f:providerID":{}}} } {kube-controller-manager Update v1 2023-02-06 00:57:08 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:node.alpha.kubernetes.io/ttl":{}}},"f:spec":{"f:podCIDR":{},"f:podCIDRs":{".":{},"v:\"10.244.2.0/24\"":{}}}} } {kube-controller-manager Update v1 2023-02-06 01:14:10 +0000 UTC FieldsV1 {"f:status":{"f:volumesAttached":{}}} status} {kubelet Update v1 2023-02-06 01:15:15 +0000 UTC FieldsV1 {"f:metadata":{"f:annotations":{"f:csi.volume.kubernetes.io/nodeid":{}},"f:labels":{"f:topology.hostpath.csi/node":{}}},"f:status":{"f:conditions":{"k:{\"type\":\"DiskPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"MemoryPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"PIDPressure\"}":{"f:lastHeartbeatTime":{}},"k:{\"type\":\"Ready\"}":{"f:lastHeartbeatTime":{},"f:lastTransitionTime":{},"f:message":{},"f:reason":{},"f:status":{}}},"f:images":{},"f:volumesInUse":{}}} status}]},Spec:NodeSpec{PodCIDR:10.244.2.0/24,DoNotUseExternalID:,ProviderID:kind://docker/kind/kind-worker2,Unschedulable:false,Taints:[]Taint{},ConfigSource:nil,PodCIDRs:[10.244.2.0/24],},Status:NodeStatus{Capacity:ResourceList{cpu: {{8 0} {<nil>} 8 DecimalSI},ephemeral-storage: {{791251738624 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{54757810176 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{cpu: {{8 0} {<nil>} 8 DecimalSI},ephemeral-storage: {{791251738624 0} {<nil>}  BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{54757810176 0} {<nil>}  BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[]NodeCondition{NodeCondition{Type:MemoryPressure,Status:False,LastHeartbeatTime:2023-02-06 01:14:10 +0000 UTC,LastTransitionTime:2023-02-06 00:56:47 +0000 UTC,Reason:KubeletHasSufficientMemory,Message:kubelet has sufficient memory available,},NodeCondition{Type:DiskPressure,Status:False,LastHeartbeatTime:2023-02-06 01:14:10 +0000 UTC,LastTransitionTime:2023-02-06 00:56:47 +0000 UTC,Reason:KubeletHasNoDiskPressure,Message:kubelet has no disk pressure,},NodeCondition{Type:PIDPressure,Status:False,LastHeartbeatTime:2023-02-06 01:14:10 +0000 UTC,LastTransitionTime:2023-02-06 00:56:47 +0000 UTC,Reason:KubeletHasSufficientPID,Message:kubelet has sufficient PID available,},NodeCondition{Type:Ready,Status:True,LastHeartbeatTime:2023-02-06 01:14:10 +0000 UTC,LastTransitionTime:2023-02-06 00:57:08 +0000 UTC,Reason:KubeletReady,Message:kubelet is posting ready status,},},Addresses:[]NodeAddress{NodeAddress{Type:InternalIP,Address:172.18.0.4,},NodeAddress{Type:Hostname,Address:kind-worker2,},},DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:de9b0ce4c4194dd295a948bc056e313c,SystemUUID:89fd3899-adba-4b64-a6ab-cac0ef1fa3b4,BootID:1091d151-f16c-4fdc-899a-0bff3500ee70,KernelVersion:5.4.0-1086-gke,OSImage:Ubuntu 22.04.1 LTS,ContainerRuntimeVersion:containerd://1.6.14,KubeletVersion:v1.25.7-rc.0.16+74817504841bde,KubeProxyVersion:v1.25.7-rc.0.16+74817504841bde,OperatingSystem:linux,Architecture:amd64,},Images:[]ContainerImage{ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:57e1263cc818c8c5dc39e96fd84b43e749372f7d8e0194473db182c02d736f7a registry.k8s.io/kube-apiserver:v1.25.7-rc.0.16_74817504841bde],SizeBytes:129108435,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:1e63ddf4e3d1ee17eb9088ac421b83265235b72a43aa83f480cff1a72d879e4d registry.k8s.io/kube-controller-manager:v1.25.7-rc.0.16_74817504841bde],SizeBytes:118450487,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/jessie-dnsutils@sha256:11e6a66017ba4e4b938c1612b7a54a3befcefd354796c04e1dba76873a13518e registry.k8s.io/e2e-test-images/jessie-dnsutils:1.5],SizeBytes:112030526,},ContainerImage{Names:[registry.k8s.io/etcd:3.5.6-0],SizeBytes:102542580,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:e84585d19592e86a87b740345f7251ea2fe5752bd3c944759025c5a8382436f2 registry.k8s.io/kube-scheduler:v1.25.7-rc.0.16_74817504841bde],SizeBytes:51931448,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/agnhost@sha256:af7e3857d87770ddb40f5ea4f89b5a2709504ab1ee31f9ea4ab5823c045f2146 registry.k8s.io/e2e-test-images/agnhost:2.40],SizeBytes:51155161,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/nautilus@sha256:99c0d6f1ad24a1aa1905d9c6534d193f268f7b23f9add2ae6bb41f31094bdd5c registry.k8s.io/e2e-test-images/nautilus:1.5],SizeBytes:49642095,},ContainerImage{Names:[docker.io/library/import-2023-02-06@sha256:7006371f635ca3e22c794b167237c78cdecc5689d6b60bbb5b6257ec2a32463a registry.k8s.io/kube-proxy:v1.25.7-rc.0.16_74817504841bde],SizeBytes:48821978,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/httpd@sha256:20f25f275d46aa728f7615a1ccc19c78b2ed89435bf943a44b339f70f45508e6 registry.k8s.io/e2e-test-images/httpd:2.4.39-2],SizeBytes:41902010,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/httpd@sha256:1b9d1b2f36cb2dbee1960e82a9344aeb11bd4c4c03abf5e1853e0559c23855e3 registry.k8s.io/e2e-test-images/httpd:2.4.38-2],SizeBytes:40764680,},ContainerImage{Names:[docker.io/kindest/kindnetd:v20221004-44d545d1],SizeBytes:25830582,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-provisioner@sha256:122bfb8c1edabb3c0edd63f06523e6940d958d19b3957dc7b1d6f81e9f1f6119 registry.k8s.io/sig-storage/csi-provisioner:v3.1.0],SizeBytes:23345856,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-resizer@sha256:9ebbf9f023e7b41ccee3d52afe39a89e3ddacdbb69269d583abfc25847cfd9e4 registry.k8s.io/sig-storage/csi-resizer:v1.4.0],SizeBytes:22381475,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-snapshotter@sha256:89e900a160a986a1a7a4eba7f5259e510398fa87ca9b8a729e7dec59e04c7709 registry.k8s.io/sig-storage/csi-snapshotter:v5.0.1],SizeBytes:22163966,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-attacher@sha256:8b9c313c05f54fb04f8d430896f5f5904b6cb157df261501b29adc04d2b2dc7b registry.k8s.io/sig-storage/csi-attacher:v3.4.0],SizeBytes:22085298,},ContainerImage{Names:[docker.io/kindest/local-path-provisioner:v0.0.22-kind.0],SizeBytes:17375346,},ContainerImage{Names:[registry.k8s.io/sig-storage/hostpathplugin@sha256:6029c252dae6178c99b580de72d7776158edbc81be0de15cedc4152a3acfed18 registry.k8s.io/sig-storage/hostpathplugin:v1.7.3],SizeBytes:15224494,},ContainerImage{Names:[registry.k8s.io/coredns/coredns:v1.9.3],SizeBytes:14837849,},ContainerImage{Names:[registry.k8s.io/sig-storage/csi-node-driver-registrar@sha256:4fd21f36075b44d1a423dfb262ad79202ce54e95f5cbc4622a6c1c38ab287ad6 registry.k8s.io/sig-storage/csi-node-driver-registrar:v2.5.0],SizeBytes:9132637,},ContainerImage{Names:[registry.k8s.io/sig-storage/livenessprobe@sha256:406f59599991916d2942d8d02f076d957ed71b541ee19f09fc01723a6e6f5932 registry.k8s.io/sig-storage/livenessprobe:v2.6.0],SizeBytes:8240918,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/nginx@sha256:13616070e3f29de4417eee434a8ef472221c9e51b3d037b5a6b46cef08eb7443 registry.k8s.io/e2e-test-images/nginx:1.14-2],SizeBytes:6979041,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/nonewprivs@sha256:8ac1264691820febacf3aea5d152cbde6d10685731ec14966a9401c6f47a68ac registry.k8s.io/e2e-test-images/nonewprivs:1.3],SizeBytes:3263463,},ContainerImage{Names:[docker.io/kindest/local-path-helper:v20220607-9a4d8d2a],SizeBytes:2859509,},ContainerImage{Names:[registry.k8s.io/e2e-test-images/busybox@sha256:c318242786b139d18676b1c09a0ad7f15fc17f8f16a5b2e625cd0dc8c9703daf registry.k8s.io/e2e-test-images/busybox:1.29-2],SizeBytes:732424,},ContainerImage{Names:[registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d registry.k8s.io/pause:3.8],SizeBytes:311286,},ContainerImage{Names:[registry.k8s.io/pause:3.7],SizeBytes:311278,},},VolumesInUse:[kubernetes.io/csi/csi-hostpath-ephemeral-1313^56aab5ed-a5b9-11ed-842e-9e5b967d35a7 kubernetes.io/csi/csi-hostpath-volume-expand-6065^568dffcc-a5b9-11ed-9853-1e18a871a9f1],VolumesAttached:[]AttachedVolume{AttachedVolume{Name:kubernetes.io/csi/csi-hostpath-volume-expand-6065^568dffcc-a5b9-11ed-9853-1e18a871a9f1,DevicePath:,},AttachedVolume{Name:kubernetes.io/csi/csi-hostpath-ephemeral-1313^56aab5ed-a5b9-11ed-842e-9e5b967d35a7,DevicePath:,},},Config:nil,},}
Feb  6 01:15:21.353: INFO: 
Logging kubelet events for node kind-worker2
Feb  6 01:15:21.356: INFO: 
Logging pods the kubelet thinks is on node kind-worker2
Feb  6 01:15:21.363: INFO: pod-cb6ef72b-cbc6-4846-a12d-77ddd06e99d1 started at 2023-02-06 00:58:16 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:15:21.363: INFO: 	Container write-pod ready: false, restart count 0
Feb  6 01:15:21.363: INFO: kube-proxy-68p4n started at 2023-02-06 00:56:56 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:15:21.363: INFO: 	Container kube-proxy ready: true, restart count 0
Feb  6 01:15:21.363: INFO: kindnet-4xswj started at 2023-02-06 00:56:56 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:15:21.363: INFO: 	Container kindnet-cni ready: true, restart count 0
Feb  6 01:15:21.363: INFO: inline-volume-tester-txq4j started at 2023-02-06 00:58:16 +0000 UTC (0+1 container statuses recorded)
Feb  6 01:15:21.363: INFO: 	Container csi-volume-tester ready: false, restart count 0
Feb  6 01:15:21.363: INFO: csi-hostpathplugin-0 started at 2023-02-06 00:57:52 +0000 UTC (0+7 container statuses recorded)
Feb  6 01:15:21.363: INFO: 	Container csi-attacher ready: true, restart count 1
Feb  6 01:15:21.363: INFO: 	Container csi-provisioner ready: true, restart count 1
Feb  6 01:15:21.363: INFO: 	Container csi-resizer ready: true, restart count 1
Feb  6 01:15:21.363: INFO: 	Container csi-snapshotter ready: true, restart count 1
Feb  6 01:15:21.363: INFO: 	Container hostpath ready: true, restart count 1
Feb  6 01:15:21.363: INFO: 	Container liveness-probe ready: true, restart count 0
Feb  6 01:15:21.363: INFO: 	Container node-driver-registrar ready: true, restart count 0
Feb  6 01:15:21.493: INFO: 
Latency metrics for node kind-worker2
Feb  6 01:15:21.493: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "ephemeral-1313" for this suite. 02/06/23 01:15:21.498

				Click to see stdout/stderrfrom junit_01.xml

Filter through log files | View test history on testgrid

Show 6698 Skipped Tests

Result	FAILURE
Tests	3 failed / 416 succeeded
Started	2023-02-06 00:42
Elapsed	47m50s
Revision	release-1.25

ci-kubernetes-kind-e2e-json-logging-1-25 #1622394211728887808

Test Failures

Kubernetes e2e suite [It] [sig-storage] CSI Volumes [Driver: csi-hostpath] [Testpattern: Dynamic PV (block volmode)(allowExpansion)] volume-expand should resize volume when PVC is edited while pod is using it 31m9s

Kubernetes e2e suite [It] [sig-storage] CSI Volumes [Driver: csi-hostpath] [Testpattern: Generic Ephemeral-volume (default fs) (immediate-binding)] ephemeral should support expansion of pvcs created for ephemeral pvcs 16m25s

Kubernetes e2e suite [It] [sig-storage] CSI Volumes [Driver: csi-hostpath] [Testpattern: Generic Ephemeral-volume (default fs) (late-binding)] ephemeral should support two pods which have the same volume definition 17m30s

Passed

Skipped