W0927 18:24:25.390] ************************************************************************** bootstrap.py is deprecated! test-infra oncall does not support any job still using bootstrap.py. Please migrate your job to podutils! https://github.com/kubernetes/test-infra/blob/master/prow/pod-utilities.md ************************************************************************** I0927 18:24:25.390] Args: --job=pull-azurefile-csi-driver-e2e --service-account=/etc/service-account/service-account.json --upload=gs://kubernetes-jenkins/logs --job=pull-azurefile-csi-driver-e2e --root=/go/src --service-account=/etc/service-account/service-account.json --repo=k8s.io/kubernetes --repo=github.com/kubernetes-sigs/azurefile-csi-driver=master:daea67167aecc01a8bfa2b24f33c4a08998cdc74,120:6666f757a86806b2431f777db7a014780d880a47 --upload=gs://kubernetes-upstream/pr-logs --scenario=kubernetes_e2e -- --gce-ssh= --test=true --up=true --down=true --deployment=acsengine --build=bazel --provider=skeleton --ginkgo-parallel=1 --acsengine-agentpoolcount=2 --acsengine-admin-username=azureuser '--acsengine-creds=$AZURE_CREDENTIALS' --acsengine-orchestratorRelease=1.16 --acsengine-mastervmsize=Standard_DS2_v2 --acsengine-agentvmsize=Standard_D4s_v3 --acsengine-location=eastus2 '--acsengine-public-key=$AZURE_SSH_PUBLIC_KEY_FILE' --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 I0927 18:24:25.391] Bootstrap pull-azurefile-csi-driver-e2e... I0927 18:24:25.394] Builder: f34ad81d-e153-11e9-918b-16773c0c7aed I0927 18:24:25.394] Image: chewong/kubekins-e2e:v20190927-1d9518f-master I0927 18:24:25.394] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177650160998551552 I0927 18:24:25.394] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0927 18:24:25.898] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I0927 18:24:25.926] process 217 exited with code 0 after 0.0m I0927 18:24:25.926] Call: gcloud config get-value account I0927 18:24:26.163] process 229 exited with code 0 after 0.0m I0927 18:24:26.163] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I0927 18:24:26.163] Root: /go/src I0927 18:24:26.164] cd to /go/src I0927 18:24:26.164] Checkout: /go/src/k8s.io/kubernetes master to /go/src/k8s.io/kubernetes I0927 18:24:26.164] Call: git init k8s.io/kubernetes I0927 18:24:26.168] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/ I0927 18:24:26.169] process 241 exited with code 0 after 0.0m I0927 18:24:26.169] Call: git config --local user.name 'K8S Bootstrap' I0927 18:24:26.171] process 242 exited with code 0 after 0.0m I0927 18:24:26.171] Call: git config --local user.email k8s_bootstrap@localhost I0927 18:24:26.174] process 243 exited with code 0 after 0.0m I0927 18:24:26.174] Call: git fetch --quiet --tags https://github.com/kubernetes/kubernetes master I0927 18:25:15.485] process 244 exited with code 0 after 0.8m I0927 18:25:15.486] Call: git checkout -B test FETCH_HEAD W0927 18:25:17.680] Switched to a new branch 'test' I0927 18:25:17.688] process 256 exited with code 0 after 0.0m I0927 18:25:17.689] Call: git show -s --format=format:%ct HEAD I0927 18:25:17.693] process 257 exited with code 0 after 0.0m I0927 18:25:17.694] Checkout: /go/src/github.com/kubernetes-sigs/azurefile-csi-driver master:daea67167aecc01a8bfa2b24f33c4a08998cdc74,120:6666f757a86806b2431f777db7a014780d880a47 to /go/src/github.com/kubernetes-sigs/azurefile-csi-driver I0927 18:25:17.694] Call: git init github.com/kubernetes-sigs/azurefile-csi-driver I0927 18:25:17.698] Initialized empty Git repository in /go/src/github.com/kubernetes-sigs/azurefile-csi-driver/.git/ I0927 18:25:17.698] process 258 exited with code 0 after 0.0m I0927 18:25:17.698] Call: git config --local user.name 'K8S Bootstrap' I0927 18:25:17.701] process 259 exited with code 0 after 0.0m I0927 18:25:17.701] Call: git config --local user.email k8s_bootstrap@localhost I0927 18:25:17.703] process 260 exited with code 0 after 0.0m I0927 18:25:17.703] Call: git fetch --quiet --tags https://github.com/kubernetes-sigs/azurefile-csi-driver master +refs/pull/120/head:refs/pr/120 I0927 18:25:21.900] process 261 exited with code 0 after 0.1m I0927 18:25:21.900] Call: git checkout -B test daea67167aecc01a8bfa2b24f33c4a08998cdc74 W0927 18:25:22.375] Switched to a new branch 'test' I0927 18:25:22.378] process 273 exited with code 0 after 0.0m I0927 18:25:22.378] Call: git show -s --format=format:%ct HEAD I0927 18:25:22.388] process 274 exited with code 0 after 0.0m I0927 18:25:22.388] Call: git merge --no-ff -m 'Merge +refs/pull/120/head:refs/pr/120' 6666f757a86806b2431f777db7a014780d880a47 I0927 18:25:22.782] Auto-merging test/utils/credentials/credentials_test.go I0927 18:25:22.783] Auto-merging test/utils/credentials/credentials.go I0927 18:25:22.783] Removing test/e2e/run-test.sh I0927 18:25:22.783] Removing hack/template/azure.json I0927 18:25:22.783] Removing .travis.yml I0927 18:25:22.783] Merge made by the 'recursive' strategy. I0927 18:25:22.786] .travis.yml | 30 -------- I0927 18:25:22.786] Makefile | 60 +++++++++++----- I0927 18:25:22.786] hack/template/azure.json | 9 --- I0927 18:25:22.787] hack/verify-golint.sh | 7 +- I0927 18:25:22.787] test/e2e/README.md | 24 +++++-- I0927 18:25:22.787] test/e2e/dynamic_provisioning.go | 17 ----- I0927 18:25:22.787] test/e2e/pre_provisioning.go | 23 +------ I0927 18:25:22.787] test/e2e/run-test.sh | 31 --------- I0927 18:25:22.787] test/e2e/suite_test.go | 87 ++++++++++++++++++++++++ I0927 18:25:22.787] test/integration/integration_test.go | 20 +++++- I0927 18:25:22.787] test/integration/run-tests-all-clouds.sh | 4 +- I0927 18:25:22.788] test/sanity/run-test.sh | 3 +- I0927 18:25:22.788] test/sanity/run-tests-all-clouds.sh | 4 +- I0927 18:25:22.788] test/sanity/sanity_test.go | 17 ++--- I0927 18:25:22.788] test/{ => utils}/azure/azure_helpers.go | 0 I0927 18:25:22.788] test/{ => utils}/credentials/credentials.go | 65 ++++++++++++------ I0927 18:25:22.788] test/{ => utils}/credentials/credentials_test.go | 54 +++++++-------- I0927 18:25:22.789] test/utils/testutil/testutil.go | 8 +++ I0927 18:25:22.789] 18 files changed, 263 insertions(+), 200 deletions(-) I0927 18:25:22.789] delete mode 100644 .travis.yml I0927 18:25:22.789] delete mode 100644 hack/template/azure.json I0927 18:25:22.789] delete mode 100755 test/e2e/run-test.sh I0927 18:25:22.789] rename test/{ => utils}/azure/azure_helpers.go (100%) I0927 18:25:22.789] rename test/{ => utils}/credentials/credentials.go (68%) I0927 18:25:22.790] rename test/{ => utils}/credentials/credentials_test.go (77%) I0927 18:25:22.790] create mode 100644 test/utils/testutil/testutil.go I0927 18:25:22.790] process 275 exited with code 0 after 0.0m I0927 18:25:22.790] Configure environment... I0927 18:25:22.791] Call: git show -s --format=format:%ct HEAD I0927 18:25:22.795] process 277 exited with code 0 after 0.0m I0927 18:25:22.796] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0927 18:25:23.405] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I0927 18:25:23.516] process 278 exited with code 0 after 0.0m I0927 18:25:23.516] Call: gcloud config get-value account I0927 18:25:23.755] process 290 exited with code 0 after 0.0m I0927 18:25:23.755] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I0927 18:25:23.755] Call: bash -c ' set -o errexit set -o nounset export KUBE_ROOT=. source hack/lib/version.sh kube::version::get_version_vars echo $KUBE_GIT_VERSION ' I0927 18:25:24.244] process 302 exited with code 0 after 0.0m I0927 18:25:24.244] Start 1177650160998551552 at v1.17.0-alpha.0.1883+7342823f3e9d48... I0927 18:25:24.246] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_ETyODF gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177650160998551552/started.json I0927 18:25:25.705] process 335 exited with code 0 after 0.0m I0927 18:25:25.706] Call: gsutil -q -h Content-Type:text/plain -h 'x-goog-meta-link: gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177650160998551552' cp /tmp/gsutil_c5fjg1 gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-e2e/1177650160998551552.txt I0927 18:25:27.088] process 513 exited with code 0 after 0.0m I0927 18:25:27.089] Call: /workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py --gce-ssh= --test=true --up=true --down=true --deployment=acsengine --build=bazel --provider=skeleton --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 W0927 18:25:27.128] starts with local mode W0927 18:25:27.128] Environment: W0927 18:25:27.128] ARTIFACTS=/workspace/_artifacts W0927 18:25:27.129] AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa W0927 18:25:27.129] AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub W0927 18:25:27.129] AZURE_CREDENTIALS=/etc/azure-cred/credentials W0927 18:25:27.129] AZURE_SSH_PUBLIC_KEY_FILE=/etc/azure-ssh/azure-ssh-pub W0927 18:25:27.129] AZ_STORAGE_CONTAINER_NAME=mystoragecontainer W0927 18:25:27.129] BAZEL_REMOTE_CACHE_ENABLED=false W0927 18:25:27.129] BAZEL_VERSION=0.23.2 W0927 18:25:27.130] BOOTSTRAP_MIGRATION=yes W0927 18:25:27.130] BUILD_ID=1177650160998551552 W0927 18:25:27.130] BUILD_NUMBER=1177650160998551552 W0927 18:25:27.130] CLOUDSDK_COMPONENT_MANAGER_DISABLE_UPDATE_CHECK=true W0927 18:25:27.130] CLOUDSDK_CONFIG=/go/src/k8s.io/kubernetes/.config/gcloud W0927 18:25:27.130] CLOUDSDK_CORE_DISABLE_PROMPTS=1 W0927 18:25:27.130] CLOUDSDK_EXPERIMENTAL_FAST_COMPONENT_UPDATE=false W0927 18:25:27.131] CLOUD_CONFIG=random W0927 18:25:27.131] DECK_PORT=tcp://10.0.160.83:80 W0927 18:25:27.131] DECK_PORT_80_TCP=tcp://10.0.160.83:80 W0927 18:25:27.132] DECK_PORT_80_TCP_ADDR=10.0.160.83 W0927 18:25:27.132] DECK_PORT_80_TCP_PORT=80 W0927 18:25:27.132] DECK_PORT_80_TCP_PROTO=tcp W0927 18:25:27.132] DECK_SERVICE_HOST=10.0.160.83 W0927 18:25:27.132] DECK_SERVICE_PORT=80 W0927 18:25:27.132] DOCKER_IN_DOCKER_ENABLED=true W0927 18:25:27.133] DOCKER_IN_DOCKER_IPV6_ENABLED=false W0927 18:25:27.133] GCS_ARTIFACTS_DIR=gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177650160998551552/artifacts W0927 18:25:27.133] GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json W0927 18:25:27.133] GOPATH=/go W0927 18:25:27.133] GO_TARBALL=go1.12.1.linux-amd64.tar.gz W0927 18:25:27.133] HOME=/workspace W0927 18:25:27.133] HOOK_PORT=tcp://10.0.144.133:8888 W0927 18:25:27.133] HOOK_PORT_8888_TCP=tcp://10.0.144.133:8888 W0927 18:25:27.134] HOOK_PORT_8888_TCP_ADDR=10.0.144.133 W0927 18:25:27.134] HOOK_PORT_8888_TCP_PORT=8888 W0927 18:25:27.134] HOOK_PORT_8888_TCP_PROTO=tcp W0927 18:25:27.134] HOOK_SERVICE_HOST=10.0.144.133 W0927 18:25:27.134] HOOK_SERVICE_PORT=8888 W0927 18:25:27.134] HOSTNAME=f34ad81d-e153-11e9-918b-16773c0c7aed W0927 18:25:27.134] IMAGE=chewong/kubekins-e2e:v20190927-1d9518f-master W0927 18:25:27.135] INSTANCE_PREFIX=bootstrap-e2e W0927 18:25:27.135] JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/root/.ssh/google_compute_engine W0927 18:25:27.135] JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/root/.ssh/google_compute_engine.pub W0927 18:25:27.135] JOB_NAME=pull-azurefile-csi-driver-e2e W0927 18:25:27.135] JOB_SPEC={"type":"presubmit","job":"pull-azurefile-csi-driver-e2e","buildid":"1177650160998551552","prowjobid":"f34ad81d-e153-11e9-918b-16773c0c7aed","refs":{"org":"Azure","repo":"kubernetes","repo_link":"https://github.com/Azure/kubernetes","base_ref":"master","base_sha":"cd95e57c71a194a80064b00a44e42a222003dac8","base_link":"https://github.com/Azure/kubernetes/commit/cd95e57c71a194a80064b00a44e42a222003dac8","pulls":[{"number":113,"author":"chewong","sha":"879465972041635fd7bc8269da49153b67e63822","link":"https://github.com/Azure/kubernetes/pull/113","commit_link":"https://github.com/Azure/kubernetes/pull/113/commits/879465972041635fd7bc8269da49153b67e63822","author_link":"https://github.com/chewong"}]}} W0927 18:25:27.136] JOB_TYPE=presubmit W0927 18:25:27.136] KUBERNETES_PORT=tcp://10.0.0.1:443 W0927 18:25:27.136] KUBERNETES_PORT_443_TCP=tcp://10.0.0.1:443 W0927 18:25:27.136] KUBERNETES_PORT_443_TCP_ADDR=10.0.0.1 W0927 18:25:27.136] KUBERNETES_PORT_443_TCP_PORT=443 W0927 18:25:27.136] KUBERNETES_PORT_443_TCP_PROTO=tcp W0927 18:25:27.136] KUBERNETES_SERVICE_HOST=10.0.0.1 W0927 18:25:27.136] KUBERNETES_SERVICE_PORT=443 W0927 18:25:27.137] KUBERNETES_SERVICE_PORT_HTTPS=443 W0927 18:25:27.137] KUBETEST_IN_DOCKER=true W0927 18:25:27.137] KUBETEST_MANUAL_DUMP=y W0927 18:25:27.137] KUBE_AWS_INSTANCE_PREFIX=bootstrap-e2e W0927 18:25:27.137] KUBE_GCE_INSTANCE_PREFIX=bootstrap-e2e W0927 18:25:27.137] KUBE_VERBOSE=0 W0927 18:25:27.137] NODE_NAME=f34ad81d-e153-11e9-918b-16773c0c7aed W0927 18:25:27.137] PATH=/go/bin:/go/bin:/usr/local/go/bin:/google-cloud-sdk/bin:/workspace:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin W0927 18:25:27.138] PROW_JOB_ID=f34ad81d-e153-11e9-918b-16773c0c7aed W0927 18:25:27.138] PULL_BASE_REF=master W0927 18:25:27.138] PULL_BASE_SHA=cd95e57c71a194a80064b00a44e42a222003dac8 W0927 18:25:27.138] PULL_NUMBER=113 W0927 18:25:27.138] PULL_PULL_SHA=879465972041635fd7bc8269da49153b67e63822 W0927 18:25:27.138] PULL_REFS=master:cd95e57c71a194a80064b00a44e42a222003dac8,113:879465972041635fd7bc8269da49153b67e63822 W0927 18:25:27.139] PWD=/workspace W0927 18:25:27.139] REGISTRY=k8sprowinternal.azurecr.io W0927 18:25:27.139] REPO_NAME=kubernetes W0927 18:25:27.139] REPO_OWNER=Azure W0927 18:25:27.139] SHLVL=2 W0927 18:25:27.139] SOURCE_DATE_EPOCH=1569608496 W0927 18:25:27.139] TERM=xterm W0927 18:25:27.139] TIDE_PORT=tcp://10.0.180.53:80 W0927 18:25:27.140] TIDE_PORT_80_TCP=tcp://10.0.180.53:80 W0927 18:25:27.140] TIDE_PORT_80_TCP_ADDR=10.0.180.53 W0927 18:25:27.140] TIDE_PORT_80_TCP_PORT=80 W0927 18:25:27.140] TIDE_PORT_80_TCP_PROTO=tcp W0927 18:25:27.140] TIDE_SERVICE_HOST=10.0.180.53 W0927 18:25:27.140] TIDE_SERVICE_PORT=80 W0927 18:25:27.140] WORKSPACE=/workspace W0927 18:25:27.140] _=./test-infra/jenkins/bootstrap.py W0927 18:25:27.141] Run: ('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') W0927 18:25:27.157] 2019/09/27 18:25:27 azure.go:230: Reading credentials file /etc/azure-cred/credentials W0927 18:25:27.157] 2019/09/27 18:25:27 process.go:153: Running: make -C /go/src/k8s.io/kubernetes bazel-release W0927 18:25:27.208] Extracting Bazel installation... I0927 18:25:27.309] make: Entering directory '/go/src/k8s.io/kubernetes' W0927 18:25:36.002] Starting local Bazel server and connecting to it... W0927 18:25:37.685] Loading: W0927 18:25:37.688] Loading: 0 packages loaded W0927 18:25:38.691] Loading: 0 packages loaded W0927 18:25:40.231] Loading: 0 packages loaded W0927 18:25:41.692] Loading: 0 packages loaded W0927 18:25:43.474] Loading: 0 packages loaded W0927 18:25:44.693] Loading: 0 packages loaded W0927 18:25:44.693] currently loading: build/release-tars W0927 18:25:46.551] Loading: 0 packages loaded W0927 18:25:46.552] currently loading: build/release-tars W0927 18:25:46.928] Analyzing: target //build/release-tars:release-tars (1 packages loaded, 0 targets configured) W0927 18:25:48.334] Analyzing: target //build/release-tars:release-tars (180 packages loaded, 1088 targets configured) W0927 18:25:49.951] Analyzing: target //build/release-tars:release-tars (1130 packages loaded, 4330 targets configured) W0927 18:25:51.813] Analyzing: target //build/release-tars:release-tars (1937 packages loaded, 11900 targets configured) W0927 18:25:53.954] Analyzing: target //build/release-tars:release-tars (2658 packages loaded, 20625 targets configured) W0927 18:25:57.698] Analyzing: target //build/release-tars:release-tars (3027 packages loaded, 27097 targets configured) W0927 18:26:01.393] Analyzing: target //build/release-tars:release-tars (3065 packages loaded, 28468 targets configured) W0927 18:26:07.315] Analyzing: target //build/release-tars:release-tars (3072 packages loaded, 34768 targets configured) W0927 18:26:23.131] INFO: Analysed target //build/release-tars:release-tars (3072 packages loaded, 42238 targets configured). W0927 18:26:23.133] Building: checking cached actions W0927 18:26:23.135] INFO: Found 1 target... W0927 18:26:23.321] [0 / 21] [-----] Writing file build/release-tars/kubernetes-node-linux-amd64.args ... (5 actions, 0 running) W0927 18:26:23.322] [0 / 21] [-----] Writing file build/release-tars/kubernetes-node-linux-amd64.args ... (5 actions, 0 running) W0927 18:26:30.892] [128 / 5,296] GoCompile vendor/github.com/coreos/bbolt/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/coreos/bbolt.a; 1s linux-sandbox ... (8 actions, 7 running) W0927 18:26:39.003] [205 / 5,296] GoCompile vendor/github.com/hashicorp/hcl/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/hashicorp/hcl.a; 1s linux-sandbox ... (8 actions, 7 running) W0927 18:26:48.366] [501 / 5,296] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 5s linux-sandbox ... (8 actions, 7 running) W0927 18:26:59.022] [617 / 5,296] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 16s linux-sandbox ... (8 actions, 7 running) W0927 18:27:11.256] [721 / 5,296] GoCompile vendor/github.com/Azure/azure-sdk-for-go/services/network/mgmt/2019-06-01/network/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/Azure/azure-sdk-for-go/services/network/mgmt/2019-06-01/network.a; 6s linux-sandbox ... (8 actions, 7 running) W0927 18:27:26.146] [841 / 5,296] GoCompile vendor/github.com/golang/protobuf/proto/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/golang/protobuf/proto.a; 1s linux-sandbox ... (8 actions running) W0927 18:27:42.689] [960 / 5,296] GoCompile staging/src/k8s.io/apiserver/pkg/authentication/authenticator/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/apiserver/pkg/authentication/authenticator.a; 1s linux-sandbox ... (8 actions, 7 running) W0927 18:28:01.666] [1,113 / 5,296] GoCompile vendor/github.com/prometheus/client_golang/prometheus/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/prometheus/client_golang/prometheus.a; 1s linux-sandbox ... (8 actions running) W0927 18:28:23.406] [1,312 / 5,296] GoCompile vendor/github.com/Azure/azure-sdk-for-go/services/compute/mgmt/2019-07-01/compute/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/Azure/azure-sdk-for-go/services/compute/mgmt/2019-07-01/compute.a; 3s linux-sandbox ... (8 actions, 7 running) W0927 18:28:48.820] [1,479 / 5,296] GoCompile vendor/github.com/google/certificate-transparency-go/x509/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/google/certificate-transparency-go/x509.a; 3s linux-sandbox ... (8 actions running) W0927 18:29:17.554] [1,951 / 5,296] Compiling vendor/golang.org/x/sys/unix/linux_amd64_stripped/go_default_library%linux_amd64%cgo_codegen%/zsysnum_freebsd_386.cgo2.c; 0s linux-sandbox ... (7 actions running) W0927 18:29:50.613] [2,496 / 5,296] GoCompile vendor/github.com/miekg/dns/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/miekg/dns.a; 3s linux-sandbox ... (8 actions running) W0927 18:30:28.636] [2,688 / 5,296] GoCompile vendor/google.golang.org/api/compute/v0.alpha/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/google.golang.org/api/compute/v0.alpha.a; 11s linux-sandbox ... (8 actions, 7 running) W0927 18:31:23.578] [2,812 / 5,296] ; 45s linux-sandbox ... (8 actions running) W0927 18:32:15.563] [2,865 / 5,296] PackageTar build/release-tars/kubernetes-src.tar.gz; 24s linux-sandbox ... (8 actions, 7 running) W0927 18:33:15.829] [3,001 / 5,296] GoCompile pkg/scheduler/api/v1/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/pkg/scheduler/api/v1.a; 6s linux-sandbox ... (8 actions, 7 running) W0927 18:34:24.801] [3,390 / 5,296] GoCompile staging/src/k8s.io/cloud-provider/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/cloud-provider.a; 3s linux-sandbox ... (8 actions, 7 running) W0927 18:35:43.942] [3,789 / 5,296] GoCompile pkg/scheduler/factory/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/pkg/scheduler/factory.a; 3s linux-sandbox ... (8 actions, 7 running) W0927 18:37:20.331] [4,129 / 5,296] GoLink cmd/kube-controller-manager/linux_amd64_pure_stripped/kube-controller-manager; 46s linux-sandbox ... (8 actions, 7 running) W0927 18:39:05.777] [4,647 / 5,296] GoCompile staging/src/k8s.io/apiextensions-apiserver/pkg/generated/openapi/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/apiextensions-apiserver/pkg/generated/openapi.a; 9s linux-sandbox ... (8 actions, 7 running) W0927 18:41:07.897] [5,213 / 5,296] GoCompile staging/src/k8s.io/kubectl/pkg/cmd/rollingupdate/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/kubectl/pkg/cmd/rollingupdate.a; 10s linux-sandbox ... (8 actions, 7 running) W0927 18:43:47.996] [5,293 / 5,296] PackageTar build/release-tars/kubernetes-server-linux-amd64.tar.gz; 21s linux-sandbox ... (2 actions running) W0927 18:44:08.444] Target //build/release-tars:release-tars up-to-date: W0927 18:44:08.444] bazel-bin/build/release-tars/kubernetes-client-linux-amd64.tar.gz W0927 18:44:08.445] bazel-bin/build/release-tars/kubernetes-node-linux-amd64.tar.gz W0927 18:44:08.445] bazel-bin/build/release-tars/kubernetes-server-linux-amd64.tar.gz W0927 18:44:08.447] bazel-bin/build/release-tars/kubernetes-manifests.tar.gz W0927 18:44:08.447] bazel-bin/build/release-tars/kubernetes-test-portable.tar.gz W0927 18:44:08.447] bazel-bin/build/release-tars/kubernetes-test-linux-amd64.tar.gz W0927 18:44:08.447] bazel-bin/build/release-tars/kubernetes.tar.gz W0927 18:44:08.448] bazel-bin/build/release-tars/kubernetes-src.tar.gz W0927 18:44:08.477] INFO: Elapsed time: 1121.252s, Critical Path: 304.28s W0927 18:44:08.478] INFO: 5205 processes: 5205 linux-sandbox. W0927 18:44:08.482] INFO: Build completed successfully, 5296 total actions W0927 18:44:08.484] INFO: Build completed successfully, 5296 total actions W0927 18:44:08.498] 2019/09/27 18:44:08 process.go:155: Step 'make -C /go/src/k8s.io/kubernetes bazel-release' finished in 18m41.340103194s W0927 18:44:08.499] 2019/09/27 18:44:08 util.go:277: Flushing memory. I0927 18:44:08.599] make: Leaving directory '/go/src/k8s.io/kubernetes' W0927 18:44:30.313] 2019/09/27 18:44:30 azure.go:899: Deleting resource group: kubetest-2db0ca2d-e154-11e9-81f6-0242b042f06d. W0927 18:44:30.912] 2019/09/27 18:44:30 azure.go:780: downloading /workspace/aks226268985/kubernetes.json from https://raw.githubusercontent.com/kubernetes/cloud-provider-azure/master/tests/k8s-azure/manifest/linux.json. W0927 18:44:30.912] 2019/09/27 18:44:30 util.go:42: curl https://raw.githubusercontent.com/kubernetes/cloud-provider-azure/master/tests/k8s-azure/manifest/linux.json W0927 18:44:31.199] 2019/09/27 18:44:31 azure.go:527: downloading /workspace/aks-engine.tar.gz from https://github.com/Azure/aks-engine/releases/download/v0.39.1/aks-engine-v0.39.1-linux-amd64.tar.gz. W0927 18:44:31.199] 2019/09/27 18:44:31 util.go:42: curl https://github.com/Azure/aks-engine/releases/download/v0.39.1/aks-engine-v0.39.1-linux-amd64.tar.gz W0927 18:44:32.427] 2019/09/27 18:44:32 azure.go:554: Extracting tar file /workspace/aks-engine.tar.gz into directory /go/src/k8s.io/kubernetes . W0927 18:44:32.428] 2019/09/27 18:44:32 process.go:153: Running: tar -xzf /workspace/aks-engine.tar.gz --strip 1 W0927 18:44:32.599] 2019/09/27 18:44:32 process.go:155: Step 'tar -xzf /workspace/aks-engine.tar.gz --strip 1' finished in 171.258797ms W0927 18:44:32.599] 2019/09/27 18:44:32 process.go:153: Running: /go/src/k8s.io/kubernetes/aks-engine generate /workspace/aks226268985/kubernetes.json --output-directory /workspace/aks226268985 I0927 18:44:32.798] INFO[0000] Generating assets into /workspace/aks226268985... W0927 18:44:42.313] 2019/09/27 18:44:42 process.go:155: Step '/go/src/k8s.io/kubernetes/aks-engine generate /workspace/aks226268985/kubernetes.json --output-directory /workspace/aks226268985' finished in 9.713845601s W0927 18:44:42.316] 2019/09/27 18:44:42 azure.go:625: Setting kubeconfig env variable: kubeconfig path: /workspace/aks226268985/kubeconfig/kubeconfig.eastus2.json. W0927 18:44:42.316] 2019/09/27 18:44:42 azure.go:627: Creating resource group: kubetest-2db0ca2d-e154-11e9-81f6-0242b042f06d. W0927 18:44:42.316] 2019/09/27 18:44:42 azure.go:629: Creating Azure resource group: kubetest-2db0ca2d-e154-11e9-81f6-0242b042f06d for cluster deployment. W0927 18:44:43.875] 2019/09/27 18:44:43 azure.go:634: Validating deployment ARM templates. W0927 18:44:45.659] 2019/09/27 18:44:45 azure.go:640: Deploying cluster kubetest-2db0ca2d-e154-11e9-81f6-0242b042f06d in resource group kubetest-2db0ca2d-e154-11e9-81f6-0242b042f06d. W0927 18:53:52.682] 2019/09/27 18:53:52 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false version W0927 18:53:53.338] 2019/09/27 18:53:53 process.go:155: Step './cluster/kubectl.sh --match-server-version=false version' finished in 655.902708ms W0927 18:53:53.338] 2019/09/27 18:53:53 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false get nodes -oyaml W0927 18:53:54.680] 2019/09/27 18:53:54 process.go:155: Step './cluster/kubectl.sh --match-server-version=false get nodes -oyaml' finished in 1.341911745s W0927 18:53:54.680] 2019/09/27 18:53:54 azure.go:944: KUBE_TEST_REPO_LIST_DOWNLOAD_LOCATION not set. Using default test image repos. W0927 18:53:54.680] 2019/09/27 18:53:54 azure.go:944: KUBE_TEST_REPO_LIST_DOWNLOAD_LOCATION not set. Using default test image repos. W0927 18:53:54.681] 2019/09/27 18:53:54 process.go:153: Running: kubectl get nodes --no-headers W0927 18:53:55.223] 2019/09/27 18:53:55 process.go:155: Step 'kubectl get nodes --no-headers' finished in 541.797037ms W0927 18:53:55.223] 2019/09/27 18:53:55 e2e.go:462: Cluster nodes: W0927 18:53:55.223] k8s-agentpool1-35547432-0 Ready 25s v1.15.0 W0927 18:53:55.223] k8s-agentpool1-35547432-1 Ready 25s v1.15.0 W0927 18:53:55.224] k8s-master-35547432-0 Ready 25s v1.15.0 W0927 18:53:55.224] 2019/09/27 18:53:55 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false version W0927 18:53:55.655] 2019/09/27 18:53:55 process.go:155: Step './cluster/kubectl.sh --match-server-version=false version' finished in 432.635703ms W0927 18:53:55.656] 2019/09/27 18:53:55 process.go:153: Running: make e2e-test I0927 18:53:55.757] go test -v -timeout=30m ./test/e2e "-ginkgo.noColor" W0927 18:53:55.858] go: finding github.com/googleapis/gnostic v0.2.0 W0927 18:53:55.858] go: finding github.com/coreos/go-systemd v0.0.0-20190321100706-95778dfbb74e W0927 18:53:55.858] go: finding github.com/go-openapi/swag v0.19.0 W0927 18:53:55.859] go: finding github.com/golang/protobuf v1.3.0 W0927 18:53:55.859] go: finding github.com/Azure/go-autorest v11.5.1+incompatible W0927 18:53:55.859] go: finding github.com/docker/distribution v0.0.0-20170905204447-5db89f0ca686 W0927 18:53:55.859] go: finding github.com/jonboulle/clockwork v0.1.0 W0927 18:53:55.860] go: finding github.com/PuerkitoBio/purell v1.1.1 W0927 18:53:56.071] go: finding golang.org/x/net v0.0.0-20190301231341-16b79f2e4e95 W0927 18:53:56.212] go: finding gopkg.in/inf.v0 v0.9.1 W0927 18:53:56.443] go: finding github.com/coreos/etcd v3.3.12+incompatible W0927 18:53:56.462] go: finding github.com/davecgh/go-spew v1.1.1 W0927 18:53:56.465] go: finding github.com/spf13/afero v1.2.2 W0927 18:53:56.584] go: finding k8s.io/apimachinery v0.0.0-20190313205120-d7deff9243b1 W0927 18:53:56.616] go: finding github.com/xiang90/probing v0.0.0-20190116061207-43a291ad63a2 W0927 18:53:56.647] go: finding github.com/prometheus/common v0.2.0 W0927 18:53:56.930] go: finding go.uber.org/multierr v1.1.0 W0927 18:53:57.031] go: finding github.com/tmc/grpc-websocket-proxy v0.0.0-20190109142713-0ad062ec5ee5 W0927 18:53:57.095] go: finding k8s.io/kube-aggregator v0.0.0-20190401185513-77f9841f26eb W0927 18:53:57.343] go: finding google.golang.org/genproto v0.0.0-20190307195333-5fe7a883aa19 W0927 18:53:57.482] go: finding k8s.io/apiextensions-apiserver v0.0.0-20190315093550-53c4693659ed W0927 18:53:57.513] go: finding github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 W0927 18:53:57.702] go: finding gopkg.in/alecthomas/kingpin.v2 v2.2.6 W0927 18:53:57.839] go: finding github.com/dgrijalva/jwt-go v3.2.0+incompatible W0927 18:53:57.915] go: finding github.com/container-storage-interface/spec v1.1.0 W0927 18:53:58.073] go: finding github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 W0927 18:53:58.103] go: finding github.com/spf13/pflag v1.0.3 W0927 18:53:58.143] go: finding github.com/gorilla/websocket v1.4.0 W0927 18:53:58.151] go: finding github.com/soheilhy/cmux v0.1.4 W0927 18:53:58.428] go: finding github.com/hashicorp/golang-lru v0.5.1 W0927 18:53:58.545] go: finding github.com/stretchr/testify v1.3.0 W0927 18:53:58.668] go: finding github.com/inconshreveable/mousetrap v1.0.0 W0927 18:53:58.699] go: finding github.com/coreos/go-semver v0.3.0 W0927 18:53:58.719] go: finding go.uber.org/atomic v1.3.2 W0927 18:53:58.879] go: finding k8s.io/kubernetes v1.14.0 W0927 18:53:59.003] go: finding github.com/go-kit/kit v0.8.0 W0927 18:53:59.308] go: finding github.com/mwitkow/go-conntrack v0.0.0-20161129095857-cc309e4a2223 W0927 18:53:59.314] go: finding github.com/kubernetes-csi/external-snapshotter v1.0.1 W0927 18:53:59.397] go: finding github.com/docker/spdystream v0.0.0-20181023171402-6480d4af844c W0927 18:53:59.433] go: finding github.com/grpc-ecosystem/go-grpc-middleware v1.0.0 W0927 18:53:59.482] go: finding go.uber.org/zap v1.9.1 W0927 18:54:00.071] go: finding github.com/pkg/errors v0.8.1 W0927 18:54:00.123] go: finding k8s.io/cluster-bootstrap v0.0.0-20190313124217-0fa624df11e9 W0927 18:54:00.211] go: finding github.com/pmezard/go-difflib v1.0.0 W0927 18:54:00.243] go: finding golang.org/x/time v0.0.0-20181108054448-85acf8d2951c W0927 18:54:00.247] go: finding github.com/prometheus/procfs v0.0.0-20190306233201-d0f344d83b0c W0927 18:54:00.259] go: finding github.com/sirupsen/logrus v1.2.0 W0927 18:54:00.797] go: finding k8s.io/client-go v11.0.0+incompatible W0927 18:54:00.799] go: finding github.com/matttproud/golang_protobuf_extensions v1.0.1 W0927 18:54:00.868] go: finding github.com/gogo/protobuf v1.2.1 W0927 18:54:00.935] go: finding github.com/ugorji/go v1.1.4 W0927 18:54:00.953] go: finding github.com/mailru/easyjson v0.0.0-20190312143242-1de009706dbe W0927 18:54:00.959] go: finding gopkg.in/yaml.v2 v2.2.2 W0927 18:54:01.076] go: finding sigs.k8s.io/yaml v1.1.0 W0927 18:54:01.198] go: finding github.com/coreos/pkg v0.0.0-20180928190104-399ea9e2e55f W0927 18:54:01.383] go: finding golang.org/x/sys v0.0.0-20190308023053-584f3b12f43e W0927 18:54:01.624] go: finding github.com/konsorten/go-windows-terminal-sequences v1.0.1 W0927 18:54:01.652] go: finding github.com/onsi/ginkgo v1.7.0 W0927 18:54:01.851] go: finding golang.org/x/crypto v0.0.0-20190228161510-8dd112bcdc25 W0927 18:54:02.115] go: finding github.com/go-openapi/jsonreference v0.19.0 W0927 18:54:02.232] go: finding github.com/Azure/azure-storage-file-go v0.5.0 W0927 18:54:02.233] go: finding github.com/alecthomas/units v0.0.0-20151022065526-2efee857e7cf W0927 18:54:02.247] go: finding github.com/google/gofuzz v0.0.0-20170612174753-24818f796faf W0927 18:54:02.690] go: finding github.com/google/btree v1.0.0 W0927 18:54:02.731] go: finding github.com/prometheus/client_golang v0.9.2 W0927 18:54:02.800] go: finding golang.org/x/sync v0.0.0-20190227155943-e225da77a7e6 W0927 18:54:02.875] go: finding go.etcd.io/bbolt v1.3.2 W0927 18:54:02.898] go: finding k8s.io/cli-runtime v0.0.0-20190314001948-2899ed30580f W0927 18:54:03.379] go: finding github.com/stretchr/objx v0.1.1 W0927 18:54:03.444] go: finding github.com/marstr/guid v1.1.0 W0927 18:54:03.539] go: finding google.golang.org/api v0.1.0 W0927 18:54:03.563] go: finding k8s.io/kube-openapi v0.0.0-20190306001800-15615b16d372 W0927 18:54:03.767] go: finding github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd W0927 18:54:03.880] go: finding github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 W0927 18:54:03.884] go: finding github.com/pborman/uuid v0.0.0-20180906182336-adf5a7427709 W0927 18:54:03.923] go: finding google.golang.org/grpc v1.19.0 W0927 18:54:03.966] go: finding github.com/pelletier/go-toml v1.4.0 W0927 18:54:04.026] go: finding golang.org/x/text v0.3.0 W0927 18:54:04.753] go: finding github.com/go-logfmt/logfmt v0.3.0 W0927 18:54:04.756] go: finding github.com/PuerkitoBio/urlesc v0.0.0-20170810143723-de5bf2ad4578 W0927 18:54:04.795] go: finding sigs.k8s.io/kustomize v2.0.3+incompatible W0927 18:54:04.848] go: finding github.com/spf13/cobra v0.0.3 W0927 18:54:04.856] go: finding github.com/julienschmidt/httprouter v1.2.0 W0927 18:54:04.895] go: finding k8s.io/legacy-cloud-providers v0.0.0-20190624091455-d8621ceb9c64 W0927 18:54:04.908] go: finding github.com/coreos/bbolt v1.3.2 W0927 18:54:05.410] go: finding github.com/golang/glog v0.0.0-20160126235308-23def4e6c14b W0927 18:54:05.420] go: finding github.com/Azure/azure-pipeline-go v0.2.1 W0927 18:54:05.528] go: finding github.com/fatih/camelcase v1.0.0 W0927 18:54:05.585] go: finding contrib.go.opencensus.io/exporter/ocagent v0.2.0 W0927 18:54:05.628] go: finding github.com/census-instrumentation/opencensus-proto v0.1.0 W0927 18:54:05.697] go: finding k8s.io/apiserver v0.0.0-20190313205120-8b27c41bdbb1 W0927 18:54:05.713] go: finding github.com/go-stack/stack v1.8.0 W0927 18:54:05.941] go: finding k8s.io/component-base v0.0.0-20190313120452-4727f38490bc W0927 18:54:06.003] go: finding k8s.io/utils v0.0.0-20190221042446-c2654d5206da W0927 18:54:06.091] go: finding github.com/golang/groupcache v0.0.0-20190129154638-5b532d6fd5ef W0927 18:54:06.157] go: finding k8s.io/csi-translation-lib v0.0.0-20190313124639-7f5cabc6aac8 W0927 18:54:06.176] go: finding golang.org/x/lint v0.0.0-20190227174305-5b3e6a55c961 W0927 18:54:06.261] go: finding github.com/GoogleCloudPlatform/k8s-cloud-provider v0.0.0-20181220005116-f8e995905100 W0927 18:54:06.328] go: finding golang.org/x/tools v0.0.0-20190226205152-f727befe758c W0927 18:54:06.612] go: finding golang.org/x/exp v0.0.0-20190121172915-509febef88a4 W0927 18:54:06.657] go: finding github.com/evanphx/json-patch v3.0.0+incompatible W0927 18:54:06.710] go: finding gopkg.in/warnings.v0 v0.1.1 W0927 18:54:06.758] go: finding github.com/client9/misspell v0.3.4 W0927 18:54:07.164] go: finding github.com/elazarl/goproxy v0.0.0-20190410145444-c548f45dcf1d W0927 18:54:07.295] go: finding go.opencensus.io v0.18.0 W0927 18:54:07.428] go: finding github.com/json-iterator/go v1.1.5 W0927 18:54:07.475] go: finding github.com/opencontainers/go-digest v1.0.0-rc1 W0927 18:54:07.503] go: finding k8s.io/cloud-provider v0.0.0-20190308083404-05ccf96446f8 W0927 18:54:07.763] go: finding k8s.io/klog v0.2.0 W0927 18:54:08.024] go: finding github.com/apache/thrift v0.0.0-20180902110319-2566ecd5d999 W0927 18:54:08.113] go: finding github.com/google/go-cmp v0.2.0 W0927 18:54:08.135] go: finding github.com/golang/mock v1.1.1 W0927 18:54:08.139] go: finding github.com/peterbourgon/diskv v2.0.1+incompatible W0927 18:54:08.332] go: finding github.com/grpc-ecosystem/go-grpc-prometheus v1.2.0 W0927 18:54:08.335] go: finding github.com/ghodss/yaml v1.0.0 W0927 18:54:08.368] go: finding gopkg.in/check.v1 v0.0.0-20161208181325-20d25e280405 W0927 18:54:08.795] go: finding github.com/go-openapi/jsonpointer v0.19.0 W0927 18:54:08.805] go: finding github.com/Azure/azure-sdk-for-go v21.4.0+incompatible W0927 18:54:08.907] go: finding github.com/alecthomas/template v0.0.0-20160405071501-a0175ee3bccc W0927 18:54:08.951] go: finding github.com/imdario/mergo v0.3.7 W0927 18:54:08.971] go: finding google.golang.org/appengine v1.4.0 W0927 18:54:09.411] go: finding github.com/satori/go.uuid v1.2.0 W0927 18:54:09.495] go: finding github.com/mattn/go-ieproxy v0.0.0-20190610004146-91bb50d98149 W0927 18:54:09.571] go: finding golang.org/x/oauth2 v0.0.0-20190226205417-e64efc72b421 W0927 18:54:09.669] go: finding github.com/rubiojr/go-vhd v0.0.0-20160810183302-0bfd3b39853c W0927 18:54:09.671] go: finding github.com/onsi/gomega v1.4.3 W0927 18:54:09.995] go: finding github.com/aws/aws-sdk-go v1.16.26 W0927 18:54:10.142] go: finding gopkg.in/gcfg.v1 v1.2.0 W0927 18:54:10.386] go: finding github.com/elazarl/goproxy/ext v0.0.0-20190410145444-c548f45dcf1d W0927 18:54:10.425] go: finding gopkg.in/tomb.v1 v1.0.0-20141024135613-dd632973f1e7 W0927 18:54:10.564] go: finding gopkg.in/square/go-jose.v2 v2.3.0 W0927 18:54:10.901] go: finding github.com/hpcloud/tail v1.0.0 W0927 18:54:10.902] go: finding github.com/openzipkin/zipkin-go v0.1.1 W0927 18:54:11.099] go: finding cloud.google.com/go v0.34.0 W0927 18:54:11.209] go: finding github.com/kr/pretty v0.1.0 W0927 18:54:11.530] go: finding k8s.io/api v0.0.0-20190313235455-40a48860b5ab W0927 18:54:11.590] go: finding github.com/rogpeppe/go-charset v0.0.0-20180617210344-2471d30d28b4 W0927 18:54:11.684] go: finding github.com/dnaeon/go-vcr v1.0.1 W0927 18:54:11.728] go: finding github.com/kisielk/errcheck v1.1.0 W0927 18:54:12.067] go: finding github.com/grpc-ecosystem/grpc-gateway v1.5.0 W0927 18:54:12.282] go: finding github.com/go-openapi/spec v0.19.0 W0927 18:54:12.364] go: finding github.com/kr/text v0.1.0 W0927 18:54:12.496] go: finding github.com/vmware/govmomi v0.20.1 W0927 18:54:12.653] go: finding github.com/kisielk/gotool v1.0.0 W0927 18:54:12.813] go: finding github.com/BurntSushi/toml v0.3.1 W0927 18:54:12.839] go: finding github.com/kr/logfmt v0.0.0-20140226030751-b84e30acd515 W0927 18:54:12.907] go: finding github.com/gregjones/httpcache v0.0.0-20190212212710-3befbb6ad0cc W0927 18:54:12.968] go: finding gopkg.in/fsnotify.v1 v1.4.7 W0927 18:54:13.183] go: finding github.com/kr/pty v1.1.5 W0927 18:54:13.333] go: finding honnef.co/go/tools v0.0.0-20190102054323-c2f93a96b099 W0927 18:54:13.525] go: finding github.com/fsnotify/fsnotify v1.4.7 W0927 18:54:13.552] go: finding github.com/emicklei/go-restful v2.9.3+incompatible W0927 18:54:13.626] go: finding github.com/google/uuid v1.1.1 W0927 18:54:19.581] go: finding github.com/jmespath/go-jmespath v0.0.0-20180206201540-c2b33e8439af W0927 18:54:20.122] go: downloading k8s.io/api v0.0.0-20190313235455-40a48860b5ab W0927 18:54:20.123] go: downloading k8s.io/kubernetes v1.14.0 W0927 18:54:20.123] go: downloading github.com/container-storage-interface/spec v1.1.0 W0927 18:54:20.124] go: downloading k8s.io/client-go v11.0.0+incompatible W0927 18:54:20.127] go: downloading github.com/Azure/go-autorest v11.5.1+incompatible W0927 18:54:20.127] go: downloading github.com/Azure/azure-sdk-for-go v21.4.0+incompatible W0927 18:54:20.128] go: downloading github.com/onsi/ginkgo v1.7.0 W0927 18:54:20.161] go: downloading k8s.io/apimachinery v0.0.0-20190313205120-d7deff9243b1 W0927 18:54:20.375] go: extracting github.com/onsi/ginkgo v1.7.0 W0927 18:54:20.375] go: extracting github.com/Azure/go-autorest v11.5.1+incompatible W0927 18:54:20.389] go: extracting github.com/container-storage-interface/spec v1.1.0 W0927 18:54:20.404] go: downloading golang.org/x/net v0.0.0-20190301231341-16b79f2e4e95 W0927 18:54:20.413] go: downloading github.com/golang/protobuf v1.3.0 W0927 18:54:20.742] go: extracting k8s.io/apimachinery v0.0.0-20190313205120-d7deff9243b1 W0927 18:54:20.849] go: extracting k8s.io/api v0.0.0-20190313235455-40a48860b5ab W0927 18:54:20.856] go: extracting github.com/golang/protobuf v1.3.0 W0927 18:54:20.912] go: downloading gopkg.in/inf.v0 v0.9.1 W0927 18:54:21.004] go: extracting gopkg.in/inf.v0 v0.9.1 W0927 18:54:21.007] go: downloading github.com/kubernetes-csi/external-snapshotter v1.0.1 W0927 18:54:21.009] go: downloading github.com/hpcloud/tail v1.0.0 W0927 18:54:21.010] go: downloading github.com/dgrijalva/jwt-go v3.2.0+incompatible W0927 18:54:21.012] go: downloading google.golang.org/grpc v1.19.0 W0927 18:54:21.104] go: extracting github.com/dgrijalva/jwt-go v3.2.0+incompatible W0927 18:54:21.125] go: extracting github.com/hpcloud/tail v1.0.0 W0927 18:54:21.126] go: downloading sigs.k8s.io/yaml v1.1.0 W0927 18:54:21.134] go: downloading github.com/gogo/protobuf v1.2.1 W0927 18:54:21.203] go: extracting sigs.k8s.io/yaml v1.1.0 W0927 18:54:21.406] go: extracting k8s.io/client-go v11.0.0+incompatible W0927 18:54:21.524] go: extracting golang.org/x/net v0.0.0-20190301231341-16b79f2e4e95 W0927 18:54:21.593] go: extracting google.golang.org/grpc v1.19.0 W0927 18:54:21.684] go: downloading gopkg.in/tomb.v1 v1.0.0-20141024135613-dd632973f1e7 W0927 18:54:21.809] go: extracting gopkg.in/tomb.v1 v1.0.0-20141024135613-dd632973f1e7 W0927 18:54:21.817] go: downloading k8s.io/legacy-cloud-providers v0.0.0-20190624091455-d8621ceb9c64 W0927 18:54:21.831] go: downloading gopkg.in/fsnotify.v1 v1.4.7 W0927 18:54:21.835] go: downloading k8s.io/utils v0.0.0-20190221042446-c2654d5206da W0927 18:54:21.930] go: extracting gopkg.in/fsnotify.v1 v1.4.7 W0927 18:54:21.940] go: downloading go.opencensus.io v0.18.0 W0927 18:54:21.986] go: extracting k8s.io/utils v0.0.0-20190221042446-c2654d5206da W0927 18:54:22.163] go: extracting k8s.io/legacy-cloud-providers v0.0.0-20190624091455-d8621ceb9c64 W0927 18:54:22.187] go: extracting go.opencensus.io v0.18.0 W0927 18:54:22.219] go: downloading github.com/googleapis/gnostic v0.2.0 W0927 18:54:22.231] go: downloading golang.org/x/sys v0.0.0-20190308023053-584f3b12f43e W0927 18:54:22.811] go: extracting github.com/googleapis/gnostic v0.2.0 W0927 18:54:23.110] go: extracting github.com/gogo/protobuf v1.2.1 W0927 18:54:23.390] go: downloading github.com/pborman/uuid v0.0.0-20180906182336-adf5a7427709 W0927 18:54:23.439] go: extracting golang.org/x/sys v0.0.0-20190308023053-584f3b12f43e W0927 18:54:23.447] go: extracting github.com/pborman/uuid v0.0.0-20180906182336-adf5a7427709 W0927 18:54:23.537] go: downloading github.com/google/uuid v1.1.1 W0927 18:54:23.598] go: extracting github.com/google/uuid v1.1.1 W0927 18:54:23.604] go: downloading google.golang.org/genproto v0.0.0-20190307195333-5fe7a883aa19 W0927 18:54:26.531] go: extracting google.golang.org/genproto v0.0.0-20190307195333-5fe7a883aa19 W0927 18:54:26.882] go: downloading github.com/davecgh/go-spew v1.1.1 W0927 18:54:27.021] go: extracting github.com/davecgh/go-spew v1.1.1 W0927 18:54:27.031] go: downloading k8s.io/cloud-provider v0.0.0-20190308083404-05ccf96446f8 W0927 18:54:27.680] go: extracting k8s.io/cloud-provider v0.0.0-20190308083404-05ccf96446f8 W0927 18:54:30.457] go: extracting github.com/kubernetes-csi/external-snapshotter v1.0.1 W0927 18:54:30.476] go: downloading contrib.go.opencensus.io/exporter/ocagent v0.2.0 W0927 18:54:30.482] go: downloading github.com/pelletier/go-toml v1.4.0 W0927 18:54:30.532] go: extracting contrib.go.opencensus.io/exporter/ocagent v0.2.0 W0927 18:54:30.538] go: downloading golang.org/x/crypto v0.0.0-20190228161510-8dd112bcdc25 W0927 18:54:30.556] go: extracting github.com/pelletier/go-toml v1.4.0 W0927 18:54:30.570] go: downloading github.com/census-instrumentation/opencensus-proto v0.1.0 W0927 18:54:30.675] go: extracting github.com/census-instrumentation/opencensus-proto v0.1.0 W0927 18:54:30.688] go: downloading google.golang.org/api v0.1.0 W0927 18:54:31.194] go: extracting golang.org/x/crypto v0.0.0-20190228161510-8dd112bcdc25 W0927 18:54:31.278] go: downloading gopkg.in/yaml.v2 v2.2.2 W0927 18:54:31.380] go: extracting gopkg.in/yaml.v2 v2.2.2 W0927 18:54:31.393] go: downloading github.com/golang/groupcache v0.0.0-20190129154638-5b532d6fd5ef W0927 18:54:31.448] go: extracting github.com/golang/groupcache v0.0.0-20190129154638-5b532d6fd5ef W0927 18:54:31.454] go: downloading github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 W0927 18:54:31.725] go: extracting github.com/Azure/azure-sdk-for-go v21.4.0+incompatible W0927 18:54:31.735] go: extracting github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 W0927 18:54:31.739] go: downloading github.com/prometheus/client_golang v0.9.2 W0927 18:54:31.863] go: extracting github.com/prometheus/client_golang v0.9.2 W0927 18:54:31.889] go: downloading github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 W0927 18:54:31.996] go: extracting github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 W0927 18:54:32.010] go: downloading github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 W0927 18:54:32.050] go: extracting github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 W0927 18:54:32.055] go: downloading k8s.io/kube-openapi v0.0.0-20190306001800-15615b16d372 W0927 18:54:32.767] go: extracting k8s.io/kube-openapi v0.0.0-20190306001800-15615b16d372 W0927 18:54:32.816] go: downloading github.com/onsi/gomega v1.4.3 W0927 18:54:32.958] go: extracting github.com/onsi/gomega v1.4.3 W0927 18:54:32.975] go: downloading github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd W0927 18:54:33.013] go: extracting github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd W0927 18:54:33.014] go: downloading github.com/prometheus/common v0.2.0 W0927 18:54:33.146] go: extracting github.com/prometheus/common v0.2.0 W0927 18:54:33.183] go: extracting k8s.io/kubernetes v1.14.0 W0927 18:54:33.198] go: downloading golang.org/x/time v0.0.0-20181108054448-85acf8d2951c W0927 18:54:33.202] go: downloading github.com/rubiojr/go-vhd v0.0.0-20160810183302-0bfd3b39853c W0927 18:54:33.204] go: downloading k8s.io/klog v0.2.0 W0927 18:54:33.207] go: downloading github.com/prometheus/procfs v0.0.0-20190306233201-d0f344d83b0c W0927 18:54:33.243] go: downloading github.com/google/gofuzz v0.0.0-20170612174753-24818f796faf W0927 18:54:33.250] go: extracting golang.org/x/time v0.0.0-20181108054448-85acf8d2951c W0927 18:54:33.251] go: downloading github.com/json-iterator/go v1.1.5 W0927 18:54:33.268] go: extracting k8s.io/klog v0.2.0 W0927 18:54:33.274] go: downloading github.com/Azure/azure-storage-file-go v0.5.0 W0927 18:54:33.290] go: extracting github.com/google/gofuzz v0.0.0-20170612174753-24818f796faf W0927 18:54:33.298] go: downloading golang.org/x/text v0.3.0 W0927 18:54:33.323] go: extracting github.com/prometheus/procfs v0.0.0-20190306233201-d0f344d83b0c W0927 18:54:33.424] go: extracting github.com/json-iterator/go v1.1.5 W0927 18:54:33.436] go: extracting github.com/Azure/azure-storage-file-go v0.5.0 W0927 18:54:33.445] go: downloading golang.org/x/oauth2 v0.0.0-20190226205417-e64efc72b421 W0927 18:54:33.499] go: extracting github.com/rubiojr/go-vhd v0.0.0-20160810183302-0bfd3b39853c W0927 18:54:33.516] go: downloading github.com/marstr/guid v1.1.0 W0927 18:54:33.533] go: extracting golang.org/x/oauth2 v0.0.0-20190226205417-e64efc72b421 W0927 18:54:33.556] go: extracting github.com/marstr/guid v1.1.0 W0927 18:54:33.557] go: downloading github.com/satori/go.uuid v1.2.0 W0927 18:54:33.596] go: extracting github.com/satori/go.uuid v1.2.0 W0927 18:54:33.603] go: downloading github.com/Azure/azure-pipeline-go v0.2.1 W0927 18:54:33.652] go: extracting github.com/Azure/azure-pipeline-go v0.2.1 W0927 18:54:35.413] go: downloading github.com/matttproud/golang_protobuf_extensions v1.0.1 W0927 18:54:35.414] go: downloading k8s.io/apiserver v0.0.0-20190313205120-8b27c41bdbb1 W0927 18:54:35.602] go: extracting github.com/matttproud/golang_protobuf_extensions v1.0.1 W0927 18:54:35.607] go: downloading github.com/spf13/pflag v1.0.3 W0927 18:54:35.674] go: extracting github.com/spf13/pflag v1.0.3 W0927 18:54:35.698] go: downloading k8s.io/component-base v0.0.0-20190313120452-4727f38490bc W0927 18:54:35.974] go: extracting k8s.io/component-base v0.0.0-20190313120452-4727f38490bc W0927 18:54:36.144] go: extracting k8s.io/apiserver v0.0.0-20190313205120-8b27c41bdbb1 W0927 18:54:36.229] go: downloading github.com/imdario/mergo v0.3.7 W0927 18:54:36.231] go: downloading github.com/docker/spdystream v0.0.0-20181023171402-6480d4af844c W0927 18:54:36.232] go: downloading github.com/mattn/go-ieproxy v0.0.0-20190610004146-91bb50d98149 W0927 18:54:36.309] go: extracting github.com/imdario/mergo v0.3.7 W0927 18:54:36.317] go: extracting github.com/mattn/go-ieproxy v0.0.0-20190610004146-91bb50d98149 W0927 18:54:36.317] go: extracting github.com/docker/spdystream v0.0.0-20181023171402-6480d4af844c W0927 18:54:36.321] go: downloading k8s.io/apiextensions-apiserver v0.0.0-20190315093550-53c4693659ed W0927 18:54:36.321] go: downloading k8s.io/kube-aggregator v0.0.0-20190401185513-77f9841f26eb W0927 18:54:37.408] go: extracting golang.org/x/text v0.3.0 W0927 18:54:37.408] go: extracting google.golang.org/api v0.1.0 W0927 18:54:37.678] go: extracting k8s.io/kube-aggregator v0.0.0-20190401185513-77f9841f26eb W0927 18:54:37.696] go: downloading github.com/fatih/camelcase v1.0.0 W0927 18:54:37.743] go: extracting github.com/fatih/camelcase v1.0.0 W0927 18:54:37.744] go: downloading github.com/pkg/errors v0.8.1 W0927 18:54:37.798] go: extracting github.com/pkg/errors v0.8.1 W0927 18:54:37.802] go: downloading k8s.io/cli-runtime v0.0.0-20190314001948-2899ed30580f W0927 18:54:37.836] go: downloading github.com/stretchr/testify v1.3.0 W0927 18:54:37.848] go: downloading github.com/hashicorp/golang-lru v0.5.1 W0927 18:54:37.852] go: downloading github.com/docker/distribution v0.0.0-20170905204447-5db89f0ca686 W0927 18:54:37.855] go: downloading gopkg.in/square/go-jose.v2 v2.3.0 W0927 18:54:37.856] go: downloading k8s.io/cluster-bootstrap v0.0.0-20190313124217-0fa624df11e9 W0927 18:54:37.918] go: extracting k8s.io/apiextensions-apiserver v0.0.0-20190315093550-53c4693659ed W0927 18:54:37.974] go: extracting github.com/hashicorp/golang-lru v0.5.1 W0927 18:54:37.978] go: downloading k8s.io/csi-translation-lib v0.0.0-20190313124639-7f5cabc6aac8 W0927 18:54:37.999] go: extracting github.com/stretchr/testify v1.3.0 W0927 18:54:38.014] go: downloading github.com/pmezard/go-difflib v1.0.0 W0927 18:54:38.044] go: extracting k8s.io/cli-runtime v0.0.0-20190314001948-2899ed30580f W0927 18:54:38.059] go: downloading github.com/spf13/cobra v0.0.3 W0927 18:54:38.062] go: downloading sigs.k8s.io/kustomize v2.0.3+incompatible W0927 18:54:38.111] go: extracting github.com/pmezard/go-difflib v1.0.0 W0927 18:54:38.112] go: extracting gopkg.in/square/go-jose.v2 v2.3.0 W0927 18:54:38.112] go: extracting k8s.io/cluster-bootstrap v0.0.0-20190313124217-0fa624df11e9 W0927 18:54:38.113] go: downloading github.com/peterbourgon/diskv v2.0.1+incompatible W0927 18:54:38.160] go: extracting k8s.io/csi-translation-lib v0.0.0-20190313124639-7f5cabc6aac8 W0927 18:54:38.163] go: downloading github.com/evanphx/json-patch v3.0.0+incompatible W0927 18:54:38.171] go: downloading github.com/gregjones/httpcache v0.0.0-20190212212710-3befbb6ad0cc W0927 18:54:38.205] go: extracting github.com/peterbourgon/diskv v2.0.1+incompatible W0927 18:54:38.238] go: extracting github.com/evanphx/json-patch v3.0.0+incompatible W0927 18:54:38.261] go: extracting github.com/gregjones/httpcache v0.0.0-20190212212710-3befbb6ad0cc W0927 18:54:38.261] go: extracting github.com/spf13/cobra v0.0.3 W0927 18:54:38.275] go: downloading github.com/google/btree v1.0.0 W0927 18:54:38.330] go: extracting github.com/google/btree v1.0.0 W0927 18:54:38.507] go: extracting github.com/docker/distribution v0.0.0-20170905204447-5db89f0ca686 W0927 18:54:38.573] go: downloading golang.org/x/sync v0.0.0-20190227155943-e225da77a7e6 W0927 18:54:38.642] go: extracting golang.org/x/sync v0.0.0-20190227155943-e225da77a7e6 W0927 18:54:38.646] go: downloading github.com/opencontainers/go-digest v1.0.0-rc1 W0927 18:54:38.684] go: extracting github.com/opencontainers/go-digest v1.0.0-rc1 W0927 18:54:38.958] go: extracting sigs.k8s.io/kustomize v2.0.3+incompatible W0927 18:54:38.988] go: downloading github.com/ghodss/yaml v1.0.0 W0927 18:54:38.989] go: downloading github.com/go-openapi/spec v0.19.0 W0927 18:54:38.989] go: downloading github.com/emicklei/go-restful v2.9.3+incompatible W0927 18:54:39.037] go: extracting github.com/ghodss/yaml v1.0.0 W0927 18:54:39.176] go: extracting github.com/emicklei/go-restful v2.9.3+incompatible W0927 18:54:39.258] go: extracting github.com/go-openapi/spec v0.19.0 W0927 18:54:39.278] go: downloading github.com/go-openapi/jsonreference v0.19.0 W0927 18:54:39.278] go: downloading github.com/go-openapi/swag v0.19.0 W0927 18:54:39.278] go: downloading github.com/go-openapi/jsonpointer v0.19.0 W0927 18:54:39.316] go: extracting github.com/go-openapi/jsonreference v0.19.0 W0927 18:54:39.318] go: downloading github.com/PuerkitoBio/purell v1.1.1 W0927 18:54:39.323] go: extracting github.com/go-openapi/jsonpointer v0.19.0 W0927 18:54:39.323] go: extracting github.com/go-openapi/swag v0.19.0 W0927 18:54:39.329] go: downloading github.com/mailru/easyjson v0.0.0-20190312143242-1de009706dbe W0927 18:54:39.346] go: extracting github.com/PuerkitoBio/purell v1.1.1 W0927 18:54:39.348] go: downloading github.com/PuerkitoBio/urlesc v0.0.0-20170810143723-de5bf2ad4578 W0927 18:54:39.372] go: extracting github.com/PuerkitoBio/urlesc v0.0.0-20170810143723-de5bf2ad4578 W0927 18:54:39.399] go: extracting github.com/mailru/easyjson v0.0.0-20190312143242-1de009706dbe I0927 18:55:30.310] Sep 27 18:55:30.309: INFO: The --provider flag is not set. Continuing as if --provider=skeleton had been used. I0927 18:55:30.310] === RUN TestE2E I0927 18:55:30.310] Running Suite: AzureFile CSI Driver End-to-End Tests I0927 18:55:30.310] ==================================================== I0927 18:55:30.310] Random Seed: 1569610530 - Will randomize all specs I0927 18:55:30.311] Will run 8 of 8 specs I0927 18:55:30.311] I0927 18:55:32.799] 2019/09/27 18:55:32 Attempting docker login with Azure service principal I0927 18:55:33.966] 2019/09/27 18:55:33 docker login is successful I0927 18:55:33.966] 2019/09/27 18:55:33 Installing Azure File CSI Driver... I0927 18:55:33.968] make[1]: Entering directory '/go/src/github.com/kubernetes-sigs/azurefile-csi-driver' I0927 18:55:33.969] # Use v2.11.0 helm to match tiller's version in clusters made by aks-engine I0927 18:55:33.969] curl https://raw.githubusercontent.com/helm/helm/master/scripts/get | DESIRED_VERSION=v2.11.0 bash I0927 18:55:33.997] % Total % Received % Xferd Average Speed Time Time Time Current I0927 18:55:33.998] Dload Upload Total Spent Left Speed I0927 18:55:34.264] 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7034 100 7034 0 0 26422 0 --:--:-- --:--:-- --:--:-- 26344 I0927 18:55:34.280] Downloading https://get.helm.sh/helm-v2.11.0-linux-amd64.tar.gz I0927 18:55:35.428] Preparing to install helm and tiller into /usr/local/bin I0927 18:55:35.455] helm installed into /usr/local/bin/helm I0927 18:55:35.484] tiller installed into /usr/local/bin/tiller I0927 18:55:35.486] Run 'helm init' to configure helm. I0927 18:55:35.501] # Make sure tiller is ready I0927 18:55:35.502] kubectl wait pod -l name=tiller --namespace kube-system --for condition=ready I0927 18:55:36.020] pod/tiller-deploy-54c96cb5df-r2p27 condition met I0927 18:55:36.023] helm version I0927 18:55:36.076] Client: &version.Version{SemVer:"v2.11.0", GitCommit:"2e55dbe1fdb5fdb96b75ff144a339489417b146b", GitTreeState:"clean"} I0927 18:55:36.997] Server: &version.Version{SemVer:"v2.11.0", GitCommit:"2e55dbe1fdb5fdb96b75ff144a339489417b146b", GitTreeState:"clean"} I0927 18:55:38.015] # Use IMAGE_VERSION from env var if it is defined. Otherwise, use a custom version for E2E I0927 18:55:38.016] # Only build and push the image if it does not exist in the registry I0927 18:55:38.016] docker pull k8sprowinternal.azurecr.io/azurefile-csi:v0.4.0 || IMAGE_VERSION=v0.4.0 make azurefile-container push I0927 18:55:38.554] Error response from daemon: manifest for k8sprowinternal.azurecr.io/azurefile-csi:v0.4.0 not found I0927 18:55:38.559] make[2]: Entering directory '/go/src/github.com/kubernetes-sigs/azurefile-csi-driver' I0927 18:55:38.559] CGO_ENABLED=0 GOOS=linux go build -a -ldflags "-X github.com/kubernetes-sigs/azurefile-csi-driver/pkg/azurefile.driverVersion=v0.4.0 -X github.com/kubernetes-sigs/azurefile-csi-driver/pkg/azurefile.gitCommit=97624462f896750bafb0f09aa3c5b24e652e8cce -X github.com/kubernetes-sigs/azurefile-csi-driver/pkg/azurefile.buildDate=2019-09-27T18:53:55Z -s -w -extldflags '-static'" -o _output/azurefileplugin ./pkg/azurefileplugin I0927 18:56:11.750] docker build --no-cache -t k8sprowinternal.azurecr.io/azurefile-csi:v0.4.0 -f ./pkg/azurefileplugin/Dockerfile . I0927 18:56:13.838] Sending build context to Docker daemon 155.7MB I0927 18:56:13.871] Step 1/6 : FROM debian:9 I0927 18:56:15.295] 9: Pulling from library/debian I0927 18:56:15.716] 092586df9206: Pulling fs layer I0927 18:56:16.472] 092586df9206: Verifying Checksum I0927 18:56:16.473] 092586df9206: Download complete I0927 18:56:20.546] 092586df9206: Pull complete I0927 18:56:20.572] Digest: sha256:5fb93ce7a427b7c1c2374d5c29d68a159de7d5e781deeda422f8d51a1a9b6480 I0927 18:56:20.591] Status: Downloaded newer image for debian:9 I0927 18:56:20.592] ---> cb15ecf641ad I0927 18:56:20.592] Step 2/6 : RUN apt-get update && apt-get install -y ca-certificates cifs-utils I0927 18:56:25.013] ---> Running in 591cf41d3594 I0927 18:56:26.240] Ign:2 http://cdn-fastly.deb.debian.org/debian stretch InRelease I0927 18:56:26.390] Get:1 http://security-cdn.debian.org/debian-security stretch/updates InRelease [94.3 kB] I0927 18:56:26.471] Get:3 http://cdn-fastly.deb.debian.org/debian stretch-updates InRelease [91.0 kB] I0927 18:56:26.543] Get:4 http://cdn-fastly.deb.debian.org/debian stretch Release [118 kB] I0927 18:56:26.587] Get:5 http://cdn-fastly.deb.debian.org/debian stretch Release.gpg [2365 B] I0927 18:56:26.638] Get:6 http://cdn-fastly.deb.debian.org/debian stretch-updates/main amd64 Packages [27.4 kB] I0927 18:56:26.724] Get:7 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 Packages [7086 kB] I0927 18:56:26.807] Get:8 http://security-cdn.debian.org/debian-security stretch/updates/main amd64 Packages [497 kB] I0927 18:56:28.039] Fetched 7916 kB in 2s (3604 kB/s) I0927 18:56:28.622] Reading package lists... I0927 18:56:29.221] Reading package lists... I0927 18:56:29.318] Building dependency tree... I0927 18:56:29.318] Reading state information... I0927 18:56:29.389] The following additional packages will be installed: I0927 18:56:29.389] bzip2 dbus file krb5-locales libapparmor1 libavahi-client3 I0927 18:56:29.390] libavahi-common-data libavahi-common3 libbsd0 libcups2 libdbus-1-3 libexpat1 I0927 18:56:29.390] libffi6 libgmp10 libgnutls30 libgpm2 libgssapi-krb5-2 libhogweed4 I0927 18:56:29.390] libk5crypto3 libkeyutils1 libkrb5-3 libkrb5support0 libldap-2.4-2 I0927 18:56:29.390] libldap-common libldb1 libmagic-mgc libmagic1 libncurses5 libp11-kit0 I0927 18:56:29.390] libpopt0 libpython-stdlib libpython2.7 libpython2.7-minimal I0927 18:56:29.390] libpython2.7-stdlib libreadline7 libsasl2-2 libsasl2-modules I0927 18:56:29.391] libsasl2-modules-db libsqlite3-0 libssl1.1 libtalloc2 libtasn1-6 libtdb1 I0927 18:56:29.391] libtevent0 libwbclient0 mime-support openssl python python-crypto python-ldb I0927 18:56:29.391] python-minimal python-samba python-talloc python-tdb python2.7 I0927 18:56:29.391] python2.7-minimal readline-common samba-common samba-common-bin samba-libs I0927 18:56:29.391] ucf xz-utils I0927 18:56:29.391] Suggested packages: I0927 18:56:29.391] bzip2-doc keyutils smbclient winbind default-dbus-session-bus I0927 18:56:29.391] | dbus-session-bus cups-common gnutls-bin gpm krb5-doc krb5-user I0927 18:56:29.392] libsasl2-modules-gssapi-mit | libsasl2-modules-gssapi-heimdal I0927 18:56:29.392] libsasl2-modules-ldap libsasl2-modules-otp libsasl2-modules-sql python-doc I0927 18:56:29.392] python-tk python-crypto-dbg python-crypto-doc python-gpgme python2.7-doc I0927 18:56:29.392] binutils binfmt-support readline-doc heimdal-clients I0927 18:56:29.955] The following NEW packages will be installed: I0927 18:56:29.955] bzip2 ca-certificates cifs-utils dbus file krb5-locales libapparmor1 I0927 18:56:29.955] libavahi-client3 libavahi-common-data libavahi-common3 libbsd0 libcups2 I0927 18:56:29.955] libdbus-1-3 libexpat1 libffi6 libgmp10 libgnutls30 libgpm2 libgssapi-krb5-2 I0927 18:56:29.955] libhogweed4 libk5crypto3 libkeyutils1 libkrb5-3 libkrb5support0 I0927 18:56:29.956] libldap-2.4-2 libldap-common libldb1 libmagic-mgc libmagic1 libncurses5 I0927 18:56:29.956] libp11-kit0 libpopt0 libpython-stdlib libpython2.7 libpython2.7-minimal I0927 18:56:29.956] libpython2.7-stdlib libreadline7 libsasl2-2 libsasl2-modules I0927 18:56:29.956] libsasl2-modules-db libsqlite3-0 libssl1.1 libtalloc2 libtasn1-6 libtdb1 I0927 18:56:29.956] libtevent0 libwbclient0 mime-support openssl python python-crypto python-ldb I0927 18:56:29.956] python-minimal python-samba python-talloc python-tdb python2.7 I0927 18:56:29.956] python2.7-minimal readline-common samba-common samba-common-bin samba-libs I0927 18:56:29.957] ucf xz-utils I0927 18:56:30.169] 0 upgraded, 64 newly installed, 0 to remove and 1 not upgraded. I0927 18:56:30.170] Need to get 21.4 MB of archives. I0927 18:56:30.170] After this operation, 81.9 MB of additional disk space will be used. I0927 18:56:30.170] Get:1 http://security-cdn.debian.org/debian-security stretch/updates/main amd64 libexpat1 amd64 2.2.0-2+deb9u3 [83.7 kB] I0927 18:56:30.322] Get:2 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libpython2.7-minimal amd64 2.7.13-2+deb9u3 [389 kB] I0927 18:56:30.453] Get:3 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python2.7-minimal amd64 2.7.13-2+deb9u3 [1382 kB] I0927 18:56:30.505] Get:4 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python-minimal amd64 2.7.13-2 [40.5 kB] I0927 18:56:30.505] Get:5 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 mime-support all 3.60 [36.7 kB] I0927 18:56:30.506] Get:6 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libffi6 amd64 3.2.1-6 [20.4 kB] I0927 18:56:30.506] Get:7 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 readline-common all 7.0-3 [70.4 kB] I0927 18:56:30.507] Get:8 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libreadline7 amd64 7.0-3 [151 kB] I0927 18:56:30.519] Get:9 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libsqlite3-0 amd64 3.16.2-5+deb9u1 [572 kB] I0927 18:56:30.525] Get:10 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libssl1.1 amd64 1.1.0k-1~deb9u1 [1356 kB] I0927 18:56:30.551] Get:11 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libpython2.7-stdlib amd64 2.7.13-2+deb9u3 [1897 kB] I0927 18:56:30.593] Get:12 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python2.7 amd64 2.7.13-2+deb9u3 [285 kB] I0927 18:56:30.724] Get:13 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libpython-stdlib amd64 2.7.13-2 [20.0 kB] I0927 18:56:30.747] Get:14 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python amd64 2.7.13-2 [154 kB] I0927 18:56:30.750] Get:15 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libbsd0 amd64 0.8.3-1 [83.0 kB] I0927 18:56:30.751] Get:16 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libwbclient0 amd64 2:4.5.16+dfsg-1+deb9u2 [124 kB] I0927 18:56:30.753] Get:17 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 ucf all 3.0036 [70.2 kB] I0927 18:56:30.755] Get:18 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 samba-common all 2:4.5.16+dfsg-1+deb9u2 [174 kB] I0927 18:56:30.757] Get:19 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libpopt0 amd64 1.16-10+b2 [49.4 kB] I0927 18:56:30.758] Get:20 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 bzip2 amd64 1.0.6-8.1 [47.5 kB] I0927 18:56:30.759] Get:21 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libapparmor1 amd64 2.11.0-3+deb9u2 [78.9 kB] I0927 18:56:30.870] Get:22 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libdbus-1-3 amd64 1.10.28-0+deb9u1 [195 kB] I0927 18:56:31.016] Get:23 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 dbus amd64 1.10.28-0+deb9u1 [212 kB] I0927 18:56:31.042] Get:24 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libmagic-mgc amd64 1:5.30-1+deb9u2 [222 kB] I0927 18:56:31.045] Get:25 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libmagic1 amd64 1:5.30-1+deb9u2 [111 kB] I0927 18:56:31.047] Get:26 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 file amd64 1:5.30-1+deb9u2 [64.1 kB] I0927 18:56:31.048] Get:27 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 krb5-locales all 1.15-1+deb9u1 [93.8 kB] I0927 18:56:31.063] Get:28 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libgmp10 amd64 2:6.1.2+dfsg-1 [253 kB] I0927 18:56:31.067] Get:29 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libhogweed4 amd64 3.3-1+b2 [136 kB] I0927 18:56:31.069] Get:30 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libp11-kit0 amd64 0.23.3-2 [111 kB] I0927 18:56:31.071] Get:31 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libtasn1-6 amd64 4.10-1.1+deb9u1 [50.6 kB] I0927 18:56:31.161] Get:32 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libgnutls30 amd64 3.5.8-5+deb9u4 [896 kB] I0927 18:56:31.307] Get:33 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libgpm2 amd64 1.20.4-6.2+b1 [34.2 kB] I0927 18:56:31.330] Get:34 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libkeyutils1 amd64 1.5.9-9 [12.4 kB] I0927 18:56:31.330] Get:35 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libkrb5support0 amd64 1.15-1+deb9u1 [61.9 kB] I0927 18:56:31.332] Get:36 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libk5crypto3 amd64 1.15-1+deb9u1 [119 kB] I0927 18:56:31.335] Get:37 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libkrb5-3 amd64 1.15-1+deb9u1 [311 kB] I0927 18:56:31.339] Get:38 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libgssapi-krb5-2 amd64 1.15-1+deb9u1 [155 kB] I0927 18:56:31.341] Get:39 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libsasl2-modules-db amd64 2.1.27~101-g0780600+dfsg-3 [68.2 kB] I0927 18:56:31.343] Get:40 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libsasl2-2 amd64 2.1.27~101-g0780600+dfsg-3 [105 kB] I0927 18:56:31.344] Get:41 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libldap-common all 2.4.44+dfsg-5+deb9u3 [85.7 kB] I0927 18:56:31.452] Get:42 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libldap-2.4-2 amd64 2.4.44+dfsg-5+deb9u3 [220 kB] I0927 18:56:31.597] Get:43 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 xz-utils amd64 5.2.2-1.2+b1 [266 kB] I0927 18:56:31.624] Get:44 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 openssl amd64 1.1.0k-1~deb9u1 [747 kB] I0927 18:56:31.634] Get:45 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 ca-certificates all 20161130+nmu1+deb9u1 [182 kB] I0927 18:56:31.636] Get:46 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libtalloc2 amd64 2.1.8-1 [36.3 kB] I0927 18:56:31.637] Get:47 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 cifs-utils amd64 2:6.7-1 [75.8 kB] I0927 18:56:31.638] Get:48 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libavahi-common-data amd64 0.6.32-2 [118 kB] I0927 18:56:31.640] Get:49 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libavahi-common3 amd64 0.6.32-2 [52.0 kB] I0927 18:56:31.640] Get:50 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libavahi-client3 amd64 0.6.32-2 [55.3 kB] I0927 18:56:31.641] Get:51 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libcups2 amd64 2.2.1-8+deb9u4 [307 kB] I0927 18:56:31.743] Get:52 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libtdb1 amd64 1.3.11-2 [43.2 kB] I0927 18:56:31.889] Get:53 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libtevent0 amd64 0.9.31-1 [29.3 kB] I0927 18:56:31.981] Get:54 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libldb1 amd64 2:1.1.27-1+deb9u1 [113 kB] I0927 18:56:31.983] Get:55 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libncurses5 amd64 6.0+20161126-1+deb9u2 [93.4 kB] I0927 18:56:31.984] Get:56 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libpython2.7 amd64 2.7.13-2+deb9u3 [1071 kB] I0927 18:56:31.997] Get:57 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libsasl2-modules amd64 2.1.27~101-g0780600+dfsg-3 [102 kB] I0927 18:56:31.998] Get:58 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python-crypto amd64 2.6.1-7 [259 kB] I0927 18:56:32.002] Get:59 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python-ldb amd64 2:1.1.27-1+deb9u1 [34.8 kB] I0927 18:56:32.002] Get:60 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python-tdb amd64 1.3.11-2 [16.2 kB] I0927 18:56:32.003] Get:61 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python-talloc amd64 2.1.8-1 [11.9 kB] I0927 18:56:32.034] Get:62 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 samba-libs amd64 2:4.5.16+dfsg-1+deb9u2 [5231 kB] I0927 18:56:32.326] Get:63 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python-samba amd64 2:4.5.16+dfsg-1+deb9u2 [1360 kB] I0927 18:56:32.367] Get:64 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 samba-common-bin amd64 2:4.5.16+dfsg-1+deb9u2 [603 kB] I0927 18:56:32.674] debconf: delaying package configuration, since apt-utils is not installed I0927 18:56:32.687] Fetched 21.4 MB in 2s (8869 kB/s) I0927 18:56:32.748] Selecting previously unselected package libpython2.7-minimal:amd64. I0927 18:56:32.753] (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 6500 files and directories currently installed.) I0927 18:56:32.753] Preparing to unpack .../00-libpython2.7-minimal_2.7.13-2+deb9u3_amd64.deb ... I0927 18:56:32.767] Unpacking libpython2.7-minimal:amd64 (2.7.13-2+deb9u3) ... I0927 18:56:32.955] Selecting previously unselected package python2.7-minimal. I0927 18:56:32.957] Preparing to unpack .../01-python2.7-minimal_2.7.13-2+deb9u3_amd64.deb ... I0927 18:56:32.973] Unpacking python2.7-minimal (2.7.13-2+deb9u3) ... I0927 18:56:33.246] Selecting previously unselected package python-minimal. I0927 18:56:33.247] Preparing to unpack .../02-python-minimal_2.7.13-2_amd64.deb ... I0927 18:56:33.262] Unpacking python-minimal (2.7.13-2) ... I0927 18:56:33.385] Selecting previously unselected package mime-support. I0927 18:56:33.386] Preparing to unpack .../03-mime-support_3.60_all.deb ... I0927 18:56:33.400] Unpacking mime-support (3.60) ... I0927 18:56:33.538] Selecting previously unselected package libexpat1:amd64. I0927 18:56:33.539] Preparing to unpack .../04-libexpat1_2.2.0-2+deb9u3_amd64.deb ... I0927 18:56:33.568] Unpacking libexpat1:amd64 (2.2.0-2+deb9u3) ... I0927 18:56:33.694] Selecting previously unselected package libffi6:amd64. I0927 18:56:33.695] Preparing to unpack .../05-libffi6_3.2.1-6_amd64.deb ... I0927 18:56:33.713] Unpacking libffi6:amd64 (3.2.1-6) ... I0927 18:56:33.816] Selecting previously unselected package readline-common. I0927 18:56:33.816] Preparing to unpack .../06-readline-common_7.0-3_all.deb ... I0927 18:56:33.831] Unpacking readline-common (7.0-3) ... I0927 18:56:33.948] Selecting previously unselected package libreadline7:amd64. I0927 18:56:33.949] Preparing to unpack .../07-libreadline7_7.0-3_amd64.deb ... I0927 18:56:33.964] Unpacking libreadline7:amd64 (7.0-3) ... I0927 18:56:34.104] Selecting previously unselected package libsqlite3-0:amd64. I0927 18:56:34.105] Preparing to unpack .../08-libsqlite3-0_3.16.2-5+deb9u1_amd64.deb ... I0927 18:56:34.119] Unpacking libsqlite3-0:amd64 (3.16.2-5+deb9u1) ... I0927 18:56:34.338] Selecting previously unselected package libssl1.1:amd64. I0927 18:56:34.339] Preparing to unpack .../09-libssl1.1_1.1.0k-1~deb9u1_amd64.deb ... I0927 18:56:34.354] Unpacking libssl1.1:amd64 (1.1.0k-1~deb9u1) ... I0927 18:56:34.590] Selecting previously unselected package libpython2.7-stdlib:amd64. I0927 18:56:34.591] Preparing to unpack .../10-libpython2.7-stdlib_2.7.13-2+deb9u3_amd64.deb ... I0927 18:56:34.605] Unpacking libpython2.7-stdlib:amd64 (2.7.13-2+deb9u3) ... I0927 18:56:34.931] Selecting previously unselected package python2.7. I0927 18:56:34.932] Preparing to unpack .../11-python2.7_2.7.13-2+deb9u3_amd64.deb ... I0927 18:56:34.947] Unpacking python2.7 (2.7.13-2+deb9u3) ... I0927 18:56:35.057] Selecting previously unselected package libpython-stdlib:amd64. I0927 18:56:35.058] Preparing to unpack .../12-libpython-stdlib_2.7.13-2_amd64.deb ... I0927 18:56:35.073] Unpacking libpython-stdlib:amd64 (2.7.13-2) ... I0927 18:56:35.238] Setting up libpython2.7-minimal:amd64 (2.7.13-2+deb9u3) ... I0927 18:56:35.306] Setting up python2.7-minimal (2.7.13-2+deb9u3) ... I0927 18:56:35.611] Linking and byte-compiling packages for runtime python2.7... I0927 18:56:35.639] Setting up python-minimal (2.7.13-2) ... I0927 18:56:35.801] Selecting previously unselected package python. I0927 18:56:35.805] (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 7326 files and directories currently installed.) I0927 18:56:35.805] Preparing to unpack .../00-python_2.7.13-2_amd64.deb ... I0927 18:56:35.824] Unpacking python (2.7.13-2) ... I0927 18:56:35.955] Selecting previously unselected package libbsd0:amd64. I0927 18:56:35.956] Preparing to unpack .../01-libbsd0_0.8.3-1_amd64.deb ... I0927 18:56:35.970] Unpacking libbsd0:amd64 (0.8.3-1) ... I0927 18:56:36.094] Selecting previously unselected package libwbclient0:amd64. I0927 18:56:36.095] Preparing to unpack .../02-libwbclient0_2%3a4.5.16+dfsg-1+deb9u2_amd64.deb ... I0927 18:56:36.111] Unpacking libwbclient0:amd64 (2:4.5.16+dfsg-1+deb9u2) ... I0927 18:56:36.249] Selecting previously unselected package ucf. I0927 18:56:36.250] Preparing to unpack .../03-ucf_3.0036_all.deb ... I0927 18:56:36.265] Moving old data out of the way I0927 18:56:36.266] Unpacking ucf (3.0036) ... I0927 18:56:36.412] Selecting previously unselected package samba-common. I0927 18:56:36.413] Preparing to unpack .../04-samba-common_2%3a4.5.16+dfsg-1+deb9u2_all.deb ... I0927 18:56:36.433] Unpacking samba-common (2:4.5.16+dfsg-1+deb9u2) ... I0927 18:56:36.562] Selecting previously unselected package libpopt0:amd64. I0927 18:56:36.563] Preparing to unpack .../05-libpopt0_1.16-10+b2_amd64.deb ... I0927 18:56:36.582] Unpacking libpopt0:amd64 (1.16-10+b2) ... I0927 18:56:36.689] Selecting previously unselected package bzip2. I0927 18:56:36.690] Preparing to unpack .../06-bzip2_1.0.6-8.1_amd64.deb ... I0927 18:56:36.705] Unpacking bzip2 (1.0.6-8.1) ... I0927 18:56:36.817] Selecting previously unselected package libapparmor1:amd64. I0927 18:56:36.818] Preparing to unpack .../07-libapparmor1_2.11.0-3+deb9u2_amd64.deb ... I0927 18:56:36.832] Unpacking libapparmor1:amd64 (2.11.0-3+deb9u2) ... I0927 18:56:36.949] Selecting previously unselected package libdbus-1-3:amd64. I0927 18:56:36.950] Preparing to unpack .../08-libdbus-1-3_1.10.28-0+deb9u1_amd64.deb ... I0927 18:56:36.964] Unpacking libdbus-1-3:amd64 (1.10.28-0+deb9u1) ... I0927 18:56:37.122] Selecting previously unselected package dbus. I0927 18:56:37.123] Preparing to unpack .../09-dbus_1.10.28-0+deb9u1_amd64.deb ... I0927 18:56:37.144] Unpacking dbus (1.10.28-0+deb9u1) ... I0927 18:56:37.286] Selecting previously unselected package libmagic-mgc. I0927 18:56:37.288] Preparing to unpack .../10-libmagic-mgc_1%3a5.30-1+deb9u2_amd64.deb ... I0927 18:56:37.303] Unpacking libmagic-mgc (1:5.30-1+deb9u2) ... I0927 18:56:37.479] Selecting previously unselected package libmagic1:amd64. I0927 18:56:37.480] Preparing to unpack .../11-libmagic1_1%3a5.30-1+deb9u2_amd64.deb ... I0927 18:56:37.497] Unpacking libmagic1:amd64 (1:5.30-1+deb9u2) ... I0927 18:56:37.595] Selecting previously unselected package file. I0927 18:56:37.596] Preparing to unpack .../12-file_1%3a5.30-1+deb9u2_amd64.deb ... I0927 18:56:37.611] Unpacking file (1:5.30-1+deb9u2) ... I0927 18:56:37.704] Selecting previously unselected package krb5-locales. I0927 18:56:37.705] Preparing to unpack .../13-krb5-locales_1.15-1+deb9u1_all.deb ... I0927 18:56:37.720] Unpacking krb5-locales (1.15-1+deb9u1) ... I0927 18:56:37.851] Selecting previously unselected package libgmp10:amd64. I0927 18:56:37.852] Preparing to unpack .../14-libgmp10_2%3a6.1.2+dfsg-1_amd64.deb ... I0927 18:56:37.869] Unpacking libgmp10:amd64 (2:6.1.2+dfsg-1) ... I0927 18:56:38.016] Selecting previously unselected package libhogweed4:amd64. I0927 18:56:38.017] Preparing to unpack .../15-libhogweed4_3.3-1+b2_amd64.deb ... I0927 18:56:38.032] Unpacking libhogweed4:amd64 (3.3-1+b2) ... I0927 18:56:38.161] Selecting previously unselected package libp11-kit0:amd64. I0927 18:56:38.162] Preparing to unpack .../16-libp11-kit0_0.23.3-2_amd64.deb ... I0927 18:56:38.176] Unpacking libp11-kit0:amd64 (0.23.3-2) ... I0927 18:56:38.327] Selecting previously unselected package libtasn1-6:amd64. I0927 18:56:38.328] Preparing to unpack .../17-libtasn1-6_4.10-1.1+deb9u1_amd64.deb ... I0927 18:56:38.344] Unpacking libtasn1-6:amd64 (4.10-1.1+deb9u1) ... I0927 18:56:38.467] Selecting previously unselected package libgnutls30:amd64. I0927 18:56:38.468] Preparing to unpack .../18-libgnutls30_3.5.8-5+deb9u4_amd64.deb ... I0927 18:56:38.485] Unpacking libgnutls30:amd64 (3.5.8-5+deb9u4) ... I0927 18:56:38.694] Selecting previously unselected package libgpm2:amd64. I0927 18:56:38.695] Preparing to unpack .../19-libgpm2_1.20.4-6.2+b1_amd64.deb ... I0927 18:56:38.709] Unpacking libgpm2:amd64 (1.20.4-6.2+b1) ... I0927 18:56:38.817] Selecting previously unselected package libkeyutils1:amd64. I0927 18:56:38.818] Preparing to unpack .../20-libkeyutils1_1.5.9-9_amd64.deb ... I0927 18:56:38.832] Unpacking libkeyutils1:amd64 (1.5.9-9) ... I0927 18:56:38.938] Selecting previously unselected package libkrb5support0:amd64. I0927 18:56:38.939] Preparing to unpack .../21-libkrb5support0_1.15-1+deb9u1_amd64.deb ... I0927 18:56:38.953] Unpacking libkrb5support0:amd64 (1.15-1+deb9u1) ... I0927 18:56:39.073] Selecting previously unselected package libk5crypto3:amd64. I0927 18:56:39.074] Preparing to unpack .../22-libk5crypto3_1.15-1+deb9u1_amd64.deb ... I0927 18:56:39.092] Unpacking libk5crypto3:amd64 (1.15-1+deb9u1) ... I0927 18:56:39.221] Selecting previously unselected package libkrb5-3:amd64. I0927 18:56:39.222] Preparing to unpack .../23-libkrb5-3_1.15-1+deb9u1_amd64.deb ... I0927 18:56:39.237] Unpacking libkrb5-3:amd64 (1.15-1+deb9u1) ... I0927 18:56:39.418] Selecting previously unselected package libgssapi-krb5-2:amd64. I0927 18:56:39.419] Preparing to unpack .../24-libgssapi-krb5-2_1.15-1+deb9u1_amd64.deb ... I0927 18:56:39.437] Unpacking libgssapi-krb5-2:amd64 (1.15-1+deb9u1) ... I0927 18:56:39.549] Selecting previously unselected package libsasl2-modules-db:amd64. I0927 18:56:39.550] Preparing to unpack .../25-libsasl2-modules-db_2.1.27~101-g0780600+dfsg-3_amd64.deb ... I0927 18:56:39.564] Unpacking libsasl2-modules-db:amd64 (2.1.27~101-g0780600+dfsg-3) ... I0927 18:56:39.677] Selecting previously unselected package libsasl2-2:amd64. I0927 18:56:39.678] Preparing to unpack .../26-libsasl2-2_2.1.27~101-g0780600+dfsg-3_amd64.deb ... I0927 18:56:39.694] Unpacking libsasl2-2:amd64 (2.1.27~101-g0780600+dfsg-3) ... I0927 18:56:39.802] Selecting previously unselected package libldap-common. I0927 18:56:39.803] Preparing to unpack .../27-libldap-common_2.4.44+dfsg-5+deb9u3_all.deb ... I0927 18:56:39.821] Unpacking libldap-common (2.4.44+dfsg-5+deb9u3) ... I0927 18:56:39.942] Selecting previously unselected package libldap-2.4-2:amd64. I0927 18:56:39.943] Preparing to unpack .../28-libldap-2.4-2_2.4.44+dfsg-5+deb9u3_amd64.deb ... I0927 18:56:39.958] Unpacking libldap-2.4-2:amd64 (2.4.44+dfsg-5+deb9u3) ... I0927 18:56:40.097] Selecting previously unselected package xz-utils. I0927 18:56:40.098] Preparing to unpack .../29-xz-utils_5.2.2-1.2+b1_amd64.deb ... I0927 18:56:40.114] Unpacking xz-utils (5.2.2-1.2+b1) ... I0927 18:56:40.246] Selecting previously unselected package openssl. I0927 18:56:40.247] Preparing to unpack .../30-openssl_1.1.0k-1~deb9u1_amd64.deb ... I0927 18:56:40.261] Unpacking openssl (1.1.0k-1~deb9u1) ... I0927 18:56:40.491] Selecting previously unselected package ca-certificates. I0927 18:56:40.493] Preparing to unpack .../31-ca-certificates_20161130+nmu1+deb9u1_all.deb ... I0927 18:56:40.509] Unpacking ca-certificates (20161130+nmu1+deb9u1) ... I0927 18:56:40.689] Selecting previously unselected package libtalloc2:amd64. I0927 18:56:40.691] Preparing to unpack .../32-libtalloc2_2.1.8-1_amd64.deb ... I0927 18:56:40.706] Unpacking libtalloc2:amd64 (2.1.8-1) ... I0927 18:56:40.822] Selecting previously unselected package cifs-utils. I0927 18:56:40.824] Preparing to unpack .../33-cifs-utils_2%3a6.7-1_amd64.deb ... I0927 18:56:40.838] Unpacking cifs-utils (2:6.7-1) ... I0927 18:56:40.937] Selecting previously unselected package libavahi-common-data:amd64. I0927 18:56:40.938] Preparing to unpack .../34-libavahi-common-data_0.6.32-2_amd64.deb ... I0927 18:56:40.952] Unpacking libavahi-common-data:amd64 (0.6.32-2) ... I0927 18:56:41.103] Selecting previously unselected package libavahi-common3:amd64. I0927 18:56:41.104] Preparing to unpack .../35-libavahi-common3_0.6.32-2_amd64.deb ... I0927 18:56:41.119] Unpacking libavahi-common3:amd64 (0.6.32-2) ... I0927 18:56:41.230] Selecting previously unselected package libavahi-client3:amd64. I0927 18:56:41.231] Preparing to unpack .../36-libavahi-client3_0.6.32-2_amd64.deb ... I0927 18:56:41.246] Unpacking libavahi-client3:amd64 (0.6.32-2) ... I0927 18:56:41.359] Selecting previously unselected package libcups2:amd64. I0927 18:56:41.360] Preparing to unpack .../37-libcups2_2.2.1-8+deb9u4_amd64.deb ... I0927 18:56:41.381] Unpacking libcups2:amd64 (2.2.1-8+deb9u4) ... I0927 18:56:41.533] Selecting previously unselected package libtdb1:amd64. I0927 18:56:41.535] Preparing to unpack .../38-libtdb1_1.3.11-2_amd64.deb ... I0927 18:56:41.549] Unpacking libtdb1:amd64 (1.3.11-2) ... I0927 18:56:41.673] Selecting previously unselected package libtevent0:amd64. I0927 18:56:41.674] Preparing to unpack .../39-libtevent0_0.9.31-1_amd64.deb ... I0927 18:56:41.690] Unpacking libtevent0:amd64 (0.9.31-1) ... I0927 18:56:41.813] Selecting previously unselected package libldb1:amd64. I0927 18:56:41.814] Preparing to unpack .../40-libldb1_2%3a1.1.27-1+deb9u1_amd64.deb ... I0927 18:56:41.828] Unpacking libldb1:amd64 (2:1.1.27-1+deb9u1) ... I0927 18:56:41.955] Selecting previously unselected package libncurses5:amd64. I0927 18:56:41.956] Preparing to unpack .../41-libncurses5_6.0+20161126-1+deb9u2_amd64.deb ... I0927 18:56:41.971] Unpacking libncurses5:amd64 (6.0+20161126-1+deb9u2) ... I0927 18:56:42.100] Selecting previously unselected package libpython2.7:amd64. I0927 18:56:42.101] Preparing to unpack .../42-libpython2.7_2.7.13-2+deb9u3_amd64.deb ... I0927 18:56:42.120] Unpacking libpython2.7:amd64 (2.7.13-2+deb9u3) ... I0927 18:56:42.409] Selecting previously unselected package libsasl2-modules:amd64. I0927 18:56:42.411] Preparing to unpack .../43-libsasl2-modules_2.1.27~101-g0780600+dfsg-3_amd64.deb ... I0927 18:56:42.434] Unpacking libsasl2-modules:amd64 (2.1.27~101-g0780600+dfsg-3) ... I0927 18:56:42.572] Selecting previously unselected package python-crypto. I0927 18:56:42.573] Preparing to unpack .../44-python-crypto_2.6.1-7_amd64.deb ... I0927 18:56:42.594] Unpacking python-crypto (2.6.1-7) ... I0927 18:56:42.771] Selecting previously unselected package python-ldb. I0927 18:56:42.772] Preparing to unpack .../45-python-ldb_2%3a1.1.27-1+deb9u1_amd64.deb ... I0927 18:56:42.788] Unpacking python-ldb (2:1.1.27-1+deb9u1) ... I0927 18:56:42.919] Selecting previously unselected package python-tdb. I0927 18:56:42.920] Preparing to unpack .../46-python-tdb_1.3.11-2_amd64.deb ... I0927 18:56:42.945] Unpacking python-tdb (1.3.11-2) ... I0927 18:56:43.085] Selecting previously unselected package python-talloc. I0927 18:56:43.086] Preparing to unpack .../47-python-talloc_2.1.8-1_amd64.deb ... I0927 18:56:43.107] Unpacking python-talloc (2.1.8-1) ... I0927 18:56:43.245] Selecting previously unselected package samba-libs:amd64. I0927 18:56:43.246] Preparing to unpack .../48-samba-libs_2%3a4.5.16+dfsg-1+deb9u2_amd64.deb ... I0927 18:56:43.262] Unpacking samba-libs:amd64 (2:4.5.16+dfsg-1+deb9u2) ... I0927 18:56:44.055] Selecting previously unselected package python-samba. I0927 18:56:44.056] Preparing to unpack .../49-python-samba_2%3a4.5.16+dfsg-1+deb9u2_amd64.deb ... I0927 18:56:44.071] Unpacking python-samba (2:4.5.16+dfsg-1+deb9u2) ... I0927 18:56:44.348] Selecting previously unselected package samba-common-bin. I0927 18:56:44.350] Preparing to unpack .../50-samba-common-bin_2%3a4.5.16+dfsg-1+deb9u2_amd64.deb ... I0927 18:56:44.364] Unpacking samba-common-bin (2:4.5.16+dfsg-1+deb9u2) ... I0927 18:56:44.801] Setting up libncurses5:amd64 (6.0+20161126-1+deb9u2) ... I0927 18:56:44.853] Setting up readline-common (7.0-3) ... I0927 18:56:44.902] Setting up libtdb1:amd64 (1.3.11-2) ... I0927 18:56:44.947] Setting up libpopt0:amd64 (1.16-10+b2) ... I0927 18:56:44.996] Setting up libexpat1:amd64 (2.2.0-2+deb9u3) ... I0927 18:56:45.040] Setting up mime-support (3.60) ... I0927 18:56:45.127] Setting up libgpm2:amd64 (1.20.4-6.2+b1) ... I0927 18:56:45.170] Setting up libldap-common (2.4.44+dfsg-5+deb9u3) ... I0927 18:56:45.241] Setting up libreadline7:amd64 (7.0-3) ... I0927 18:56:45.292] Setting up libsasl2-modules-db:amd64 (2.1.27~101-g0780600+dfsg-3) ... I0927 18:56:45.343] Setting up libsasl2-2:amd64 (2.1.27~101-g0780600+dfsg-3) ... I0927 18:56:45.392] Setting up libbsd0:amd64 (0.8.3-1) ... I0927 18:56:45.441] Setting up ucf (3.0036) ... I0927 18:56:45.573] debconf: unable to initialize frontend: Dialog I0927 18:56:45.574] debconf: (TERM is not set, so the dialog frontend is not usable.) I0927 18:56:45.574] debconf: falling back to frontend: Readline I0927 18:56:45.574] debconf: unable to initialize frontend: Readline I0927 18:56:45.574] debconf: (Can't locate Term/ReadLine.pm in @INC (you may need to install the Term::ReadLine module) (@INC contains: /etc/perl /usr/local/lib/x86_64-linux-gnu/perl/5.24.1 /usr/local/share/perl/5.24.1 /usr/lib/x86_64-linux-gnu/perl5/5.24 /usr/share/perl5 /usr/lib/x86_64-linux-gnu/perl/5.24 /usr/share/perl/5.24 /usr/local/lib/site_perl /usr/lib/x86_64-linux-gnu/perl-base .) at /usr/share/perl5/Debconf/FrontEnd/Readline.pm line 7.) I0927 18:56:45.575] debconf: falling back to frontend: Teletype I0927 18:56:45.647] Setting up libtasn1-6:amd64 (4.10-1.1+deb9u1) ... I0927 18:56:45.696] Setting up libmagic-mgc (1:5.30-1+deb9u2) ... I0927 18:56:45.747] Setting up bzip2 (1.0.6-8.1) ... I0927 18:56:45.795] Setting up libmagic1:amd64 (1:5.30-1+deb9u2) ... I0927 18:56:45.876] Setting up libgmp10:amd64 (2:6.1.2+dfsg-1) ... I0927 18:56:45.924] Setting up krb5-locales (1.15-1+deb9u1) ... I0927 18:56:45.973] Processing triggers for libc-bin (2.24-11+deb9u4) ... I0927 18:56:46.026] Setting up libapparmor1:amd64 (2.11.0-3+deb9u2) ... I0927 18:56:46.095] Setting up libssl1.1:amd64 (1.1.0k-1~deb9u1) ... I0927 18:56:46.206] debconf: unable to initialize frontend: Dialog I0927 18:56:46.206] debconf: (TERM is not set, so the dialog frontend is not usable.) I0927 18:56:46.207] debconf: falling back to frontend: Readline I0927 18:56:46.207] debconf: unable to initialize frontend: Readline I0927 18:56:46.207] debconf: (Can't locate Term/ReadLine.pm in @INC (you may need to install the Term::ReadLine module) (@INC contains: /etc/perl /usr/local/lib/x86_64-linux-gnu/perl/5.24.1 /usr/local/share/perl/5.24.1 /usr/lib/x86_64-linux-gnu/perl5/5.24 /usr/share/perl5 /usr/lib/x86_64-linux-gnu/perl/5.24 /usr/share/perl/5.24 /usr/local/lib/site_perl /usr/lib/x86_64-linux-gnu/perl-base .) at /usr/share/perl5/Debconf/FrontEnd/Readline.pm line 7.) I0927 18:56:46.207] debconf: falling back to frontend: Teletype I0927 18:56:46.282] Setting up xz-utils (5.2.2-1.2+b1) ... I0927 18:56:46.318] update-alternatives: using /usr/bin/xz to provide /usr/bin/lzma (lzma) in auto mode I0927 18:56:46.349] Setting up openssl (1.1.0k-1~deb9u1) ... I0927 18:56:46.411] Setting up libsqlite3-0:amd64 (3.16.2-5+deb9u1) ... I0927 18:56:46.461] Setting up libtalloc2:amd64 (2.1.8-1) ... I0927 18:56:46.509] Setting up libffi6:amd64 (3.2.1-6) ... I0927 18:56:46.560] Setting up libkeyutils1:amd64 (1.5.9-9) ... I0927 18:56:46.616] Setting up libsasl2-modules:amd64 (2.1.27~101-g0780600+dfsg-3) ... I0927 18:56:46.722] Setting up ca-certificates (20161130+nmu1+deb9u1) ... I0927 18:56:46.842] debconf: unable to initialize frontend: Dialog I0927 18:56:46.842] debconf: (TERM is not set, so the dialog frontend is not usable.) I0927 18:56:46.842] debconf: falling back to frontend: Readline I0927 18:56:46.842] debconf: unable to initialize frontend: Readline I0927 18:56:46.843] debconf: (Can't locate Term/ReadLine.pm in @INC (you may need to install the Term::ReadLine module) (@INC contains: /etc/perl /usr/local/lib/x86_64-linux-gnu/perl/5.24.1 /usr/local/share/perl/5.24.1 /usr/lib/x86_64-linux-gnu/perl5/5.24 /usr/share/perl5 /usr/lib/x86_64-linux-gnu/perl/5.24 /usr/share/perl/5.24 /usr/local/lib/site_perl /usr/lib/x86_64-linux-gnu/perl-base .) at /usr/share/perl5/Debconf/FrontEnd/Readline.pm line 7.) I0927 18:56:46.843] debconf: falling back to frontend: Teletype I0927 18:56:47.296] Updating certificates in /etc/ssl/certs... I0927 18:56:48.895] 151 added, 0 removed; done. I0927 18:56:49.024] Setting up libpython2.7-stdlib:amd64 (2.7.13-2+deb9u3) ... I0927 18:56:49.070] Setting up libdbus-1-3:amd64 (1.10.28-0+deb9u1) ... I0927 18:56:49.121] Setting up libavahi-common-data:amd64 (0.6.32-2) ... I0927 18:56:49.174] Setting up libwbclient0:amd64 (2:4.5.16+dfsg-1+deb9u2) ... I0927 18:56:49.223] Setting up python2.7 (2.7.13-2+deb9u3) ... I0927 18:56:50.000] Setting up samba-common (2:4.5.16+dfsg-1+deb9u2) ... I0927 18:56:50.162] debconf: unable to initialize frontend: Dialog I0927 18:56:50.162] debconf: (TERM is not set, so the dialog frontend is not usable.) I0927 18:56:50.162] debconf: falling back to frontend: Readline I0927 18:56:50.162] debconf: unable to initialize frontend: Readline I0927 18:56:50.162] debconf: (Can't locate Term/ReadLine.pm in @INC (you may need to install the Term::ReadLine module) (@INC contains: /etc/perl /usr/local/lib/x86_64-linux-gnu/perl/5.24.1 /usr/local/share/perl/5.24.1 /usr/lib/x86_64-linux-gnu/perl5/5.24 /usr/share/perl5 /usr/lib/x86_64-linux-gnu/perl/5.24 /usr/share/perl/5.24 /usr/local/lib/site_perl /usr/lib/x86_64-linux-gnu/perl-base .) at /usr/share/perl5/Debconf/FrontEnd/Readline.pm line 7.) I0927 18:56:50.163] debconf: falling back to frontend: Teletype I0927 18:56:50.232] I0927 18:56:50.232] Creating config file /etc/samba/smb.conf with new version I0927 18:56:50.412] Setting up file (1:5.30-1+deb9u2) ... I0927 18:56:50.472] Setting up libkrb5support0:amd64 (1.15-1+deb9u1) ... I0927 18:56:50.542] Setting up libhogweed4:amd64 (3.3-1+b2) ... I0927 18:56:50.602] Setting up libpython-stdlib:amd64 (2.7.13-2) ... I0927 18:56:50.657] Setting up libtevent0:amd64 (0.9.31-1) ... I0927 18:56:50.717] Setting up libp11-kit0:amd64 (0.23.3-2) ... I0927 18:56:50.771] Setting up libpython2.7:amd64 (2.7.13-2+deb9u3) ... I0927 18:56:50.827] Setting up libavahi-common3:amd64 (0.6.32-2) ... I0927 18:56:50.881] Setting up dbus (1.10.28-0+deb9u1) ... I0927 18:56:51.261] invoke-rc.d: could not determine current runlevel I0927 18:56:51.264] invoke-rc.d: policy-rc.d denied execution of start. I0927 18:56:51.288] Setting up python (2.7.13-2) ... I0927 18:56:51.351] Setting up python-talloc (2.1.8-1) ... I0927 18:56:51.403] Setting up libk5crypto3:amd64 (1.15-1+deb9u1) ... I0927 18:56:51.449] Setting up python-crypto (2.6.1-7) ... I0927 18:56:51.980] Setting up python-tdb (1.3.11-2) ... I0927 18:56:52.408] Setting up libgnutls30:amd64 (3.5.8-5+deb9u4) ... I0927 18:56:52.466] Setting up libldap-2.4-2:amd64 (2.4.44+dfsg-5+deb9u3) ... I0927 18:56:52.521] Setting up libavahi-client3:amd64 (0.6.32-2) ... I0927 18:56:52.568] Setting up libkrb5-3:amd64 (1.15-1+deb9u1) ... I0927 18:56:52.622] Setting up cifs-utils (2:6.7-1) ... I0927 18:56:52.691] update-alternatives: using /usr/lib/x86_64-linux-gnu/cifs-utils/idmapwb.so to provide /etc/cifs-utils/idmap-plugin (idmap-plugin) in auto mode I0927 18:56:52.724] Setting up libldb1:amd64 (2:1.1.27-1+deb9u1) ... I0927 18:56:52.776] Setting up libgssapi-krb5-2:amd64 (1.15-1+deb9u1) ... I0927 18:56:52.841] Setting up libcups2:amd64 (2.2.1-8+deb9u4) ... I0927 18:56:52.897] Setting up python-ldb (2:1.1.27-1+deb9u1) ... I0927 18:56:53.327] Setting up samba-libs:amd64 (2:4.5.16+dfsg-1+deb9u2) ... I0927 18:56:53.407] Setting up python-samba (2:4.5.16+dfsg-1+deb9u2) ... I0927 18:56:54.090] Setting up samba-common-bin (2:4.5.16+dfsg-1+deb9u2) ... I0927 18:56:54.136] Processing triggers for libc-bin (2.24-11+deb9u4) ... I0927 18:56:54.188] Processing triggers for ca-certificates (20161130+nmu1+deb9u1) ... I0927 18:56:54.206] Updating certificates in /etc/ssl/certs... I0927 18:56:54.668] 0 added, 0 removed; done. I0927 18:56:54.668] Running hooks in /etc/ca-certificates/update.d... I0927 18:56:54.669] done. I0927 18:56:59.448] Removing intermediate container 591cf41d3594 I0927 18:56:59.448] ---> 07cf22983162 I0927 18:56:59.448] Step 3/6 : LABEL maintainers="andyzhangx" I0927 18:57:00.279] ---> Running in 71282cad9b35 I0927 18:57:01.113] Removing intermediate container 71282cad9b35 I0927 18:57:01.114] ---> f44c9b41d8a3 I0927 18:57:01.114] Step 4/6 : LABEL description="AzureFile CSI Driver" I0927 18:57:01.306] ---> Running in 241dab6a94bf I0927 18:57:02.088] Removing intermediate container 241dab6a94bf I0927 18:57:02.088] ---> 60e20097c280 I0927 18:57:02.088] Step 5/6 : COPY ./_output/azurefileplugin /azurefileplugin I0927 18:57:03.911] ---> 3c7cf2a6b5f6 I0927 18:57:03.911] Step 6/6 : ENTRYPOINT ["/azurefileplugin"] I0927 18:57:04.061] ---> Running in 11e86923d387 I0927 18:57:05.084] Removing intermediate container 11e86923d387 I0927 18:57:05.085] ---> 64a41ef69cf4 I0927 18:57:05.210] Successfully built 64a41ef69cf4 I0927 18:57:05.479] Successfully tagged k8sprowinternal.azurecr.io/azurefile-csi:v0.4.0 I0927 18:57:05.482] docker push k8sprowinternal.azurecr.io/azurefile-csi:v0.4.0 I0927 18:57:05.576] The push refers to repository [k8sprowinternal.azurecr.io/azurefile-csi] I0927 18:57:05.592] ac907aadfa7b: Preparing I0927 18:57:05.592] b64f667cfffb: Preparing I0927 18:57:05.592] 55e6b89812f3: Preparing I0927 18:57:05.829] 55e6b89812f3: Layer already exists I0927 18:57:09.173] ac907aadfa7b: Pushed I0927 18:57:12.805] b64f667cfffb: Pushed I0927 18:57:13.813] v0.4.0: digest: sha256:d232a0e4dfd80cd8e46c167bfe942b333f9e354556861f0f5d0d1904f88b644d size: 953 I0927 18:57:13.836] make[2]: Leaving directory '/go/src/github.com/kubernetes-sigs/azurefile-csi-driver' I0927 18:57:13.836] helm install charts/latest/azurefile-csi-driver -n azurefile-csi-driver --namespace kube-system --wait \ I0927 18:57:13.836] --set image.pullPolicy=IfNotPresent \ I0927 18:57:13.837] --set image.repository=k8sprowinternal.azurecr.io/azurefile-csi \ I0927 18:57:13.837] --set image.tag=v0.4.0 I0927 19:02:22.392] Error: release azurefile-csi-driver failed: timed out waiting for the condition I0927 19:02:22.395] Makefile:63: recipe for target 'e2e-bootstrap' failed I0927 19:02:22.395] make[1]: *** [e2e-bootstrap] Error 1 I0927 19:02:22.396] make[1]: Leaving directory '/go/src/github.com/kubernetes-sigs/azurefile-csi-driver' I0927 19:02:22.396] Failure [412.086 seconds] I0927 19:02:22.396] [BeforeSuite] BeforeSuite I0927 19:02:22.397] /go/src/github.com/kubernetes-sigs/azurefile-csi-driver/test/e2e/suite_test.go:54 I0927 19:02:22.397] I0927 19:02:22.397] Expected error: I0927 19:02:22.397] <*exec.ExitError | 0xc000a38020>: { I0927 19:02:22.397] ProcessState: { I0927 19:02:22.397] pid: 23279, I0927 19:02:22.398] status: 512, I0927 19:02:22.398] rusage: { I0927 19:02:22.398] Utime: {Sec: 176, Usec: 409957}, I0927 19:02:22.398] Stime: {Sec: 10, Usec: 604972}, I0927 19:02:22.398] Maxrss: 720816, I0927 19:02:22.398] Ixrss: 0, I0927 19:02:22.399] Idrss: 0, I0927 19:02:22.399] Isrss: 0, I0927 19:02:22.399] Minflt: 385816, I0927 19:02:22.399] Majflt: 66, I0927 19:02:22.399] Nswap: 0, I0927 19:02:22.400] Inblock: 264520, I0927 19:02:22.400] Oublock: 910208, I0927 19:02:22.400] Msgsnd: 0, I0927 19:02:22.400] Msgrcv: 0, I0927 19:02:22.400] Nsignals: 0, I0927 19:02:22.401] Nvcsw: 296681, I0927 19:02:22.401] Nivcsw: 176552, I0927 19:02:22.401] }, I0927 19:02:22.401] }, I0927 19:02:22.401] Stderr: nil, I0927 19:02:22.402] } I0927 19:02:22.402] exit status 2 I0927 19:02:22.402] not to have occurred I0927 19:02:22.402] I0927 19:02:22.402] /go/src/github.com/kubernetes-sigs/azurefile-csi-driver/test/e2e/suite_test.go:93 I0927 19:02:22.403] ------------------------------ I0927 19:02:22.403] 2019/09/27 19:02:22 Uninstalling Azure File CSI Driver... I0927 19:02:22.403] make[1]: Entering directory '/go/src/github.com/kubernetes-sigs/azurefile-csi-driver' I0927 19:02:22.403] helm delete --purge azurefile-csi-driver I0927 19:02:26.471] release "azurefile-csi-driver" deleted I0927 19:02:26.473] make[1]: Leaving directory '/go/src/github.com/kubernetes-sigs/azurefile-csi-driver' I0927 19:02:26.473] 2019/09/27 19:02:26 Azure File CSI Driver uninstalled I0927 19:02:26.474] I0927 19:02:26.474] Ran 8 of 0 Specs in 416.164 seconds I0927 19:02:26.474] FAIL! -- 0 Passed | 8 Failed | 0 Pending | 0 Skipped I0927 19:02:26.474] --- FAIL: TestE2E (416.16s) I0927 19:02:26.474] FAIL I0927 19:02:26.476] FAIL github.com/kubernetes-sigs/azurefile-csi-driver/test/e2e 416.216s I0927 19:02:26.666] Makefile:58: recipe for target 'e2e-test' failed W0927 19:02:26.766] make: *** [e2e-test] Error 1 W0927 19:02:26.767] 2019/09/27 19:02:26 process.go:155: Step 'make e2e-test' finished in 8m31.010660144s W0927 19:02:26.767] 2019/09/27 19:02:26 azure.go:899: Deleting resource group: kubetest-2db0ca2d-e154-11e9-81f6-0242b042f06d. W0927 19:14:36.978] 2019/09/27 19:14:36 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml. W0927 19:14:36.978] 2019/09/27 19:14:36 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}" W0927 19:14:37.668] 2019/09/27 19:14:37 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 689.783427ms W0927 19:14:37.668] 2019/09/27 19:14:37 main.go:319: Something went wrong: encountered 1 errors: [error during make e2e-test: exit status 2] W0927 19:14:37.674] Traceback (most recent call last): W0927 19:14:37.674] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in W0927 19:14:37.674] main(parse_args()) W0927 19:14:37.674] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main W0927 19:14:37.675] mode.start(runner_args) W0927 19:14:37.675] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start W0927 19:14:37.675] check_env(env, self.command, *args) W0927 19:14:37.675] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env W0927 19:14:37.675] subprocess.check_call(cmd, env=env) W0927 19:14:37.675] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W0927 19:14:37.675] raise CalledProcessError(retcode, cmd) W0927 19:14:37.676] 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')' returned non-zero exit status 1 E0927 19:14:37.684] Command failed I0927 19:14:37.684] process 691 exited with code 1 after 49.2m E0927 19:14:37.684] FAIL: pull-azurefile-csi-driver-e2e I0927 19:14:37.685] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0927 19:14:38.493] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I0927 19:14:38.521] process 32551 exited with code 0 after 0.0m I0927 19:14:38.521] Call: gcloud config get-value account I0927 19:14:38.763] process 32563 exited with code 0 after 0.0m I0927 19:14:38.763] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I0927 19:14:38.763] Upload result and artifacts... I0927 19:14:38.764] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177650160998551552 I0927 19:14:38.764] Call: gsutil ls gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177650160998551552/artifacts W0927 19:14:39.941] CommandException: One or more URLs matched no objects. E0927 19:14:40.019] Command failed I0927 19:14:40.019] process 32575 exited with code 1 after 0.0m W0927 19:14:40.020] Remote dir gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177650160998551552/artifacts not exist yet I0927 19:14:40.020] 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_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177650160998551552/artifacts I0927 19:14:41.581] process 32717 exited with code 0 after 0.0m I0927 19:14:41.582] Call: git rev-parse HEAD I0927 19:14:41.585] process 796 exited with code 0 after 0.0m I0927 19:14:41.585] Call: git rev-parse HEAD I0927 19:14:41.589] process 797 exited with code 0 after 0.0m I0927 19:14:41.590] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-e2e/jobResultsCache.json I0927 19:14:42.560] process 798 exited with code 0 after 0.0m I0927 19:14:42.561] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-e2e/jobResultsCache.json#1569608514263134' I0927 19:14:43.751] process 940 exited with code 0 after 0.0m I0927 19:14:43.752] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1569608514263134 cp /tmp/gsutil_XJbo3u gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-e2e/jobResultsCache.json I0927 19:14:45.105] process 1084 exited with code 0 after 0.0m I0927 19:14:45.105] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/jobResultsCache.json I0927 19:14:46.033] process 1262 exited with code 0 after 0.0m I0927 19:14:46.033] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/jobResultsCache.json#1569608517598229' I0927 19:14:47.310] process 1404 exited with code 0 after 0.0m I0927 19:14:47.311] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1569608517598229 cp /tmp/gsutil_MDJgwq gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/jobResultsCache.json I0927 19:14:48.773] process 1548 exited with code 0 after 0.0m I0927 19:14:48.774] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_HtDIrj gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177650160998551552/finished.json I0927 19:14:50.065] process 1726 exited with code 0 after 0.0m I0927 19:14:50.066] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_QN3Ahe gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/latest-build.txt I0927 19:14:51.449] process 1904 exited with code 0 after 0.0m I0927 19:14:51.450] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_Z5uyA2 gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-e2e/latest-build.txt I0927 19:14:52.891] process 2082 exited with code 0 after 0.0m