kubernetes-sigs/azuredisk-csi-driver PR #166 pull-azuredisk-csi-driver-e2e #1179880985387339779
Recent runs || View in Spyglass
Recent runs || View in Spyglass
error during make e2e-test: exit status 2
from junit_runner.xml
Filter through log files
Build
Check APIReachability
Deferred TearDown
DumpClusterLogs
IsUp
TearDown
TearDown Previous
Timeout
Up
kubectl version
list nodes
test setup
... skipping 367 lines ... I1003 22:46:23.012] pod/tiller-deploy-54c96cb5df-bkf9j condition met I1003 22:46:23.014] helm version I1003 22:46:23.064] Client: &version.Version{SemVer:"v2.11.0", GitCommit:"2e55dbe1fdb5fdb96b75ff144a339489417b146b", GitTreeState:"clean"} I1003 22:46:23.964] Server: &version.Version{SemVer:"v2.11.0", GitCommit:"2e55dbe1fdb5fdb96b75ff144a339489417b146b", GitTreeState:"clean"} I1003 22:46:23.966] # Only build and push the image if it does not exist in the registry I1003 22:46:23.967] docker pull k8sprowinternal.azurecr.io/azuredisk-csi:e2e-2de810f9504b359da5ad074573d8f3450ba10eeb || make azuredisk-container push I1003 22:46:24.188] Error response from daemon: manifest for k8sprowinternal.azurecr.io/azuredisk-csi:e2e-2de810f9504b359da5ad074573d8f3450ba10eeb not found I1003 22:46:24.192] make[2]: Entering directory '/go/src/github.com/kubernetes-sigs/azuredisk-csi-driver' I1003 22:46:24.199] if [ ! -d ./vendor ]; then dep ensure -vendor-only; fi I1003 22:46:24.200] CGO_ENABLED=0 GOOS=linux go build -a -ldflags "-X github.com/kubernetes-sigs/azuredisk-csi-driver/pkg/azuredisk.driverVersion=e2e-2de810f9504b359da5ad074573d8f3450ba10eeb -X github.com/kubernetes-sigs/azuredisk-csi-driver/pkg/azuredisk.gitCommit=2de810f9504b359da5ad074573d8f3450ba10eeb -X github.com/kubernetes-sigs/azuredisk-csi-driver/pkg/azuredisk.buildDate=2019-10-03T22:46:24Z -X github.com/kubernetes-sigs/azuredisk-csi-driver/pkg/azuredisk.DriverName=disk.csi.azure.com -X github.com/kubernetes-sigs/azuredisk-csi-driver/pkg/azuredisk.topologyKey=topology.disk.csi.azure.com/zone -extldflags "-static"" -o _output/azurediskplugin ./pkg/azurediskplugin I1003 22:47:01.011] docker build --no-cache -t k8sprowinternal.azurecr.io/azuredisk-csi:e2e-2de810f9504b359da5ad074573d8f3450ba10eeb -f ./pkg/azurediskplugin/Dockerfile . I1003 22:47:02.863] Sending build context to Docker daemon 187.1MB I1003 22:47:02.887] Step 1/6 : FROM debian:9 ... skipping 308 lines ... I1003 22:49:15.186] Oct 3 22:49:15.186: INFO: PersistentVolumeClaim pvc-qrk88 found but phase is Pending instead of Bound. I1003 22:49:17.257] Oct 3 22:49:17.257: INFO: PersistentVolumeClaim pvc-qrk88 found and phase=Bound (10.42526729s) I1003 22:49:17.257] STEP: checking the PVC I1003 22:49:17.328] STEP: validating provisioned PV I1003 22:49:17.399] STEP: checking the PV I1003 22:49:17.400] STEP: deploying the pod I1003 22:49:17.472] STEP: checking that the pods command exits with no error I1003 22:49:17.472] Oct 3 22:49:17.472: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-7nznt" in namespace "azuredisk-9306" to be "success or failure" I1003 22:49:17.543] Oct 3 22:49:17.543: INFO: Pod "azuredisk-volume-tester-7nznt": Phase="Pending", Reason="", readiness=false. Elapsed: 70.798945ms I1003 22:49:19.614] Oct 3 22:49:19.614: INFO: Pod "azuredisk-volume-tester-7nznt": Phase="Pending", Reason="", readiness=false. Elapsed: 2.142039197s I1003 22:49:21.692] Oct 3 22:49:21.692: INFO: Pod "azuredisk-volume-tester-7nznt": Phase="Pending", Reason="", readiness=false. Elapsed: 4.220259502s I1003 22:49:23.764] Oct 3 22:49:23.763: INFO: Pod "azuredisk-volume-tester-7nznt": Phase="Pending", Reason="", readiness=false. Elapsed: 6.291565054s I1003 22:49:25.838] Oct 3 22:49:25.838: INFO: Pod "azuredisk-volume-tester-7nznt": Phase="Pending", Reason="", readiness=false. Elapsed: 8.365843928s ... skipping 92 lines ... I1003 22:51:42.233] Oct 3 22:51:42.233: INFO: PersistentVolumeClaim pvc-9tnd9 found but phase is Pending instead of Bound. I1003 22:51:44.304] Oct 3 22:51:44.304: INFO: PersistentVolumeClaim pvc-9tnd9 found and phase=Bound (10.426375387s) I1003 22:51:44.304] STEP: checking the PVC I1003 22:51:44.375] STEP: validating provisioned PV I1003 22:51:44.446] STEP: checking the PV I1003 22:51:44.446] STEP: deploying the pod I1003 22:51:44.518] STEP: checking that the pods command exits with no error I1003 22:51:44.519] Oct 3 22:51:44.518: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-2p97f" in namespace "azuredisk-6910" to be "success or failure" I1003 22:51:44.589] Oct 3 22:51:44.589: INFO: Pod "azuredisk-volume-tester-2p97f": Phase="Pending", Reason="", readiness=false. Elapsed: 70.624243ms I1003 22:51:46.661] Oct 3 22:51:46.661: INFO: Pod "azuredisk-volume-tester-2p97f": Phase="Pending", Reason="", readiness=false. Elapsed: 2.142365497s I1003 22:51:48.733] Oct 3 22:51:48.733: INFO: Pod "azuredisk-volume-tester-2p97f": Phase="Pending", Reason="", readiness=false. Elapsed: 4.214462352s I1003 22:51:50.805] Oct 3 22:51:50.804: INFO: Pod "azuredisk-volume-tester-2p97f": Phase="Pending", Reason="", readiness=false. Elapsed: 6.286064104s I1003 22:51:52.876] Oct 3 22:51:52.876: INFO: Pod "azuredisk-volume-tester-2p97f": Phase="Pending", Reason="", readiness=false. Elapsed: 8.357580956s ... skipping 172 lines ... I1003 23:01:08.946] Oct 3 23:01:08.945: INFO: Waiting up to 5m0s for PersistentVolumeClaims [pvc-9tmsd] to have phase Bound I1003 23:01:09.018] Oct 3 23:01:09.018: INFO: PersistentVolumeClaim pvc-9tmsd found and phase=Bound (72.101166ms) I1003 23:01:09.018] STEP: checking the PVC I1003 23:01:09.090] STEP: validating provisioned PV I1003 23:01:09.161] STEP: checking the PV I1003 23:01:09.161] STEP: deploying the pod I1003 23:01:09.232] STEP: checking that the pods command exits with an error I1003 23:01:09.233] Oct 3 23:01:09.232: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-x585l" in namespace "azuredisk-1268" to be "Error status code" I1003 23:01:09.304] Oct 3 23:01:09.304: INFO: Pod "azuredisk-volume-tester-x585l": Phase="Pending", Reason="", readiness=false. Elapsed: 71.664063ms I1003 23:01:11.376] Oct 3 23:01:11.376: INFO: Pod "azuredisk-volume-tester-x585l": Phase="Pending", Reason="", readiness=false. Elapsed: 2.143315635s I1003 23:01:13.448] Oct 3 23:01:13.448: INFO: Pod "azuredisk-volume-tester-x585l": Phase="Pending", Reason="", readiness=false. Elapsed: 4.215892214s I1003 23:01:15.521] Oct 3 23:01:15.521: INFO: Pod "azuredisk-volume-tester-x585l": Phase="Pending", Reason="", readiness=false. Elapsed: 6.288583492s I1003 23:01:17.594] Oct 3 23:01:17.593: INFO: Pod "azuredisk-volume-tester-x585l": Phase="Pending", Reason="", readiness=false. Elapsed: 8.360975368s I1003 23:01:19.665] Oct 3 23:01:19.665: INFO: Pod "azuredisk-volume-tester-x585l": Phase="Pending", Reason="", readiness=false. Elapsed: 10.432991439s ... skipping 23 lines ... I1003 23:02:09.408] Oct 3 23:02:09.408: INFO: Pod "azuredisk-volume-tester-x585l": Phase="Pending", Reason="", readiness=false. Elapsed: 1m0.175612968s I1003 23:02:11.483] Oct 3 23:02:11.483: INFO: Pod "azuredisk-volume-tester-x585l": Phase="Pending", Reason="", readiness=false. Elapsed: 1m2.250441637s I1003 23:02:13.555] Oct 3 23:02:13.555: INFO: Pod "azuredisk-volume-tester-x585l": Phase="Pending", Reason="", readiness=false. Elapsed: 1m4.322942788s I1003 23:02:15.627] Oct 3 23:02:15.627: INFO: Pod "azuredisk-volume-tester-x585l": Phase="Pending", Reason="", readiness=false. Elapsed: 1m6.394946433s I1003 23:02:17.700] Oct 3 23:02:17.699: INFO: Pod "azuredisk-volume-tester-x585l": Phase="Pending", Reason="", readiness=false. Elapsed: 1m8.467103379s I1003 23:02:19.772] Oct 3 23:02:19.772: INFO: Pod "azuredisk-volume-tester-x585l": Phase="Pending", Reason="", readiness=false. Elapsed: 1m10.539345424s I1003 23:02:21.844] Oct 3 23:02:21.844: INFO: Pod "azuredisk-volume-tester-x585l": Phase="Failed", Reason="", readiness=false. Elapsed: 1m12.611898571s I1003 23:02:21.845] STEP: Saw pod failure I1003 23:02:21.845] Oct 3 23:02:21.844: INFO: Pod "azuredisk-volume-tester-x585l" satisfied condition "Error status code" I1003 23:02:21.845] STEP: checking that pod logs contain expected message I1003 23:02:21.922] Oct 3 23:02:21.922: INFO: deleting Pod "azuredisk-1268"/"azuredisk-volume-tester-x585l" I1003 23:02:21.998] Oct 3 23:02:21.997: INFO: Pod azuredisk-volume-tester-x585l has the following logs: /bin/sh: can't create /mnt/test-1/data: Read-only file system I1003 23:02:21.998] I1003 23:02:21.998] STEP: Deleting pod azuredisk-volume-tester-x585l in namespace azuredisk-1268 I1003 23:02:22.075] Oct 3 23:02:22.075: INFO: deleting PVC "azuredisk-1268"/"pvc-9tmsd" ... skipping 160 lines ... I1003 23:08:26.645] Oct 3 23:08:26.645: INFO: PersistentVolumeClaim pvc-gsdlj found but phase is Pending instead of Bound. I1003 23:08:28.717] Oct 3 23:08:28.717: INFO: PersistentVolumeClaim pvc-gsdlj found and phase=Bound (10.43136621s) I1003 23:08:28.717] STEP: checking the PVC I1003 23:08:28.788] STEP: validating provisioned PV I1003 23:08:28.859] STEP: checking the PV I1003 23:08:28.860] STEP: deploying the pod I1003 23:08:28.932] STEP: checking that the pods command exits with an error I1003 23:08:28.932] Oct 3 23:08:28.932: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-qs8q8" in namespace "azuredisk-2283" to be "Error status code" I1003 23:08:29.004] Oct 3 23:08:29.004: INFO: Pod "azuredisk-volume-tester-qs8q8": Phase="Pending", Reason="", readiness=false. Elapsed: 72.041861ms I1003 23:08:31.076] Oct 3 23:08:31.076: INFO: Pod "azuredisk-volume-tester-qs8q8": Phase="Pending", Reason="", readiness=false. Elapsed: 2.144237292s I1003 23:08:33.149] Oct 3 23:08:33.149: INFO: Pod "azuredisk-volume-tester-qs8q8": Phase="Pending", Reason="", readiness=false. Elapsed: 4.216793526s I1003 23:08:35.221] Oct 3 23:08:35.221: INFO: Pod "azuredisk-volume-tester-qs8q8": Phase="Pending", Reason="", readiness=false. Elapsed: 6.289136557s I1003 23:08:37.294] Oct 3 23:08:37.293: INFO: Pod "azuredisk-volume-tester-qs8q8": Phase="Pending", Reason="", readiness=false. Elapsed: 8.361362487s I1003 23:08:39.366] Oct 3 23:08:39.366: INFO: Pod "azuredisk-volume-tester-qs8q8": Phase="Pending", Reason="", readiness=false. Elapsed: 10.433619217s ... skipping 24 lines ... I1003 23:09:31.182] Oct 3 23:09:31.182: INFO: Pod "azuredisk-volume-tester-qs8q8": Phase="Pending", Reason="", readiness=false. Elapsed: 1m2.250123109s I1003 23:09:33.254] Oct 3 23:09:33.254: INFO: Pod "azuredisk-volume-tester-qs8q8": Phase="Pending", Reason="", readiness=false. Elapsed: 1m4.322122227s I1003 23:09:35.326] Oct 3 23:09:35.326: INFO: Pod "azuredisk-volume-tester-qs8q8": Phase="Pending", Reason="", readiness=false. Elapsed: 1m6.39367294s I1003 23:09:37.397] Oct 3 23:09:37.397: INFO: Pod "azuredisk-volume-tester-qs8q8": Phase="Pending", Reason="", readiness=false. Elapsed: 1m8.464947451s I1003 23:09:39.468] Oct 3 23:09:39.468: INFO: Pod "azuredisk-volume-tester-qs8q8": Phase="Pending", Reason="", readiness=false. Elapsed: 1m10.536117561s I1003 23:09:41.540] Oct 3 23:09:41.540: INFO: Pod "azuredisk-volume-tester-qs8q8": Phase="Pending", Reason="", readiness=false. Elapsed: 1m12.607855075s I1003 23:09:43.612] Oct 3 23:09:43.611: INFO: Pod "azuredisk-volume-tester-qs8q8": Phase="Failed", Reason="", readiness=false. Elapsed: 1m14.679437987s I1003 23:09:43.612] STEP: Saw pod failure I1003 23:09:43.612] Oct 3 23:09:43.611: INFO: Pod "azuredisk-volume-tester-qs8q8" satisfied condition "Error status code" I1003 23:09:43.613] STEP: checking that pod logs contain expected message I1003 23:09:43.688] Oct 3 23:09:43.687: INFO: deleting Pod "azuredisk-2283"/"azuredisk-volume-tester-qs8q8" I1003 23:09:43.765] Oct 3 23:09:43.765: INFO: Pod azuredisk-volume-tester-qs8q8 has the following logs: touch: /mnt/test-1/data: Read-only file system I1003 23:09:43.765] I1003 23:09:43.766] STEP: Deleting pod azuredisk-volume-tester-qs8q8 in namespace azuredisk-2283 I1003 23:09:43.850] Oct 3 23:09:43.849: INFO: deleting PVC "azuredisk-2283"/"pvc-gsdlj" ... skipping 410 lines ... I1003 23:16:18.163] I1003 23:16:18.163] goroutine 2218 [select]: I1003 23:16:18.163] github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/k8s.io/apimachinery/pkg/util/wait.poller.func1.1(0xc0007b24e0, 0x77359400, 0x8bb2c97000, 0xc0007b2480) I1003 23:16:18.163] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/k8s.io/apimachinery/pkg/util/wait/wait.go:473 +0x153 I1003 23:16:18.163] created by github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/k8s.io/apimachinery/pkg/util/wait.poller.func1 I1003 23:16:18.163] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/k8s.io/apimachinery/pkg/util/wait/wait.go:456 +0x8c I1003 23:16:18.164] FAIL github.com/kubernetes-sigs/azuredisk-csi-driver/test/e2e 1800.054s I1003 23:16:18.319] Makefile:59: recipe for target 'e2e-test' failed W1003 23:16:18.419] make: *** [e2e-test] Error 1 W1003 23:16:18.419] 2019/10/03 23:16:18 process.go:155: Step 'make e2e-test' finished in 30m50.89614331s W1003 23:16:18.420] 2019/10/03 23:16:18 azure.go:901: Deleting resource group: kubetest-ca77b650-e62a-11e9-8665-024276f6d56b. W1003 23:26:12.624] 2019/10/03 23:26:12 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml. W1003 23:26:12.624] 2019/10/03 23:26:12 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}" W1003 23:26:13.396] 2019/10/03 23:26:13 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 772.347578ms W1003 23:26:13.397] 2019/10/03 23:26:13 main.go:319: Something went wrong: encountered 1 errors: [error during make e2e-test: exit status 2] W1003 23:26:13.400] Traceback (most recent call last): W1003 23:26:13.400] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in <module> W1003 23:26:13.401] main(parse_args()) W1003 23:26:13.401] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main W1003 23:26:13.401] mode.start(runner_args) W1003 23:26:13.402] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start W1003 23:26:13.402] check_env(env, self.command, *args) W1003 23:26:13.402] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env W1003 23:26:13.402] subprocess.check_call(cmd, env=env) W1003 23:26:13.402] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W1003 23:26:13.403] raise CalledProcessError(retcode, cmd) W1003 23:26:13.403] 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-disk-csi-driver=True')' returned non-zero exit status 1 E1003 23:26:13.408] Command failed I1003 23:26:13.409] process 690 exited with code 1 after 74.4m E1003 23:26:13.409] FAIL: pull-azuredisk-csi-driver-e2e I1003 23:26:13.409] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1003 23:26:21.513] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1003 23:26:21.561] process 27771 exited with code 0 after 0.1m I1003 23:26:21.561] Call: gcloud config get-value account I1003 23:26:22.419] process 27783 exited with code 0 after 0.0m I1003 23:26:22.420] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1003 23:26:22.420] Upload result and artifacts... I1003 23:26:22.420] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179880985387339779 I1003 23:26:22.421] Call: gsutil ls gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179880985387339779/artifacts W1003 23:26:23.929] CommandException: One or more URLs matched no objects. E1003 23:26:24.005] Command failed I1003 23:26:24.006] process 27795 exited with code 1 after 0.0m W1003 23:26:24.006] Remote dir gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179880985387339779/artifacts not exist yet I1003 23:26:24.006] Call: gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179880985387339779/artifacts I1003 23:26:25.541] process 27937 exited with code 0 after 0.0m I1003 23:26:25.541] Call: git rev-parse HEAD I1003 23:26:25.544] process 28464 exited with code 0 after 0.0m ... skipping 21 lines ...