sigs.k8s.io/azurefile-csi-driver PR #120 pull-azurefile-csi-driver-e2e #1182483760709373952
Recent runs || View in Spyglass
Recent runs || View in Spyglass
error during ./cluster/log-dump/log-dump.sh /workspace/_artifacts gs://kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1182483760709373952/artifacts: exit status 1
from junit_runner.xml
Filter through log files
Build
Check APIReachability
Deferred TearDown
IsUp
TearDown
TearDown Previous
Test
Timeout
Up
kubectl version
list nodes
test setup
... skipping 651 lines ... I1011 03:07:30.084] pod/tiller-deploy-54c96cb5df-ls57l condition met I1011 03:07:30.087] helm version I1011 03:07:30.143] Client: &version.Version{SemVer:"v2.11.0", GitCommit:"2e55dbe1fdb5fdb96b75ff144a339489417b146b", GitTreeState:"clean"} I1011 03:07:33.079] Server: &version.Version{SemVer:"v2.11.0", GitCommit:"2e55dbe1fdb5fdb96b75ff144a339489417b146b", GitTreeState:"clean"} I1011 03:07:33.080] # Only build and push the image if it does not exist in the registry I1011 03:07:33.081] docker pull k8sprowinternal.azurecr.io/azurefile-csi:e2e-78fd0851bcc9d9407385c5678d72c3b7e73d2222 || make azurefile-container push I1011 03:07:33.252] Error response from daemon: manifest for k8sprowinternal.azurecr.io/azurefile-csi:e2e-78fd0851bcc9d9407385c5678d72c3b7e73d2222 not found I1011 03:07:33.257] make[2]: Entering directory '/go/src/sigs.k8s.io/azurefile-csi-driver' I1011 03:07:33.257] CGO_ENABLED=0 GOOS=linux go build -a -ldflags "-X github.com/kubernetes-sigs/azurefile-csi-driver/pkg/azurefile.driverVersion=e2e-78fd0851bcc9d9407385c5678d72c3b7e73d2222 -X github.com/kubernetes-sigs/azurefile-csi-driver/pkg/azurefile.gitCommit=78fd0851bcc9d9407385c5678d72c3b7e73d2222 -X github.com/kubernetes-sigs/azurefile-csi-driver/pkg/azurefile.buildDate=2019-10-11T03:05:40Z -s -w -extldflags '-static'" -o _output/azurefileplugin ./pkg/azurefileplugin I1011 03:08:04.597] docker build --no-cache -t k8sprowinternal.azurecr.io/azurefile-csi:e2e-78fd0851bcc9d9407385c5678d72c3b7e73d2222 -f ./pkg/azurefileplugin/Dockerfile . I1011 03:08:06.737] Sending build context to Docker daemon 155.7MB I1011 03:08:06.778] Step 1/6 : FROM debian:9 I1011 03:08:07.747] 9: Pulling from library/debian ... skipping 566 lines ... I1011 03:11:17.842] Oct 11 03:11:17.841: INFO: PersistentVolumeClaim pvc-k8cpg found but phase is Pending instead of Bound. I1011 03:11:19.913] Oct 11 03:11:19.912: INFO: PersistentVolumeClaim pvc-k8cpg found and phase=Bound (37.487250993s) I1011 03:11:19.913] STEP: checking the PVC I1011 03:11:19.983] STEP: validating provisioned PV I1011 03:11:20.055] STEP: checking the PV I1011 03:11:20.055] STEP: deploying the pod I1011 03:11:20.194] STEP: checking that the pods command exits with an error I1011 03:11:20.195] Oct 11 03:11:20.194: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-vn4pz" in namespace "azurefile-2430" to be "Error status code" I1011 03:11:20.265] Oct 11 03:11:20.265: INFO: Pod "azurefile-volume-tester-vn4pz": Phase="Pending", Reason="", readiness=false. Elapsed: 70.242646ms I1011 03:11:22.336] Oct 11 03:11:22.336: INFO: Pod "azurefile-volume-tester-vn4pz": Phase="Pending", Reason="", readiness=false. Elapsed: 2.141329239s I1011 03:11:24.407] Oct 11 03:11:24.407: INFO: Pod "azurefile-volume-tester-vn4pz": Phase="Pending", Reason="", readiness=false. Elapsed: 4.212836936s I1011 03:11:26.479] Oct 11 03:11:26.478: INFO: Pod "azurefile-volume-tester-vn4pz": Phase="Failed", Reason="", readiness=false. Elapsed: 6.28413523s I1011 03:11:26.479] STEP: Saw pod failure I1011 03:11:26.479] Oct 11 03:11:26.478: INFO: Pod "azurefile-volume-tester-vn4pz" satisfied condition "Error status code" I1011 03:11:26.480] STEP: checking that pod logs contain expected message I1011 03:11:26.578] Oct 11 03:11:26.578: INFO: deleting Pod "azurefile-2430"/"azurefile-volume-tester-vn4pz" I1011 03:11:26.653] Oct 11 03:11:26.653: INFO: Pod azurefile-volume-tester-vn4pz has the following logs: touch: /mnt/test-1/data: Read-only file system I1011 03:11:26.653] I1011 03:11:26.653] STEP: Deleting pod azurefile-volume-tester-vn4pz in namespace azurefile-2430 I1011 03:11:26.729] Oct 11 03:11:26.729: INFO: deleting PVC "azurefile-2430"/"pvc-k8cpg" ... skipping 137 lines ... I1011 03:12:12.311] Oct 11 03:12:12.311: INFO: PersistentVolumeClaim pvc-fzzf5 found but phase is Pending instead of Bound. I1011 03:12:14.383] Oct 11 03:12:14.383: INFO: PersistentVolumeClaim pvc-fzzf5 found and phase=Bound (2.143982747s) I1011 03:12:14.383] STEP: checking the PVC I1011 03:12:14.454] STEP: validating provisioned PV I1011 03:12:14.525] STEP: checking the PV I1011 03:12:14.525] STEP: deploying the pod I1011 03:12:14.598] STEP: checking that the pods command exits with no error I1011 03:12:14.598] Oct 11 03:12:14.598: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-prhhx" in namespace "azurefile-3561" to be "success or failure" I1011 03:12:14.669] Oct 11 03:12:14.669: INFO: Pod "azurefile-volume-tester-prhhx": Phase="Pending", Reason="", readiness=false. Elapsed: 71.010851ms I1011 03:12:16.741] Oct 11 03:12:16.741: INFO: Pod "azurefile-volume-tester-prhhx": Phase="Pending", Reason="", readiness=false. Elapsed: 2.14314034s I1011 03:12:18.813] Oct 11 03:12:18.813: INFO: Pod "azurefile-volume-tester-prhhx": Phase="Pending", Reason="", readiness=false. Elapsed: 4.215235628s I1011 03:12:20.886] Oct 11 03:12:20.885: INFO: Pod "azurefile-volume-tester-prhhx": Phase="Pending", Reason="", readiness=false. Elapsed: 6.287687918s I1011 03:12:22.958] Oct 11 03:12:22.958: INFO: Pod "azurefile-volume-tester-prhhx": Phase="Pending", Reason="", readiness=false. Elapsed: 8.360108207s ... skipping 131 lines ... I1011 03:15:09.867] Oct 11 03:15:09.866: INFO: Waiting up to 5m0s for PersistentVolumeClaims [pvc-6px5s] to have phase Bound I1011 03:15:09.940] Oct 11 03:15:09.940: INFO: PersistentVolumeClaim pvc-6px5s found and phase=Bound (73.561969ms) I1011 03:15:09.940] STEP: checking the PVC I1011 03:15:10.011] STEP: validating provisioned PV I1011 03:15:10.082] STEP: checking the PV I1011 03:15:10.083] STEP: deploying the pod I1011 03:15:10.155] STEP: checking that the pods command exits with an error I1011 03:15:10.155] Oct 11 03:15:10.155: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-mlxch" in namespace "azurefile-8899" to be "Error status code" I1011 03:15:10.226] Oct 11 03:15:10.226: INFO: Pod "azurefile-volume-tester-mlxch": Phase="Pending", Reason="", readiness=false. Elapsed: 71.272453ms I1011 03:15:12.298] Oct 11 03:15:12.298: INFO: Pod "azurefile-volume-tester-mlxch": Phase="Pending", Reason="", readiness=false. Elapsed: 2.143092505s I1011 03:15:14.370] Oct 11 03:15:14.370: INFO: Pod "azurefile-volume-tester-mlxch": Phase="Pending", Reason="", readiness=false. Elapsed: 4.215056358s I1011 03:15:16.442] Oct 11 03:15:16.442: INFO: Pod "azurefile-volume-tester-mlxch": Phase="Pending", Reason="", readiness=false. Elapsed: 6.287356213s I1011 03:15:18.514] Oct 11 03:15:18.514: INFO: Pod "azurefile-volume-tester-mlxch": Phase="Pending", Reason="", readiness=false. Elapsed: 8.359228765s I1011 03:15:20.586] Oct 11 03:15:20.586: INFO: Pod "azurefile-volume-tester-mlxch": Phase="Failed", Reason="", readiness=false. Elapsed: 10.431461019s I1011 03:15:20.587] STEP: Saw pod failure I1011 03:15:20.587] Oct 11 03:15:20.586: INFO: Pod "azurefile-volume-tester-mlxch" satisfied condition "Error status code" I1011 03:15:20.587] STEP: checking that pod logs contain expected message I1011 03:15:20.662] Oct 11 03:15:20.662: INFO: deleting Pod "azurefile-8899"/"azurefile-volume-tester-mlxch" I1011 03:15:20.745] Oct 11 03:15:20.744: INFO: Pod azurefile-volume-tester-mlxch has the following logs: /bin/sh: can't create /mnt/test-1/data: Read-only file system I1011 03:15:20.745] I1011 03:15:20.745] STEP: Deleting pod azurefile-volume-tester-mlxch in namespace azurefile-8899 I1011 03:15:20.821] Oct 11 03:15:20.821: INFO: deleting PVC "azurefile-8899"/"pvc-6px5s" ... skipping 166 lines ... I1011 03:17:44.954] helm delete --purge azurefile-csi-driver I1011 03:17:49.543] release "azurefile-csi-driver" deleted I1011 03:17:49.545] make[1]: Leaving directory '/go/src/sigs.k8s.io/azurefile-csi-driver' I1011 03:17:49.545] 2019/10/11 03:17:49 Azure File CSI Driver uninstalled I1011 03:17:49.545] I1011 03:17:49.545] Ran 8 of 8 Specs in 625.960 seconds I1011 03:17:49.545] SUCCESS! -- 8 Passed | 0 Failed | 0 Pending | 0 Skipped I1011 03:17:49.546] --- PASS: TestE2E (625.96s) I1011 03:17:49.546] PASS I1011 03:17:49.548] ok github.com/kubernetes-sigs/azurefile-csi-driver/test/e2e 626.012s W1011 03:17:49.737] 2019/10/11 03:17:49 process.go:155: Step 'make e2e-test' finished in 12m8.83381956s W1011 03:17:49.738] 2019/10/11 03:17:49 e2e.go:519: Dumping logs from nodes to GCS directly at path: gs://kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1182483760709373952/artifacts W1011 03:17:49.738] 2019/10/11 03:17:49 process.go:153: Running: ./cluster/log-dump/log-dump.sh /workspace/_artifacts gs://kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1182483760709373952/artifacts ... skipping 7 lines ... I1011 03:17:49.849] Detecting project I1011 03:17:49.849] Skeleton Provider: detect-project not implemented I1011 03:17:49.849] Dumping logs from master locally to '/workspace/_artifacts' W1011 03:27:43.407] 2019/10/11 03:27:43 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml. W1011 03:27:43.408] 2019/10/11 03:27:43 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}" W1011 03:27:44.093] 2019/10/11 03:27:44 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 685.366603ms W1011 03:27:44.093] 2019/10/11 03:27:44 main.go:319: Something went wrong: encountered 1 errors: [error during ./cluster/log-dump/log-dump.sh /workspace/_artifacts gs://kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1182483760709373952/artifacts: exit status 1] W1011 03:27:44.095] Traceback (most recent call last): W1011 03:27:44.095] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in <module> W1011 03:27:44.095] main(parse_args()) W1011 03:27:44.095] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main W1011 03:27:44.095] mode.start(runner_args) W1011 03:27:44.096] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start W1011 03:27:44.096] check_env(env, self.command, *args) W1011 03:27:44.096] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env W1011 03:27:44.096] subprocess.check_call(cmd, env=env) W1011 03:27:44.096] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W1011 03:27:44.096] raise CalledProcessError(retcode, cmd) W1011 03:27:44.097] subprocess.CalledProcessError: Command '('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--build=bazel', '--up', '--down', '--test', '--deployment=acsengine', '--provider=skeleton', '--cluster=bootstrap-e2e', '--gcp-network=bootstrap-e2e', '--ginkgo-parallel=1', '--acsengine-agentpoolcount=2', '--acsengine-admin-username=azureuser', '--acsengine-creds=/etc/azure-cred/credentials', '--acsengine-orchestratorRelease=1.16', '--acsengine-mastervmsize=Standard_DS2_v2', '--acsengine-agentvmsize=Standard_D4s_v3', '--acsengine-location=eastus2', '--acsengine-public-key=/etc/azure-ssh/azure-ssh-pub', '--acsengine-template-url=https://raw.githubusercontent.com/kubernetes/cloud-provider-azure/master/tests/k8s-azure/manifest/linux.json', '--acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.39.1/aks-engine-v0.39.1-linux-amd64.tar.gz', '--test-azure-file-csi-driver=True', '--timeout=420m', '--logexporter-gcs-path=gs://kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1182483760709373952/artifacts')' returned non-zero exit status 1 E1011 03:27:44.102] Command failed I1011 03:27:44.102] process 689 exited with code 1 after 55.2m E1011 03:27:44.102] FAIL: pull-azurefile-csi-driver-e2e I1011 03:27:44.103] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1011 03:27:44.854] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1011 03:27:44.886] process 32658 exited with code 0 after 0.0m I1011 03:27:44.886] Call: gcloud config get-value account I1011 03:27:45.131] process 32670 exited with code 0 after 0.0m I1011 03:27:45.131] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1011 03:27:45.131] Upload result and artifacts... I1011 03:27:45.131] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1182483760709373952 I1011 03:27:45.132] Call: gsutil ls gs://kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1182483760709373952/artifacts W1011 03:27:46.319] CommandException: One or more URLs matched no objects. E1011 03:27:46.397] Command failed I1011 03:27:46.397] process 32682 exited with code 1 after 0.0m W1011 03:27:46.397] Remote dir gs://kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1182483760709373952/artifacts not exist yet I1011 03:27:46.397] Call: gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1182483760709373952/artifacts I1011 03:27:47.928] process 356 exited with code 0 after 0.0m I1011 03:27:47.928] Call: git rev-parse HEAD I1011 03:27:47.931] process 901 exited with code 0 after 0.0m ... skipping 21 lines ...