W1025 16:59:39.545] **************************************************************************
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
**************************************************************************
I1025 16:59:39.546] Args: --job=ci-containerd-node-e2e-features --service-account=/etc/service-account/service-account.json --upload=gs://kubernetes-jenkins/logs --root=/go/src --repo=k8s.io/kubernetes --repo=github.com/containerd/cri=master --timeout=90 --scenario=kubernetes_e2e -- --node-args=--image-config-file=/workspace/test-infra/jobs/e2e_node/containerd/containerd-master/image-config.yaml --deployment=node --gcp-project=cri-containerd-node-e2e --gcp-zone=us-central1-b '--node-test-args=--container-runtime=remote --container-runtime-endpoint=unix:///run/containerd/containerd.sock --container-runtime-process-name=/home/containerd/usr/local/bin/containerd --container-runtime-pid-file= --kubelet-flags="--cgroups-per-qos=true --cgroup-root=/ --runtime-cgroups=/system.slice/containerd.service" --extra-log="{\"name\": \"containerd.log\", \"journalctl\": [\"-u\", \"containerd\"]}"' --node-tests=true --provider=gce '--test_args=--nodes=8 --focus="\[NodeFeature:.+\]" --skip="\[Flaky\]|\[Serial\]"' --timeout=65m
I1025 16:59:39.547] Bootstrap ci-containerd-node-e2e-features...
I1025 16:59:39.549] Builder: 6e3fbad2-16e3-11eb-b256-6ee25ea2e440
I1025 16:59:39.550] Image: gcr.io/k8s-testimages/kubekins-e2e:v20201021-a62ce8a-master
I1025 16:59:39.550] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320409631381524480
I1025 16:59:39.550] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1025 16:59:40.282] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1025 16:59:40.426] process 35 exited with code 0 after 0.0m
I1025 16:59:40.426] Call:  gcloud config get-value account
I1025 16:59:41.156] process 48 exited with code 0 after 0.0m
I1025 16:59:41.157] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I1025 16:59:41.157] Root: /go/src
I1025 16:59:41.157] cd to /go/src
I1025 16:59:41.157] Checkout: /go/src/k8s.io/kubernetes master to /go/src/k8s.io/kubernetes
I1025 16:59:41.157] Call:  git init k8s.io/kubernetes
I1025 16:59:41.166] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/
I1025 16:59:41.167] process 61 exited with code 0 after 0.0m
I1025 16:59:41.167] Call:  git config --local user.name 'K8S Bootstrap'
I1025 16:59:41.172] process 62 exited with code 0 after 0.0m
I1025 16:59:41.173] Call:  git config --local user.email k8s_bootstrap@localhost
I1025 16:59:41.177] process 63 exited with code 0 after 0.0m
I1025 16:59:41.178] Call:  git fetch --quiet --tags https://github.com/kubernetes/kubernetes master
I1025 17:00:53.868] process 64 exited with code 0 after 1.2m
I1025 17:00:53.868] Call:  git checkout -B test FETCH_HEAD
W1025 17:00:57.678] Switched to a new branch 'test'
I1025 17:00:57.714] process 75 exited with code 0 after 0.1m
I1025 17:00:57.714] Call:  git show -s --format=format:%ct HEAD
I1025 17:00:57.732] process 76 exited with code 0 after 0.0m
I1025 17:00:57.732] Checkout: /go/src/github.com/containerd/cri master to /go/src/github.com/containerd/cri
I1025 17:00:57.732] Call:  git init github.com/containerd/cri
I1025 17:00:57.741] Initialized empty Git repository in /go/src/github.com/containerd/cri/.git/
I1025 17:00:57.741] process 77 exited with code 0 after 0.0m
I1025 17:00:57.741] Call:  git config --local user.name 'K8S Bootstrap'
I1025 17:00:57.747] process 78 exited with code 0 after 0.0m
I1025 17:00:57.748] Call:  git config --local user.email k8s_bootstrap@localhost
I1025 17:00:57.756] process 79 exited with code 0 after 0.0m
I1025 17:00:57.756] Call:  git fetch --quiet --tags https://github.com/containerd/cri master
I1025 17:00:59.609] process 80 exited with code 0 after 0.0m
I1025 17:00:59.609] Call:  git checkout -B test FETCH_HEAD
W1025 17:01:00.141] Switched to a new branch 'test'
I1025 17:01:00.155] process 91 exited with code 0 after 0.0m
I1025 17:01:00.155] Call:  git show -s --format=format:%ct HEAD
I1025 17:01:00.161] process 92 exited with code 0 after 0.0m
I1025 17:01:00.162] Configure environment...
I1025 17:01:00.162] Call:  git show -s --format=format:%ct HEAD
I1025 17:01:00.177] process 93 exited with code 0 after 0.0m
I1025 17:01:00.178] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1025 17:01:00.944] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1025 17:01:01.346] process 94 exited with code 0 after 0.0m
I1025 17:01:01.346] Call:  gcloud config get-value account
I1025 17:01:02.045] process 107 exited with code 0 after 0.0m
I1025 17:01:02.046] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I1025 17:01:02.046] 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
'
I1025 17:01:02.585] process 120 exited with code 0 after 0.0m
I1025 17:01:02.586] Start 1320409631381524480 at v1.20.0-alpha.3.114+5935fcd704fe89...
I1025 17:01:02.589] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_Itg1NM gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320409631381524480/started.json
I1025 17:01:04.998] process 153 exited with code 0 after 0.0m
I1025 17:01:04.999] Call:  /workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py --node-args=--image-config-file=/workspace/test-infra/jobs/e2e_node/containerd/containerd-master/image-config.yaml --deployment=node --gcp-project=cri-containerd-node-e2e --gcp-zone=us-central1-b '--node-test-args=--container-runtime=remote --container-runtime-endpoint=unix:///run/containerd/containerd.sock --container-runtime-process-name=/home/containerd/usr/local/bin/containerd --container-runtime-pid-file= --kubelet-flags="--cgroups-per-qos=true --cgroup-root=/ --runtime-cgroups=/system.slice/containerd.service" --extra-log="{\"name\": \"containerd.log\", \"journalctl\": [\"-u\", \"containerd\"]}"' --node-tests=true --provider=gce '--test_args=--nodes=8 --focus="\[NodeFeature:.+\]" --skip="\[Flaky\]|\[Serial\]"' --timeout=65m
W1025 17:01:05.061] starts with local mode
W1025 17:01:05.062] Environment:
W1025 17:01:05.062] ARTIFACTS=/workspace/_artifacts
W1025 17:01:05.062] AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa
W1025 17:01:05.062] AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub
W1025 17:01:05.062] BAZEL_REMOTE_CACHE_ENABLED=false
W1025 17:01:05.063] BAZEL_VERSION=2.2.0
W1025 17:01:05.063] BOOTSTRAP_MIGRATION=yes
W1025 17:01:05.063] BOSKOS_METRICS_PORT=tcp://10.63.252.110:9090
W1025 17:01:05.063] BOSKOS_METRICS_PORT_9090_TCP=tcp://10.63.252.110:9090
W1025 17:01:05.063] BOSKOS_METRICS_PORT_9090_TCP_ADDR=10.63.252.110
W1025 17:01:05.064] BOSKOS_METRICS_PORT_9090_TCP_PORT=9090
W1025 17:01:05.064] BOSKOS_METRICS_PORT_9090_TCP_PROTO=tcp
W1025 17:01:05.064] BOSKOS_METRICS_SERVICE_HOST=10.63.252.110
W1025 17:01:05.064] BOSKOS_METRICS_SERVICE_PORT=9090
W1025 17:01:05.064] BOSKOS_METRICS_SERVICE_PORT_METRICS=9090
W1025 17:01:05.064] BOSKOS_PORT=tcp://10.63.250.132:80
W1025 17:01:05.064] BOSKOS_PORT_80_TCP=tcp://10.63.250.132:80
W1025 17:01:05.065] BOSKOS_PORT_80_TCP_ADDR=10.63.250.132
W1025 17:01:05.065] BOSKOS_PORT_80_TCP_PORT=80
W1025 17:01:05.065] BOSKOS_PORT_80_TCP_PROTO=tcp
W1025 17:01:05.065] BOSKOS_SERVICE_HOST=10.63.250.132
W1025 17:01:05.065] BOSKOS_SERVICE_PORT=80
W1025 17:01:05.065] BOSKOS_SERVICE_PORT_DEFAULT=80
W1025 17:01:05.065] BUILD_ID=1320409631381524480
W1025 17:01:05.065] BUILD_NUMBER=1320409631381524480
W1025 17:01:05.066] CI=true
W1025 17:01:05.066] CLOUDSDK_COMPONENT_MANAGER_DISABLE_UPDATE_CHECK=true
W1025 17:01:05.066] CLOUDSDK_CONFIG=/go/src/k8s.io/kubernetes/.config/gcloud
W1025 17:01:05.066] CLOUDSDK_CORE_DISABLE_PROMPTS=1
W1025 17:01:05.066] CLOUDSDK_EXPERIMENTAL_FAST_COMPONENT_UPDATE=false
W1025 17:01:05.066] DOCKER_IN_DOCKER_ENABLED=false
W1025 17:01:05.067] DOCKER_IN_DOCKER_IPV6_ENABLED=false
W1025 17:01:05.067] E2E_GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json
W1025 17:01:05.067] GCS_ARTIFACTS_DIR=gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320409631381524480/artifacts
W1025 17:01:05.067] GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json
W1025 17:01:05.067] GOOGLE_APPLICATION_CREDENTIALS_DEPRECATED=Migrate to workload identity, contact sig-testing
W1025 17:01:05.067] GOPATH=/go
W1025 17:01:05.068] GOPROXY=https://proxy.golang.org
W1025 17:01:05.068] GO_TARBALL=go1.15.2.linux-amd64.tar.gz
W1025 17:01:05.068] HOME=/workspace
W1025 17:01:05.068] HOSTNAME=6e3fbad2-16e3-11eb-b256-6ee25ea2e440
W1025 17:01:05.068] IMAGE=gcr.io/k8s-testimages/kubekins-e2e:v20201021-a62ce8a-master
W1025 17:01:05.068] INSTANCE_PREFIX=bootstrap-e2e
W1025 17:01:05.068] JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/workspace/.ssh/google_compute_engine
W1025 17:01:05.069] JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/workspace/.ssh/google_compute_engine.pub
W1025 17:01:05.069] JOB_NAME=ci-containerd-node-e2e-features
W1025 17:01:05.069] JOB_SPEC={"type":"periodic","job":"ci-containerd-node-e2e-features","buildid":"1320409631381524480","prowjobid":"6e3fbad2-16e3-11eb-b256-6ee25ea2e440"}
W1025 17:01:05.069] JOB_TYPE=periodic
W1025 17:01:05.069] KUBERNETES_PORT=tcp://10.63.240.1:443
W1025 17:01:05.069] KUBERNETES_PORT_443_TCP=tcp://10.63.240.1:443
W1025 17:01:05.070] KUBERNETES_PORT_443_TCP_ADDR=10.63.240.1
W1025 17:01:05.070] KUBERNETES_PORT_443_TCP_PORT=443
W1025 17:01:05.070] KUBERNETES_PORT_443_TCP_PROTO=tcp
W1025 17:01:05.070] KUBERNETES_SERVICE_HOST=10.63.240.1
W1025 17:01:05.070] KUBERNETES_SERVICE_PORT=443
W1025 17:01:05.070] KUBERNETES_SERVICE_PORT_HTTPS=443
W1025 17:01:05.070] KUBETEST_IN_DOCKER=true
W1025 17:01:05.070] KUBETEST_MANUAL_DUMP=y
W1025 17:01:05.071] KUBE_AWS_INSTANCE_PREFIX=bootstrap-e2e
W1025 17:01:05.071] KUBE_GCE_INSTANCE_PREFIX=bootstrap-e2e
W1025 17:01:05.071] LOG_DUMP_SCRIPT_PATH=/workspace/log-dump.sh
W1025 17:01:05.071] NODE_NAME=6e3fbad2-16e3-11eb-b256-6ee25ea2e440
W1025 17:01:05.071] 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
W1025 17:01:05.071] PROW_JOB_ID=6e3fbad2-16e3-11eb-b256-6ee25ea2e440
W1025 17:01:05.072] PWD=/workspace
W1025 17:01:05.072] SHLVL=2
W1025 17:01:05.072] SOURCE_DATE_EPOCH=1603511220
W1025 17:01:05.072] TERM=xterm
W1025 17:01:05.072] USER=prow
W1025 17:01:05.072] WORKSPACE=/workspace
W1025 17:01:05.072] _=./test-infra/jenkins/bootstrap.py
W1025 17:01:05.073] Run: ('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--up', '--down', '--test', '--deployment=node', '--provider=gce', '--cluster=bootstrap-e2e', '--gcp-network=bootstrap-e2e', '--node-args=--image-config-file=/workspace/test-infra/jobs/e2e_node/containerd/containerd-master/image-config.yaml', '--gcp-project=cri-containerd-node-e2e', '--gcp-zone=us-central1-b', '--node-test-args=--container-runtime=remote --container-runtime-endpoint=unix:///run/containerd/containerd.sock --container-runtime-process-name=/home/containerd/usr/local/bin/containerd --container-runtime-pid-file= --kubelet-flags="--cgroups-per-qos=true --cgroup-root=/ --runtime-cgroups=/system.slice/containerd.service" --extra-log="{\\"name\\": \\"containerd.log\\", \\"journalctl\\": [\\"-u\\", \\"containerd\\"]}"', '--node-tests=true', '--test_args=--nodes=8 --focus="\\[NodeFeature:.+\\]" --skip="\\[Flaky\\]|\\[Serial\\]"', '--timeout=65m')
W1025 17:01:05.497] 2020/10/25 17:01:05 Warning: Couldn't find directory src/sigs.k8s.io/cloud-provider-azure under any of GOPATH /go, defaulting to /go/src/k8s.io/cloud-provider-azure
W1025 17:01:05.501] 2020/10/25 17:01:05 main.go:329: Limiting testing to 1h5m0s
W1025 17:01:05.501] 2020/10/25 17:01:05 process.go:153: Running: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1025 17:01:06.243] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
W1025 17:01:06.372] 2020/10/25 17:01:06 process.go:155: Step 'gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json' finished in 870.894608ms
W1025 17:01:06.373] 2020/10/25 17:01:06 process.go:153: Running: gcloud config set project cri-containerd-node-e2e
W1025 17:01:06.934] Updated property [core/project].
W1025 17:01:07.214] 2020/10/25 17:01:07 process.go:155: Step 'gcloud config set project cri-containerd-node-e2e' finished in 841.4241ms
W1025 17:01:07.214] 2020/10/25 17:01:07 main.go:779: Checking existing of GCP ssh keys...
W1025 17:01:07.214] 2020/10/25 17:01:07 main.go:789: Checking presence of public key in cri-containerd-node-e2e
W1025 17:01:07.214] 2020/10/25 17:01:07 process.go:153: Running: gcloud compute --project=cri-containerd-node-e2e project-info describe
W1025 17:01:09.366] 2020/10/25 17:01:09 process.go:155: Step 'gcloud compute --project=cri-containerd-node-e2e project-info describe' finished in 2.151238503s
W1025 17:01:09.368] 2020/10/25 17:01:09 node.go:62: Noop - Node KubectlCommand()
W1025 17:01:09.369] 2020/10/25 17:01:09 node.go:53: Noop - Node Down()
W1025 17:01:09.369] 2020/10/25 17:01:09 node.go:34: Noop - Node Up()
W1025 17:01:09.370] 2020/10/25 17:01:09 node.go:48: Noop - Node TestSetup()
W1025 17:01:09.370] 2020/10/25 17:01:09 e2e.go:560: cwd : /go/src/k8s.io/kubernetes
W1025 17:01:09.372] 2020/10/25 17:01:09 process.go:153: Running: go run /go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go --cleanup --logtostderr --vmodule=*=4 --ssh-env=gce --results-dir=/workspace/_artifacts --project=cri-containerd-node-e2e --zone=us-central1-b --ssh-user=prow --ssh-key=/workspace/.ssh/google_compute_engine --ginkgo-flags=--nodes=8 --focus="\[NodeFeature:.+\]" --skip="\[Flaky\]|\[Serial\]" --test_args=--container-runtime=remote --container-runtime-endpoint=unix:///run/containerd/containerd.sock --container-runtime-process-name=/home/containerd/usr/local/bin/containerd --container-runtime-pid-file= --kubelet-flags="--cgroups-per-qos=true --cgroup-root=/ --runtime-cgroups=/system.slice/containerd.service" --extra-log="{\"name\": \"containerd.log\", \"journalctl\": [\"-u\", \"containerd\"]}" --test-timeout=1h5m0s --image-config-file=/workspace/test-infra/jobs/e2e_node/containerd/containerd-master/image-config.yaml
W1025 17:02:22.402] I1025 17:02:22.401377    4725 run_remote.go:501] found images [{creationTime:{wall:749000000 ext:63738746175 loc:0xc000474150} name:cos-85-13310-1041-24} {creationTime:{wall:948000000 ext:63738132225 loc:0xc0004740e0} name:cos-85-13310-1041-17} {creationTime:{wall:951000000 ext:63737797521 loc:0xc000474070} name:cos-85-13310-1041-14} {creationTime:{wall:449000000 ext:63736586944 loc:0xc0004741c0} name:cos-85-13310-1041-9} {creationTime:{wall:241000000 ext:63736054782 loc:0xc0004745b0} name:cos-rc-85-13310-1041-1} {creationTime:{wall:578000000 ext:63734848446 loc:0xc000474540} name:cos-rc-85-13310-1040-0} {creationTime:{wall:877000000 ext:63734695858 loc:0xc0004744d0} name:cos-rc-85-13310-1037-0} {creationTime:{wall:580000000 ext:63734153893 loc:0xc000474460} name:cos-rc-85-13310-1034-0} {creationTime:{wall:643000000 ext:63733310807 loc:0xc0004743f0} name:cos-rc-85-13310-1032-0} {creationTime:{wall:981000000 ext:63732438114 loc:0xc000474380} name:cos-rc-85-13310-1028-0} {creationTime:{wall:757000000 ext:63731743261 loc:0xc000474310} name:cos-rc-85-13310-1023-0} {creationTime:{wall:875000000 ext:63731230865 loc:0xc0004742a0} name:cos-rc-85-13310-1019-0} {creationTime:{wall:861000000 ext:63730454305 loc:0xc000474230} name:cos-rc-85-13310-1015-0} {creationTime:{wall:382000000 ext:63729939006 loc:0xc000474620} name:cos-rc-85-13310-12-0}] based on regex "" and family "cos-85-lts" in project "cos-cloud"
W1025 17:02:22.403] I1025 17:02:22.401475    4725 run_remote.go:403] parsing instance metadata: "user-data</go/src/github.com/containerd/containerd/test/e2e_node/init.yaml,containerd-configure-sh</go/src/github.com/containerd/containerd/cluster/gce/configure.sh,containerd-extra-init-sh</go/src/github.com/containerd/containerd/test/e2e_node/gci-init.sh,containerd-env</workspace/test-infra/jobs/e2e_node/containerd/containerd-master/env,gci-update-strategy=update_disabled"
W1025 17:02:22.407] F1025 17:02:22.401556    4725 run_remote.go:788] Failed to read metadata file "/go/src/github.com/containerd/containerd/test/e2e_node/init.yaml": open /go/src/github.com/containerd/containerd/test/e2e_node/init.yaml: no such file or directory
W1025 17:02:22.407] goroutine 1 [running]:
W1025 17:02:22.407] k8s.io/klog/v2.stacks(0xc00012e001, 0xc00013e9a0, 0xf3, 0x141)
W1025 17:02:22.408] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:996 +0xb9
W1025 17:02:22.408] k8s.io/klog/v2.(*loggingT).output(0x27ba960, 0xc000000003, 0x0, 0x0, 0xc000474770, 0x271c802, 0xd, 0x314, 0x0)
W1025 17:02:22.409] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:945 +0x191
W1025 17:02:22.409] k8s.io/klog/v2.(*loggingT).printf(0x27ba960, 0x3, 0x0, 0x0, 0x1b1529c, 0x23, 0xc0008270e0, 0x2, 0x2)
W1025 17:02:22.410] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:733 +0x17a
W1025 17:02:22.410] k8s.io/klog/v2.Fatalf(...)
W1025 17:02:22.411] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:1456
W1025 17:02:22.411] main.parseInstanceMetadata(0xc00023a480, 0x178, 0x0)
W1025 17:02:22.411] 	/go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go:788 +0x46f
W1025 17:02:22.412] main.getImageMetadata(0xc00023a480, 0x178, 0x0)
W1025 17:02:22.412] 	/go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go:404 +0xe6
W1025 17:02:22.413] main.main()
W1025 17:02:22.413] 	/go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go:257 +0x68f
W1025 17:02:22.414] 
W1025 17:02:22.414] goroutine 19 [chan receive]:
W1025 17:02:22.414] k8s.io/klog/v2.(*loggingT).flushDaemon(0x27ba960)
W1025 17:02:22.415] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:1131 +0x8b
W1025 17:02:22.415] created by k8s.io/klog/v2.init.0
W1025 17:02:22.416] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:416 +0xd8
W1025 17:02:22.416] 
W1025 17:02:22.416] goroutine 11 [select]:
W1025 17:02:22.417] go.opencensus.io/stats/view.(*worker).start(0xc0000cb360)
W1025 17:02:22.417] 	/go/src/k8s.io/kubernetes/vendor/go.opencensus.io/stats/view/worker.go:154 +0x105
W1025 17:02:22.417] created by go.opencensus.io/stats/view.init.0
W1025 17:02:22.418] 	/go/src/k8s.io/kubernetes/vendor/go.opencensus.io/stats/view/worker.go:32 +0x57
W1025 17:02:22.418] 
W1025 17:02:22.418] goroutine 67 [IO wait]:
W1025 17:02:22.419] internal/poll.runtime_pollWait(0x7f11f9bfa3f8, 0x72, 0x1cd0a00)
W1025 17:02:22.419] 	/usr/local/go/src/runtime/netpoll.go:220 +0x55
W1025 17:02:22.419] internal/poll.(*pollDesc).wait(0xc000180a18, 0x72, 0xc0001eb000, 0xf69, 0xf69)
W1025 17:02:22.419] 	/usr/local/go/src/internal/poll/fd_poll_runtime.go:87 +0x45
W1025 17:02:22.420] internal/poll.(*pollDesc).waitRead(...)
W1025 17:02:22.420] 	/usr/local/go/src/internal/poll/fd_poll_runtime.go:92
W1025 17:02:22.421] internal/poll.(*FD).Read(0xc000180a00, 0xc0001eb000, 0xf69, 0xf69, 0x0, 0x0, 0x0)
W1025 17:02:22.421] 	/usr/local/go/src/internal/poll/fd_unix.go:159 +0x1b1
W1025 17:02:22.422] net.(*netFD).Read(0xc000180a00, 0xc0001eb000, 0xf69, 0xf69, 0x203000, 0x62647b, 0xc00028d2e0)
W1025 17:02:22.422] 	/usr/local/go/src/net/fd_posix.go:55 +0x4f
W1025 17:02:22.422] net.(*conn).Read(0xc0000b8c18, 0xc0001eb000, 0xf69, 0xf69, 0x0, 0x0, 0x0)
W1025 17:02:22.423] 	/usr/local/go/src/net/net.go:182 +0x8e
W1025 17:02:22.423] crypto/tls.(*atLeastReader).Read(0xc0004c0080, 0xc0001eb000, 0xf69, 0xf69, 0x22, 0xc36, 0xc0003a3730)
W1025 17:02:22.424] 	/usr/local/go/src/crypto/tls/conn.go:779 +0x62
W1025 17:02:22.424] bytes.(*Buffer).ReadFrom(0xc00028d400, 0x1ccdec0, 0xc0004c0080, 0x40d085, 0x18b3c60, 0x1a78700)
W1025 17:02:22.424] 	/usr/local/go/src/bytes/buffer.go:204 +0xb1
W1025 17:02:22.425] crypto/tls.(*Conn).readFromUntil(0xc00028d180, 0x1ccf600, 0xc0000b8c18, 0x5, 0xc0000b8c18, 0x11)
W1025 17:02:22.426] 	/usr/local/go/src/crypto/tls/conn.go:801 +0xf3
W1025 17:02:22.426] crypto/tls.(*Conn).readRecordOrCCS(0xc00028d180, 0x0, 0x0, 0x11)
W1025 17:02:22.426] 	/usr/local/go/src/crypto/tls/conn.go:608 +0x115
W1025 17:02:22.426] crypto/tls.(*Conn).readRecord(...)
W1025 17:02:22.427] 	/usr/local/go/src/crypto/tls/conn.go:576
W1025 17:02:22.427] crypto/tls.(*Conn).Read(0xc00028d180, 0xc0000d8000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
W1025 17:02:22.428] 	/usr/local/go/src/crypto/tls/conn.go:1252 +0x15f
W1025 17:02:22.429] bufio.(*Reader).Read(0xc0004530e0, 0xc00003d1b8, 0x9, 0x9, 0x0, 0xc0003a3d20, 0x6e66b4)
W1025 17:02:22.429] 	/usr/local/go/src/bufio/bufio.go:227 +0x222
W1025 17:02:22.429] io.ReadAtLeast(0x1ccdd40, 0xc0004530e0, 0xc00003d1b8, 0x9, 0x9, 0x9, 0xc000483c60, 0xc00016e000, 0x11)
W1025 17:02:22.430] 	/usr/local/go/src/io/io.go:314 +0x87
W1025 17:02:22.430] io.ReadFull(...)
W1025 17:02:22.430] 	/usr/local/go/src/io/io.go:333
W1025 17:02:22.431] net/http.http2readFrameHeader(0xc00003d1b8, 0x9, 0x9, 0x1ccdd40, 0xc0004530e0, 0x0, 0x0, 0x0, 0x0)
W1025 17:02:22.431] 	/usr/local/go/src/net/http/h2_bundle.go:1477 +0x89
W1025 17:02:22.432] net/http.(*http2Framer).ReadFrame(0xc00003d180, 0xc0007180a0, 0x0, 0x0, 0x0)
W1025 17:02:22.432] 	/usr/local/go/src/net/http/h2_bundle.go:1735 +0xa5
W1025 17:02:22.433] net/http.(*http2clientConnReadLoop).run(0xc0003a3fa8, 0x1, 0x0)
W1025 17:02:22.433] 	/usr/local/go/src/net/http/h2_bundle.go:8240 +0x8d
W1025 17:02:22.433] net/http.(*http2ClientConn).readLoop(0xc0000a2780)
W1025 17:02:22.434] 	/usr/local/go/src/net/http/h2_bundle.go:8168 +0x6f
W1025 17:02:22.434] created by net/http.(*http2Transport).newClientConn
W1025 17:02:22.434] 	/usr/local/go/src/net/http/h2_bundle.go:7173 +0x66e
W1025 17:02:22.435] 
W1025 17:02:22.435] goroutine 85 [IO wait]:
W1025 17:02:22.435] internal/poll.runtime_pollWait(0x7f11f9bfa318, 0x72, 0x1cd0a00)
W1025 17:02:22.436] 	/usr/local/go/src/runtime/netpoll.go:220 +0x55
W1025 17:02:22.436] internal/poll.(*pollDesc).wait(0xc000299a18, 0x72, 0xc000460000, 0xf69, 0xf69)
W1025 17:02:22.437] 	/usr/local/go/src/internal/poll/fd_poll_runtime.go:87 +0x45
W1025 17:02:22.437] internal/poll.(*pollDesc).waitRead(...)
W1025 17:02:22.437] 	/usr/local/go/src/internal/poll/fd_poll_runtime.go:92
W1025 17:02:22.438] internal/poll.(*FD).Read(0xc000299a00, 0xc000460000, 0xf69, 0xf69, 0x0, 0x0, 0x0)
W1025 17:02:22.438] 	/usr/local/go/src/internal/poll/fd_unix.go:159 +0x1b1
W1025 17:02:22.439] net.(*netFD).Read(0xc000299a00, 0xc000460000, 0xf69, 0xf69, 0x203000, 0x62647b, 0xc0001204e0)
W1025 17:02:22.439] 	/usr/local/go/src/net/fd_posix.go:55 +0x4f
W1025 17:02:22.439] net.(*conn).Read(0xc0000b8050, 0xc000460000, 0xf69, 0xf69, 0x0, 0x0, 0x0)
W1025 17:02:22.440] 	/usr/local/go/src/net/net.go:182 +0x8e
W1025 17:02:22.440] crypto/tls.(*atLeastReader).Read(0xc0005982e0, 0xc000460000, 0xf69, 0xf69, 0x22, 0xe7d, 0xc000083730)
W1025 17:02:22.441] 	/usr/local/go/src/crypto/tls/conn.go:779 +0x62
W1025 17:02:22.441] bytes.(*Buffer).ReadFrom(0xc000120600, 0x1ccdec0, 0xc0005982e0, 0x40d085, 0x18b3c60, 0x1a78700)
W1025 17:02:22.441] 	/usr/local/go/src/bytes/buffer.go:204 +0xb1
W1025 17:02:22.441] crypto/tls.(*Conn).readFromUntil(0xc000120380, 0x1ccf600, 0xc0000b8050, 0x5, 0xc0000b8050, 0x11)
W1025 17:02:22.442] 	/usr/local/go/src/crypto/tls/conn.go:801 +0xf3
W1025 17:02:22.442] crypto/tls.(*Conn).readRecordOrCCS(0xc000120380, 0x0, 0x0, 0x11)
W1025 17:02:22.443] 	/usr/local/go/src/crypto/tls/conn.go:608 +0x115
W1025 17:02:22.443] crypto/tls.(*Conn).readRecord(...)
W1025 17:02:22.444] 	/usr/local/go/src/crypto/tls/conn.go:576
W1025 17:02:22.445] crypto/tls.(*Conn).Read(0xc000120380, 0xc0005b4000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
W1025 17:02:22.445] 	/usr/local/go/src/crypto/tls/conn.go:1252 +0x15f
W1025 17:02:22.446] bufio.(*Reader).Read(0xc0002fd6e0, 0xc000462498, 0x9, 0x9, 0x0, 0xc000083d20, 0x6e66b4)
W1025 17:02:22.447] 	/usr/local/go/src/bufio/bufio.go:227 +0x222
W1025 17:02:22.447] io.ReadAtLeast(0x1ccdd40, 0xc0002fd6e0, 0xc000462498, 0x9, 0x9, 0x9, 0xc0004c61a0, 0xc000461000, 0x11)
W1025 17:02:22.448] 	/usr/local/go/src/io/io.go:314 +0x87
W1025 17:02:22.448] io.ReadFull(...)
W1025 17:02:22.448] 	/usr/local/go/src/io/io.go:333
W1025 17:02:22.449] net/http.http2readFrameHeader(0xc000462498, 0x9, 0x9, 0x1ccdd40, 0xc0002fd6e0, 0x0, 0x0, 0x0, 0x0)
W1025 17:02:22.449] 	/usr/local/go/src/net/http/h2_bundle.go:1477 +0x89
W1025 17:02:22.449] net/http.(*http2Framer).ReadFrame(0xc000462460, 0xc000706060, 0x0, 0x0, 0x0)
W1025 17:02:22.450] 	/usr/local/go/src/net/http/h2_bundle.go:1735 +0xa5
W1025 17:02:22.450] net/http.(*http2clientConnReadLoop).run(0xc000083fa8, 0x1, 0x0)
W1025 17:02:22.451] 	/usr/local/go/src/net/http/h2_bundle.go:8240 +0x8d
W1025 17:02:22.451] net/http.(*http2ClientConn).readLoop(0xc000456a80)
W1025 17:02:22.451] 	/usr/local/go/src/net/http/h2_bundle.go:8168 +0x6f
W1025 17:02:22.451] created by net/http.(*http2Transport).newClientConn
W1025 17:02:22.452] 	/usr/local/go/src/net/http/h2_bundle.go:7173 +0x66e
W1025 17:02:22.452] exit status 255
W1025 17:02:22.921] 2020/10/25 17:02:22 process.go:155: Step 'go run /go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go --cleanup --logtostderr --vmodule=*=4 --ssh-env=gce --results-dir=/workspace/_artifacts --project=cri-containerd-node-e2e --zone=us-central1-b --ssh-user=prow --ssh-key=/workspace/.ssh/google_compute_engine --ginkgo-flags=--nodes=8 --focus="\[NodeFeature:.+\]" --skip="\[Flaky\]|\[Serial\]" --test_args=--container-runtime=remote --container-runtime-endpoint=unix:///run/containerd/containerd.sock --container-runtime-process-name=/home/containerd/usr/local/bin/containerd --container-runtime-pid-file= --kubelet-flags="--cgroups-per-qos=true --cgroup-root=/ --runtime-cgroups=/system.slice/containerd.service" --extra-log="{\"name\": \"containerd.log\", \"journalctl\": [\"-u\", \"containerd\"]}" --test-timeout=1h5m0s --image-config-file=/workspace/test-infra/jobs/e2e_node/containerd/containerd-master/image-config.yaml' finished in 1m13.550429303s
W1025 17:02:22.921] 2020/10/25 17:02:22 e2e.go:544: Dumping logs locally to: /workspace/_artifacts
W1025 17:02:22.922] 2020/10/25 17:02:22 process.go:153: Running: ./cluster/log-dump/log-dump.sh /workspace/_artifacts
I1025 17:02:23.023] Checking for custom logdump instances, if any
I1025 17:02:23.024] Sourcing kube-util.sh
I1025 17:02:23.072] Detecting project
I1025 17:02:23.073] Project: cri-containerd-node-e2e
I1025 17:02:23.073] Network Project: cri-containerd-node-e2e
I1025 17:02:23.073] Zone: us-central1-b
I1025 17:02:23.106] Dumping logs from master locally to '/workspace/_artifacts'
W1025 17:02:23.207] Trying to find master named 'bootstrap-e2e-master'
W1025 17:02:23.208] Looking for address 'bootstrap-e2e-master-ip'
W1025 17:02:24.377] ERROR: (gcloud.compute.addresses.describe) Could not fetch resource:
W1025 17:02:24.378]  - The resource 'projects/cri-containerd-node-e2e/regions/us-central1/addresses/bootstrap-e2e-master-ip' was not found
W1025 17:02:24.378] 
W1025 17:02:24.581] Could not detect Kubernetes master node.  Make sure you've launched a cluster with 'kube-up.sh'
I1025 17:02:24.682] Master not detected. Is the cluster up?
I1025 17:02:24.682] Dumping logs from nodes locally to '/workspace/_artifacts'
I1025 17:02:24.683] Detecting nodes in the cluster
I1025 17:02:27.498] No nodes found!
W1025 17:02:31.051] INSTANCE_GROUPS=
W1025 17:02:31.052] NODE_NAMES=
W1025 17:02:31.059] 2020/10/25 17:02:31 process.go:155: Step './cluster/log-dump/log-dump.sh /workspace/_artifacts' finished in 8.138607654s
W1025 17:02:31.059] 2020/10/25 17:02:31 node.go:53: Noop - Node Down()
W1025 17:02:31.060] 2020/10/25 17:02:31 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W1025 17:02:31.060] 2020/10/25 17:02:31 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"
W1025 17:02:31.461] 2020/10/25 17:02:31 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 401.349233ms
W1025 17:02:31.463] 2020/10/25 17:02:31 main.go:316: Something went wrong: encountered 1 errors: [error during go run /go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go --cleanup --logtostderr --vmodule=*=4 --ssh-env=gce --results-dir=/workspace/_artifacts --project=cri-containerd-node-e2e --zone=us-central1-b --ssh-user=prow --ssh-key=/workspace/.ssh/google_compute_engine --ginkgo-flags=--nodes=8 --focus="\[NodeFeature:.+\]" --skip="\[Flaky\]|\[Serial\]" --test_args=--container-runtime=remote --container-runtime-endpoint=unix:///run/containerd/containerd.sock --container-runtime-process-name=/home/containerd/usr/local/bin/containerd --container-runtime-pid-file= --kubelet-flags="--cgroups-per-qos=true --cgroup-root=/ --runtime-cgroups=/system.slice/containerd.service" --extra-log="{\"name\": \"containerd.log\", \"journalctl\": [\"-u\", \"containerd\"]}" --test-timeout=1h5m0s --image-config-file=/workspace/test-infra/jobs/e2e_node/containerd/containerd-master/image-config.yaml: exit status 1]
W1025 17:02:31.468] Traceback (most recent call last):
W1025 17:02:31.469]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 720, in <module>
W1025 17:02:31.469]     main(parse_args())
W1025 17:02:31.469]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 570, in main
W1025 17:02:31.470]     mode.start(runner_args)
W1025 17:02:31.470]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 228, in start
W1025 17:02:31.470]     check_env(env, self.command, *args)
W1025 17:02:31.471]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W1025 17:02:31.471]     subprocess.check_call(cmd, env=env)
W1025 17:02:31.471]   File "/usr/lib/python2.7/subprocess.py", line 190, in check_call
W1025 17:02:31.472]     raise CalledProcessError(retcode, cmd)
W1025 17:02:31.474] subprocess.CalledProcessError: Command '('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--up', '--down', '--test', '--deployment=node', '--provider=gce', '--cluster=bootstrap-e2e', '--gcp-network=bootstrap-e2e', '--node-args=--image-config-file=/workspace/test-infra/jobs/e2e_node/containerd/containerd-master/image-config.yaml', '--gcp-project=cri-containerd-node-e2e', '--gcp-zone=us-central1-b', '--node-test-args=--container-runtime=remote --container-runtime-endpoint=unix:///run/containerd/containerd.sock --container-runtime-process-name=/home/containerd/usr/local/bin/containerd --container-runtime-pid-file= --kubelet-flags="--cgroups-per-qos=true --cgroup-root=/ --runtime-cgroups=/system.slice/containerd.service" --extra-log="{\\"name\\": \\"containerd.log\\", \\"journalctl\\": [\\"-u\\", \\"containerd\\"]}"', '--node-tests=true', '--test_args=--nodes=8 --focus="\\[NodeFeature:.+\\]" --skip="\\[Flaky\\]|\\[Serial\\]"', '--timeout=65m')' returned non-zero exit status 1
E1025 17:02:31.491] Command failed
I1025 17:02:31.491] process 332 exited with code 1 after 1.4m
E1025 17:02:31.492] FAIL: ci-containerd-node-e2e-features
I1025 17:02:31.492] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1025 17:02:32.531] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1025 17:02:32.665] process 4875 exited with code 0 after 0.0m
I1025 17:02:32.665] Call:  gcloud config get-value account
I1025 17:02:33.454] process 4888 exited with code 0 after 0.0m
I1025 17:02:33.454] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I1025 17:02:33.454] Upload result and artifacts...
I1025 17:02:33.454] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320409631381524480
I1025 17:02:33.455] Call:  gsutil ls gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320409631381524480/artifacts
W1025 17:02:34.734] CommandException: One or more URLs matched no objects.
E1025 17:02:35.015] Command failed
I1025 17:02:35.016] process 4901 exited with code 1 after 0.0m
W1025 17:02:35.016] Remote dir gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320409631381524480/artifacts not exist yet
I1025 17:02:35.016] Call:  gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320409631381524480/artifacts
I1025 17:02:37.342] process 5044 exited with code 0 after 0.0m
I1025 17:02:37.343] Call:  git rev-parse HEAD
I1025 17:02:37.348] process 5569 exited with code 0 after 0.0m
I1025 17:02:37.349] Call:  git rev-parse HEAD
I1025 17:02:37.354] process 5570 exited with code 0 after 0.0m
I1025 17:02:37.354] Call:  gsutil stat gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/jobResultsCache.json
I1025 17:02:38.892] process 5571 exited with code 0 after 0.0m
I1025 17:02:38.893] Call:  gsutil -q cat 'gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/jobResultsCache.json#1603641652777431'
I1025 17:02:40.358] process 5714 exited with code 0 after 0.0m
I1025 17:02:40.364] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1603641652777431 cp /tmp/gsutil_w1pzrD gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/jobResultsCache.json
I1025 17:02:42.310] process 5859 exited with code 0 after 0.0m
I1025 17:02:42.312] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_ARHA3i gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320409631381524480/finished.json
I1025 17:02:44.109] process 6038 exited with code 0 after 0.0m
I1025 17:02:44.110] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_gfHoP1 gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/latest-build.txt
I1025 17:02:45.798] process 6217 exited with code 0 after 0.0m