[I2024-02-11T13:25:13.216803Z 287 0 sink.go:276] SinkServer: warm-up started [I2024-02-11T13:25:13.216844Z 287 0 sink.go:346] SinkServer: starting HTTP server... [I2024-02-11T13:25:13.217790Z 287 0 sink.go:281] SinkServer: warm-up ended [I2024-02-11T13:25:13.218094Z 287 0 cmd_stream.go:473] rdb-stream: starting the test command - ["/home/swarming/.swarming/w/ir/cache/tools/bin/result_adapter" "go" "-v=false" "--" "/home/swarming/.swarming/w/ir/x/w/goroot/bin/go" "tool" "dist" "test" "-json" "archive/tar" "bufio" "compress/bzip2" "compress/flate" "crypto/boring" "crypto/ed25519" "crypto/hmac" "crypto/internal/alias" "crypto/internal/boring" "crypto/internal/edwards25519/field" "crypto/tls/fipsonly" "crypto/x509" "debug/buildinfo" "debug/elf" "debug/gosym" "embed" "encoding/base32" "encoding/base64" "encoding/hex" "encoding/json" "fmt" "go/doc" "go/internal/srcimporter" "go/printer" "go/scanner" "hash/fnv" "html/template" "image" "image/gif" "image/jpeg" "image/png" "internal/buildcfg" "internal/coverage/cmerge" "internal/coverage/slicereader" "internal/cpu" "internal/diff" "internal/itoa" "internal/xcoff" "log/slog" "net" "net/http/httputil" "net/http/internal/ascii" "net/internal/cgotest" "net/netip" "net/smtp" "net/textproto" "os/signal" "path" "path/filepath" "regexp" "regexp/syntax" "runtime" "runtime/debug" "runtime/internal/wasitest" "testing/slogtest" "text/tabwriter" "cmd/cgo/internal/swig" "cmd/cgo/internal/testerrors" "cmd/cgo/internal/testfortran" "cmd/cgo/internal/testgodefs" "cmd/compile/internal/importer" "cmd/compile/internal/loopvar" "cmd/compile/internal/noder" "cmd/compile/internal/types2" "cmd/go" "cmd/go/internal/envcmd" "cmd/go/internal/fsys" "cmd/go/internal/generate" "cmd/go/internal/modfetch/codehost" "cmd/go/internal/modindex" "cmd/go/internal/par" "cmd/go/internal/toolchain" "cmd/go/internal/vcweb" "cmd/internal/archive" "cmd/internal/buildid" "cmd/internal/edit" "cmd/internal/obj" "cmd/link/internal/ld" "cmd/objdump" "runtime:cpu124" "iter:iter" "os/user:pie_internal" "flag:race" "flag:race-external" "cmd/cgo/internal/test:external-g0" "cmd/cgo/internal/testtls:static" "cmd/cgo/internal/testnocgo:auto-pie" "cmd/internal/testdir:2_10" "cmd/internal/testdir:4_10" "cmd/internal/testdir:8_10" "cmd/api:check"] go tool dist: Failed: exit status 1 ok archive/tar 0.290s ok bufio 0.106s ok compress/bzip2 0.136s ok compress/flate 0.806s ok crypto/boring 0.061s ok crypto/ed25519 0.138s ok crypto/hmac 0.019s ok crypto/internal/alias 0.022s ok crypto/internal/boring 0.012s ok crypto/internal/edwards25519/field 0.025s ok crypto/tls/fipsonly 0.015s ok crypto/x509 1.695s ok debug/buildinfo 0.020s ok debug/elf 0.267s ok debug/gosym 0.396s ok embed 0.015s [no tests to run] ok encoding/base32 0.025s ok encoding/base64 0.017s ok encoding/hex 0.013s ok encoding/json 0.513s ok fmt 0.089s ok go/doc 0.082s ok go/internal/srcimporter 19.362s ok go/printer 0.337s ok go/scanner 0.034s ok hash/fnv 0.022s ok html/template 0.193s ok image 0.097s ok image/gif 0.494s ok image/jpeg 0.316s ok image/png 0.569s ok internal/buildcfg 0.022s ok internal/coverage/cmerge 0.037s ok internal/coverage/slicereader 0.033s ok internal/cpu 0.065s ok internal/diff 0.020s ok internal/itoa 0.028s ok internal/xcoff 0.040s ok log/slog 0.062s ok net 3.836s ok net/http/httputil 0.707s ok net/http/internal/ascii 0.061s ok net/internal/cgotest 0.046s ok net/netip 0.697s ok net/smtp 0.046s ok net/textproto 0.068s ok os/signal 2.579s ok path 0.045s ok path/filepath 0.059s ok regexp 0.907s ok regexp/syntax 1.346s ok runtime 61.536s ok runtime/debug 0.140s ok runtime/internal/wasitest 0.017s ok testing/slogtest 0.021s ok text/tabwriter 0.019s ok cmd/cgo/internal/swig 3.940s ok cmd/cgo/internal/testerrors 15.166s ok cmd/cgo/internal/testfortran 4.868s ok cmd/cgo/internal/testgodefs 3.630s ok cmd/compile/internal/importer 2.608s ok cmd/compile/internal/loopvar 54.146s ok cmd/compile/internal/noder 0.033s ok cmd/compile/internal/types2 25.238s --- FAIL: TestScript (0.16s) vcs-test.golang.org rerouted to http://127.0.0.1:45197 https://vcs-test.golang.org rerouted to https://127.0.0.1:39869 go test proxy running at GOPROXY=http://127.0.0.1:42813/mod --- FAIL: TestScript/env_changed (0.11s) script_test.go:132: 2024-02-11T13:26:16Z script_test.go:134: $WORK=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-2334661678/tmpdir999810215/env_changed810602940 script_test.go:156: PATH=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-2334661678/tmpdir999810215/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/cipd_bin_packages/cpython3:/home/swarming/.swarming/w/ir/cipd_bin_packages/cpython3/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= GOEXE= GOEXPERIMENT=boringcrypto GOOS=linux TESTGO_GOHOSTOS=linux GOPROXY=http://127.0.0.1:42813/mod GOPRIVATE= GOROOT=/home/swarming/.swarming/w/ir/x/w/goroot GOROOT_FINAL=/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-2334661678/tmpdir999810215/testbin/go TESTGO_VCSTEST_HOST=127.0.0.1:45197 TESTGO_VCSTEST_TLS_HOST=127.0.0.1:39869 TESTGO_VCSTEST_CERT=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-2334661678/vcstest3773263223/cert.pem TESTGONETWORK=panic GOSUMDB=localhost.localdev/sumdb+00000c67+AcTrnkbUA+TU4heY3hkjiSES/DSQniBqIeQ/YppAUtK6 GONOPROXY= GONOSUMDB= GOVCS=*:all devnull=/dev/null goversion=1.23 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 TESTGOVCS=panic CGO_ENABLED=1 CC=gcc GCCGO=gccgo WORK=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-2334661678/tmpdir999810215/env_changed810602940 TMPDIR=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-2334661678/tmpdir999810215/env_changed810602940/tmp GOPATH=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-2334661678/tmpdir999810215/env_changed810602940/gopath PWD=/home/swarming/.swarming/w/ir/x/t/cmd-go-test-2334661678/tmpdir999810215/env_changed810602940/gopath/src # Test query for non-defaults in the env (0.106s) > env GOTOOLCHAIN=local > env GOSUMDB=nodefault > env GOPROXY=nodefault > env GO111MODULE=auto > go env -changed [stdout] GO111MODULE='auto' GOCACHE='/home/swarming/.swarming/w/ir/x/w/gocache' GOEXPERIMENT='boringcrypto' GOPATH='/home/swarming/.swarming/w/ir/x/t/cmd-go-test-2334661678/tmpdir999810215/env_changed810602940/gopath' GOPROXY='nodefault' GOSUMDB='nodefault' GOTOOLCHAIN='local' GOVCS='*:all' CC='gcc' CGO_ENABLED='1' > stdout 'GOTOOLCHAIN=local' script_test.go:156: FAIL: testdata/script/env_changed.txt:9: stdout GOTOOLCHAIN=local: no match for `(?m)GOTOOLCHAIN=local` in stdout FAIL FAIL cmd/go 40.466s ok cmd/go/internal/envcmd 0.045s ok cmd/go/internal/fsys 0.074s ok cmd/go/internal/generate 0.030s ok cmd/go/internal/modfetch/codehost 4.246s ok cmd/go/internal/modindex 0.317s ok cmd/go/internal/par 0.030s ok cmd/go/internal/toolchain 0.035s ok cmd/go/internal/vcweb 0.023s ok cmd/internal/archive 1.917s ok cmd/internal/buildid 0.461s ok cmd/internal/edit 0.086s ok cmd/internal/obj 1.493s ok cmd/link/internal/ld 14.171s ok cmd/objdump 14.481s Warning: no '=' in invocation-link-artifacts pair: "", ignoring [I2024-02-11T13:26:46.282010Z 287 0 cmd_stream.go:469] rdb-stream: the test process terminated [I2024-02-11T13:26:46.282181Z 287 0 sink.go:371] SinkServer: shutdown started [I2024-02-11T13:26:46.282236Z 287 0 sink.go:349] SinkServer: HTTP server stopped with "http: Server closed" [I2024-02-11T13:26:46.282265Z 287 0 sink_server.go:95] SinkServer: draining TestResult channel started [I2024-02-11T13:26:47.331559Z 287 0 sink_server.go:97] SinkServer: draining TestResult channel ended [I2024-02-11T13:26:47.331627Z 287 0 sink_server.go:99] SinkServer: draining Artifact channel started [I2024-02-11T13:26:47.616193Z 287 0 sink_server.go:101] SinkServer: draining Artifact channel ended [I2024-02-11T13:26:47.616266Z 287 0 sink.go:374] SinkServer: shutdown completed successfully [I2024-02-11T13:26:47.616310Z 287 0 cmd_stream.go:401] rdb-stream: exiting with 1