W1026 07:13:37.105] **************************************************************************
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 07:13:37.106] 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 07:13:37.106] Bootstrap ci-containerd-node-e2e-features...
I1026 07:13:37.108] Builder: bb6ac084-175a-11eb-b256-6ee25ea2e440
I1026 07:13:37.108] Image: gcr.io/k8s-testimages/kubekins-e2e:v20201021-a62ce8a-master
I1026 07:13:37.109] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320624545744293888
I1026 07:13:37.109] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1026 07:13:37.782] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1026 07:13:37.901] process 35 exited with code 0 after 0.0m
I1026 07:13:37.902] Call:  gcloud config get-value account
I1026 07:13:38.561] process 48 exited with code 0 after 0.0m
I1026 07:13:38.562] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I1026 07:13:38.562] Root: /go/src
I1026 07:13:38.562] cd to /go/src
I1026 07:13:38.562] Checkout: /go/src/k8s.io/kubernetes master to /go/src/k8s.io/kubernetes
I1026 07:13:38.563] Call:  git init k8s.io/kubernetes
I1026 07:13:38.570] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/
I1026 07:13:38.570] process 61 exited with code 0 after 0.0m
I1026 07:13:38.570] Call:  git config --local user.name 'K8S Bootstrap'
I1026 07:13:38.577] process 62 exited with code 0 after 0.0m
I1026 07:13:38.577] Call:  git config --local user.email k8s_bootstrap@localhost
I1026 07:13:38.582] process 63 exited with code 0 after 0.0m
I1026 07:13:38.582] Call:  git fetch --quiet --tags https://github.com/kubernetes/kubernetes master
I1026 07:14:48.073] process 64 exited with code 0 after 1.2m
I1026 07:14:48.074] Call:  git checkout -B test FETCH_HEAD
W1026 07:14:50.925] Switched to a new branch 'test'
I1026 07:14:50.964] process 75 exited with code 0 after 0.0m
I1026 07:14:50.965] Call:  git show -s --format=format:%ct HEAD
I1026 07:14:50.981] process 76 exited with code 0 after 0.0m
I1026 07:14:50.981] Checkout: /go/src/github.com/containerd/cri master to /go/src/github.com/containerd/cri
I1026 07:14:50.981] Call:  git init github.com/containerd/cri
I1026 07:14:50.988] Initialized empty Git repository in /go/src/github.com/containerd/cri/.git/
I1026 07:14:50.989] process 77 exited with code 0 after 0.0m
I1026 07:14:50.989] Call:  git config --local user.name 'K8S Bootstrap'
I1026 07:14:50.994] process 78 exited with code 0 after 0.0m
I1026 07:14:50.995] Call:  git config --local user.email k8s_bootstrap@localhost
I1026 07:14:50.999] process 79 exited with code 0 after 0.0m
I1026 07:14:51.000] Call:  git fetch --quiet --tags https://github.com/containerd/cri master
I1026 07:14:52.780] process 80 exited with code 0 after 0.0m
I1026 07:14:52.781] Call:  git checkout -B test FETCH_HEAD
W1026 07:14:53.257] Switched to a new branch 'test'
I1026 07:14:53.274] process 91 exited with code 0 after 0.0m
I1026 07:14:53.275] Call:  git show -s --format=format:%ct HEAD
I1026 07:14:53.280] process 92 exited with code 0 after 0.0m
I1026 07:14:53.280] Configure environment...
I1026 07:14:53.281] Call:  git show -s --format=format:%ct HEAD
I1026 07:14:53.295] process 93 exited with code 0 after 0.0m
I1026 07:14:53.296] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1026 07:14:54.009] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1026 07:14:54.261] process 94 exited with code 0 after 0.0m
I1026 07:14:54.261] Call:  gcloud config get-value account
I1026 07:14:54.906] process 107 exited with code 0 after 0.0m
I1026 07:14:54.906] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I1026 07:14:54.907] 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 07:14:55.674] process 120 exited with code 0 after 0.0m
I1026 07:14:55.674] Start 1320624545744293888 at v1.20.0-alpha.3.116+16e18a590f32b9...
I1026 07:14:55.677] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_PFMgs3 gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320624545744293888/started.json
I1026 07:14:57.259] process 153 exited with code 0 after 0.0m
I1026 07:14:57.260] 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 07:14:57.315] starts with local mode
W1026 07:14:57.315] Environment:
W1026 07:14:57.315] ARTIFACTS=/workspace/_artifacts
W1026 07:14:57.316] AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa
W1026 07:14:57.316] AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub
W1026 07:14:57.316] BAZEL_REMOTE_CACHE_ENABLED=false
W1026 07:14:57.316] BAZEL_VERSION=2.2.0
W1026 07:14:57.316] BOOTSTRAP_MIGRATION=yes
W1026 07:14:57.316] BOSKOS_METRICS_PORT=tcp://10.63.252.110:9090
W1026 07:14:57.316] BOSKOS_METRICS_PORT_9090_TCP=tcp://10.63.252.110:9090
W1026 07:14:57.317] BOSKOS_METRICS_PORT_9090_TCP_ADDR=10.63.252.110
W1026 07:14:57.317] BOSKOS_METRICS_PORT_9090_TCP_PORT=9090
W1026 07:14:57.317] BOSKOS_METRICS_PORT_9090_TCP_PROTO=tcp
W1026 07:14:57.317] BOSKOS_METRICS_SERVICE_HOST=10.63.252.110
W1026 07:14:57.317] BOSKOS_METRICS_SERVICE_PORT=9090
W1026 07:14:57.317] BOSKOS_METRICS_SERVICE_PORT_METRICS=9090
W1026 07:14:57.317] BOSKOS_PORT=tcp://10.63.250.132:80
W1026 07:14:57.318] BOSKOS_PORT_80_TCP=tcp://10.63.250.132:80
W1026 07:14:57.318] BOSKOS_PORT_80_TCP_ADDR=10.63.250.132
W1026 07:14:57.318] BOSKOS_PORT_80_TCP_PORT=80
W1026 07:14:57.318] BOSKOS_PORT_80_TCP_PROTO=tcp
W1026 07:14:57.318] BOSKOS_SERVICE_HOST=10.63.250.132
W1026 07:14:57.318] BOSKOS_SERVICE_PORT=80
W1026 07:14:57.318] BOSKOS_SERVICE_PORT_DEFAULT=80
W1026 07:14:57.318] BUILD_ID=1320624545744293888
W1026 07:14:57.318] BUILD_NUMBER=1320624545744293888
W1026 07:14:57.319] CI=true
W1026 07:14:57.319] CLOUDSDK_COMPONENT_MANAGER_DISABLE_UPDATE_CHECK=true
W1026 07:14:57.319] CLOUDSDK_CONFIG=/go/src/k8s.io/kubernetes/.config/gcloud
W1026 07:14:57.319] CLOUDSDK_CORE_DISABLE_PROMPTS=1
W1026 07:14:57.319] CLOUDSDK_EXPERIMENTAL_FAST_COMPONENT_UPDATE=false
W1026 07:14:57.319] DOCKER_IN_DOCKER_ENABLED=false
W1026 07:14:57.319] DOCKER_IN_DOCKER_IPV6_ENABLED=false
W1026 07:14:57.319] E2E_GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json
W1026 07:14:57.320] GCS_ARTIFACTS_DIR=gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320624545744293888/artifacts
W1026 07:14:57.320] GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json
W1026 07:14:57.320] GOOGLE_APPLICATION_CREDENTIALS_DEPRECATED=Migrate to workload identity, contact sig-testing
W1026 07:14:57.320] GOPATH=/go
W1026 07:14:57.320] GOPROXY=https://proxy.golang.org
W1026 07:14:57.320] GO_TARBALL=go1.15.2.linux-amd64.tar.gz
W1026 07:14:57.320] HOME=/workspace
W1026 07:14:57.321] HOSTNAME=bb6ac084-175a-11eb-b256-6ee25ea2e440
W1026 07:14:57.321] IMAGE=gcr.io/k8s-testimages/kubekins-e2e:v20201021-a62ce8a-master
W1026 07:14:57.321] INSTANCE_PREFIX=bootstrap-e2e
W1026 07:14:57.321] JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/workspace/.ssh/google_compute_engine
W1026 07:14:57.321] JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/workspace/.ssh/google_compute_engine.pub
W1026 07:14:57.321] JOB_NAME=ci-containerd-node-e2e-features
W1026 07:14:57.321] JOB_SPEC={"type":"periodic","job":"ci-containerd-node-e2e-features","buildid":"1320624545744293888","prowjobid":"bb6ac084-175a-11eb-b256-6ee25ea2e440"}
W1026 07:14:57.322] JOB_TYPE=periodic
W1026 07:14:57.322] KUBERNETES_PORT=tcp://10.63.240.1:443
W1026 07:14:57.322] KUBERNETES_PORT_443_TCP=tcp://10.63.240.1:443
W1026 07:14:57.322] KUBERNETES_PORT_443_TCP_ADDR=10.63.240.1
W1026 07:14:57.322] KUBERNETES_PORT_443_TCP_PORT=443
W1026 07:14:57.322] KUBERNETES_PORT_443_TCP_PROTO=tcp
W1026 07:14:57.322] KUBERNETES_SERVICE_HOST=10.63.240.1
W1026 07:14:57.322] KUBERNETES_SERVICE_PORT=443
W1026 07:14:57.322] KUBERNETES_SERVICE_PORT_HTTPS=443
W1026 07:14:57.322] KUBETEST_IN_DOCKER=true
W1026 07:14:57.323] KUBETEST_MANUAL_DUMP=y
W1026 07:14:57.323] KUBE_AWS_INSTANCE_PREFIX=bootstrap-e2e
W1026 07:14:57.323] KUBE_GCE_INSTANCE_PREFIX=bootstrap-e2e
W1026 07:14:57.323] LOG_DUMP_SCRIPT_PATH=/workspace/log-dump.sh
W1026 07:14:57.323] NODE_NAME=bb6ac084-175a-11eb-b256-6ee25ea2e440
W1026 07:14:57.323] 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 07:14:57.323] PROW_JOB_ID=bb6ac084-175a-11eb-b256-6ee25ea2e440
W1026 07:14:57.324] PWD=/workspace
W1026 07:14:57.324] SHLVL=2
W1026 07:14:57.324] SOURCE_DATE_EPOCH=1603672261
W1026 07:14:57.324] TERM=xterm
W1026 07:14:57.324] USER=prow
W1026 07:14:57.324] WORKSPACE=/workspace
W1026 07:14:57.324] _=./test-infra/jenkins/bootstrap.py
W1026 07:14:57.325] 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 07:14:57.546] 2020/10/26 07:14:57 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 07:14:57.548] 2020/10/26 07:14:57 main.go:329: Limiting testing to 1h5m0s
W1026 07:14:57.549] 2020/10/26 07:14:57 process.go:153: Running: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1026 07:14:58.231] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
W1026 07:14:58.358] 2020/10/26 07:14:58 process.go:155: Step 'gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json' finished in 809.291895ms
W1026 07:14:58.358] 2020/10/26 07:14:58 process.go:153: Running: gcloud config set project cri-containerd-node-e2e
W1026 07:14:58.911] Updated property [core/project].
W1026 07:14:59.171] 2020/10/26 07:14:59 process.go:155: Step 'gcloud config set project cri-containerd-node-e2e' finished in 813.235351ms
W1026 07:14:59.172] 2020/10/26 07:14:59 main.go:779: Checking existing of GCP ssh keys...
W1026 07:14:59.172] 2020/10/26 07:14:59 main.go:789: Checking presence of public key in cri-containerd-node-e2e
W1026 07:14:59.172] 2020/10/26 07:14:59 process.go:153: Running: gcloud compute --project=cri-containerd-node-e2e project-info describe
W1026 07:15:00.700] 2020/10/26 07:15:00 process.go:155: Step 'gcloud compute --project=cri-containerd-node-e2e project-info describe' finished in 1.528937904s
W1026 07:15:00.703] 2020/10/26 07:15:00 node.go:62: Noop - Node KubectlCommand()
W1026 07:15:00.703] 2020/10/26 07:15:00 node.go:53: Noop - Node Down()
W1026 07:15:00.704] 2020/10/26 07:15:00 node.go:34: Noop - Node Up()
W1026 07:15:00.704] 2020/10/26 07:15:00 node.go:48: Noop - Node TestSetup()
W1026 07:15:00.704] 2020/10/26 07:15:00 e2e.go:560: cwd : /go/src/k8s.io/kubernetes
W1026 07:15:00.705] 2020/10/26 07:15:00 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 07:15:34.785] I1026 07:15:34.784323    4623 run_remote.go:501] found images [{creationTime:{wall:749000000 ext:63738746175 loc:0xc0001ea150} name:cos-85-13310-1041-24} {creationTime:{wall:948000000 ext:63738132225 loc:0xc0001ea0e0} name:cos-85-13310-1041-17} {creationTime:{wall:951000000 ext:63737797521 loc:0xc0001ea070} name:cos-85-13310-1041-14} {creationTime:{wall:449000000 ext:63736586944 loc:0xc0001ea1c0} name:cos-85-13310-1041-9} {creationTime:{wall:241000000 ext:63736054782 loc:0xc0001ea620} name:cos-rc-85-13310-1041-1} {creationTime:{wall:578000000 ext:63734848446 loc:0xc0001ea5b0} name:cos-rc-85-13310-1040-0} {creationTime:{wall:877000000 ext:63734695858 loc:0xc0001ea540} name:cos-rc-85-13310-1037-0} {creationTime:{wall:580000000 ext:63734153893 loc:0xc0001ea4d0} name:cos-rc-85-13310-1034-0} {creationTime:{wall:643000000 ext:63733310807 loc:0xc0001ea3f0} name:cos-rc-85-13310-1032-0} {creationTime:{wall:981000000 ext:63732438114 loc:0xc0001ea380} name:cos-rc-85-13310-1028-0} {creationTime:{wall:757000000 ext:63731743261 loc:0xc0001ea310} name:cos-rc-85-13310-1023-0} {creationTime:{wall:875000000 ext:63731230865 loc:0xc0001ea2a0} name:cos-rc-85-13310-1019-0} {creationTime:{wall:861000000 ext:63730454305 loc:0xc0001ea230} name:cos-rc-85-13310-1015-0} {creationTime:{wall:382000000 ext:63729939006 loc:0xc0001ea690} name:cos-rc-85-13310-12-0}] based on regex "" and family "cos-85-lts" in project "cos-cloud"
W1026 07:15:34.786] I1026 07:15:34.784406    4623 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 07:15:34.786] F1026 07:15:34.784477    4623 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 07:15:34.786] goroutine 1 [running]:
W1026 07:15:34.787] k8s.io/klog/v2.stacks(0xc0000d4001, 0xc0000e69a0, 0xf3, 0x141)
W1026 07:15:34.787] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:996 +0xb9
W1026 07:15:34.787] k8s.io/klog/v2.(*loggingT).output(0x27ba960, 0xc000000003, 0x0, 0x0, 0xc00059c150, 0x271c802, 0xd, 0x314, 0x0)
W1026 07:15:34.787] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:945 +0x191
W1026 07:15:34.788] k8s.io/klog/v2.(*loggingT).printf(0x27ba960, 0x3, 0x0, 0x0, 0x1b1529c, 0x23, 0xc0007e30e0, 0x2, 0x2)
W1026 07:15:34.788] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:733 +0x17a
W1026 07:15:34.788] k8s.io/klog/v2.Fatalf(...)
W1026 07:15:34.788] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:1456
W1026 07:15:34.788] main.parseInstanceMetadata(0xc000234180, 0x178, 0x0)
W1026 07:15:34.788] 	/go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go:788 +0x46f
W1026 07:15:34.789] main.getImageMetadata(0xc000234180, 0x178, 0x0)
W1026 07:15:34.789] 	/go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go:404 +0xe6
W1026 07:15:34.789] main.main()
W1026 07:15:34.789] 	/go/src/k8s.io/kubernetes/test/e2e_node/runner/remote/run_remote.go:257 +0x68f
W1026 07:15:34.789] 
W1026 07:15:34.789] goroutine 19 [chan receive]:
W1026 07:15:34.790] k8s.io/klog/v2.(*loggingT).flushDaemon(0x27ba960)
W1026 07:15:34.790] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:1131 +0x8b
W1026 07:15:34.790] created by k8s.io/klog/v2.init.0
W1026 07:15:34.790] 	/go/src/k8s.io/kubernetes/vendor/k8s.io/klog/v2/klog.go:416 +0xd8
W1026 07:15:34.790] 
W1026 07:15:34.790] goroutine 12 [select]:
W1026 07:15:34.790] go.opencensus.io/stats/view.(*worker).start(0xc0004a1270)
W1026 07:15:34.791] 	/go/src/k8s.io/kubernetes/vendor/go.opencensus.io/stats/view/worker.go:154 +0x105
W1026 07:15:34.791] created by go.opencensus.io/stats/view.init.0
W1026 07:15:34.791] 	/go/src/k8s.io/kubernetes/vendor/go.opencensus.io/stats/view/worker.go:32 +0x57
W1026 07:15:34.791] 
W1026 07:15:34.791] goroutine 67 [IO wait]:
W1026 07:15:34.791] internal/poll.runtime_pollWait(0x7f657872a3f8, 0x72, 0x1cd0a00)
W1026 07:15:34.791] 	/usr/local/go/src/runtime/netpoll.go:220 +0x55
W1026 07:15:34.792] internal/poll.(*pollDesc).wait(0xc00020e818, 0x72, 0xc0002bc000, 0xf68, 0xf68)
W1026 07:15:34.792] 	/usr/local/go/src/internal/poll/fd_poll_runtime.go:87 +0x45
W1026 07:15:34.792] internal/poll.(*pollDesc).waitRead(...)
W1026 07:15:34.792] 	/usr/local/go/src/internal/poll/fd_poll_runtime.go:92
W1026 07:15:34.792] internal/poll.(*FD).Read(0xc00020e800, 0xc0002bc000, 0xf68, 0xf68, 0x0, 0x0, 0x0)
W1026 07:15:34.792] 	/usr/local/go/src/internal/poll/fd_unix.go:159 +0x1b1
W1026 07:15:34.793] net.(*netFD).Read(0xc00020e800, 0xc0002bc000, 0xf68, 0xf68, 0x203000, 0x62647b, 0xc00028e160)
W1026 07:15:34.793] 	/usr/local/go/src/net/fd_posix.go:55 +0x4f
W1026 07:15:34.793] net.(*conn).Read(0xc0000d4070, 0xc0002bc000, 0xf68, 0xf68, 0x0, 0x0, 0x0)
W1026 07:15:34.793] 	/usr/local/go/src/net/net.go:182 +0x8e
W1026 07:15:34.793] crypto/tls.(*atLeastReader).Read(0xc000488d80, 0xc0002bc000, 0xf68, 0xf68, 0x22, 0xc54, 0xc000331730)
W1026 07:15:34.793] 	/usr/local/go/src/crypto/tls/conn.go:779 +0x62
W1026 07:15:34.793] bytes.(*Buffer).ReadFrom(0xc00028e280, 0x1ccdec0, 0xc000488d80, 0x40d085, 0x18b3c60, 0x1a78700)
W1026 07:15:34.793] 	/usr/local/go/src/bytes/buffer.go:204 +0xb1
W1026 07:15:34.794] crypto/tls.(*Conn).readFromUntil(0xc00028e000, 0x1ccf600, 0xc0000d4070, 0x5, 0xc0000d4070, 0x11)
W1026 07:15:34.794] 	/usr/local/go/src/crypto/tls/conn.go:801 +0xf3
W1026 07:15:34.794] crypto/tls.(*Conn).readRecordOrCCS(0xc00028e000, 0x0, 0x0, 0x11)
W1026 07:15:34.794] 	/usr/local/go/src/crypto/tls/conn.go:608 +0x115
W1026 07:15:34.794] crypto/tls.(*Conn).readRecord(...)
W1026 07:15:34.794] 	/usr/local/go/src/crypto/tls/conn.go:576
W1026 07:15:34.794] crypto/tls.(*Conn).Read(0xc00028e000, 0xc000195000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
W1026 07:15:34.795] 	/usr/local/go/src/crypto/tls/conn.go:1252 +0x15f
W1026 07:15:34.795] bufio.(*Reader).Read(0xc00044d320, 0xc0003f9538, 0x9, 0x9, 0x0, 0xc000331d20, 0x6e66b4)
W1026 07:15:34.795] 	/usr/local/go/src/bufio/bufio.go:227 +0x222
W1026 07:15:34.795] io.ReadAtLeast(0x1ccdd40, 0xc00044d320, 0xc0003f9538, 0x9, 0x9, 0x9, 0xc000488c60, 0xc0004fd000, 0x11)
W1026 07:15:34.795] 	/usr/local/go/src/io/io.go:314 +0x87
W1026 07:15:34.795] io.ReadFull(...)
W1026 07:15:34.795] 	/usr/local/go/src/io/io.go:333
W1026 07:15:34.796] net/http.http2readFrameHeader(0xc0003f9538, 0x9, 0x9, 0x1ccdd40, 0xc00044d320, 0x0, 0x0, 0x0, 0x0)
W1026 07:15:34.796] 	/usr/local/go/src/net/http/h2_bundle.go:1477 +0x89
W1026 07:15:34.796] net/http.(*http2Framer).ReadFrame(0xc0003f9500, 0xc00048e360, 0x0, 0x0, 0x0)
W1026 07:15:34.796] 	/usr/local/go/src/net/http/h2_bundle.go:1735 +0xa5
W1026 07:15:34.796] net/http.(*http2clientConnReadLoop).run(0xc000331fa8, 0x0, 0x0)
W1026 07:15:34.796] 	/usr/local/go/src/net/http/h2_bundle.go:8240 +0x8d
W1026 07:15:34.796] net/http.(*http2ClientConn).readLoop(0xc000322d80)
W1026 07:15:34.797] 	/usr/local/go/src/net/http/h2_bundle.go:8168 +0x6f
W1026 07:15:34.797] created by net/http.(*http2Transport).newClientConn
W1026 07:15:34.797] 	/usr/local/go/src/net/http/h2_bundle.go:7173 +0x66e
W1026 07:15:34.797] 
W1026 07:15:34.797] goroutine 98 [IO wait]:
W1026 07:15:34.797] internal/poll.runtime_pollWait(0x7f657872a238, 0x72, 0x1cd0a00)
W1026 07:15:34.797] 	/usr/local/go/src/runtime/netpoll.go:220 +0x55
W1026 07:15:34.798] internal/poll.(*pollDesc).wait(0xc0002a2b98, 0x72, 0xc000576000, 0xf69, 0xf69)
W1026 07:15:34.798] 	/usr/local/go/src/internal/poll/fd_poll_runtime.go:87 +0x45
W1026 07:15:34.798] internal/poll.(*pollDesc).waitRead(...)
W1026 07:15:34.798] 	/usr/local/go/src/internal/poll/fd_poll_runtime.go:92
W1026 07:15:34.798] internal/poll.(*FD).Read(0xc0002a2b80, 0xc000576000, 0xf69, 0xf69, 0x0, 0x0, 0x0)
W1026 07:15:34.798] 	/usr/local/go/src/internal/poll/fd_unix.go:159 +0x1b1
W1026 07:15:34.798] net.(*netFD).Read(0xc0002a2b80, 0xc000576000, 0xf69, 0xf69, 0x203000, 0x62647b, 0xc00028e860)
W1026 07:15:34.799] 	/usr/local/go/src/net/fd_posix.go:55 +0x4f
W1026 07:15:34.799] net.(*conn).Read(0xc00048a060, 0xc000576000, 0xf69, 0xf69, 0x0, 0x0, 0x0)
W1026 07:15:34.799] 	/usr/local/go/src/net/net.go:182 +0x8e
W1026 07:15:34.799] crypto/tls.(*atLeastReader).Read(0xc00033cce0, 0xc000576000, 0xf69, 0xf69, 0x22, 0x581, 0xc000554730)
W1026 07:15:34.799] 	/usr/local/go/src/crypto/tls/conn.go:779 +0x62
W1026 07:15:34.799] bytes.(*Buffer).ReadFrom(0xc00028e980, 0x1ccdec0, 0xc00033cce0, 0x40d085, 0x18b3c60, 0x1a78700)
W1026 07:15:34.799] 	/usr/local/go/src/bytes/buffer.go:204 +0xb1
W1026 07:15:34.799] crypto/tls.(*Conn).readFromUntil(0xc00028e700, 0x1ccf600, 0xc00048a060, 0x5, 0xc00048a060, 0x11)
W1026 07:15:34.800] 	/usr/local/go/src/crypto/tls/conn.go:801 +0xf3
W1026 07:15:34.800] crypto/tls.(*Conn).readRecordOrCCS(0xc00028e700, 0x0, 0x0, 0x11)
W1026 07:15:34.800] 	/usr/local/go/src/crypto/tls/conn.go:608 +0x115
W1026 07:15:34.800] crypto/tls.(*Conn).readRecord(...)
W1026 07:15:34.800] 	/usr/local/go/src/crypto/tls/conn.go:576
W1026 07:15:34.800] crypto/tls.(*Conn).Read(0xc00028e700, 0xc000744000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
W1026 07:15:34.800] 	/usr/local/go/src/crypto/tls/conn.go:1252 +0x15f
W1026 07:15:34.800] bufio.(*Reader).Read(0xc00038f9e0, 0xc000752818, 0x9, 0x9, 0x0, 0xc000554d20, 0x6e66b4)
W1026 07:15:34.800] 	/usr/local/go/src/bufio/bufio.go:227 +0x222
W1026 07:15:34.801] io.ReadAtLeast(0x1ccdd40, 0xc00038f9e0, 0xc000752818, 0x9, 0x9, 0x9, 0xc0004fe180, 0xc000577000, 0x11)
W1026 07:15:34.801] 	/usr/local/go/src/io/io.go:314 +0x87
W1026 07:15:34.801] io.ReadFull(...)
W1026 07:15:34.801] 	/usr/local/go/src/io/io.go:333
W1026 07:15:34.801] net/http.http2readFrameHeader(0xc000752818, 0x9, 0x9, 0x1ccdd40, 0xc00038f9e0, 0x0, 0x0, 0x0, 0x0)
W1026 07:15:34.801] 	/usr/local/go/src/net/http/h2_bundle.go:1477 +0x89
W1026 07:15:34.802] net/http.(*http2Framer).ReadFrame(0xc0007527e0, 0xc0007d7c60, 0x0, 0x0, 0x0)
W1026 07:15:34.802] 	/usr/local/go/src/net/http/h2_bundle.go:1735 +0xa5
W1026 07:15:34.802] net/http.(*http2clientConnReadLoop).run(0xc000554fa8, 0x1, 0x0)
W1026 07:15:34.802] 	/usr/local/go/src/net/http/h2_bundle.go:8240 +0x8d
W1026 07:15:34.802] net/http.(*http2ClientConn).readLoop(0xc0000a3c80)
W1026 07:15:34.802] 	/usr/local/go/src/net/http/h2_bundle.go:8168 +0x6f
W1026 07:15:34.802] created by net/http.(*http2Transport).newClientConn
W1026 07:15:34.803] 	/usr/local/go/src/net/http/h2_bundle.go:7173 +0x66e
W1026 07:15:34.803] exit status 255
W1026 07:15:35.114] 2020/10/26 07:15:35 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 34.409583334s
W1026 07:15:35.114] 2020/10/26 07:15:35 e2e.go:544: Dumping logs locally to: /workspace/_artifacts
W1026 07:15:35.114] 2020/10/26 07:15:35 process.go:153: Running: ./cluster/log-dump/log-dump.sh /workspace/_artifacts
W1026 07:15:35.198] Trying to find master named 'bootstrap-e2e-master'
W1026 07:15:35.199] Looking for address 'bootstrap-e2e-master-ip'
I1026 07:15:35.299] Checking for custom logdump instances, if any
I1026 07:15:35.300] Sourcing kube-util.sh
I1026 07:15:35.300] Detecting project
I1026 07:15:35.300] Project: cri-containerd-node-e2e
I1026 07:15:35.300] Network Project: cri-containerd-node-e2e
I1026 07:15:35.300] Zone: us-central1-b
I1026 07:15:35.301] Dumping logs from master locally to '/workspace/_artifacts'
W1026 07:15:36.117] ERROR: (gcloud.compute.addresses.describe) Could not fetch resource:
W1026 07:15:36.118]  - The resource 'projects/cri-containerd-node-e2e/regions/us-central1/addresses/bootstrap-e2e-master-ip' was not found
W1026 07:15:36.118] 
W1026 07:15:36.298] Could not detect Kubernetes master node.  Make sure you've launched a cluster with 'kube-up.sh'
I1026 07:15:36.399] Master not detected. Is the cluster up?
I1026 07:15:36.399] Dumping logs from nodes locally to '/workspace/_artifacts'
I1026 07:15:36.399] Detecting nodes in the cluster
I1026 07:15:38.767] No nodes found!
W1026 07:15:41.096] INSTANCE_GROUPS=
W1026 07:15:41.097] NODE_NAMES=
W1026 07:15:41.100] 2020/10/26 07:15:41 process.go:155: Step './cluster/log-dump/log-dump.sh /workspace/_artifacts' finished in 5.987366494s
W1026 07:15:41.101] 2020/10/26 07:15:41 node.go:53: Noop - Node Down()
W1026 07:15:41.101] 2020/10/26 07:15:41 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W1026 07:15:41.101] 2020/10/26 07:15:41 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"
W1026 07:15:41.412] 2020/10/26 07:15:41 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 310.350001ms
W1026 07:15:41.413] 2020/10/26 07:15:41 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 07:15:41.416] Traceback (most recent call last):
W1026 07:15:41.417]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 720, in <module>
W1026 07:15:41.417]     main(parse_args())
W1026 07:15:41.417]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 570, in main
W1026 07:15:41.417]     mode.start(runner_args)
W1026 07:15:41.418]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 228, in start
W1026 07:15:41.418]     check_env(env, self.command, *args)
W1026 07:15:41.418]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W1026 07:15:41.418]     subprocess.check_call(cmd, env=env)
W1026 07:15:41.418]   File "/usr/lib/python2.7/subprocess.py", line 190, in check_call
W1026 07:15:41.418]     raise CalledProcessError(retcode, cmd)
W1026 07:15:41.420] 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 07:15:41.428] Command failed
I1026 07:15:41.428] process 332 exited with code 1 after 0.7m
E1026 07:15:41.428] FAIL: ci-containerd-node-e2e-features
I1026 07:15:41.429] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1026 07:15:42.081] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1026 07:15:42.204] process 4773 exited with code 0 after 0.0m
I1026 07:15:42.204] Call:  gcloud config get-value account
I1026 07:15:42.842] process 4786 exited with code 0 after 0.0m
I1026 07:15:42.843] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I1026 07:15:42.843] Upload result and artifacts...
I1026 07:15:42.843] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320624545744293888
I1026 07:15:42.843] Call:  gsutil ls gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320624545744293888/artifacts
W1026 07:15:43.873] CommandException: One or more URLs matched no objects.
E1026 07:15:44.076] Command failed
I1026 07:15:44.077] process 4799 exited with code 1 after 0.0m
W1026 07:15:44.077] Remote dir gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320624545744293888/artifacts not exist yet
I1026 07:15:44.077] 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/1320624545744293888/artifacts
I1026 07:15:45.753] process 4942 exited with code 0 after 0.0m
I1026 07:15:45.754] Call:  git rev-parse HEAD
I1026 07:15:45.759] process 5467 exited with code 0 after 0.0m
I1026 07:15:45.759] Call:  git rev-parse HEAD
I1026 07:15:45.763] process 5468 exited with code 0 after 0.0m
I1026 07:15:45.763] Call:  gsutil stat gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/jobResultsCache.json
I1026 07:15:46.937] process 5469 exited with code 0 after 0.0m
I1026 07:15:46.938] Call:  gsutil -q cat 'gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/jobResultsCache.json#1603692891310445'
I1026 07:15:48.175] process 5612 exited with code 0 after 0.0m
I1026 07:15:48.180] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1603692891310445 cp /tmp/gsutil_qylMVI gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/jobResultsCache.json
I1026 07:15:49.724] process 5757 exited with code 0 after 0.0m
I1026 07:15:49.725] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_Axmr91 gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/1320624545744293888/finished.json
I1026 07:15:51.165] process 5936 exited with code 0 after 0.0m
I1026 07:15:51.166] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_UH6C30 gs://kubernetes-jenkins/logs/ci-containerd-node-e2e-features/latest-build.txt
I1026 07:15:52.662] process 6115 exited with code 0 after 0.0m