[I2025-03-12T12:37:18.995850Z 210271 0 sink.go:277] SinkServer: warm-up started [I2025-03-12T12:37:18.995926Z 210271 0 sink.go:350] SinkServer: starting HTTP server... [I2025-03-12T12:37:18.997135Z 210271 0 sink.go:282] SinkServer: warm-up ended [I2025-03-12T12:37:18.997444Z 210271 0 cmd_stream.go:492] rdb-stream: starting the test command - ["/home/swarming/.swarming/w/ir/cache/tools/bin/result_adapter" "go" "-v=false" "-dump-json" "/home/swarming/.swarming/w/ir/x/w/dist.testjson" "--" "/home/swarming/.swarming/w/ir/x/w/goroot/bin/go" "tool" "dist" "test" "-json" "runtime/coverage" "runtime/pprof" "sort" "strconv" "strings" "sync" "sync/atomic" "syscall" "testing" "time" "cmd/api" "cmd/cgo/internal/test" "cmd/cgo/internal/testcarchive" "cmd/cgo/internal/testcshared" "cmd/cgo/internal/testerrors" "cmd/cgo/internal/testfortran" "cmd/cgo/internal/testplugin" "cmd/cgo/internal/testsanitizers" "cmd/cgo/internal/testshared" "cmd/cgo/internal/testso" "cmd/cgo/internal/teststdio" "cmd/compile" "cmd/compile/internal/amd64" "cmd/compile/internal/importer" "cmd/compile/internal/loopvar" "cmd/compile/internal/ssa" "cmd/compile/internal/syntax" "cmd/compile/internal/test" "cmd/compile/internal/types2" "cmd/go" "cmp" "container/heap" "crypto" "crypto/des" "crypto/ecdh" "crypto/fips140" "crypto/internal/boring/bcache" "crypto/internal/fips140/aes/gcm" "crypto/internal/fips140/ecdsa" "crypto/internal/fips140/edwards25519" "crypto/internal/fips140/hkdf" "crypto/internal/fips140/hmac" "crypto/internal/fips140/nistec" "crypto/internal/fips140/sha256" "crypto/internal/fips140/sha512" "crypto/internal/fips140/ssh" "crypto/internal/fips140/subtle" "crypto/internal/fips140/tls12" "crypto/internal/fips140deps/cpu" "crypto/internal/fips140deps/godebug" "crypto/internal/sysrand/internal/seccomp" "debug/macho" "embed/internal/embedtest" "errors" "go/constant" "go/format" "go/importer" "go/version" "html" "image/color" "internal/coverage/encodecounter" "internal/coverage/encodemeta" "internal/coverage/slicewriter" "internal/goexperiment" "internal/goos" "internal/gover" "internal/profile" "internal/trace/traceviewer" "internal/trace/traceviewer/format" "log" "log/internal" "log/slog/internal/benchmarks" "maps" "mime/multipart" "mime/quotedprintable" "net/http/cookiejar" "net/rpc/jsonrpc" "os/exec" "os/user" "runtime/cgo" "runtime/race" "runtime/race/internal/amd64v1" "runtime/trace" "text/template/parse" "unicode/utf16" "vendor/golang.org/x/crypto/cryptobyte" "vendor/golang.org/x/crypto/cryptobyte/asn1" "vendor/golang.org/x/crypto/internal/poly1305" "vendor/golang.org/x/sys/cpu" "cmd/addr2line" "cmd/asm/internal/lex" "cmd/buildid" "cmd/cgo/internal/test/issue27340" "cmd/cgo/internal/test/issue41761a" "cmd/cgo/internal/test/issue8828" "cmd/cgo/internal/test/issue9510b" "cmd/cgo/internal/testlife" "cmd/compile/internal/abi" "cmd/compile/internal/arm" "cmd/compile/internal/arm64" "cmd/compile/internal/deadlocals" "cmd/compile/internal/dwarfgen" "cmd/compile/internal/escape" "cmd/compile/internal/liveness" "cmd/compile/internal/logopt" "cmd/compile/internal/mips64" "cmd/compile/internal/riscv64" "cmd/compile/internal/staticinit" "cmd/compile/internal/types" "cmd/compile/internal/x86" "cmd/doc" "cmd/fix" "cmd/go/internal/auth" "cmd/go/internal/base" "cmd/go/internal/tool" "cmd/go/internal/version" "cmd/go/internal/workcmd" "cmd/internal/browser" "cmd/internal/codesign" "cmd/internal/cov" "cmd/internal/cov/covcmd" "cmd/internal/gcprog" "cmd/internal/hash" "cmd/internal/obj/mips" "cmd/internal/obj/s390x" "cmd/internal/pathcache" "cmd/internal/pkgpath" "cmd/internal/script/scripttest" "cmd/internal/sys" "cmd/link/internal/arm" "cmd/link/internal/arm64" "cmd/link/internal/loadelf" "cmd/link/internal/loadmacho" "cmd/link/internal/mips" "cmd/link/internal/x86" "cmd/vendor/github.com/google/pprof/internal/driver" "cmd/vendor/github.com/google/pprof/internal/measurement" "cmd/vendor/github.com/google/pprof/internal/symbolizer" "cmd/vendor/golang.org/x/arch/loong64/loong64asm" "cmd/vendor/golang.org/x/arch/ppc64/ppc64asm" "cmd/vendor/golang.org/x/mod/module" "cmd/vendor/golang.org/x/sync/semaphore" "cmd/vendor/golang.org/x/telemetry" "cmd/vendor/golang.org/x/telemetry/counter" "cmd/vendor/golang.org/x/telemetry/counter/countertest" "cmd/vendor/golang.org/x/term" "cmd/vendor/golang.org/x/text/language" "cmd/vendor/golang.org/x/text/unicode/norm" "cmd/vendor/golang.org/x/tools/go/analysis/internal/analysisflags" "cmd/vendor/golang.org/x/tools/go/analysis/passes/bools" "cmd/vendor/golang.org/x/tools/go/analysis/passes/shift" "cmd/vendor/golang.org/x/tools/go/analysis/passes/sigchanyzer" "cmd/vendor/golang.org/x/tools/go/analysis/passes/stdmethods" "cmd/vendor/golang.org/x/tools/internal/astutil/edge" "cmd/vendor/golang.org/x/tools/internal/bisect" "os/user:osusergo" "cmd/cgo/internal/testtls:external" "cmd/internal/testdir:1_10" "cmd/internal/testdir:7_10"] go tool dist: Failed: exit status 1 ok runtime/pprof 106.442s ok sort 4.143s ok strconv 2.792s ok strings 1.496s ok sync 5.500s ok sync/atomic 97.028s ok syscall 4.366s ok testing 2.951s ok time 30.453s ok cmd/api 17.867s ok cmd/cgo/internal/test 1.615s ok cmd/cgo/internal/testcarchive 91.189s ok cmd/cgo/internal/testcshared 54.214s ok cmd/cgo/internal/testerrors 62.827s ok cmd/cgo/internal/testfortran 9.638s ok cmd/cgo/internal/testplugin 164.017s ok cmd/cgo/internal/testsanitizers 68.414s ok cmd/cgo/internal/testshared 385.743s ok cmd/cgo/internal/testso 11.842s ok cmd/cgo/internal/teststdio 12.210s ok cmd/compile 9.153s ok cmd/compile/internal/amd64 5.892s ok cmd/compile/internal/importer 99.121s ok cmd/compile/internal/loopvar 88.751s ok cmd/compile/internal/ssa 452.383s ok cmd/compile/internal/syntax 12.148s ok cmd/compile/internal/test 121.834s ok cmd/compile/internal/types2 44.247s --- FAIL: TestScript (0.24s) vcs-test.golang.org rerouted to http://127.0.0.1:37633 https://vcs-test.golang.org rerouted to https://127.0.0.1:44633 go test proxy running at GOPROXY=http://127.0.0.1:35943/mod --- FAIL: TestScript/test_fuzz_fuzztime (94.57s) script_test.go:139: 2025-03-12T12:44:38Z script_test.go:141: $WORK=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-1863312865/tmpdir465913577/test_fuzz_fuzztime861364886 script_test.go:163: PATH=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-1863312865/tmpdir465913577/testbin:/home/swarming/.swarming/w/ir/x/w/goroot/bin:/home/swarming/.swarming/w/ir/x/w/goroot/bin:/home/swarming/.swarming/w/ir/x/w/goroot/bin:/home/swarming/.swarming/w/ir/cache/tools/bin:/home/swarming/.swarming/w/ir/bbagent_utility_packages:/home/swarming/.swarming/w/ir/bbagent_utility_packages/bin:/home/swarming/.swarming/w/ir/cipd_bin_packages:/home/swarming/.swarming/w/ir/cipd_bin_packages/bin:/home/swarming/.swarming/w/ir/cache/cipd_client:/home/swarming/.swarming/w/ir/cache/cipd_client/bin:/home/swarming/.swarming/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin HOME=/no-home CCACHE_DISABLE=1 GOARCH=amd64 TESTGO_GOHOSTARCH=amd64 GOCACHE=/home/swarming/.swarming/w/ir/x/w/gocache GOCOVERDIR= GODEBUG=gotypesalias=1 GOEXE= GOEXPERIMENT=aliastypeparams GOOS=linux TESTGO_GOHOSTOS=linux GOPROXY=http://127.0.0.1:35943/mod GOPRIVATE= GOROOT=/home/swarming/.swarming/w/ir/x/w/goroot GOTRACEBACK=system TESTGO_GOROOT=/home/swarming/.swarming/w/ir/x/w/goroot TESTGO_EXE=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-1863312865/tmpdir465913577/testbin/go TESTGO_VCSTEST_HOST=127.0.0.1:37633 TESTGO_VCSTEST_TLS_HOST=127.0.0.1:44633 TESTGO_VCSTEST_CERT=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-1863312865/vcstest1280851004/cert.pem TESTGONETWORK=panic GOSUMDB=localhost.localdev/sumdb+00000c67+AcTrnkbUA+TU4heY3hkjiSES/DSQniBqIeQ/YppAUtK6 GONOPROXY= GONOSUMDB= GOVCS=*:all devnull=/dev/null goversion=1.25 CMDGO_TEST_RUN_MAIN=true HGRCPATH= GOTOOLCHAIN=auto newline= GIT_TRACE_CURL=1 GIT_TRACE_CURL_NO_DATA=1 GIT_REDACT_COOKIES=o,SSO,GSSO_Uberproxy CGO_ENABLED=1 CC=gcc GCCGO=gccgo WORK=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-1863312865/tmpdir465913577/test_fuzz_fuzztime861364886 TEST_TELEMETRY_DIR=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-1863312865/tmpdir465913577/test_fuzz_fuzztime861364886/telemetry TMPDIR=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-1863312865/tmpdir465913577/test_fuzz_fuzztime861364886/tmp GOPATH=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-1863312865/tmpdir465913577/test_fuzz_fuzztime861364886/gopath PWD=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-1863312865/tmpdir465913577/test_fuzz_fuzztime861364886/gopath/src > [!fuzz] skip [condition not met] > [short] skip [condition not met] > env GOCACHE=$WORK/cache # There are no seed values, so 'go test' should finish quickly. (39.347s) > go test [stdout] PASS ok fuzz 0.044s # For the fuzzing phase, we reduce GOMAXPROCS to avoid consuming too many # resources during the test. Ideally this would just free up resources to run # other parallel tests more quickly, but unfortunately it is actually necessary # in some 32-bit environments to prevent the fuzzing engine from running out of # address space (see https://go.dev/issue/65434). (0.000s) > env GOMAXPROCS=2 # Fuzzing should exit 0 after fuzztime, even if timeout is short. (49.456s) > go test -timeout=3s -fuzz=FuzzFast -fuzztime=5s [stdout] warning: starting with empty corpus fuzz: elapsed: 0s, execs: 0 (0/sec), new interesting: 0 (total: 0) fuzz: elapsed: 3s, execs: 107313 (35766/sec), new interesting: 0 (total: 0) fuzz: elapsed: 5s, execs: 183674 (37148/sec), new interesting: 0 (total: 0) PASS ok fuzz 5.061s # We should see the same behavior when invoking the test binary directly. (5.663s) > go test -c > exec ./fuzz.test$GOEXE -test.timeout=3s -test.fuzz=FuzzFast -test.fuzztime=5s -test.parallel=1 -test.fuzzcachedir=$WORK/cache [stdout] --- FAIL: FuzzFast (5.04s) context deadline exceeded FAIL [stderr] warning: the test binary was not built with coverage instrumentation, so fuzzing will run without coverage guidance and may be inefficient warning: starting with empty corpus fuzz: elapsed: 0s, execs: 0 (0/sec) fuzz: elapsed: 3s, execs: 117461 (39154/sec) fuzz: elapsed: 5s, execs: 199293 (40103/sec) script_test.go:163: FAIL: testdata/script/test_fuzz_fuzztime.txt:20: exec ./fuzz.test -test.timeout=3s -test.fuzz=FuzzFast -test.fuzztime=5s -test.parallel=1 -test.fuzzcachedir=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-1863312865/tmpdir465913577/test_fuzz_fuzztime861364886/cache: exit status 1 FAIL FAIL cmd/go 423.446s ok cmp 0.012s ok container/heap 0.026s ok crypto 1.180s ok crypto/des 0.027s ok crypto/ecdh 0.862s ok crypto/internal/boring/bcache 0.140s ok crypto/internal/fips140/aes/gcm 0.026s [no tests to run] ok crypto/internal/fips140/ecdsa 0.045s ok crypto/internal/fips140/edwards25519 10.291s ok crypto/internal/fips140/nistec 0.027s ok crypto/internal/fips140/subtle 0.008s ok debug/macho 0.017s ok embed/internal/embedtest 0.042s ok errors 0.028s ok go/constant 0.018s ok go/format 0.043s ok go/importer 0.871s ok go/version 0.092s ok html 0.037s ok image/color 0.117s ok internal/coverage/slicewriter 0.049s ok internal/gover 0.084s ok internal/profile 0.041s ok log 0.387s ok log/slog/internal/benchmarks 0.006s ok maps 0.021s ok mime/multipart 4.867s ok mime/quotedprintable 2.645s ok net/http/cookiejar 0.073s ok net/rpc/jsonrpc 0.069s ok os/exec 3.989s ok os/user 0.051s ok runtime/cgo 0.120s ok runtime/trace 0.206s ok text/template/parse 0.047s ok unicode/utf16 0.062s ok cmd/addr2line 2.996s ok cmd/asm/internal/lex 0.031s ok cmd/cgo/internal/testlife 5.412s ok cmd/compile/internal/dwarfgen 1.396s ok cmd/compile/internal/liveness 0.243s ok cmd/compile/internal/logopt 2.569s ok cmd/compile/internal/types 0.101s ok cmd/doc 0.714s ok cmd/fix 44.625s ok cmd/go/internal/auth 0.064s ok cmd/internal/cov 1.790s ok cmd/internal/obj/s390x 0.046s ok cmd/internal/pkgpath 0.644s ok cmd/internal/sys 0.144s Warning: no '=' in invocation-link-artifacts pair: "", ignoring [W2025-03-12T12:47:04.738452Z 210271 0 cmd_stream.go:504] rdb-stream: test process exited with error: exit status 1 [I2025-03-12T12:47:04.738567Z 210271 0 cmd_stream.go:488] rdb-stream: the test process terminated [I2025-03-12T12:47:04.738692Z 210271 0 sink.go:375] SinkServer: shutdown started [I2025-03-12T12:47:04.738779Z 210271 0 sink.go:353] SinkServer: HTTP server stopped with "http: Server closed" [I2025-03-12T12:47:04.738809Z 210271 0 sink_server.go:96] SinkServer: draining TestResult channel started [I2025-03-12T12:47:05.336212Z 210271 0 sink_server.go:98] SinkServer: draining TestResult channel ended [I2025-03-12T12:47:05.336265Z 210271 0 sink_server.go:100] SinkServer: draining Artifact channel started [I2025-03-12T12:47:06.163674Z 210271 0 sink_server.go:102] SinkServer: draining Artifact channel ended [I2025-03-12T12:47:06.163739Z 210271 0 sink.go:378] SinkServer: shutdown completed successfully [I2025-03-12T12:47:06.163777Z 210271 0 cmd_stream.go:420] rdb-stream: exiting with 1