W1026 21:27:45.554] **************************************************************************
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
**************************************************************************
I1026 21:27:45.555] 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
I1026 21:27:45.555] Bootstrap ci-containerd-node-e2e-features...
I1026 21:27:45.564] Builder: 059041ed-17d2-11eb-8cf5-bee87f5cdaa3
I1026 21:27:45.565] Image: gcr.io/k8s-testimages/kubekins-e2e:v20201021-a62ce8a-master
I1026 21:27:45.565] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320839438858719232
I1026 21:27:45.565] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1026 21:27:46.768] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1026 21:27:46.948] process 34 exited with code 0 after 0.0m
I1026 21:27:46.948] Call:  gcloud config get-value account
I1026 21:27:48.195] process 47 exited with code 0 after 0.0m
I1026 21:27:48.195] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I1026 21:27:48.196] Root: /go/src
I1026 21:27:48.197] cd to /go/src
I1026 21:27:48.197] Checkout: /go/src/k8s.io/kubernetes master to /go/src/k8s.io/kubernetes
I1026 21:27:48.197] Call:  git init k8s.io/kubernetes
I1026 21:27:48.216] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/
I1026 21:27:48.218] process 60 exited with code 0 after 0.0m
I1026 21:27:48.218] Call:  git config --local user.name 'K8S Bootstrap'
I1026 21:27:48.226] process 61 exited with code 0 after 0.0m
I1026 21:27:48.226] Call:  git config --local user.email k8s_bootstrap@localhost
I1026 21:27:48.238] process 62 exited with code 0 after 0.0m
I1026 21:27:48.239] Call:  git fetch --quiet --tags https://github.com/kubernetes/kubernetes master
I1026 21:29:23.430] process 63 exited with code 0 after 1.6m
I1026 21:29:23.431] Call:  git checkout -B test FETCH_HEAD
W1026 21:29:27.215] Switched to a new branch 'test'
I1026 21:29:27.248] process 74 exited with code 0 after 0.1m
I1026 21:29:27.249] Call:  git show -s --format=format:%ct HEAD
I1026 21:29:27.261] process 75 exited with code 0 after 0.0m
I1026 21:29:27.261] Checkout: /go/src/github.com/containerd/cri master to /go/src/github.com/containerd/cri
I1026 21:29:27.261] Call:  git init github.com/containerd/cri
I1026 21:29:27.269] Initialized empty Git repository in /go/src/github.com/containerd/cri/.git/
I1026 21:29:27.270] process 76 exited with code 0 after 0.0m
I1026 21:29:27.271] Call:  git config --local user.name 'K8S Bootstrap'
I1026 21:29:27.276] process 77 exited with code 0 after 0.0m
I1026 21:29:27.276] Call:  git config --local user.email k8s_bootstrap@localhost
I1026 21:29:27.287] process 78 exited with code 0 after 0.0m
I1026 21:29:27.287] Call:  git fetch --quiet --tags https://github.com/containerd/cri master
I1026 21:29:29.753] process 79 exited with code 0 after 0.0m
I1026 21:29:29.753] Call:  git checkout -B test FETCH_HEAD
W1026 21:29:30.371] Switched to a new branch 'test'
I1026 21:29:30.385] process 90 exited with code 0 after 0.0m
I1026 21:29:30.386] Call:  git show -s --format=format:%ct HEAD
I1026 21:29:30.391] process 91 exited with code 0 after 0.0m
I1026 21:29:30.392] Configure environment...
I1026 21:29:30.394] Call:  git show -s --format=format:%ct HEAD
I1026 21:29:30.408] process 92 exited with code 0 after 0.0m
I1026 21:29:30.409] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1026 21:29:31.396] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1026 21:29:32.542] process 93 exited with code 0 after 0.0m
I1026 21:29:32.542] Call:  gcloud config get-value account
I1026 21:29:33.444] process 106 exited with code 0 after 0.0m
I1026 21:29:33.444] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I1026 21:29:33.444] 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
'
I1026 21:29:34.135] process 119 exited with code 0 after 0.0m
I1026 21:29:34.136] Start 1320839438858719232 at v1.20.0-alpha.3.144+6675eba3eff1c8...
I1026 21:29:34.139] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_zIsdPE gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320839438858719232/started.json
I1026 21:29:36.747] process 152 exited with code 0 after 0.0m
I1026 21:29:36.748] 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
W1026 21:29:36.812] starts with local mode
W1026 21:29:36.812] Environment:
W1026 21:29:36.813] ARTIFACTS=/workspace/_artifacts
W1026 21:29:36.813] AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa
W1026 21:29:36.813] AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub
W1026 21:29:36.813] BAZEL_REMOTE_CACHE_ENABLED=false
W1026 21:29:36.813] BAZEL_VERSION=2.2.0
W1026 21:29:36.813] BOOTSTRAP_MIGRATION=yes
W1026 21:29:36.813] BOSKOS_METRICS_PORT=tcp://10.63.252.110:9090
W1026 21:29:36.813] BOSKOS_METRICS_PORT_9090_TCP=tcp://10.63.252.110:9090
W1026 21:29:36.814] BOSKOS_METRICS_PORT_9090_TCP_ADDR=10.63.252.110
W1026 21:29:36.814] BOSKOS_METRICS_PORT_9090_TCP_PORT=9090
W1026 21:29:36.814] BOSKOS_METRICS_PORT_9090_TCP_PROTO=tcp
W1026 21:29:36.814] BOSKOS_METRICS_SERVICE_HOST=10.63.252.110
W1026 21:29:36.814] BOSKOS_METRICS_SERVICE_PORT=9090
W1026 21:29:36.814] BOSKOS_METRICS_SERVICE_PORT_METRICS=9090
W1026 21:29:36.814] BOSKOS_PORT=tcp://10.63.250.132:80
W1026 21:29:36.814] BOSKOS_PORT_80_TCP=tcp://10.63.250.132:80
W1026 21:29:36.815] BOSKOS_PORT_80_TCP_ADDR=10.63.250.132
W1026 21:29:36.815] BOSKOS_PORT_80_TCP_PORT=80
W1026 21:29:36.815] BOSKOS_PORT_80_TCP_PROTO=tcp
W1026 21:29:36.815] BOSKOS_SERVICE_HOST=10.63.250.132
W1026 21:29:36.815] BOSKOS_SERVICE_PORT=80
W1026 21:29:36.816] BOSKOS_SERVICE_PORT_DEFAULT=80
W1026 21:29:36.816] BUILD_ID=1320839438858719232
W1026 21:29:36.816] BUILD_NUMBER=1320839438858719232
W1026 21:29:36.816] CI=true
W1026 21:29:36.817] CLOUDSDK_COMPONENT_MANAGER_DISABLE_UPDATE_CHECK=true
W1026 21:29:36.817] CLOUDSDK_CONFIG=/go/src/k8s.io/kubernetes/.config/gcloud
W1026 21:29:36.817] CLOUDSDK_CORE_DISABLE_PROMPTS=1
W1026 21:29:36.817] CLOUDSDK_EXPERIMENTAL_FAST_COMPONENT_UPDATE=false
W1026 21:29:36.818] DOCKER_IN_DOCKER_ENABLED=false
W1026 21:29:36.818] DOCKER_IN_DOCKER_IPV6_ENABLED=false
W1026 21:29:36.818] E2E_GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json
W1026 21:29:36.818] GCS_ARTIFACTS_DIR=gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320839438858719232/artifacts
W1026 21:29:36.819] GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json
W1026 21:29:36.819] GOOGLE_APPLICATION_CREDENTIALS_DEPRECATED=Migrate to workload identity, contact sig-testing
W1026 21:29:36.819] GOPATH=/go
W1026 21:29:36.819] GOPROXY=https://proxy.golang.org
W1026 21:29:36.819] GO_TARBALL=go1.15.2.linux-amd64.tar.gz
W1026 21:29:36.819] HOME=/workspace
W1026 21:29:36.820] HOSTNAME=059041ed-17d2-11eb-8cf5-bee87f5cdaa3
W1026 21:29:36.820] IMAGE=gcr.io/k8s-testimages/kubekins-e2e:v20201021-a62ce8a-master
W1026 21:29:36.820] INSTANCE_PREFIX=bootstrap-e2e
W1026 21:29:36.820] JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/workspace/.ssh/google_compute_engine
W1026 21:29:36.820] JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/workspace/.ssh/google_compute_engine.pub
W1026 21:29:36.820] JOB_NAME=ci-containerd-node-e2e-features
W1026 21:29:36.821] JOB_SPEC={"type":"periodic","job":"ci-containerd-node-e2e-features","buildid":"1320839438858719232","prowjobid":"059041ed-17d2-11eb-8cf5-bee87f5cdaa3"}
W1026 21:29:36.821] JOB_TYPE=periodic
W1026 21:29:36.821] KUBERNETES_PORT=tcp://10.63.240.1:443
W1026 21:29:36.821] KUBERNETES_PORT_443_TCP=tcp://10.63.240.1:443
W1026 21:29:36.821] KUBERNETES_PORT_443_TCP_ADDR=10.63.240.1
W1026 21:29:36.821] KUBERNETES_PORT_443_TCP_PORT=443
W1026 21:29:36.822] KUBERNETES_PORT_443_TCP_PROTO=tcp
W1026 21:29:36.822] KUBERNETES_SERVICE_HOST=10.63.240.1
W1026 21:29:36.822] KUBERNETES_SERVICE_PORT=443
W1026 21:29:36.822] KUBERNETES_SERVICE_PORT_HTTPS=443
W1026 21:29:36.822] KUBETEST_IN_DOCKER=true
W1026 21:29:36.822] KUBETEST_MANUAL_DUMP=y
W1026 21:29:36.822] KUBE_AWS_INSTANCE_PREFIX=bootstrap-e2e
W1026 21:29:36.823] KUBE_GCE_INSTANCE_PREFIX=bootstrap-e2e
W1026 21:29:36.823] LOG_DUMP_SCRIPT_PATH=/workspace/log-dump.sh
W1026 21:29:36.823] NODE_NAME=059041ed-17d2-11eb-8cf5-bee87f5cdaa3
W1026 21:29:36.823] 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
W1026 21:29:36.823] PROW_JOB_ID=059041ed-17d2-11eb-8cf5-bee87f5cdaa3
W1026 21:29:36.824] PWD=/workspace
W1026 21:29:36.824] SHLVL=2
W1026 21:29:36.824] SOURCE_DATE_EPOCH=1603743359
W1026 21:29:36.828] TERM=xterm
W1026 21:29:36.828] USER=prow
W1026 21:29:36.828] WORKSPACE=/workspace
W1026 21:29:36.829] _=./test-infra/jenkins/bootstrap.py
W1026 21:29:36.830] 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')
W1026 21:29:37.124] 2020/10/26 21:29:37 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
W1026 21:29:37.125] 2020/10/26 21:29:37 main.go:329: Limiting testing to 1h5m0s
W1026 21:29:37.126] 2020/10/26 21:29:37 process.go:153: Running: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1026 21:29:38.119] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
W1026 21:29:38.303] 2020/10/26 21:29:38 process.go:155: Step 'gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json' finished in 1.177051555s
W1026 21:29:38.303] 2020/10/26 21:29:38 process.go:153: Running: gcloud config set project cri-containerd-node-e2e
W1026 21:29:39.068] Updated property [core/project].
W1026 21:29:39.448] 2020/10/26 21:29:39 process.go:155: Step 'gcloud config set project cri-containerd-node-e2e' finished in 1.144530855s
W1026 21:29:39.448] 2020/10/26 21:29:39 main.go:779: Checking existing of GCP ssh keys...
W1026 21:29:39.449] 2020/10/26 21:29:39 main.go:789: Checking presence of public key in cri-containerd-node-e2e
W1026 21:29:39.449] 2020/10/26 21:29:39 process.go:153: Running: gcloud compute --project=cri-containerd-node-e2e project-info describe
W1026 21:29:41.792] 2020/10/26 21:29:41 process.go:155: Step 'gcloud compute --project=cri-containerd-node-e2e project-info describe' finished in 2.341684734s
W1026 21:29:41.793] 2020/10/26 21:29:41 node.go:62: Noop - Node KubectlCommand()
W1026 21:29:41.793] 2020/10/26 21:29:41 node.go:53: Noop - Node Down()
W1026 21:29:41.793] 2020/10/26 21:29:41 node.go:34: Noop - Node Up()
W1026 21:29:41.793] 2020/10/26 21:29:41 node.go:48: Noop - Node TestSetup()
W1026 21:29:41.793] 2020/10/26 21:29:41 e2e.go:560: cwd : /go/src/k8s.io/kubernetes
W1026 21:29:41.794] 2020/10/26 21:29:41 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
W1026 21:30:40.924] I1026 21:30:40.921857    4608 run_remote.go:501] found images [{creationTime:{wall:749000000 ext:63738746175 loc:0xc0001e4150} name:cos-85-13310-1041-24} {creationTime:{wall:948000000 ext:63738132225 loc:0xc0001e40e0} name:cos-85-13310-1041-17} {creationTime:{wall:951000000 ext:63737797521 loc:0xc0001e4070} name:cos-85-13310-1041-14} {creationTime:{wall:449000000 ext:63736586944 loc:0xc0001e41c0} name:cos-85-13310-1041-9} {creationTime:{wall:241000000 ext:63736054782 loc:0xc0001e45b0} name:cos-rc-85-13310-1041-1} {creationTime:{wall:578000000 ext:63734848446 loc:0xc0001e4540} name:cos-rc-85-13310-1040-0} {creationTime:{wall:877000000 ext:63734695858 loc:0xc0001e44d0} name:cos-rc-85-13310-1037-0} {creationTime:{wall:580000000 ext:63734153893 loc:0xc0001e4460} name:cos-rc-85-13310-1034-0} {creationTime:{wall:643000000 ext:63733310807 loc:0xc0001e43f0} name:cos-rc-85-13310-1032-0} {creationTime:{wall:981000000 ext:63732438114 loc:0xc0001e4380} name:cos-rc-85-13310-1028-0} {creationTime:{wall:757000000 ext:63731743261 loc:0xc0001e4310} name:cos-rc-85-13310-1023-0} {creationTime:{wall:875000000 ext:63731230865 loc:0xc0001e42a0} name:cos-rc-85-13310-1019-0} {creationTime:{wall:861000000 ext:63730454305 loc:0xc0001e4230} name:cos-rc-85-13310-1015-0} {creationTime:{wall:382000000 ext:63729939006 loc:0xc0001e4620} name:cos-rc-85-13310-12-0}] based on regex "" and family "cos-85-lts" in project "cos-cloud"
W1026 21:30:40.925] I1026 21:30:40.922571    4608 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"
W1026 21:30:40.925] F1026 21:30:40.922726    4608 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
W1026 21:30:40.925] goroutine 1 [running]:
W1026 21:30:40.925] k8s.io/klog/v2.stacks(0xc000010001, 0xc0001229a0, 0xf3, 0x141)
W1026 21:30:40.925] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:996 +0xb9
W1026 21:30:40.926] k8s.io/klog/v2.(*loggingT).output(0x27bc860, 0xc000000003, 0x0, 0x0, 0xc00036c070, 0x271e0f6, 0xd, 0x314, 0x0)
W1026 21:30:40.926] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:945 +0x191
W1026 21:30:40.927] k8s.io/klog/v2.(*loggingT).printf(0x27bc860, 0x3, 0x0, 0x0, 0x1b1661c, 0x23, 0xc0008390e0, 0x2, 0x2)
W1026 21:30:40.927] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:733 +0x17a
W1026 21:30:40.927] k8s.io/klog/v2.Fatalf(...)
W1026 21:30:40.927] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:1456
W1026 21:30:40.927] main.parseInstanceMetadata(0xc0002d4180, 0x178, 0x0)
W1026 21:30:40.928] 	/go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go:788 +0x46f
W1026 21:30:40.928] main.getImageMetadata(0xc0002d4180, 0x178, 0x0)
W1026 21:30:40.928] 	/go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go:404 +0xe6
W1026 21:30:40.928] main.main()
W1026 21:30:40.928] 	/go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go:257 +0x68f
W1026 21:30:40.929] 
W1026 21:30:40.929] goroutine 6 [chan receive]:
W1026 21:30:40.929] k8s.io/klog/v2.(*loggingT).flushDaemon(0x27bc860)
W1026 21:30:40.929] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:1131 +0x8b
W1026 21:30:40.929] created by k8s.io/klog/v2.init.0
W1026 21:30:40.930] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:416 +0xd8
W1026 21:30:40.930] 
W1026 21:30:40.930] goroutine 12 [select]:
W1026 21:30:40.931] go.opencensus.io/stats/view.(*worker).start(0xc00022d400)
W1026 21:30:40.931] 	/go/src/k8s.io/kubernetes/vendor/go.opencensus.io/stats/view/worker.go:154 +0x105
W1026 21:30:40.931] created by go.opencensus.io/stats/view.init.0
W1026 21:30:40.932] 	/go/src/k8s.io/kubernetes/vendor/go.opencensus.io/stats/view/worker.go:32 +0x57
W1026 21:30:40.932] 
W1026 21:30:40.932] goroutine 68 [IO wait]:
W1026 21:30:40.932] internal/poll.runtime_pollWait(0x7f6a0c619c38, 0x72, 0x1cd1fe0)
W1026 21:30:40.932] 	/usr/local/go/src/runtime/netpoll.go:220 +0x55
W1026 21:30:40.932] internal/poll.(*pollDesc).wait(0xc0000d3118, 0x72, 0xc000333000, 0xf6a, 0xf6a)
W1026 21:30:40.933] 	/usr/local/go/src/internal/poll/fd_poll_runtime.go:87 +0x45
W1026 21:30:40.933] internal/poll.(*pollDesc).waitRead(...)
W1026 21:30:40.933] 	/usr/local/go/src/internal/poll/fd_poll_runtime.go:92
W1026 21:30:40.933] internal/poll.(*FD).Read(0xc0000d3100, 0xc000333000, 0xf6a, 0xf6a, 0x0, 0x0, 0x0)
W1026 21:30:40.934] 	/usr/local/go/src/internal/poll/fd_unix.go:159 +0x1b1
W1026 21:30:40.934] net.(*netFD).Read(0xc0000d3100, 0xc000333000, 0xf6a, 0xf6a, 0x203000, 0x62647b, 0xc000210860)
W1026 21:30:40.934] 	/usr/local/go/src/net/fd_posix.go:55 +0x4f
W1026 21:30:40.934] net.(*conn).Read(0xc0004bb140, 0xc000333000, 0xf6a, 0xf6a, 0x0, 0x0, 0x0)
W1026 21:30:40.934] 	/usr/local/go/src/net/net.go:182 +0x8e
W1026 21:30:40.935] crypto/tls.(*atLeastReader).Read(0xc0001a40c0, 0xc000333000, 0xf6a, 0xf6a, 0x22, 0xf65, 0xc0003e3730)
W1026 21:30:40.935] 	/usr/local/go/src/crypto/tls/conn.go:779 +0x62
W1026 21:30:40.935] bytes.(*Buffer).ReadFrom(0xc000210980, 0x1ccf4a0, 0xc0001a40c0, 0x40d085, 0x18b4ea0, 0x1a799c0)
W1026 21:30:40.936] 	/usr/local/go/src/bytes/buffer.go:204 +0xb1
W1026 21:30:40.936] crypto/tls.(*Conn).readFromUntil(0xc000210700, 0x1cd0be0, 0xc0004bb140, 0x5, 0xc0004bb140, 0x11)
W1026 21:30:40.937] 	/usr/local/go/src/crypto/tls/conn.go:801 +0xf3
W1026 21:30:40.937] crypto/tls.(*Conn).readRecordOrCCS(0xc000210700, 0x0, 0x0, 0x11)
W1026 21:30:40.937] 	/usr/local/go/src/crypto/tls/conn.go:608 +0x115
W1026 21:30:40.937] crypto/tls.(*Conn).readRecord(...)
W1026 21:30:40.937] 	/usr/local/go/src/crypto/tls/conn.go:576
W1026 21:30:40.937] crypto/tls.(*Conn).Read(0xc000210700, 0xc0001c7000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
W1026 21:30:40.938] 	/usr/local/go/src/crypto/tls/conn.go:1252 +0x15f
W1026 21:30:40.938] bufio.(*Reader).Read(0xc00007c900, 0xc00003cd58, 0x9, 0x9, 0x0, 0xc0003e3d20, 0x6e66b4)
W1026 21:30:40.938] 	/usr/local/go/src/bufio/bufio.go:227 +0x222
W1026 21:30:40.938] io.ReadAtLeast(0x1ccf320, 0xc00007c900, 0xc00003cd58, 0x9, 0x9, 0x9, 0xc0000de1a0, 0xc00019b000, 0x11)
W1026 21:30:40.938] 	/usr/local/go/src/io/io.go:314 +0x87
W1026 21:30:40.939] io.ReadFull(...)
W1026 21:30:40.939] 	/usr/local/go/src/io/io.go:333
W1026 21:30:40.939] net/http.http2readFrameHeader(0xc00003cd58, 0x9, 0x9, 0x1ccf320, 0xc00007c900, 0x0, 0x0, 0x0, 0x0)
W1026 21:30:40.939] 	/usr/local/go/src/net/http/h2_bundle.go:1477 +0x89
W1026 21:30:40.939] net/http.(*http2Framer).ReadFrame(0xc00003cd20, 0xc0004a41a0, 0x0, 0x0, 0x0)
W1026 21:30:40.939] 	/usr/local/go/src/net/http/h2_bundle.go:1735 +0xa5
W1026 21:30:40.940] net/http.(*http2clientConnReadLoop).run(0xc0003e3fa8, 0x1, 0x0)
W1026 21:30:40.940] 	/usr/local/go/src/net/http/h2_bundle.go:8240 +0x8d
W1026 21:30:40.940] net/http.(*http2ClientConn).readLoop(0xc000202c00)
W1026 21:30:40.941] 	/usr/local/go/src/net/http/h2_bundle.go:8168 +0x6f
W1026 21:30:40.941] created by net/http.(*http2Transport).newClientConn
W1026 21:30:40.941] 	/usr/local/go/src/net/http/h2_bundle.go:7173 +0x66e
W1026 21:30:40.941] 
W1026 21:30:40.941] goroutine 73 [IO wait]:
W1026 21:30:40.942] internal/poll.runtime_pollWait(0x7f6a0c619b58, 0x72, 0x1cd1fe0)
W1026 21:30:40.942] 	/usr/local/go/src/runtime/netpoll.go:220 +0x55
W1026 21:30:40.942] internal/poll.(*pollDesc).wait(0xc0000d2f18, 0x72, 0xc0003c0000, 0xf68, 0xf68)
W1026 21:30:40.942] 	/usr/local/go/src/internal/poll/fd_poll_runtime.go:87 +0x45
W1026 21:30:40.942] internal/poll.(*pollDesc).waitRead(...)
W1026 21:30:40.942] 	/usr/local/go/src/internal/poll/fd_poll_runtime.go:92
W1026 21:30:40.943] internal/poll.(*FD).Read(0xc0000d2f00, 0xc0003c0000, 0xf68, 0xf68, 0x0, 0x0, 0x0)
W1026 21:30:40.943] 	/usr/local/go/src/internal/poll/fd_unix.go:159 +0x1b1
W1026 21:30:40.943] net.(*netFD).Read(0xc0000d2f00, 0xc0003c0000, 0xf68, 0xf68, 0x203000, 0x62647b, 0xc000210160)
W1026 21:30:40.943] 	/usr/local/go/src/net/fd_posix.go:55 +0x4f
W1026 21:30:40.944] net.(*conn).Read(0xc0004ba100, 0xc0003c0000, 0xf68, 0xf68, 0x0, 0x0, 0x0)
W1026 21:30:40.944] 	/usr/local/go/src/net/net.go:182 +0x8e
W1026 21:30:40.944] crypto/tls.(*atLeastReader).Read(0xc000390ee0, 0xc0003c0000, 0xf68, 0xf68, 0x22, 0x114, 0xc000085730)
W1026 21:30:40.944] 	/usr/local/go/src/crypto/tls/conn.go:779 +0x62
W1026 21:30:40.944] bytes.(*Buffer).ReadFrom(0xc000210280, 0x1ccf4a0, 0xc000390ee0, 0x40d085, 0x18b4ea0, 0x1a799c0)
W1026 21:30:40.945] 	/usr/local/go/src/bytes/buffer.go:204 +0xb1
W1026 21:30:40.945] crypto/tls.(*Conn).readFromUntil(0xc000210000, 0x1cd0be0, 0xc0004ba100, 0x5, 0xc0004ba100, 0x11)
W1026 21:30:40.945] 	/usr/local/go/src/crypto/tls/conn.go:801 +0xf3
W1026 21:30:40.945] crypto/tls.(*Conn).readRecordOrCCS(0xc000210000, 0x0, 0x0, 0x11)
W1026 21:30:40.945] 	/usr/local/go/src/crypto/tls/conn.go:608 +0x115
W1026 21:30:40.945] crypto/tls.(*Conn).readRecord(...)
W1026 21:30:40.945] 	/usr/local/go/src/crypto/tls/conn.go:576
W1026 21:30:40.946] crypto/tls.(*Conn).Read(0xc000210000, 0xc0003d2000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
W1026 21:30:40.946] 	/usr/local/go/src/crypto/tls/conn.go:1252 +0x15f
W1026 21:30:40.946] bufio.(*Reader).Read(0xc0004bf380, 0xc00003d538, 0x9, 0x9, 0x0, 0xc000085d20, 0x6e66b4)
W1026 21:30:40.946] 	/usr/local/go/src/bufio/bufio.go:227 +0x222
W1026 21:30:40.946] io.ReadAtLeast(0x1ccf320, 0xc0004bf380, 0xc00003d538, 0x9, 0x9, 0x9, 0xc0002c03c0, 0xc0003c1000, 0x11)
W1026 21:30:40.946] 	/usr/local/go/src/io/io.go:314 +0x87
W1026 21:30:40.946] io.ReadFull(...)
W1026 21:30:40.947] 	/usr/local/go/src/io/io.go:333
W1026 21:30:40.947] net/http.http2readFrameHeader(0xc00003d538, 0x9, 0x9, 0x1ccf320, 0xc0004bf380, 0x0, 0x0, 0x0, 0x0)
W1026 21:30:40.947] 	/usr/local/go/src/net/http/h2_bundle.go:1477 +0x89
W1026 21:30:40.947] net/http.(*http2Framer).ReadFrame(0xc00003d500, 0xc00078bc80, 0x0, 0x0, 0x0)
W1026 21:30:40.947] 	/usr/local/go/src/net/http/h2_bundle.go:1735 +0xa5
W1026 21:30:40.947] net/http.(*http2clientConnReadLoop).run(0xc000085fa8, 0x1, 0x0)
W1026 21:30:40.948] 	/usr/local/go/src/net/http/h2_bundle.go:8240 +0x8d
W1026 21:30:40.948] net/http.(*http2ClientConn).readLoop(0xc000202f00)
W1026 21:30:40.948] 	/usr/local/go/src/net/http/h2_bundle.go:8168 +0x6f
W1026 21:30:40.948] created by net/http.(*http2Transport).newClientConn
W1026 21:30:40.948] 	/usr/local/go/src/net/http/h2_bundle.go:7173 +0x66e
W1026 21:30:40.948] exit status 255
W1026 21:30:41.256] 2020/10/26 21:30: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 59.464020368s
W1026 21:30:41.256] 2020/10/26 21:30:41 e2e.go:544: Dumping logs locally to: /workspace/_artifacts
W1026 21:30:41.256] 2020/10/26 21:30:41 process.go:153: Running: ./cluster/log-dump/log-dump.sh /workspace/_artifacts
W1026 21:30:41.354] Trying to find master named 'bootstrap-e2e-master'
W1026 21:30:41.354] Looking for address 'bootstrap-e2e-master-ip'
I1026 21:30:41.455] Checking for custom logdump instances, if any
I1026 21:30:41.455] Sourcing kube-util.sh
I1026 21:30:41.455] Detecting project
I1026 21:30:41.455] Project: cri-containerd-node-e2e
I1026 21:30:41.456] Network Project: cri-containerd-node-e2e
I1026 21:30:41.456] Zone: us-central1-b
I1026 21:30:41.456] Dumping logs from master locally to '/workspace/_artifacts'
W1026 21:30:42.413] ERROR: (gcloud.compute.addresses.describe) Could not fetch resource:
W1026 21:30:42.413]  - The resource 'projects/cri-containerd-node-e2e/regions/us-central1/addresses/bootstrap-e2e-master-ip' was not found
W1026 21:30:42.413] 
W1026 21:30:42.677] Could not detect Kubernetes master node.  Make sure you've launched a cluster with 'kube-up.sh'
I1026 21:30:42.778] Master not detected. Is the cluster up?
I1026 21:30:42.778] Dumping logs from nodes locally to '/workspace/_artifacts'
I1026 21:30:42.778] Detecting nodes in the cluster
I1026 21:30:45.462] No nodes found!
W1026 21:30:47.861] INSTANCE_GROUPS=
W1026 21:30:47.862] NODE_NAMES=
W1026 21:30:47.864] 2020/10/26 21:30:47 process.go:155: Step './cluster/log-dump/log-dump.sh /workspace/_artifacts' finished in 6.610404982s
W1026 21:30:47.865] 2020/10/26 21:30:47 node.go:53: Noop - Node Down()
W1026 21:30:47.865] 2020/10/26 21:30:47 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W1026 21:30:47.865] 2020/10/26 21:30:47 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"
W1026 21:30:48.181] 2020/10/26 21:30:48 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 316.14035ms
W1026 21:30:48.183] 2020/10/26 21:30:48 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]
W1026 21:30:48.185] Traceback (most recent call last):
W1026 21:30:48.185]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 720, in <module>
W1026 21:30:48.185]     main(parse_args())
W1026 21:30:48.186]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 570, in main
W1026 21:30:48.186]     mode.start(runner_args)
W1026 21:30:48.186]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 228, in start
W1026 21:30:48.186]     check_env(env, self.command, *args)
W1026 21:30:48.187]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W1026 21:30:48.187]     subprocess.check_call(cmd, env=env)
W1026 21:30:48.187]   File "/usr/lib/python2.7/subprocess.py", line 190, in check_call
W1026 21:30:48.187]     raise CalledProcessError(retcode, cmd)
W1026 21:30:48.188] 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
E1026 21:30:48.197] Command failed
I1026 21:30:48.197] process 331 exited with code 1 after 1.2m
E1026 21:30:48.197] FAIL: ci-containerd-node-e2e-features
I1026 21:30:48.198] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1026 21:30:48.888] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1026 21:30:49.024] process 4759 exited with code 0 after 0.0m
I1026 21:30:49.024] Call:  gcloud config get-value account
I1026 21:30:49.669] process 4772 exited with code 0 after 0.0m
I1026 21:30:49.670] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I1026 21:30:49.670] Upload result and artifacts...
I1026 21:30:49.670] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320839438858719232
I1026 21:30:49.670] Call:  gsutil ls gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320839438858719232/artifacts
W1026 21:30:50.660] CommandException: One or more URLs matched no objects.
E1026 21:30:50.909] Command failed
I1026 21:30:50.910] process 4785 exited with code 1 after 0.0m
W1026 21:30:50.910] Remote dir gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320839438858719232/artifacts not exist yet
I1026 21:30:50.910] 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/1320839438858719232/artifacts
I1026 21:30:52.934] process 4928 exited with code 0 after 0.0m
I1026 21:30:52.935] Call:  git rev-parse HEAD
I1026 21:30:52.939] process 5453 exited with code 0 after 0.0m
I1026 21:30:52.939] Call:  git rev-parse HEAD
I1026 21:30:52.943] process 5454 exited with code 0 after 0.0m
I1026 21:30:52.943] Call:  gsutil stat gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/jobResultsCache.json
I1026 21:30:54.280] process 5455 exited with code 0 after 0.0m
I1026 21:30:54.281] Call:  gsutil -q cat 'gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/jobResultsCache.json#1603744133513113'
I1026 21:30:55.601] process 5598 exited with code 0 after 0.0m
I1026 21:30:55.605] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1603744133513113 cp /tmp/gsutil_MVM4cr gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/jobResultsCache.json
I1026 21:30:57.120] process 5743 exited with code 0 after 0.0m
I1026 21:30:57.121] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_EzAFfZ gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320839438858719232/finished.json
I1026 21:30:58.692] process 5922 exited with code 0 after 0.0m
I1026 21:30:58.693] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_oe1rIQ gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/latest-build.txt
I1026 21:31:00.284] process 6101 exited with code 0 after 0.0m