This job view page is being replaced by Spyglass soon. Check out the new job view.
PRsmarterclayton: Report a watch error instead of eating it when we can't decode
ResultFAILURE
Tests 1 failed / 796 succeeded
Started2019-02-12 06:15
Elapsed18m37s
Revision
Buildergke-prow-containerd-pool-99179761-c7v4
Refs master:f5073439
73937:cc64c988
pod949755a2-2e8d-11e9-842b-0a580a6c061e
infra-commit490a26c55
pod949755a2-2e8d-11e9-842b-0a580a6c061e
repok8s.io/kubernetes
repo-commit5b870305220f15cc036113d1cde7dc69b8ee1f74
repos{u'k8s.io/kubernetes': u'master:f50734399b82a3b73200d7197cf89b0773c5cced,73937:cc64c988f1f6804a6d88eb14ad39603017510867'}

Test Failures


//pkg/scheduler/internal/queue:go_default_test 0.00s

bazel test //pkg/scheduler/internal/queue:go_default_test
exec ${PAGER:-/usr/bin/less} "$0" || exit 1
Executing tests from //pkg/scheduler/internal/queue:go_default_test
-----------------------------------------------------------------------------
--- FAIL: TestPriorityQueue_PendingPods (0.00s)
    scheduling_queue_test.go:457: Unexpected list of pending Pods for node.
FAIL

				from junit_bazel.xml

Filter through log files | View test history on testgrid


Show 796 Passed Tests

Error lines from build-log.txt

... skipping 142 lines ...
W0212 06:22:27.579] INFO: From  <rule context for //pkg/generated/openapi:zz_generated.openapi>:
W0212 06:22:27.580] 2019/02/11 22:41:27 Code for OpenAPI definitions generated
W0212 06:23:13.910] [6,665 / 7,089] 2 / 797 tests; GoLink cmd/controller-manager/app/options/linux_amd64_race_stripped/go_default_test; 20s linux-sandbox ... (8 actions, 7 running)
W0212 06:24:07.368] [6,773 / 7,096] 2 / 797 tests; GoLink cmd/kube-controller-manager/app/options/linux_amd64_race_stripped/go_default_test; 29s linux-sandbox ... (8 actions, 7 running)
W0212 06:25:09.064] [6,952 / 7,110] 15 / 797 tests; GoLink test/integration/apimachinery/linux_amd64_race_stripped/go_default_test; 17s linux-sandbox ... (8 actions running)
W0212 06:26:19.939] [7,351 / 7,359] 202 / 797 tests; Testing //cmd/kubeadm/app/phases/upgrade:go_default_test; 72s linux-sandbox ... (8 actions running)
W0212 06:27:12.287] FAIL: //pkg/kubelet/cm/devicemanager:go_default_test (see /bazel-scratch/.cache/bazel/_bazel_root/e9f728bbd90b3fba632eb31b20e1dacd/execroot/__main__/bazel-out/k8-fastbuild/testlogs/pkg/kubelet/cm/devicemanager/go_default_test/test_attempts/attempt_1.log)
W0212 06:27:24.780] 
W0212 06:27:24.781] FLAKY: //pkg/kubelet/cm/devicemanager:go_default_test (Summary)
W0212 06:27:24.781]       /bazel-scratch/.cache/bazel/_bazel_root/e9f728bbd90b3fba632eb31b20e1dacd/execroot/__main__/bazel-out/k8-fastbuild/testlogs/pkg/kubelet/cm/devicemanager/go_default_test/test_attempts/attempt_1.log
W0212 06:27:24.785] INFO: From Testing //pkg/kubelet/cm/devicemanager:go_default_test:
I0212 06:27:24.888] ==================== Test output for //pkg/kubelet/cm/devicemanager:go_default_test:
I0212 06:27:24.888] 2019/02/12 06:27:01 Starting to serve on /tmp/mock.sock
I0212 06:27:24.888] 2019/02/12 06:27:01 Starting to serve on /tmp/mock.sock
I0212 06:27:24.888] 2019/02/12 06:27:01 ListAndWatch
I0212 06:27:24.889] E0212 06:27:01.050404      15 endpoint.go:106] listAndWatch ended unexpectedly for device plugin mock with error rpc error: code = Unavailable desc = transport is closing
I0212 06:27:24.889] 2019/02/12 06:27:01 Starting to serve on /tmp/mock.sock
I0212 06:27:24.889] 2019/02/12 06:27:01 ListAndWatch
I0212 06:27:24.889] 2019/02/12 06:27:01 Allocate, &AllocateRequest{ContainerRequests:[&ContainerAllocateRequest{DevicesIDs:[ADeviceId],}],}
I0212 06:27:24.890] E0212 06:27:01.066573      15 endpoint.go:106] listAndWatch ended unexpectedly for device plugin mock with error rpc error: code = Canceled desc = grpc: the client connection is closing
I0212 06:27:24.890] 2019/02/12 06:27:01 Starting to serve on /tmp/device_plugin008963363/device-plugin.sock
I0212 06:27:24.890] 2019/02/12 06:27:01 Starting to serve on /tmp/device_plugin082350630/device-plugin.sock
I0212 06:27:24.890] E0212 06:27:11.074901      15 plugin_watcher.go:120] error dial failed at socket /tmp/device_plugin082350630/device-plugin.sock, err: failed to dial socket /tmp/device_plugin082350630/device-plugin.sock, err: context deadline exceeded when handling create event: "/tmp/device_plugin082350630/device-plugin.sock": CREATE
I0212 06:27:24.891] --- FAIL: TestNewManagerImplStartProbeMode (11.01s)
I0212 06:27:24.891]     <autogenerated>:1: 
I0212 06:27:24.891]         	Error Trace:	manager_test.go:275
I0212 06:27:24.891]         	            				manager_test.go:81
I0212 06:27:24.891]         	Error:      	Received unexpected error:
I0212 06:27:24.892]         	            	timeout on stopping watcher
I0212 06:27:24.892]         	Test:       	TestNewManagerImplStartProbeMode
I0212 06:27:24.892] 2019/02/12 06:27:12 Starting to serve on /tmp/device_plugin023587661/device-plugin.sock
I0212 06:27:24.892] 2019/02/12 06:27:12 Deprecation file not found. Invoke registration
I0212 06:27:24.893] 2019/02/12 06:27:12 ListAndWatch
I0212 06:27:24.893] 2019/02/12 06:27:12 Starting to serve on /tmp/device_plugin023587661/device-plugin.sock.new
I0212 06:27:24.893] 2019/02/12 06:27:12 Deprecation file not found. Invoke registration
I0212 06:27:24.893] 2019/02/12 06:27:12 ListAndWatch
I0212 06:27:24.894] 2019/02/12 06:27:12 Starting to serve on /tmp/device_plugin023587661/device-plugin.sock.third
I0212 06:27:24.894] 2019/02/12 06:27:12 Deprecation file not found. Invoke registration
I0212 06:27:24.894] 2019/02/12 06:27:12 ListAndWatch
I0212 06:27:24.894] E0212 06:27:12.116885      15 endpoint.go:106] listAndWatch ended unexpectedly for device plugin fake-domain/resource with error rpc error: code = Unavailable desc = transport is closing
I0212 06:27:24.895] E0212 06:27:12.117890      15 endpoint.go:106] listAndWatch ended unexpectedly for device plugin fake-domain/resource with error rpc error: code = Unavailable desc = transport is closing
I0212 06:27:24.895] E0212 06:27:12.118922      15 endpoint.go:106] listAndWatch ended unexpectedly for device plugin fake-domain/resource with error rpc error: code = Unavailable desc = transport is closing
I0212 06:27:24.895] 2019/02/12 06:27:12 Starting to serve on /tmp/device_plugin023587661/device-plugin.sock
I0212 06:27:24.896] 2019/02/12 06:27:12 Deprecation file not found. Invoke registration
I0212 06:27:24.896] 2019/02/12 06:27:12 ListAndWatch
I0212 06:27:24.896] 2019/02/12 06:27:12 Starting to serve on /tmp/device_plugin023587661/device-plugin.sock.new
I0212 06:27:24.896] 2019/02/12 06:27:12 Deprecation file not found. Invoke registration
I0212 06:27:24.896] 2019/02/12 06:27:12 ListAndWatch
I0212 06:27:24.897] 2019/02/12 06:27:12 Starting to serve on /tmp/device_plugin023587661/device-plugin.sock.third
I0212 06:27:24.897] 2019/02/12 06:27:12 Deprecation file not found. Invoke registration
I0212 06:27:24.897] 2019/02/12 06:27:12 ListAndWatch
I0212 06:27:24.897] E0212 06:27:12.151820      15 endpoint.go:106] listAndWatch ended unexpectedly for device plugin fake-domain/resource with error rpc error: code = Unavailable desc = transport is closing
I0212 06:27:24.898] E0212 06:27:12.152439      15 endpoint.go:106] listAndWatch ended unexpectedly for device plugin fake-domain/resource with error rpc error: code = Unavailable desc = transport is closing
I0212 06:27:24.898] E0212 06:27:12.154359      15 endpoint.go:106] listAndWatch ended unexpectedly for device plugin fake-domain/resource with error rpc error: code = Unavailable desc = transport is closing
I0212 06:27:24.898] 2019/02/12 06:27:12 Starting to serve on /tmp/device_plugin466665614/device-plugin.sock
I0212 06:27:24.899] E0212 06:27:12.160581      15 plugin_watcher.go:120] error failed to get plugin info using RPC GetInfo at socket /tmp/device_plugin466665614/server.sock, err: rpc error: code = Unimplemented desc = unknown service pluginregistration.Registration when handling create event: "/tmp/device_plugin466665614/server.sock": CREATE
I0212 06:27:24.899] 2019/02/12 06:27:12 GetInfo
I0212 06:27:24.899] 2019/02/12 06:27:12 ListAndWatch
I0212 06:27:24.900] E0212 06:27:12.169836      15 plugin_watcher.go:120] error stat file /tmp/device_plugin466665614/.068828821 failed: stat /tmp/device_plugin466665614/.068828821: no such file or directory when handling create event: "/tmp/device_plugin466665614/.068828821": CREATE
I0212 06:27:24.900] 2019/02/12 06:27:12 Starting to serve on /tmp/device_plugin466665614/device-plugin.sock.new
I0212 06:27:24.900] 2019/02/12 06:27:12 GetInfo
I0212 06:27:24.900] 2019/02/12 06:27:12 ListAndWatch
I0212 06:27:24.901] E0212 06:27:12.182896      15 plugin_watcher.go:120] error stat file /tmp/device_plugin466665614/.723559664 failed: stat /tmp/device_plugin466665614/.723559664: no such file or directory when handling create event: "/tmp/device_plugin466665614/.723559664": CREATE
I0212 06:27:24.901] 2019/02/12 06:27:12 Starting to serve on /tmp/device_plugin466665614/device-plugin.sock.third
I0212 06:27:24.901] 2019/02/12 06:27:12 GetInfo
I0212 06:27:24.901] 2019/02/12 06:27:12 ListAndWatch
I0212 06:27:24.901] E0212 06:27:12.194459      15 plugin_watcher.go:120] error stat file /tmp/device_plugin466665614/.527915919 failed: stat /tmp/device_plugin466665614/.527915919: no such file or directory when handling create event: "/tmp/device_plugin466665614/.527915919": CREATE
I0212 06:27:24.902] E0212 06:27:12.194992      15 endpoint.go:106] listAndWatch ended unexpectedly for device plugin fake-domain/resource with error rpc error: code = Unavailable desc = transport is closing
I0212 06:27:24.902] E0212 06:27:12.195788      15 endpoint.go:106] listAndWatch ended unexpectedly for device plugin fake-domain/resource with error rpc error: code = Canceled desc = grpc: the client connection is closing
I0212 06:27:24.902] E0212 06:27:12.196733      15 endpoint.go:106] listAndWatch ended unexpectedly for device plugin fake-domain/resource with error rpc error: code = Unavailable desc = transport is closing
I0212 06:27:24.903] E0212 06:27:12.199860      15 manager.go:482] unexpected: unhealthyDevices and endpoints are out of sync
I0212 06:27:24.903] FAIL
I0212 06:27:24.903] ================================================================================
W0212 06:27:41.239] [7,787 / 7,795] 262 / 797 tests, 1 failed; Testing //pkg/master:go_default_test; 150s linux-sandbox ... (8 actions running)
W0212 06:29:15.592] [8,419 / 8,429] 340 / 797 tests, 1 failed; GoLink cmd/genkubedocs/linux_amd64_race_stripped/go_default_test; 46s linux-sandbox ... (8 actions, 7 running)
W0212 06:31:05.185] [9,385 / 9,395] 516 / 797 tests, 1 failed; Testing //pkg/controller/volume/persistentvolume:go_default_test; 39s linux-sandbox ... (8 actions, 7 running)
W0212 06:32:29.299] FAIL: //pkg/scheduler/internal/queue:go_default_test (see /bazel-scratch/.cache/bazel/_bazel_root/e9f728bbd90b3fba632eb31b20e1dacd/execroot/__main__/bazel-out/k8-fastbuild/testlogs/pkg/scheduler/internal/queue/go_default_test/test_attempts/attempt_1.log)
W0212 06:33:01.550] FAIL: //pkg/scheduler/internal/queue:go_default_test (see /bazel-scratch/.cache/bazel/_bazel_root/e9f728bbd90b3fba632eb31b20e1dacd/execroot/__main__/bazel-out/k8-fastbuild/testlogs/pkg/scheduler/internal/queue/go_default_test/test_attempts/attempt_2.log)
W0212 06:33:03.299] 
W0212 06:33:03.299] FAILED: //pkg/scheduler/internal/queue:go_default_test (Summary)
W0212 06:33:03.299]       /bazel-scratch/.cache/bazel/_bazel_root/e9f728bbd90b3fba632eb31b20e1dacd/execroot/__main__/bazel-out/k8-fastbuild/testlogs/pkg/scheduler/internal/queue/go_default_test/test_attempts/attempt_1.log
W0212 06:33:03.300]       /bazel-scratch/.cache/bazel/_bazel_root/e9f728bbd90b3fba632eb31b20e1dacd/execroot/__main__/bazel-out/k8-fastbuild/testlogs/pkg/scheduler/internal/queue/go_default_test/test_attempts/attempt_2.log
W0212 06:33:03.301]       /bazel-scratch/.cache/bazel/_bazel_root/e9f728bbd90b3fba632eb31b20e1dacd/execroot/__main__/bazel-out/k8-fastbuild/testlogs/pkg/scheduler/internal/queue/go_default_test/test.log
W0212 06:33:03.301] FAIL: //pkg/scheduler/internal/queue:go_default_test (see /bazel-scratch/.cache/bazel/_bazel_root/e9f728bbd90b3fba632eb31b20e1dacd/execroot/__main__/bazel-out/k8-fastbuild/testlogs/pkg/scheduler/internal/queue/go_default_test/test.log)
W0212 06:33:03.301] INFO: From Testing //pkg/scheduler/internal/queue:go_default_test:
I0212 06:33:03.402] ==================== Test output for //pkg/scheduler/internal/queue:go_default_test:
I0212 06:33:03.402] --- FAIL: TestPriorityQueue_PendingPods (0.00s)
I0212 06:33:03.402]     scheduling_queue_test.go:457: Unexpected list of pending Pods for node.
I0212 06:33:03.402] FAIL
I0212 06:33:03.402] ================================================================================
I0212 06:33:03.403] ==================== Test output for //pkg/scheduler/internal/queue:go_default_test:
I0212 06:33:03.403] --- FAIL: TestPriorityQueue_PendingPods (0.00s)
I0212 06:33:03.403]     scheduling_queue_test.go:457: Unexpected list of pending Pods for node.
I0212 06:33:03.403] ==================
I0212 06:33:03.403] WARNING: DATA RACE
I0212 06:33:03.403] Read at 0x00c0002dfcb0 by goroutine 91:
I0212 06:33:03.404]   runtime.mapiterinit()
I0212 06:33:03.404]       GOROOT/src/runtime/map.go:734 +0x0
... skipping 445 lines ...
I0212 06:33:04.313]       GOROOT/src/testing/testing.go:1117 +0x4ee
I0212 06:33:04.313]   testing.(*M).Run()
I0212 06:33:04.313]       GOROOT/src/testing/testing.go:1034 +0x2ee
I0212 06:33:04.313]   main.main()
I0212 06:33:04.313]       bazel-out/k8-fastbuild/bin/pkg/scheduler/internal/queue/linux_amd64_race_stripped/go_default_test%/testmain.go:114 +0x2fb
I0212 06:33:04.313] ==================
I0212 06:33:04.314] --- FAIL: TestHighProirotyFlushUnschedulableQLeftover (30.00s)
I0212 06:33:04.314]     testing.go:771: race detected during execution of test
I0212 06:33:04.314] FAIL
I0212 06:33:04.314] ================================================================================
I0212 06:33:04.314] ==================== Test output for //pkg/scheduler/internal/queue:go_default_test:
I0212 06:33:04.314] --- FAIL: TestPriorityQueue_PendingPods (0.00s)
I0212 06:33:04.314]     scheduling_queue_test.go:457: Unexpected list of pending Pods for node.
I0212 06:33:04.314] FAIL
I0212 06:33:04.315] ================================================================================
W0212 06:33:10.037] [10,133 / 10,141] 669 / 797 tests, 2 failed; Testing //cmd/kubeadm/app/phases/certs:go_default_test; 90s linux-sandbox ... (8 actions running)
W0212 06:34:06.571] INFO: Elapsed time: 1008.252s, Critical Path: 244.70s
W0212 06:34:06.571] INFO: 9024 processes: 7918 remote cache hit, 1106 linux-sandbox.
W0212 06:34:06.571] INFO: Build completed, 2 tests FAILED, 10673 total actions
I0212 06:34:06.672] //cluster:clientbin_test                                        (cached) PASSED in 0.0s
I0212 06:34:06.672] //cluster:common_test                                           (cached) PASSED in 0.3s
I0212 06:34:06.672] //cluster:kube-util_test                                        (cached) PASSED in 0.0s
I0212 06:34:06.672] //cluster/gce/cos:go_default_test                               (cached) PASSED in 0.0s
I0212 06:34:06.673] //cluster/gce/custom:go_default_test                            (cached) PASSED in 0.0s
I0212 06:34:06.673] //cluster/gce/gci:go_default_test                               (cached) PASSED in 0.0s
... skipping 783 lines ...
I0212 06:34:06.818] //staging/src/k8s.io/sample-apiserver/pkg/admission/wardleinitializer:go_default_test PASSED in 1.8s
I0212 06:34:06.818] //staging/src/k8s.io/sample-controller:go_default_test                   PASSED in 2.3s
I0212 06:34:06.819] //test/e2e/framework/providers/gce:go_default_test                       PASSED in 3.5s
I0212 06:34:06.819] //test/e2e/framework/timer:go_default_test                               PASSED in 2.3s
I0212 06:34:06.819] //test/integration/apimachinery:go_default_test                          PASSED in 159.5s
I0212 06:34:06.819] //test/integration/apiserver/apply:go_default_test                       PASSED in 106.3s
I0212 06:34:06.819] //pkg/kubelet/cm/devicemanager:go_default_test                            FLAKY, failed in 1 out of 2 in 12.5s
I0212 06:34:06.819]   Stats over 2 runs: max = 12.5s, min = 11.9s, avg = 12.2s, dev = 0.3s
I0212 06:34:06.820]   /bazel-scratch/.cache/bazel/_bazel_root/e9f728bbd90b3fba632eb31b20e1dacd/execroot/__main__/bazel-out/k8-fastbuild/testlogs/pkg/kubelet/cm/devicemanager/go_default_test/test_attempts/attempt_1.log
I0212 06:34:06.820] //pkg/scheduler/internal/queue:go_default_test                           FAILED in 3 out of 3 in 32.2s
I0212 06:34:06.820]   Stats over 3 runs: max = 32.2s, min = 1.7s, avg = 12.1s, dev = 14.3s
I0212 06:34:06.820]   /bazel-scratch/.cache/bazel/_bazel_root/e9f728bbd90b3fba632eb31b20e1dacd/execroot/__main__/bazel-out/k8-fastbuild/testlogs/pkg/scheduler/internal/queue/go_default_test/test_attempts/attempt_1.log
I0212 06:34:06.821]   /bazel-scratch/.cache/bazel/_bazel_root/e9f728bbd90b3fba632eb31b20e1dacd/execroot/__main__/bazel-out/k8-fastbuild/testlogs/pkg/scheduler/internal/queue/go_default_test/test_attempts/attempt_2.log
I0212 06:34:06.821]   /bazel-scratch/.cache/bazel/_bazel_root/e9f728bbd90b3fba632eb31b20e1dacd/execroot/__main__/bazel-out/k8-fastbuild/testlogs/pkg/scheduler/internal/queue/go_default_test/test.log
I0212 06:34:06.821] 
I0212 06:34:06.821] Executed 272 out of 797 tests: 796 tests pass and 1 fails locally.
I0212 06:34:06.821] There were tests whose specified size is too big. Use the --test_verbose_timeout_warnings command line option to see which ones these are.
W0212 06:34:06.922] INFO: Build completed, 2 tests FAILED, 10673 total actions
W0212 06:34:06.922] Run: ('/workspace/./test-infra/jenkins/../scenarios/../hack/coalesce.py',)
I0212 06:34:07.121] Build passed, tests failed or timed out
E0212 06:34:07.127] Command failed
I0212 06:34:07.127] process 522 exited with code 3 after 17.5m
E0212 06:34:07.128] FAIL: pull-kubernetes-bazel-test
I0212 06:34:07.128] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0212 06:34:07.672] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0212 06:34:07.725] process 33654 exited with code 0 after 0.0m
I0212 06:34:07.725] Call:  gcloud config get-value account
I0212 06:34:08.025] process 33666 exited with code 0 after 0.0m
I0212 06:34:08.026] Will upload results to gs://kubernetes-jenkins/pr-logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0212 06:34:08.026] Upload result and artifacts...
I0212 06:34:08.026] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/pr-logs/pull/73937/pull-kubernetes-bazel-test/75536
I0212 06:34:08.026] Call:  gsutil ls gs://kubernetes-jenkins/pr-logs/pull/73937/pull-kubernetes-bazel-test/75536/artifacts
W0212 06:34:09.037] CommandException: One or more URLs matched no objects.
E0212 06:34:09.158] Command failed
I0212 06:34:09.159] process 33678 exited with code 1 after 0.0m
W0212 06:34:09.159] Remote dir gs://kubernetes-jenkins/pr-logs/pull/73937/pull-kubernetes-bazel-test/75536/artifacts not exist yet
I0212 06:34:09.159] Call:  gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-jenkins/pr-logs/pull/73937/pull-kubernetes-bazel-test/75536/artifacts
I0212 06:34:11.105] process 33821 exited with code 0 after 0.0m
W0212 06:34:11.106] metadata path /workspace/_artifacts/metadata.json does not exist
W0212 06:34:11.106] metadata not found or invalid, init with empty metadata
... skipping 23 lines ...