W1025 11:54:36.991] **************************************************************************
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 11:54:36.991] 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 11:54:36.991] Bootstrap ci-containerd-node-e2e-features...
I1025 11:54:36.993] Builder: d2907e4a-16b8-11eb-b256-6ee25ea2e440
I1025 11:54:36.993] Image: gcr.io/k8s-testimages/kubekins-e2e:v20201021-a62ce8a-master
I1025 11:54:36.993] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320332875375054848
I1025 11:54:36.994] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1025 11:54:37.669] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1025 11:54:37.800] process 35 exited with code 0 after 0.0m
I1025 11:54:37.800] Call:  gcloud config get-value account
I1025 11:54:38.409] process 48 exited with code 0 after 0.0m
I1025 11:54:38.409] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I1025 11:54:38.409] Root: /go/src
I1025 11:54:38.410] cd to /go/src
I1025 11:54:38.410] Checkout: /go/src/k8s.io/kubernetes master to /go/src/k8s.io/kubernetes
I1025 11:54:38.410] Call:  git init k8s.io/kubernetes
I1025 11:54:38.416] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/
I1025 11:54:38.417] process 61 exited with code 0 after 0.0m
I1025 11:54:38.417] Call:  git config --local user.name 'K8S Bootstrap'
I1025 11:54:38.421] process 62 exited with code 0 after 0.0m
I1025 11:54:38.422] Call:  git config --local user.email k8s_bootstrap@localhost
I1025 11:54:38.425] process 63 exited with code 0 after 0.0m
I1025 11:54:38.425] Call:  git fetch --quiet --tags https://github.com/kubernetes/kubernetes master
I1025 11:55:43.807] process 64 exited with code 0 after 1.1m
I1025 11:55:43.807] Call:  git checkout -B test FETCH_HEAD
W1025 11:55:46.563] Switched to a new branch 'test'
I1025 11:55:46.592] process 75 exited with code 0 after 0.0m
I1025 11:55:46.592] Call:  git show -s --format=format:%ct HEAD
I1025 11:55:46.609] process 76 exited with code 0 after 0.0m
I1025 11:55:46.609] Checkout: /go/src/github.com/containerd/cri master to /go/src/github.com/containerd/cri
I1025 11:55:46.609] Call:  git init github.com/containerd/cri
I1025 11:55:46.616] Initialized empty Git repository in /go/src/github.com/containerd/cri/.git/
I1025 11:55:46.617] process 77 exited with code 0 after 0.0m
I1025 11:55:46.617] Call:  git config --local user.name 'K8S Bootstrap'
I1025 11:55:46.621] process 78 exited with code 0 after 0.0m
I1025 11:55:46.621] Call:  git config --local user.email k8s_bootstrap@localhost
I1025 11:55:46.624] process 79 exited with code 0 after 0.0m
I1025 11:55:46.625] Call:  git fetch --quiet --tags https://github.com/containerd/cri master
I1025 11:55:48.834] process 80 exited with code 0 after 0.0m
I1025 11:55:48.835] Call:  git checkout -B test FETCH_HEAD
W1025 11:55:49.294] Switched to a new branch 'test'
I1025 11:55:49.304] process 91 exited with code 0 after 0.0m
I1025 11:55:49.305] Call:  git show -s --format=format:%ct HEAD
I1025 11:55:49.310] process 92 exited with code 0 after 0.0m
I1025 11:55:49.310] Configure environment...
I1025 11:55:49.310] Call:  git show -s --format=format:%ct HEAD
I1025 11:55:49.323] process 93 exited with code 0 after 0.0m
I1025 11:55:49.324] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1025 11:55:50.123] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1025 11:55:50.371] process 94 exited with code 0 after 0.0m
I1025 11:55:50.372] Call:  gcloud config get-value account
I1025 11:55:50.972] process 107 exited with code 0 after 0.0m
I1025 11:55:50.973] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I1025 11:55:50.973] 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 11:55:51.601] process 120 exited with code 0 after 0.0m
I1025 11:55:51.602] Start 1320332875375054848 at v1.20.0-alpha.3.114+5935fcd704fe89...
I1025 11:55:51.604] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_qtC3uB gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320332875375054848/started.json
I1025 11:55:53.878] process 153 exited with code 0 after 0.0m
I1025 11:55:53.878] 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 11:55:53.927] starts with local mode
W1025 11:55:53.928] Environment:
W1025 11:55:53.928] ARTIFACTS=/workspace/_artifacts
W1025 11:55:53.928] AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa
W1025 11:55:53.928] AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub
W1025 11:55:53.929] BAZEL_REMOTE_CACHE_ENABLED=false
W1025 11:55:53.929] BAZEL_VERSION=2.2.0
W1025 11:55:53.929] BOOTSTRAP_MIGRATION=yes
W1025 11:55:53.929] BOSKOS_METRICS_PORT=tcp://10.63.252.110:9090
W1025 11:55:53.929] BOSKOS_METRICS_PORT_9090_TCP=tcp://10.63.252.110:9090
W1025 11:55:53.929] BOSKOS_METRICS_PORT_9090_TCP_ADDR=10.63.252.110
W1025 11:55:53.929] BOSKOS_METRICS_PORT_9090_TCP_PORT=9090
W1025 11:55:53.929] BOSKOS_METRICS_PORT_9090_TCP_PROTO=tcp
W1025 11:55:53.930] BOSKOS_METRICS_SERVICE_HOST=10.63.252.110
W1025 11:55:53.930] BOSKOS_METRICS_SERVICE_PORT=9090
W1025 11:55:53.930] BOSKOS_METRICS_SERVICE_PORT_METRICS=9090
W1025 11:55:53.930] BOSKOS_PORT=tcp://10.63.250.132:80
W1025 11:55:53.930] BOSKOS_PORT_80_TCP=tcp://10.63.250.132:80
W1025 11:55:53.930] BOSKOS_PORT_80_TCP_ADDR=10.63.250.132
W1025 11:55:53.930] BOSKOS_PORT_80_TCP_PORT=80
W1025 11:55:53.930] BOSKOS_PORT_80_TCP_PROTO=tcp
W1025 11:55:53.931] BOSKOS_SERVICE_HOST=10.63.250.132
W1025 11:55:53.931] BOSKOS_SERVICE_PORT=80
W1025 11:55:53.931] BOSKOS_SERVICE_PORT_DEFAULT=80
W1025 11:55:53.931] BUILD_ID=1320332875375054848
W1025 11:55:53.931] BUILD_NUMBER=1320332875375054848
W1025 11:55:53.931] CI=true
W1025 11:55:53.931] CLOUDSDK_COMPONENT_MANAGER_DISABLE_UPDATE_CHECK=true
W1025 11:55:53.932] CLOUDSDK_CONFIG=/go/src/k8s.io/kubernetes/.config/gcloud
W1025 11:55:53.932] CLOUDSDK_CORE_DISABLE_PROMPTS=1
W1025 11:55:53.932] CLOUDSDK_EXPERIMENTAL_FAST_COMPONENT_UPDATE=false
W1025 11:55:53.932] DOCKER_IN_DOCKER_ENABLED=false
W1025 11:55:53.932] DOCKER_IN_DOCKER_IPV6_ENABLED=false
W1025 11:55:53.932] E2E_GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json
W1025 11:55:53.932] GCS_ARTIFACTS_DIR=gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320332875375054848/artifacts
W1025 11:55:53.933] GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json
W1025 11:55:53.933] GOOGLE_APPLICATION_CREDENTIALS_DEPRECATED=Migrate to workload identity, contact sig-testing
W1025 11:55:53.933] GOPATH=/go
W1025 11:55:53.933] GOPROXY=https://proxy.golang.org
W1025 11:55:53.933] GO_TARBALL=go1.15.2.linux-amd64.tar.gz
W1025 11:55:53.933] HOME=/workspace
W1025 11:55:53.933] HOSTNAME=d2907e4a-16b8-11eb-b256-6ee25ea2e440
W1025 11:55:53.933] IMAGE=gcr.io/k8s-testimages/kubekins-e2e:v20201021-a62ce8a-master
W1025 11:55:53.933] INSTANCE_PREFIX=bootstrap-e2e
W1025 11:55:53.934] JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/workspace/.ssh/google_compute_engine
W1025 11:55:53.934] JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/workspace/.ssh/google_compute_engine.pub
W1025 11:55:53.934] JOB_NAME=ci-containerd-node-e2e-features
W1025 11:55:53.934] JOB_SPEC={"type":"periodic","job":"ci-containerd-node-e2e-features","buildid":"1320332875375054848","prowjobid":"d2907e4a-16b8-11eb-b256-6ee25ea2e440"}
W1025 11:55:53.934] JOB_TYPE=periodic
W1025 11:55:53.934] KUBERNETES_PORT=tcp://10.63.240.1:443
W1025 11:55:53.934] KUBERNETES_PORT_443_TCP=tcp://10.63.240.1:443
W1025 11:55:53.935] KUBERNETES_PORT_443_TCP_ADDR=10.63.240.1
W1025 11:55:53.935] KUBERNETES_PORT_443_TCP_PORT=443
W1025 11:55:53.935] KUBERNETES_PORT_443_TCP_PROTO=tcp
W1025 11:55:53.935] KUBERNETES_SERVICE_HOST=10.63.240.1
W1025 11:55:53.935] KUBERNETES_SERVICE_PORT=443
W1025 11:55:53.935] KUBERNETES_SERVICE_PORT_HTTPS=443
W1025 11:55:53.935] KUBETEST_IN_DOCKER=true
W1025 11:55:53.935] KUBETEST_MANUAL_DUMP=y
W1025 11:55:53.936] KUBE_AWS_INSTANCE_PREFIX=bootstrap-e2e
W1025 11:55:53.936] KUBE_GCE_INSTANCE_PREFIX=bootstrap-e2e
W1025 11:55:53.936] LOG_DUMP_SCRIPT_PATH=/workspace/log-dump.sh
W1025 11:55:53.936] NODE_NAME=d2907e4a-16b8-11eb-b256-6ee25ea2e440
W1025 11:55:53.936] 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 11:55:53.936] PROW_JOB_ID=d2907e4a-16b8-11eb-b256-6ee25ea2e440
W1025 11:55:53.936] PWD=/workspace
W1025 11:55:53.937] SHLVL=2
W1025 11:55:53.937] SOURCE_DATE_EPOCH=1603511220
W1025 11:55:53.937] TERM=xterm
W1025 11:55:53.937] USER=prow
W1025 11:55:53.937] WORKSPACE=/workspace
W1025 11:55:53.937] _=./test-infra/jenkins/bootstrap.py
W1025 11:55:53.938] 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 11:55:54.161] 2020/10/25 11:55:54 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 11:55:54.162] 2020/10/25 11:55:54 main.go:329: Limiting testing to 1h5m0s
W1025 11:55:54.163] 2020/10/25 11:55:54 process.go:153: Running: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1025 11:55:54.912] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
W1025 11:55:55.046] 2020/10/25 11:55:55 process.go:155: Step 'gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json' finished in 882.51231ms
W1025 11:55:55.047] 2020/10/25 11:55:55 process.go:153: Running: gcloud config set project cri-containerd-node-e2e
W1025 11:55:55.523] Updated property [core/project].
W1025 11:55:55.759] 2020/10/25 11:55:55 process.go:155: Step 'gcloud config set project cri-containerd-node-e2e' finished in 712.392583ms
W1025 11:55:55.759] 2020/10/25 11:55:55 main.go:779: Checking existing of GCP ssh keys...
W1025 11:55:55.759] 2020/10/25 11:55:55 main.go:789: Checking presence of public key in cri-containerd-node-e2e
W1025 11:55:55.759] 2020/10/25 11:55:55 process.go:153: Running: gcloud compute --project=cri-containerd-node-e2e project-info describe
W1025 11:55:57.693] 2020/10/25 11:55:57 process.go:155: Step 'gcloud compute --project=cri-containerd-node-e2e project-info describe' finished in 1.934496624s
W1025 11:55:57.695] 2020/10/25 11:55:57 node.go:62: Noop - Node KubectlCommand()
W1025 11:55:57.696] 2020/10/25 11:55:57 node.go:53: Noop - Node Down()
W1025 11:55:57.696] 2020/10/25 11:55:57 node.go:34: Noop - Node Up()
W1025 11:55:57.696] 2020/10/25 11:55:57 node.go:48: Noop - Node TestSetup()
W1025 11:55:57.696] 2020/10/25 11:55:57 e2e.go:560: cwd : /go/src/k8s.io/kubernetes
W1025 11:55:57.697] 2020/10/25 11:55:57 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 11:56:41.574] I1025 11:56:41.573291    4642 run_remote.go:501] found images [{creationTime:{wall:749000000 ext:63738746175 loc:0xc0001c0150} name:cos-85-13310-1041-24} {creationTime:{wall:948000000 ext:63738132225 loc:0xc0001c00e0} name:cos-85-13310-1041-17} {creationTime:{wall:951000000 ext:63737797521 loc:0xc0001c0070} name:cos-85-13310-1041-14} {creationTime:{wall:449000000 ext:63736586944 loc:0xc0001c01c0} name:cos-85-13310-1041-9} {creationTime:{wall:241000000 ext:63736054782 loc:0xc0001c0620} name:cos-rc-85-13310-1041-1} {creationTime:{wall:578000000 ext:63734848446 loc:0xc0001c05b0} name:cos-rc-85-13310-1040-0} {creationTime:{wall:877000000 ext:63734695858 loc:0xc0001c0540} name:cos-rc-85-13310-1037-0} {creationTime:{wall:580000000 ext:63734153893 loc:0xc0001c04d0} name:cos-rc-85-13310-1034-0} {creationTime:{wall:643000000 ext:63733310807 loc:0xc0001c0460} name:cos-rc-85-13310-1032-0} {creationTime:{wall:981000000 ext:63732438114 loc:0xc0001c03f0} name:cos-rc-85-13310-1028-0} {creationTime:{wall:757000000 ext:63731743261 loc:0xc0001c0310} name:cos-rc-85-13310-1023-0} {creationTime:{wall:875000000 ext:63731230865 loc:0xc0001c02a0} name:cos-rc-85-13310-1019-0} {creationTime:{wall:861000000 ext:63730454305 loc:0xc0001c0230} name:cos-rc-85-13310-1015-0} {creationTime:{wall:382000000 ext:63729939006 loc:0xc0001c0690} name:cos-rc-85-13310-12-0}] based on regex "" and family "cos-85-lts" in project "cos-cloud"
W1025 11:56:41.575] I1025 11:56:41.573367    4642 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 11:56:41.576] F1025 11:56:41.573426    4642 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 11:56:41.576] goroutine 1 [running]:
W1025 11:56:41.576] k8s.io/klog/v2.stacks(0xc000010001, 0xc0001229a0, 0xf3, 0x141)
W1025 11:56:41.576] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:996 +0xb9
W1025 11:56:41.576] k8s.io/klog/v2.(*loggingT).output(0x27ba960, 0xc000000003, 0x0, 0x0, 0xc0001c07e0, 0x271c802, 0xd, 0x314, 0x0)
W1025 11:56:41.576] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:945 +0x191
W1025 11:56:41.577] k8s.io/klog/v2.(*loggingT).printf(0x27ba960, 0x3, 0x0, 0x0, 0x1b1529c, 0x23, 0xc0005fb0e0, 0x2, 0x2)
W1025 11:56:41.577] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:733 +0x17a
W1025 11:56:41.577] k8s.io/klog/v2.Fatalf(...)
W1025 11:56:41.577] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:1456
W1025 11:56:41.577] main.parseInstanceMetadata(0xc0002da180, 0x178, 0x0)
W1025 11:56:41.577] 	/go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go:788 +0x46f
W1025 11:56:41.578] main.getImageMetadata(0xc0002da180, 0x178, 0x0)
W1025 11:56:41.578] 	/go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go:404 +0xe6
W1025 11:56:41.578] main.main()
W1025 11:56:41.578] 	/go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go:257 +0x68f
W1025 11:56:41.578] 
W1025 11:56:41.578] goroutine 6 [chan receive]:
W1025 11:56:41.578] k8s.io/klog/v2.(*loggingT).flushDaemon(0x27ba960)
W1025 11:56:41.579] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:1131 +0x8b
W1025 11:56:41.579] created by k8s.io/klog/v2.init.0
W1025 11:56:41.579] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:416 +0xd8
W1025 11:56:41.579] 
W1025 11:56:41.579] goroutine 16 [select]:
W1025 11:56:41.579] go.opencensus.io/stats/view.(*worker).start(0xc0003bacd0)
W1025 11:56:41.580] 	/go/src/k8s.io/kubernetes/vendor/go.opencensus.io/stats/view/worker.go:154 +0x105
W1025 11:56:41.580] created by go.opencensus.io/stats/view.init.0
W1025 11:56:41.580] 	/go/src/k8s.io/kubernetes/vendor/go.opencensus.io/stats/view/worker.go:32 +0x57
W1025 11:56:41.580] 
W1025 11:56:41.580] goroutine 76 [IO wait]:
W1025 11:56:41.580] internal/poll.runtime_pollWait(0x7fa8014e9c38, 0x72, 0x1cd0a00)
W1025 11:56:41.580] 	/usr/local/go/src/runtime/netpoll.go:220 +0x55
W1025 11:56:41.581] internal/poll.(*pollDesc).wait(0xc0004ba698, 0x72, 0xc000317000, 0xf6a, 0xf6a)
W1025 11:56:41.581] 	/usr/local/go/src/internal/poll/fd_poll_runtime.go:87 +0x45
W1025 11:56:41.581] internal/poll.(*pollDesc).waitRead(...)
W1025 11:56:41.581] 	/usr/local/go/src/internal/poll/fd_poll_runtime.go:92
W1025 11:56:41.581] internal/poll.(*FD).Read(0xc0004ba680, 0xc000317000, 0xf6a, 0xf6a, 0x0, 0x0, 0x0)
W1025 11:56:41.581] 	/usr/local/go/src/internal/poll/fd_unix.go:159 +0x1b1
W1025 11:56:41.582] net.(*netFD).Read(0xc0004ba680, 0xc000317000, 0xf6a, 0xf6a, 0x203000, 0x62647b, 0xc0000da4e0)
W1025 11:56:41.582] 	/usr/local/go/src/net/fd_posix.go:55 +0x4f
W1025 11:56:41.582] net.(*conn).Read(0xc0000b4ea0, 0xc000317000, 0xf6a, 0xf6a, 0x0, 0x0, 0x0)
W1025 11:56:41.582] 	/usr/local/go/src/net/net.go:182 +0x8e
W1025 11:56:41.582] crypto/tls.(*atLeastReader).Read(0xc0003418e0, 0xc000317000, 0xf6a, 0xf6a, 0x22, 0xf65, 0xc0003c7730)
W1025 11:56:41.582] 	/usr/local/go/src/crypto/tls/conn.go:779 +0x62
W1025 11:56:41.582] bytes.(*Buffer).ReadFrom(0xc0000da600, 0x1ccdec0, 0xc0003418e0, 0x40d085, 0x18b3c60, 0x1a78700)
W1025 11:56:41.583] 	/usr/local/go/src/bytes/buffer.go:204 +0xb1
W1025 11:56:41.583] crypto/tls.(*Conn).readFromUntil(0xc0000da380, 0x1ccf600, 0xc0000b4ea0, 0x5, 0xc0000b4ea0, 0x11)
W1025 11:56:41.583] 	/usr/local/go/src/crypto/tls/conn.go:801 +0xf3
W1025 11:56:41.583] crypto/tls.(*Conn).readRecordOrCCS(0xc0000da380, 0x0, 0x0, 0x11)
W1025 11:56:41.583] 	/usr/local/go/src/crypto/tls/conn.go:608 +0x115
W1025 11:56:41.583] crypto/tls.(*Conn).readRecord(...)
W1025 11:56:41.584] 	/usr/local/go/src/crypto/tls/conn.go:576
W1025 11:56:41.584] crypto/tls.(*Conn).Read(0xc0000da380, 0xc0001c7000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
W1025 11:56:41.584] 	/usr/local/go/src/crypto/tls/conn.go:1252 +0x15f
W1025 11:56:41.584] bufio.(*Reader).Read(0xc00039e000, 0xc000913d18, 0x9, 0x9, 0x0, 0xc0003c7d20, 0x6e66b4)
W1025 11:56:41.584] 	/usr/local/go/src/bufio/bufio.go:227 +0x222
W1025 11:56:41.584] io.ReadAtLeast(0x1ccdd40, 0xc00039e000, 0xc000913d18, 0x9, 0x9, 0x9, 0xc0003417a0, 0xc000557000, 0x11)
W1025 11:56:41.585] 	/usr/local/go/src/io/io.go:314 +0x87
W1025 11:56:41.585] io.ReadFull(...)
W1025 11:56:41.585] 	/usr/local/go/src/io/io.go:333
W1025 11:56:41.585] net/http.http2readFrameHeader(0xc000913d18, 0x9, 0x9, 0x1ccdd40, 0xc00039e000, 0x0, 0x0, 0x0, 0x0)
W1025 11:56:41.585] 	/usr/local/go/src/net/http/h2_bundle.go:1477 +0x89
W1025 11:56:41.585] net/http.(*http2Framer).ReadFrame(0xc000913ce0, 0xc00056bc40, 0x0, 0x0, 0x0)
W1025 11:56:41.585] 	/usr/local/go/src/net/http/h2_bundle.go:1735 +0xa5
W1025 11:56:41.586] net/http.(*http2clientConnReadLoop).run(0xc0003c7fa8, 0x1, 0x0)
W1025 11:56:41.586] 	/usr/local/go/src/net/http/h2_bundle.go:8240 +0x8d
W1025 11:56:41.586] net/http.(*http2ClientConn).readLoop(0xc0000a3080)
W1025 11:56:41.586] 	/usr/local/go/src/net/http/h2_bundle.go:8168 +0x6f
W1025 11:56:41.586] created by net/http.(*http2Transport).newClientConn
W1025 11:56:41.586] 	/usr/local/go/src/net/http/h2_bundle.go:7173 +0x66e
W1025 11:56:41.586] 
W1025 11:56:41.587] goroutine 109 [IO wait]:
W1025 11:56:41.587] internal/poll.runtime_pollWait(0x7fa8014e9a78, 0x72, 0x1cd0a00)
W1025 11:56:41.587] 	/usr/local/go/src/runtime/netpoll.go:220 +0x55
W1025 11:56:41.587] internal/poll.(*pollDesc).wait(0xc0004ba518, 0x72, 0xc0006b0000, 0xf69, 0xf69)
W1025 11:56:41.587] 	/usr/local/go/src/internal/poll/fd_poll_runtime.go:87 +0x45
W1025 11:56:41.587] internal/poll.(*pollDesc).waitRead(...)
W1025 11:56:41.587] 	/usr/local/go/src/internal/poll/fd_poll_runtime.go:92
W1025 11:56:41.588] internal/poll.(*FD).Read(0xc0004ba500, 0xc0006b0000, 0xf69, 0xf69, 0x0, 0x0, 0x0)
W1025 11:56:41.588] 	/usr/local/go/src/internal/poll/fd_unix.go:159 +0x1b1
W1025 11:56:41.588] net.(*netFD).Read(0xc0004ba500, 0xc0006b0000, 0xf69, 0xf69, 0x203000, 0x62647b, 0xc0000dabe0)
W1025 11:56:41.588] 	/usr/local/go/src/net/fd_posix.go:55 +0x4f
W1025 11:56:41.588] net.(*conn).Read(0xc0000b4058, 0xc0006b0000, 0xf69, 0xf69, 0x0, 0x0, 0x0)
W1025 11:56:41.588] 	/usr/local/go/src/net/net.go:182 +0x8e
W1025 11:56:41.589] crypto/tls.(*atLeastReader).Read(0xc0002fcca0, 0xc0006b0000, 0xf69, 0xf69, 0x22, 0xf64, 0xc000688730)
W1025 11:56:41.589] 	/usr/local/go/src/crypto/tls/conn.go:779 +0x62
W1025 11:56:41.589] bytes.(*Buffer).ReadFrom(0xc0000dad00, 0x1ccdec0, 0xc0002fcca0, 0x40d085, 0x18b3c60, 0x1a78700)
W1025 11:56:41.589] 	/usr/local/go/src/bytes/buffer.go:204 +0xb1
W1025 11:56:41.589] crypto/tls.(*Conn).readFromUntil(0xc0000daa80, 0x1ccf600, 0xc0000b4058, 0x5, 0xc0000b4058, 0x11)
W1025 11:56:41.589] 	/usr/local/go/src/crypto/tls/conn.go:801 +0xf3
W1025 11:56:41.589] crypto/tls.(*Conn).readRecordOrCCS(0xc0000daa80, 0x0, 0x0, 0x11)
W1025 11:56:41.590] 	/usr/local/go/src/crypto/tls/conn.go:608 +0x115
W1025 11:56:41.590] crypto/tls.(*Conn).readRecord(...)
W1025 11:56:41.590] 	/usr/local/go/src/crypto/tls/conn.go:576
W1025 11:56:41.590] crypto/tls.(*Conn).Read(0xc0000daa80, 0xc0006bc000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
W1025 11:56:41.590] 	/usr/local/go/src/crypto/tls/conn.go:1252 +0x15f
W1025 11:56:41.590] bufio.(*Reader).Read(0xc0000af320, 0xc00003c738, 0x9, 0x9, 0x0, 0xc000688d20, 0x6e66b4)
W1025 11:56:41.591] 	/usr/local/go/src/bufio/bufio.go:227 +0x222
W1025 11:56:41.591] io.ReadAtLeast(0x1ccdd40, 0xc0000af320, 0xc00003c738, 0x9, 0x9, 0x9, 0xc0003a4320, 0xc0006b1000, 0x11)
W1025 11:56:41.591] 	/usr/local/go/src/io/io.go:314 +0x87
W1025 11:56:41.591] io.ReadFull(...)
W1025 11:56:41.591] 	/usr/local/go/src/io/io.go:333
W1025 11:56:41.591] net/http.http2readFrameHeader(0xc00003c738, 0x9, 0x9, 0x1ccdd40, 0xc0000af320, 0x0, 0x0, 0x0, 0x0)
W1025 11:56:41.592] 	/usr/local/go/src/net/http/h2_bundle.go:1477 +0x89
W1025 11:56:41.592] net/http.(*http2Framer).ReadFrame(0xc00003c700, 0xc000850b60, 0x0, 0x0, 0x0)
W1025 11:56:41.592] 	/usr/local/go/src/net/http/h2_bundle.go:1735 +0xa5
W1025 11:56:41.592] net/http.(*http2clientConnReadLoop).run(0xc000688fa8, 0x1, 0x0)
W1025 11:56:41.592] 	/usr/local/go/src/net/http/h2_bundle.go:8240 +0x8d
W1025 11:56:41.592] net/http.(*http2ClientConn).readLoop(0xc0004e0600)
W1025 11:56:41.592] 	/usr/local/go/src/net/http/h2_bundle.go:8168 +0x6f
W1025 11:56:41.593] created by net/http.(*http2Transport).newClientConn
W1025 11:56:41.593] 	/usr/local/go/src/net/http/h2_bundle.go:7173 +0x66e
W1025 11:56:41.593] exit status 255
W1025 11:56:41.833] 2020/10/25 11:56:41 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 44.135966934s
W1025 11:56:41.833] 2020/10/25 11:56:41 e2e.go:544: Dumping logs locally to: /workspace/_artifacts
W1025 11:56:41.833] 2020/10/25 11:56:41 process.go:153: Running: ./cluster/log-dump/log-dump.sh /workspace/_artifacts
W1025 11:56:41.915] Trying to find master named 'bootstrap-e2e-master'
W1025 11:56:41.915] Looking for address 'bootstrap-e2e-master-ip'
I1025 11:56:42.016] Checking for custom logdump instances, if any
I1025 11:56:42.016] Sourcing kube-util.sh
I1025 11:56:42.016] Detecting project
I1025 11:56:42.016] Project: cri-containerd-node-e2e
I1025 11:56:42.016] Network Project: cri-containerd-node-e2e
I1025 11:56:42.016] Zone: us-central1-b
I1025 11:56:42.017] Dumping logs from master locally to '/workspace/_artifacts'
W1025 11:56:42.760] ERROR: (gcloud.compute.addresses.describe) Could not fetch resource:
W1025 11:56:42.760]  - The resource 'projects/cri-containerd-node-e2e/regions/us-central1/addresses/bootstrap-e2e-master-ip' was not found
W1025 11:56:42.760] 
W1025 11:56:42.930] Could not detect Kubernetes master node.  Make sure you've launched a cluster with 'kube-up.sh'
I1025 11:56:43.031] Master not detected. Is the cluster up?
I1025 11:56:43.031] Dumping logs from nodes locally to '/workspace/_artifacts'
I1025 11:56:43.032] Detecting nodes in the cluster
I1025 11:56:45.115] No nodes found!
W1025 11:56:47.295] INSTANCE_GROUPS=
W1025 11:56:47.295] NODE_NAMES=
W1025 11:56:47.298] 2020/10/25 11:56:47 process.go:155: Step './cluster/log-dump/log-dump.sh /workspace/_artifacts' finished in 5.465839216s
W1025 11:56:47.298] 2020/10/25 11:56:47 node.go:53: Noop - Node Down()
W1025 11:56:47.298] 2020/10/25 11:56:47 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W1025 11:56:47.298] 2020/10/25 11:56:47 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"
W1025 11:56:47.617] 2020/10/25 11:56:47 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 318.205462ms
W1025 11:56:47.618] 2020/10/25 11:56:47 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 11:56:47.620] Traceback (most recent call last):
W1025 11:56:47.621]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 720, in <module>
W1025 11:56:47.621]     main(parse_args())
W1025 11:56:47.621]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 570, in main
W1025 11:56:47.621]     mode.start(runner_args)
W1025 11:56:47.621]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 228, in start
W1025 11:56:47.621]     check_env(env, self.command, *args)
W1025 11:56:47.622]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W1025 11:56:47.622]     subprocess.check_call(cmd, env=env)
W1025 11:56:47.622]   File "/usr/lib/python2.7/subprocess.py", line 190, in check_call
W1025 11:56:47.622]     raise CalledProcessError(retcode, cmd)
W1025 11:56:47.623] 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 11:56:47.630] Command failed
I1025 11:56:47.630] process 332 exited with code 1 after 0.9m
E1025 11:56:47.631] FAIL: ci-containerd-node-e2e-features
I1025 11:56:47.631] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1025 11:56:48.270] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1025 11:56:48.373] process 4793 exited with code 0 after 0.0m
I1025 11:56:48.374] Call:  gcloud config get-value account
I1025 11:56:48.971] process 4806 exited with code 0 after 0.0m
I1025 11:56:48.971] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I1025 11:56:48.971] Upload result and artifacts...
I1025 11:56:48.971] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320332875375054848
I1025 11:56:48.972] Call:  gsutil ls gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320332875375054848/artifacts
W1025 11:56:49.890] CommandException: One or more URLs matched no objects.
E1025 11:56:50.087] Command failed
I1025 11:56:50.088] process 4819 exited with code 1 after 0.0m
W1025 11:56:50.088] Remote dir gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320332875375054848/artifacts not exist yet
I1025 11:56:50.088] 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/1320332875375054848/artifacts
I1025 11:56:51.645] process 4962 exited with code 0 after 0.0m
I1025 11:56:51.646] Call:  git rev-parse HEAD
I1025 11:56:51.650] process 5487 exited with code 0 after 0.0m
I1025 11:56:51.650] Call:  git rev-parse HEAD
I1025 11:56:51.654] process 5488 exited with code 0 after 0.0m
I1025 11:56:51.655] Call:  gsutil stat gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/jobResultsCache.json
I1025 11:56:52.754] process 5489 exited with code 0 after 0.0m
I1025 11:56:52.755] Call:  gsutil -q cat 'gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/jobResultsCache.json#1603623352916941'
I1025 11:56:53.948] process 5632 exited with code 0 after 0.0m
I1025 11:56:53.952] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1603623352916941 cp /tmp/gsutil_eu_ZjV gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/jobResultsCache.json
I1025 11:56:55.351] process 5777 exited with code 0 after 0.0m
I1025 11:56:55.352] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_5rksPg gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320332875375054848/finished.json
I1025 11:56:56.757] process 5956 exited with code 0 after 0.0m
I1025 11:56:56.758] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_QCRxEQ gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/latest-build.txt
I1025 11:56:58.223] process 6135 exited with code 0 after 0.0m