ResultNot Finished
Started2018-12-07 03:06
Versionv1.14.0-alpha.0.901+53a37cf49dec7e
Buildergke-prow-default-pool-3c8994a8-lvxs
Refs master:b7030166
69803:712cbc5a
71734:7ee0e002
pod07b49411-f9cd-11e8-92b6-0a580a6c0310

Build Still Running!


Error lines from build-log.txt

... skipping 2020 lines ...
I1207 03:55:19.206] [sig-storage] In-tree Volumes
I1207 03:55:19.206] test/e2e/storage/utils/framework.go:22
I1207 03:55:19.206]   [Driver: hostPath]
I1207 03:55:19.206]   test/e2e/storage/in_tree_volumes.go:78
I1207 03:55:19.206]     [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
I1207 03:55:19.207]     test/e2e/storage/testsuites/volumemode.go:109
I1207 03:55:19.207]       should fail to create pod by failing to mount volume [BeforeEach]
I1207 03:55:19.207]       test/e2e/storage/testsuites/volumemode.go:242
I1207 03:55:19.207] 
I1207 03:55:19.207]       Dec  7 03:55:03.840: Driver hostPath doesn't support PreprovisionedPV -- skipping
I1207 03:55:19.207] 
I1207 03:55:19.207]       test/e2e/framework/util.go:292
I1207 03:55:19.207] ------------------------------
... skipping 42 lines ...
I1207 03:55:19.974]   test/e2e/storage/testsuites/subpath.go:120
I1207 03:55:19.974] STEP: Deleting pod
I1207 03:55:19.974] Dec  7 03:54:37.585: INFO: Deleting pod "pod-subpath-test-aws-dynamicpv-ns58" in namespace "e2e-tests-volumes-69f2q"
I1207 03:55:19.975] STEP: Deleting pvc
I1207 03:55:19.975] Dec  7 03:54:37.817: INFO: Deleting PersistentVolumeClaim "pvc-bprmp"
I1207 03:55:19.975] Dec  7 03:54:38.050: INFO: Waiting up to 5m0s for PersistentVolume pvc-c24ab8c1-f9d3-11e8-b230-0a9adda7ec62 to get deleted
I1207 03:55:19.975] Dec  7 03:54:38.282: INFO: PersistentVolume pvc-c24ab8c1-f9d3-11e8-b230-0a9adda7ec62 found and phase=Failed (232.004749ms)
I1207 03:55:19.975] Dec  7 03:54:43.515: INFO: PersistentVolume pvc-c24ab8c1-f9d3-11e8-b230-0a9adda7ec62 found and phase=Failed (5.465089931s)
I1207 03:55:19.975] Dec  7 03:54:48.747: INFO: PersistentVolume pvc-c24ab8c1-f9d3-11e8-b230-0a9adda7ec62 found and phase=Failed (10.697699914s)
I1207 03:55:19.975] Dec  7 03:54:53.980: INFO: PersistentVolume pvc-c24ab8c1-f9d3-11e8-b230-0a9adda7ec62 found and phase=Failed (15.9303354s)
I1207 03:55:19.975] Dec  7 03:54:59.213: INFO: PersistentVolume pvc-c24ab8c1-f9d3-11e8-b230-0a9adda7ec62 found and phase=Failed (21.162989428s)
I1207 03:55:19.975] Dec  7 03:55:04.445: INFO: PersistentVolume pvc-c24ab8c1-f9d3-11e8-b230-0a9adda7ec62 was removed
I1207 03:55:19.976] STEP: Deleting sc
I1207 03:55:19.976] [AfterEach] [Driver: aws]
I1207 03:55:19.976]   test/e2e/storage/in_tree_volumes.go:87
I1207 03:55:19.976] [AfterEach] [sig-storage] In-tree Volumes
I1207 03:55:19.976]   test/e2e/framework/framework.go:154
... skipping 1837 lines ...
I1207 03:56:15.487] Dec  7 03:55:34.390: INFO: PV ReclaimPolicy unspecified, default: Retain
I1207 03:56:15.487] Dec  7 03:55:34.856: INFO: Waiting for PV local-pvzfv9c to bind to PVC pvc-w5xsh
I1207 03:56:15.487] Dec  7 03:55:34.856: INFO: Waiting up to 3m0s for PersistentVolumeClaims [pvc-w5xsh] to have phase Bound
I1207 03:56:15.487] Dec  7 03:55:35.089: INFO: PersistentVolumeClaim pvc-w5xsh found and phase=Bound (232.829105ms)
I1207 03:56:15.488] Dec  7 03:55:35.089: INFO: Waiting up to 3m0s for PersistentVolume local-pvzfv9c to have phase Bound
I1207 03:56:15.488] Dec  7 03:55:35.321: INFO: PersistentVolume local-pvzfv9c found and phase=Bound (232.598251ms)
I1207 03:56:15.488] [It] should fail scheduling due to different NodeSelector
I1207 03:56:15.488]   test/e2e/storage/persistent_volumes-local.go:428
I1207 03:56:15.488] STEP: local-volume-type: dir
I1207 03:56:15.488] STEP: Initializing test volumes
I1207 03:56:15.489] Dec  7 03:55:35.786: INFO: Running '/go/src/k8s.io/kubernetes/kubernetes/platforms/linux/amd64/kubectl --server=https://api.e2e-a74748c183-dba53.test-cncf-aws.k8s.io --kubeconfig=/tmp/kops060486941/kubeconfig exec --namespace=e2e-tests-persistent-local-volumes-test-rhrlq hostexec-ip-172-20-35-222.ap-south-1.compute.internal -- nsenter --mount=/rootfs/proc/1/ns/mnt -- sh -c mkdir -p /tmp/local-volume-test-f3c32459-f9d3-11e8-b817-0a580a3c585b'
I1207 03:56:15.489] Dec  7 03:55:38.536: INFO: stderr: ""
I1207 03:56:15.489] Dec  7 03:55:38.536: INFO: stdout: ""
... skipping 28 lines ...
I1207 03:56:15.493] 
I1207 03:56:15.493] • [SLOW TEST:54.654 seconds]
I1207 03:56:15.493] [sig-storage] PersistentVolumes-local 
I1207 03:56:15.494] test/e2e/storage/utils/framework.go:22
I1207 03:56:15.494]   Pod with node different from PV's NodeAffinity
I1207 03:56:15.494]   test/e2e/storage/persistent_volumes-local.go:402
I1207 03:56:15.494]     should fail scheduling due to different NodeSelector
I1207 03:56:15.494]     test/e2e/storage/persistent_volumes-local.go:428
I1207 03:56:15.494] ------------------------------
I1207 03:56:16.648] [BeforeEach] [sig-cli] Kubectl client
I1207 03:56:16.648]   test/e2e/framework/framework.go:153
I1207 03:56:16.649] STEP: Creating a kubernetes client
I1207 03:56:16.649] Dec  7 03:54:39.721: INFO: >>> kubeConfig: /tmp/kops060486941/kubeconfig
... skipping 97 lines ...
I1207 03:56:16.664] STEP: creating an object not containing a namespace with in-cluster config
I1207 03:56:16.664] Dec  7 03:55:43.062: INFO: Running '/go/src/k8s.io/kubernetes/kubernetes/platforms/linux/amd64/kubectl --server=https://api.e2e-a74748c183-dba53.test-cncf-aws.k8s.io --kubeconfig=/tmp/kops060486941/kubeconfig exec --namespace=e2e-tests-kubectl-cjzkd nginx -- /bin/sh -c /tmp/kubectl create -f /tmp/invalid-configmap-without-namespace.yaml --v=6 2>&1'
I1207 03:56:16.664] Dec  7 03:55:45.977: INFO: rc: 255
I1207 03:56:16.664] STEP: trying to use kubectl with invalid token
I1207 03:56:16.664] Dec  7 03:55:45.978: INFO: Running '/go/src/k8s.io/kubernetes/kubernetes/platforms/linux/amd64/kubectl --server=https://api.e2e-a74748c183-dba53.test-cncf-aws.k8s.io --kubeconfig=/tmp/kops060486941/kubeconfig exec --namespace=e2e-tests-kubectl-cjzkd nginx -- /bin/sh -c /tmp/kubectl get pods --token=invalid --v=7 2>&1'
I1207 03:56:16.665] Dec  7 03:55:48.904: INFO: rc: 255
I1207 03:56:16.665] Dec  7 03:55:48.904: INFO: got err error running &{/go/src/k8s.io/kubernetes/kubernetes/platforms/linux/amd64/kubectl [kubectl --server=https://api.e2e-a74748c183-dba53.test-cncf-aws.k8s.io --kubeconfig=/tmp/kops060486941/kubeconfig exec --namespace=e2e-tests-kubectl-cjzkd nginx -- /bin/sh -c /tmp/kubectl get pods --token=invalid --v=7 2>&1] []  <nil> I1207 03:55:48.766645      91 merged_client_builder.go:159] Using in-cluster namespace
I1207 03:56:16.665] I1207 03:55:48.767220      91 merged_client_builder.go:122] Using in-cluster configuration
I1207 03:56:16.665] I1207 03:55:48.770185      91 merged_client_builder.go:122] Using in-cluster configuration
I1207 03:56:16.665] I1207 03:55:48.774765      91 merged_client_builder.go:122] Using in-cluster configuration
I1207 03:56:16.665] I1207 03:55:48.775046      91 round_trippers.go:416] GET https://100.64.0.1:443/api/v1/namespaces/e2e-tests-kubectl-cjzkd/pods?limit=500
I1207 03:56:16.665] I1207 03:55:48.775073      91 round_trippers.go:423] Request Headers:
I1207 03:56:16.665] I1207 03:55:48.775093      91 round_trippers.go:426]     User-Agent: kubectl/v1.14.0 (linux/amd64) kubernetes/53a37cf
... skipping 6 lines ...
I1207 03:56:16.666]   "metadata": {},
I1207 03:56:16.666]   "status": "Failure",
I1207 03:56:16.666]   "message": "Unauthorized",
I1207 03:56:16.666]   "reason": "Unauthorized",
I1207 03:56:16.666]   "code": 401
I1207 03:56:16.666] }]
I1207 03:56:16.666] F1207 03:55:48.780902      91 helpers.go:116] error: You must be logged in to the server (Unauthorized)
I1207 03:56:16.667]  command terminated with exit code 255
I1207 03:56:16.667]  [] <nil> 0xc000ac9440 exit status 255 <nil> <nil> true [0xc000e84370 0xc000e84388 0xc000e843a0] [0xc000e84370 0xc000e84388 0xc000e843a0] [0xc000e84380 0xc000e84398] [0x930950 0x930950] 0xc001300f00 <nil>}:
I1207 03:56:16.667] Command stdout:
I1207 03:56:16.667] I1207 03:55:48.766645      91 merged_client_builder.go:159] Using in-cluster namespace
I1207 03:56:16.667] I1207 03:55:48.767220      91 merged_client_builder.go:122] Using in-cluster configuration
I1207 03:56:16.667] I1207 03:55:48.770185      91 merged_client_builder.go:122] Using in-cluster configuration
... skipping 10 lines ...
I1207 03:56:16.668]   "metadata": {},
I1207 03:56:16.668]   "status": "Failure",
I1207 03:56:16.668]   "message": "Unauthorized",
I1207 03:56:16.668]   "reason": "Unauthorized",
I1207 03:56:16.668]   "code": 401
I1207 03:56:16.669] }]
I1207 03:56:16.669] F1207 03:55:48.780902      91 helpers.go:116] error: You must be logged in to the server (Unauthorized)
I1207 03:56:16.669] 
I1207 03:56:16.669] stderr:
I1207 03:56:16.669] command terminated with exit code 255
I1207 03:56:16.669] 
I1207 03:56:16.669] error:
I1207 03:56:16.669] exit status 255
I1207 03:56:16.669] 
I1207 03:56:16.669] STEP: trying to use kubectl with invalid server
I1207 03:56:16.669] Dec  7 03:55:48.904: INFO: Running '/go/src/k8s.io/kubernetes/kubernetes/platforms/linux/amd64/kubectl --server=https://api.e2e-a74748c183-dba53.test-cncf-aws.k8s.io --kubeconfig=/tmp/kops060486941/kubeconfig exec --namespace=e2e-tests-kubectl-cjzkd nginx -- /bin/sh -c /tmp/kubectl get pods --server=invalid --v=6 2>&1'
I1207 03:56:16.670] Dec  7 03:55:51.736: INFO: rc: 255
I1207 03:56:16.670] Dec  7 03:55:51.736: INFO: got err error running &{/go/src/k8s.io/kubernetes/kubernetes/platforms/linux/amd64/kubectl [kubectl --server=https://api.e2e-a74748c183-dba53.test-cncf-aws.k8s.io --kubeconfig=/tmp/kops060486941/kubeconfig exec --namespace=e2e-tests-kubectl-cjzkd nginx -- /bin/sh -c /tmp/kubectl get pods --server=invalid --v=6 2>&1] []  <nil> I1207 03:55:51.558332     102 merged_client_builder.go:159] Using in-cluster namespace
I1207 03:56:16.670] I1207 03:55:51.598841     102 round_trippers.go:438] GET http://invalid/api?timeout=32s  in 39 milliseconds
I1207 03:56:16.670] I1207 03:55:51.598881     102 cached_discovery.go:113] skipped caching discovery info due to Get http://invalid/api?timeout=32s: dial tcp: lookup invalid on 100.64.0.10:53: no such host
I1207 03:56:16.670] I1207 03:55:51.603690     102 round_trippers.go:438] GET http://invalid/api?timeout=32s  in 3 milliseconds
I1207 03:56:16.670] I1207 03:55:51.603721     102 cached_discovery.go:113] skipped caching discovery info due to Get http://invalid/api?timeout=32s: dial tcp: lookup invalid on 100.64.0.10:53: no such host
I1207 03:56:16.671] I1207 03:55:51.603754     102 shortcut.go:89] Error loading discovery information: Get http://invalid/api?timeout=32s: dial tcp: lookup invalid on 100.64.0.10:53: no such host
I1207 03:56:16.671] I1207 03:55:51.607021     102 round_trippers.go:438] GET http://invalid/api?timeout=32s  in 3 milliseconds
I1207 03:56:16.671] I1207 03:55:51.607049     102 cached_discovery.go:113] skipped caching discovery info due to Get http://invalid/api?timeout=32s: dial tcp: lookup invalid on 100.64.0.10:53: no such host
I1207 03:56:16.671] I1207 03:55:51.610457     102 round_trippers.go:438] GET http://invalid/api?timeout=32s  in 3 milliseconds
I1207 03:56:16.671] I1207 03:55:51.610519     102 cached_discovery.go:113] skipped caching discovery info due to Get http://invalid/api?timeout=32s: dial tcp: lookup invalid on 100.64.0.10:53: no such host
I1207 03:56:16.671] I1207 03:55:51.613989     102 round_trippers.go:438] GET http://invalid/api?timeout=32s  in 3 milliseconds
I1207 03:56:16.671] I1207 03:55:51.614020     102 cached_discovery.go:113] skipped caching discovery info due to Get http://invalid/api?timeout=32s: dial tcp: lookup invalid on 100.64.0.10:53: no such host
I1207 03:56:16.671] I1207 03:55:51.614040     102 helpers.go:216] Connection error: Get http://invalid/api?timeout=32s: dial tcp: lookup invalid on 100.64.0.10:53: no such host
I1207 03:56:16.672] F1207 03:55:51.614052     102 helpers.go:116] Unable to connect to the server: dial tcp: lookup invalid on 100.64.0.10:53: no such host
I1207 03:56:16.672]  command terminated with exit code 255
I1207 03:56:16.672]  [] <nil> 0xc000ac9f50 exit status 255 <nil> <nil> true [0xc000e843a8 0xc000e843c0 0xc000e843d8] [0xc000e843a8 0xc000e843c0 0xc000e843d8] [0xc000e843b8 0xc000e843d0] [0x930950 0x930950] 0xc001301200 <nil>}:
I1207 03:56:16.672] Command stdout:
I1207 03:56:16.672] I1207 03:55:51.558332     102 merged_client_builder.go:159] Using in-cluster namespace
I1207 03:56:16.672] I1207 03:55:51.598841     102 round_trippers.go:438] GET http://invalid/api?timeout=32s  in 39 milliseconds
I1207 03:56:16.673] I1207 03:55:51.598881     102 cached_discovery.go:113] skipped caching discovery info due to Get http://invalid/api?timeout=32s: dial tcp: lookup invalid on 100.64.0.10:53: no such host
I1207 03:56:16.673] I1207 03:55:51.603690     102 round_trippers.go:438] GET http://invalid/api?timeout=32s  in 3 milliseconds
I1207 03:56:16.673] I1207 03:55:51.603721     102 cached_discovery.go:113] skipped caching discovery info due to Get http://invalid/api?timeout=32s: dial tcp: lookup invalid on 100.64.0.10:53: no such host
I1207 03:56:16.673] I1207 03:55:51.603754     102 shortcut.go:89] Error loading discovery information: Get http://invalid/api?timeout=32s: dial tcp: lookup invalid on 100.64.0.10:53: no such host
I1207 03:56:16.673] I1207 03:55:51.607021     102 round_trippers.go:438] GET http://invalid/api?timeout=32s  in 3 milliseconds
I1207 03:56:16.673] I1207 03:55:51.607049     102 cached_discovery.go:113] skipped caching discovery info due to Get http://invalid/api?timeout=32s: dial tcp: lookup invalid on 100.64.0.10:53: no such host
I1207 03:56:16.674] I1207 03:55:51.610457     102 round_trippers.go:438] GET http://invalid/api?timeout=32s  in 3 milliseconds
I1207 03:56:16.674] I1207 03:55:51.610519     102 cached_discovery.go:113] skipped caching discovery info due to Get http://invalid/api?timeout=32s: dial tcp: lookup invalid on 100.64.0.10:53: no such host
I1207 03:56:16.674] I1207 03:55:51.613989     102 round_trippers.go:438] GET http://invalid/api?timeout=32s  in 3 milliseconds
I1207 03:56:16.674] I1207 03:55:51.614020     102 cached_discovery.go:113] skipped caching discovery info due to Get http://invalid/api?timeout=32s: dial tcp: lookup invalid on 100.64.0.10:53: no such host
I1207 03:56:16.674] I1207 03:55:51.614040     102 helpers.go:216] Connection error: Get http://invalid/api?timeout=32s: dial tcp: lookup invalid on 100.64.0.10:53: no such host
I1207 03:56:16.674] F1207 03:55:51.614052     102 helpers.go:116] Unable to connect to the server: dial tcp: lookup invalid on 100.64.0.10:53: no such host
I1207 03:56:16.674] 
I1207 03:56:16.674] stderr:
I1207 03:56:16.674] command terminated with exit code 255
I1207 03:56:16.675] 
I1207 03:56:16.675] error:
I1207 03:56:16.675] exit status 255
I1207 03:56:16.675] 
I1207 03:56:16.675] STEP: trying to use kubectl with invalid namespace
I1207 03:56:16.675] Dec  7 03:55:51.736: INFO: Running '/go/src/k8s.io/kubernetes/kubernetes/platforms/linux/amd64/kubectl --server=https://api.e2e-a74748c183-dba53.test-cncf-aws.k8s.io --kubeconfig=/tmp/kops060486941/kubeconfig exec --namespace=e2e-tests-kubectl-cjzkd nginx -- /bin/sh -c /tmp/kubectl get pods --namespace=invalid --v=6 2>&1'
I1207 03:56:16.675] Dec  7 03:55:54.543: INFO: stderr: ""
I1207 03:56:16.675] Dec  7 03:55:54.543: INFO: stdout: "I1207 03:55:54.404871     117 merged_client_builder.go:122] Using in-cluster configuration\nI1207 03:55:54.408398     117 merged_client_builder.go:122] Using in-cluster configuration\nI1207 03:55:54.411553     117 merged_client_builder.go:122] Using in-cluster configuration\nI1207 03:55:54.420154     117 round_trippers.go:438] GET https://100.64.0.1:443/api/v1/namespaces/invalid/pods?limit=500 200 OK in 8 milliseconds\nNo resources found.\n"
... skipping 2781 lines ...
I1207 03:57:43.056] Dec  7 03:57:43.052: INFO: namespace e2e-tests-pod-disks-6swt9 deletion completed in 15.147402306s
I1207 03:57:43.056] 
I1207 03:57:43.056] 
I1207 03:57:43.056] S [SKIPPING] in Spec Setup (BeforeEach) [23.080 seconds]
I1207 03:57:43.057] [sig-storage] Pod Disks
I1207 03:57:43.057] test/e2e/storage/utils/framework.go:22
I1207 03:57:43.057]   should be able to delete a non-existent PD without error [BeforeEach]
I1207 03:57:43.057]   test/e2e/storage/pd.go:436
I1207 03:57:43.057] 
I1207 03:57:43.057]   Dec  7 03:57:27.669: Requires at least 2 nodes (not -1)
I1207 03:57:43.057] 
I1207 03:57:43.057]   test/e2e/framework/util.go:292
I1207 03:57:43.057] ------------------------------
... skipping 3292 lines ...
I1207 03:59:51.795] Dec  7 03:58:24.138: INFO: Running '/go/src/k8s.io/kubernetes/kubernetes/platforms/linux/amd64/kubectl --server=https://api.e2e-a74748c183-dba53.test-cncf-aws.k8s.io --kubeconfig=/tmp/kops060486941/kubeconfig create -f - --namespace=e2e-tests-kubectl-q9mtq'
I1207 03:59:51.795] Dec  7 03:58:25.527: INFO: stderr: ""
I1207 03:59:51.795] Dec  7 03:58:25.527: INFO: stdout: "deployment.extensions/redis-slave created\n"
I1207 03:59:51.796] STEP: validating guestbook app
I1207 03:59:51.796] Dec  7 03:58:25.527: INFO: Waiting for all frontend pods to be Running.
I1207 03:59:51.796] Dec  7 03:58:45.779: INFO: Waiting for frontend to serve content.
I1207 03:59:51.796] Dec  7 03:58:51.024: INFO: Failed to get response from guestbook. err: <nil>, response: <br />
I1207 03:59:51.796] <b>Fatal error</b>:  Uncaught exception 'Predis\Connection\ConnectionException' with message 'Connection timed out [tcp://redis-slave:6379]' in /usr/local/lib/php/Predis/Connection/AbstractConnection.php:155
I1207 03:59:51.796] Stack trace:
I1207 03:59:51.796] #0 /usr/local/lib/php/Predis/Connection/StreamConnection.php(128): Predis\Connection\AbstractConnection-&gt;onConnectionError('Connection time...', 110)
I1207 03:59:51.796] #1 /usr/local/lib/php/Predis/Connection/StreamConnection.php(178): Predis\Connection\StreamConnection-&gt;createStreamSocket(Object(Predis\Connection\Parameters), 'tcp://redis-sla...', 4)
I1207 03:59:51.796] #2 /usr/local/lib/php/Predis/Connection/StreamConnection.php(100): Predis\Connection\StreamConnection-&gt;tcpStreamInitializer(Object(Predis\Connection\Parameters))
I1207 03:59:51.797] #3 /usr/local/lib/php/Predis/Connection/AbstractConnection.php(81): Predis\Connection\StreamConnection-&gt;createResource()
I1207 03:59:51.797] #4 /usr/local/lib/php/Predis/Connection/StreamConnection.php(258): Predis\Connection\AbstractConnection-&gt;connect()
... skipping 1011 lines ...
I1207 04:00:23.417] [sig-storage] In-tree Volumes
I1207 04:00:23.417] test/e2e/storage/utils/framework.go:22
I1207 04:00:23.417]   [Driver: gluster]
I1207 04:00:23.417]   test/e2e/storage/in_tree_volumes.go:78
I1207 04:00:23.418]     [Testpattern: Dynamic PV (block volmode)] volumeMode
I1207 04:00:23.418]     test/e2e/storage/testsuites/volumemode.go:109
I1207 04:00:23.418]       should fail in binding dynamic provisioned PV to PVC [BeforeEach]
I1207 04:00:23.418]       test/e2e/storage/testsuites/volumemode.go:315
I1207 04:00:23.418] 
I1207 04:00:23.418]       Dec  7 04:00:08.113: Driver gluster doesn't support DynamicPV -- skipping
I1207 04:00:23.418] 
I1207 04:00:23.418]       test/e2e/framework/util.go:292
I1207 04:00:23.418] ------------------------------
... skipping 1065 lines ...
I1207 04:01:01.059] [sig-storage] In-tree Volumes
I1207 04:01:01.059] test/e2e/storage/utils/framework.go:22
I1207 04:01:01.059]   [Driver: emptydir]
I1207 04:01:01.059]   test/e2e/storage/in_tree_volumes.go:78
I1207 04:01:01.059]     [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
I1207 04:01:01.059]     test/e2e/storage/testsuites/volumemode.go:109
I1207 04:01:01.059]       should fail to create pod by failing to mount volume [BeforeEach]
I1207 04:01:01.059]       test/e2e/storage/testsuites/volumemode.go:242
I1207 04:01:01.059] 
I1207 04:01:01.060]       Dec  7 04:00:45.683: Driver emptydir doesn't support PreprovisionedPV -- skipping
I1207 04:01:01.060] 
I1207 04:01:01.060]       test/e2e/framework/util.go:292
I1207 04:01:01.060] ------------------------------
... skipping 227 lines ...
I1207 04:01:13.180] STEP: Creating a kubernetes client
I1207 04:01:13.180] Dec  7 04:00:46.776: INFO: >>> kubeConfig: /tmp/kops060486941/kubeconfig
I1207 04:01:13.180] STEP: Building a namespace api object, basename init-container
I1207 04:01:13.180] STEP: Waiting for a default service account to be provisioned in namespace
I1207 04:01:13.180] [BeforeEach] [k8s.io] InitContainer [NodeConformance]
I1207 04:01:13.180]   test/e2e/common/init_container.go:43
I1207 04:01:13.180] [It] should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I1207 04:01:13.181]   test/e2e/framework/framework.go:699
I1207 04:01:13.181] STEP: creating the pod
I1207 04:01:13.181] Dec  7 04:00:54.415: INFO: PodSpec: initContainers in spec.initContainers
I1207 04:01:13.181] [AfterEach] [k8s.io] InitContainer [NodeConformance]
I1207 04:01:13.181]   test/e2e/framework/framework.go:154
I1207 04:01:13.181] Dec  7 04:00:57.885: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 3 lines ...
I1207 04:01:13.182] Dec  7 04:01:13.178: INFO: namespace e2e-tests-init-container-dtcsp deletion completed in 15.05965863s
I1207 04:01:13.182] 
I1207 04:01:13.182] 
I1207 04:01:13.182] • [SLOW TEST:26.403 seconds]
I1207 04:01:13.182] [k8s.io] InitContainer [NodeConformance]
I1207 04:01:13.182] test/e2e/framework/framework.go:694
I1207 04:01:13.183]   should not start app containers and fail the pod if init containers fail on a RestartNever pod [Conformance]
I1207 04:01:13.183]   test/e2e/framework/framework.go:699
I1207 04:01:13.183] ------------------------------
I1207 04:01:13.247] SSS
I1207 04:01:13.247] ------------------------------
I1207 04:01:13.247] [BeforeEach] [sig-storage] PersistentVolumes-local 
I1207 04:01:13.248]   test/e2e/framework/framework.go:153
... skipping 170 lines ...
I1207 04:01:15.886]   test/e2e/storage/testsuites/subpath.go:120
I1207 04:01:15.886] STEP: Deleting pod
I1207 04:01:15.886] Dec  7 04:00:49.086: INFO: Deleting pod "pod-subpath-test-aws-dynamicpv-ls74" in namespace "e2e-tests-volumes-w9bdf"
I1207 04:01:15.886] STEP: Deleting pvc
I1207 04:01:15.886] Dec  7 04:00:49.319: INFO: Deleting PersistentVolumeClaim "pvc-skbwc"
I1207 04:01:15.886] Dec  7 04:00:49.554: INFO: Waiting up to 5m0s for PersistentVolume pvc-9560a9fe-f9d4-11e8-b230-0a9adda7ec62 to get deleted
I1207 04:01:15.887] Dec  7 04:00:49.788: INFO: PersistentVolume pvc-9560a9fe-f9d4-11e8-b230-0a9adda7ec62 found and phase=Failed (233.857597ms)
I1207 04:01:15.887] Dec  7 04:00:55.024: INFO: PersistentVolume pvc-9560a9fe-f9d4-11e8-b230-0a9adda7ec62 found and phase=Failed (5.469231907s)
I1207 04:01:15.887] Dec  7 04:01:00.260: INFO: PersistentVolume pvc-9560a9fe-f9d4-11e8-b230-0a9adda7ec62 was removed
I1207 04:01:15.888] STEP: Deleting sc
I1207 04:01:15.888] [AfterEach] [Driver: aws]
I1207 04:01:15.888]   test/e2e/storage/in_tree_volumes.go:87
I1207 04:01:15.888] [AfterEach] [sig-storage] In-tree Volumes
I1207 04:01:15.889]   test/e2e/framework/framework.go:154
... skipping 891 lines ...
I1207 04:01:47.357]   test/e2e/storage/testsuites/subpath.go:120
I1207 04:01:47.357] STEP: Deleting pod
I1207 04:01:47.358] Dec  7 04:01:10.083: INFO: Deleting pod "pod-subpath-test-aws-dynamicpv-2qnn" in namespace "e2e-tests-volumes-c624w"
I1207 04:01:47.358] STEP: Deleting pvc
I1207 04:01:47.362] Dec  7 04:01:10.316: INFO: Deleting PersistentVolumeClaim "pvc-gqhc9"
I1207 04:01:47.362] Dec  7 04:01:10.551: INFO: Waiting up to 5m0s for PersistentVolume pvc-9ee4fc25-f9d4-11e8-b230-0a9adda7ec62 to get deleted
I1207 04:01:47.362] Dec  7 04:01:10.785: INFO: PersistentVolume pvc-9ee4fc25-f9d4-11e8-b230-0a9adda7ec62 found and phase=Failed (233.590088ms)
I1207 04:01:47.362] Dec  7 04:01:16.020: INFO: PersistentVolume pvc-9ee4fc25-f9d4-11e8-b230-0a9adda7ec62 found and phase=Failed (5.468852779s)
I1207 04:01:47.362] Dec  7 04:01:21.254: INFO: PersistentVolume pvc-9ee4fc25-f9d4-11e8-b230-0a9adda7ec62 found and phase=Failed (10.703050167s)
I1207 04:01:47.363] Dec  7 04:01:26.490: INFO: PersistentVolume pvc-9ee4fc25-f9d4-11e8-b230-0a9adda7ec62 found and phase=Failed (15.938755876s)
I1207 04:01:47.363] Dec  7 04:01:31.729: INFO: PersistentVolume pvc-9ee4fc25-f9d4-11e8-b230-0a9adda7ec62 was removed
I1207 04:01:47.363] STEP: Deleting sc
I1207 04:01:47.363] [AfterEach] [Driver: aws]
I1207 04:01:47.363]   test/e2e/storage/in_tree_volumes.go:87
I1207 04:01:47.363] [AfterEach] [sig-storage] In-tree Volumes
I1207 04:01:47.363]   test/e2e/framework/framework.go:154
... skipping 125 lines ...
I1207 04:01:50.114]   test/e2e/storage/testsuites/subpath.go:120
I1207 04:01:50.114] STEP: Deleting pod
I1207 04:01:50.114] Dec  7 04:01:12.946: INFO: Deleting pod "pod-subpath-test-aws-dynamicpv-dvzs" in namespace "e2e-tests-volumes-98n2d"
I1207 04:01:50.114] STEP: Deleting pvc
I1207 04:01:50.114] Dec  7 04:01:13.178: INFO: Deleting PersistentVolumeClaim "pvc-n5l6p"
I1207 04:01:50.114] Dec  7 04:01:13.411: INFO: Waiting up to 5m0s for PersistentVolume pvc-90a8c0f4-f9d4-11e8-b230-0a9adda7ec62 to get deleted
I1207 04:01:50.114] Dec  7 04:01:13.643: INFO: PersistentVolume pvc-90a8c0f4-f9d4-11e8-b230-0a9adda7ec62 found and phase=Failed (232.026655ms)
I1207 04:01:50.114] Dec  7 04:01:18.876: INFO: PersistentVolume pvc-90a8c0f4-f9d4-11e8-b230-0a9adda7ec62 found and phase=Failed (5.464557659s)
I1207 04:01:50.114] Dec  7 04:01:24.109: INFO: PersistentVolume pvc-90a8c0f4-f9d4-11e8-b230-0a9adda7ec62 found and phase=Failed (10.697275933s)
I1207 04:01:50.115] Dec  7 04:01:29.342: INFO: PersistentVolume pvc-90a8c0f4-f9d4-11e8-b230-0a9adda7ec62 found and phase=Failed (15.930497117s)
I1207 04:01:50.115] Dec  7 04:01:34.575: INFO: PersistentVolume pvc-90a8c0f4-f9d4-11e8-b230-0a9adda7ec62 was removed
I1207 04:01:50.115] STEP: Deleting sc
I1207 04:01:50.115] [AfterEach] [Driver: aws]
I1207 04:01:50.115]   test/e2e/storage/in_tree_volumes.go:87
I1207 04:01:50.115] [AfterEach] [sig-storage] In-tree Volumes
I1207 04:01:50.115]   test/e2e/framework/framework.go:154
... skipping 85 lines ...
I1207 04:01:52.353] Dec  7 03:59:34.869: INFO: Token eyJ2IjoibWV0YS5rOHMuaW8vdjEiLCJydiI6MjMwODEsInN0YXJ0IjoidGVtcGxhdGUtMDAzOVx1MDAwMCJ9 has not expired yet
I1207 04:01:52.354] Dec  7 03:59:54.869: INFO: Token eyJ2IjoibWV0YS5rOHMuaW8vdjEiLCJydiI6MjMwODEsInN0YXJ0IjoidGVtcGxhdGUtMDAzOVx1MDAwMCJ9 has not expired yet
I1207 04:01:52.360] Dec  7 04:00:14.869: INFO: Token eyJ2IjoibWV0YS5rOHMuaW8vdjEiLCJydiI6MjMwODEsInN0YXJ0IjoidGVtcGxhdGUtMDAzOVx1MDAwMCJ9 has not expired yet
I1207 04:01:52.361] Dec  7 04:00:34.869: INFO: Token eyJ2IjoibWV0YS5rOHMuaW8vdjEiLCJydiI6MjMwODEsInN0YXJ0IjoidGVtcGxhdGUtMDAzOVx1MDAwMCJ9 has not expired yet
I1207 04:01:52.361] Dec  7 04:00:54.869: INFO: Token eyJ2IjoibWV0YS5rOHMuaW8vdjEiLCJydiI6MjMwODEsInN0YXJ0IjoidGVtcGxhdGUtMDAzOVx1MDAwMCJ9 has not expired yet
I1207 04:01:52.361] Dec  7 04:01:14.869: INFO: Token eyJ2IjoibWV0YS5rOHMuaW8vdjEiLCJydiI6MjMwODEsInN0YXJ0IjoidGVtcGxhdGUtMDAzOVx1MDAwMCJ9 has not expired yet
I1207 04:01:52.361] Dec  7 04:01:34.868: INFO: got error The provided continue parameter is too old to display a consistent list result. You can start a new list without the continue parameter, or use the continue token in this response to retrieve the remainder of the results. Continuing with the provided token results in an inconsistent list - objects that were created, modified, or deleted between the time the first chunk was returned and now may show up in the list.
I1207 04:01:52.362] Dec  7 04:01:34.868: INFO: Retrieved inconsistent continue eyJ2IjoibWV0YS5rOHMuaW8vdjEiLCJydiI6LTEsInN0YXJ0IjoidGVtcGxhdGUtMDAzOVx1MDAwMCJ9
I1207 04:01:52.362] STEP: retrieving the second page again with the token received with the error message
I1207 04:01:52.362] STEP: retrieving all remaining pages
I1207 04:01:52.362] Dec  7 04:01:35.337: INFO: Retrieved 40/40 results with rv 31699 and continue eyJ2IjoibWV0YS5rOHMuaW8vdjEiLCJydiI6MzE2OTksInN0YXJ0IjoidGVtcGxhdGUtMDExOVx1MDAwMCJ9
I1207 04:01:52.362] Dec  7 04:01:35.572: INFO: Retrieved 40/40 results with rv 31699 and continue eyJ2IjoibWV0YS5rOHMuaW8vdjEiLCJydiI6MzE2OTksInN0YXJ0IjoidGVtcGxhdGUtMDE1OVx1MDAwMCJ9
I1207 04:01:52.362] Dec  7 04:01:35.807: INFO: Retrieved 40/40 results with rv 31699 and continue eyJ2IjoibWV0YS5rOHMuaW8vdjEiLCJydiI6MzE2OTksInN0YXJ0IjoidGVtcGxhdGUtMDE5OVx1MDAwMCJ9
I1207 04:01:52.363] Dec  7 04:01:36.042: INFO: Retrieved 40/40 results with rv 31699 and continue eyJ2IjoibWV0YS5rOHMuaW8vdjEiLCJydiI6MzE2OTksInN0YXJ0IjoidGVtcGxhdGUtMDIzOVx1MDAwMCJ9
I1207 04:01:52.363] Dec  7 04:01:36.277: INFO: Retrieved 40/40 results with rv 31699 and continue eyJ2IjoibWV0YS5rOHMuaW8vdjEiLCJydiI6MzE2OTksInN0YXJ0IjoidGVtcGxhdGUtMDI3OVx1MDAwMCJ9
... skipping 182 lines ...
I1207 04:01:56.967] [sig-storage] In-tree Volumes
I1207 04:01:56.967] test/e2e/storage/utils/framework.go:22
I1207 04:01:56.968]   [Driver: emptydir]
I1207 04:01:56.968]   test/e2e/storage/in_tree_volumes.go:78
I1207 04:01:56.968]     [Testpattern: Dynamic PV (block volmode)] volumeMode
I1207 04:01:56.968]     test/e2e/storage/testsuites/volumemode.go:109
I1207 04:01:56.968]       should fail in binding dynamic provisioned PV to PVC [BeforeEach]
I1207 04:01:56.968]       test/e2e/storage/testsuites/volumemode.go:315
I1207 04:01:56.968] 
I1207 04:01:56.968]       Dec  7 04:01:41.649: Driver emptydir doesn't support DynamicPV -- skipping
I1207 04:01:56.968] 
I1207 04:01:56.968]       test/e2e/framework/util.go:292
I1207 04:01:56.968] ------------------------------
... skipping 862 lines ...
I1207 04:02:20.152] Dec  7 04:02:20.149: INFO: AfterEach: Cleaning up test resources
I1207 04:02:20.152] 
I1207 04:02:20.152] 
I1207 04:02:20.153] S [SKIPPING] in Spec Setup (BeforeEach) [23.183 seconds]
I1207 04:02:20.153] [sig-storage] PersistentVolumes:vsphere
I1207 04:02:20.153] test/e2e/storage/utils/framework.go:22
I1207 04:02:20.153]   should test that deleting the PV before the pod does not cause pod deletion to fail on vspehre volume detach [BeforeEach]
I1207 04:02:20.153]   test/e2e/storage/vsphere/persistent_volumes-vsphere.go:165
I1207 04:02:20.153] 
I1207 04:02:20.153]   Dec  7 04:02:04.850: Only supported for providers [vsphere] (not aws)
I1207 04:02:20.153] 
I1207 04:02:20.153]   test/e2e/framework/util.go:292
I1207 04:02:20.153] ------------------------------
... skipping 99 lines ...
I1207 04:02:24.273] STEP: locating the "nfs-server" server pod
I1207 04:02:24.274] Dec  7 03:56:26.543: INFO: Running '/go/src/k8s.io/kubernetes/kubernetes/platforms/linux/amd64/kubectl --server=https://api.e2e-a74748c183-dba53.test-cncf-aws.k8s.io --kubeconfig=/tmp/kops060486941/kubeconfig logs nfs-server nfs-server --namespace=e2e-tests-volumes-lrrn4'
I1207 04:02:24.274] Dec  7 03:56:27.835: INFO: stderr: ""
I1207 04:02:24.274] Dec  7 03:56:27.835: INFO: stdout: "Serving /exports\nServing /\nrpcinfo: can't contact rpcbind: : RPC: Unable to receive; errno = Connection refused\nStarting rpcbind\nexportfs: / does not support NFS export\nNFS started\n"
I1207 04:02:24.274] Dec  7 03:56:27.835: INFO: nfs server pod IP address: 100.96.2.198
I1207 04:02:24.274] Dec  7 03:56:27.835: INFO: PV ReclaimPolicy unspecified, default: Retain
I1207 04:02:24.275] [It] should fail to create pod by failing to mount volume
I1207 04:02:24.275]   test/e2e/storage/testsuites/volumemode.go:242
I1207 04:02:24.275] STEP: Creating sc
I1207 04:02:24.275] STEP: Creating pv and pvc
I1207 04:02:24.275] Dec  7 03:56:28.535: INFO: Waiting for PV pvqlnkr to bind to PVC pvc-79pcj
I1207 04:02:24.275] Dec  7 03:56:28.535: INFO: Waiting up to 3m0s for PersistentVolumeClaims [pvc-79pcj] to have phase Bound
I1207 04:02:24.275] Dec  7 03:56:28.767: INFO: PersistentVolumeClaim pvc-79pcj found and phase=Bound (231.797541ms)
... skipping 26 lines ...
I1207 04:02:24.279] [sig-storage] In-tree Volumes
I1207 04:02:24.279] test/e2e/storage/utils/framework.go:22
I1207 04:02:24.279]   [Driver: nfs]
I1207 04:02:24.279]   test/e2e/storage/in_tree_volumes.go:78
I1207 04:02:24.279]     [Testpattern: Pre-provisioned PV (block volmode)] volumeMode
I1207 04:02:24.279]     test/e2e/storage/testsuites/volumemode.go:109
I1207 04:02:24.279]       should fail to create pod by failing to mount volume
I1207 04:02:24.279]       test/e2e/storage/testsuites/volumemode.go:242
I1207 04:02:24.279] ------------------------------
I1207 04:02:27.401] S
I1207 04:02:27.401] ------------------------------
I1207 04:02:27.401] [BeforeEach] [sig-storage] PersistentVolumes-local 
I1207 04:02:27.401]   test/e2e/framework/framework.go:153
... skipping 407 lines ...
I1207 04:02:41.958] Dec  7 04:02:01.103: INFO: PV ReclaimPolicy unspecified, default: Retain
I1207 04:02:41.958] Dec  7 04:02:01.573: INFO: Waiting for PV local-pvxjbdh to bind to PVC pvc-94tsp
I1207 04:02:41.958] Dec  7 04:02:01.573: INFO: Waiting up to 3m0s for PersistentVolumeClaims [pvc-94tsp] to have phase Bound
I1207 04:02:41.958] Dec  7 04:02:01.807: INFO: PersistentVolumeClaim pvc-94tsp found and phase=Bound (234.377512ms)
I1207 04:02:41.958] Dec  7 04:02:01.807: INFO: Waiting up to 3m0s for PersistentVolume local-pvxjbdh to have phase Bound
I1207 04:02:41.959] Dec  7 04:02:02.041: INFO: PersistentVolume local-pvxjbdh found and phase=Bound (233.74008ms)
I1207 04:02:41.959] [It] should fail scheduling due to different NodeAffinity
I1207 04:02:41.959]   test/e2e/storage/persistent_volumes-local.go:424
I1207 04:02:41.959] STEP: local-volume-type: dir
I1207 04:02:41.959] STEP: Initializing test volumes
I1207 04:02:41.959] Dec  7 04:02:02.509: INFO: Running '/go/src/k8s.io/kubernetes/kubernetes/platforms/linux/amd64/kubectl --server=https://api.e2e-a74748c183-dba53.test-cncf-aws.k8s.io --kubeconfig=/tmp/kops060486941/kubeconfig exec --namespace=e2e-tests-persistent-local-volumes-test-9c4fk hostexec-ip-172-20-35-222.ap-south-1.compute.internal -- nsenter --mount=/rootfs/proc/1/ns/mnt -- sh -c mkdir -p /tmp/local-volume-test-da445e3e-f9d4-11e8-961b-0a580a3c585b'
I1207 04:02:41.959] Dec  7 04:02:05.255: INFO: stderr: ""
I1207 04:02:41.959] Dec  7 04:02:05.255: INFO: stdout: ""
... skipping 28 lines ...
I1207 04:02:41.963] 
I1207 04:02:41.963] • [SLOW TEST:54.720 seconds]
I1207 04:02:41.963] [sig-storage] PersistentVolumes-local 
I1207 04:02:41.963] test/e2e/storage/utils/framework.go:22
I1207 04:02:41.963]   Pod with node different from PV's NodeAffinity
I1207 04:02:41.963]   test/e2e/storage/persistent_volumes-local.go:402
I1207 04:02:41.963]     should fail scheduling due to different NodeAffinity
I1207 04:02:41.963]     test/e2e/storage/persistent_volumes-local.go:424
I1207 04:02:41.963] ------------------------------
I1207 04:02:42.570] S
I1207 04:02:42.570] ------------------------------
I1207 04:02:42.570] [BeforeEach] [sig-storage] In-tree Volumes
I1207 04:02:42.570]   test/e2e/framework/framework.go:153
... skipping 39 lines ...