Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

cmd/go: TestScript failures #55311

Closed
gopherbot opened this issue Sep 21, 2022 · 117 comments
Closed

cmd/go: TestScript failures #55311

gopherbot opened this issue Sep 21, 2022 · 117 comments
Labels
FrozenDueToAge NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one.

Comments

@gopherbot
Copy link
Contributor

gopherbot commented Sep 21, 2022

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"

Issue created automatically to collect these failures.

Example (log):

go test proxy running at GOPROXY=http://127.0.0.1:36901/mod
==================
WARNING: DATA RACE
Read at 0x000001427d60 by goroutine 275:
  cmd/go/internal/base.AtExit()
      /tmp/workdir/go/src/cmd/go/internal/base/base.go:110 +0x5d0
  cmd/go/internal/work.(*Builder).Init()
      /tmp/workdir/go/src/cmd/go/internal/work/action.go:273 +0x54c
  cmd/go_test.(*testScript).cmdCc()
      /tmp/workdir/go/src/cmd/go/script_test.go:560 +0x12f
...
--- FAIL: TestScript (0.04s)
    --- FAIL: TestScript/doc (2.67s)
        script_test.go:270: 
            # (2022-08-08T17:13:12Z)
            # go doc --help (0.149s)
            # go help doc (0.087s)
            # go doc <pkg> (0.309s)
            # go doc <pkg> <sym> (0.315s)
            # go doc <pkg> <sym> <method> (0.159s)
            # go doc <pkg>.<sym> (0.171s)
...
            # (2022-08-08T17:13:13Z)
            # Issue 35837: "go vet -<analyzer> <std package>" should use the requested
            # analyzers, not the default analyzers for 'go test'. (1.859s)
            # Issue 37030: "go vet <std package>" without other flags should disable the
            # unsafeptr check by default. (1.915s)
            # However, it should be enabled if requested explicitly. (1.683s)
            # -unreachable is disabled during test but on during plain vet. (1.801s)
            # A flag terminator should be allowed before the package list. (0.652s)

        testing.go:1319: race detected during execution of test

watchflakes

@gopherbot gopherbot added the NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one. label Sep 21, 2022
@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "cmd/go" && test == "TestScript"
2022-08-08 16:57 freebsd-amd64-race go@c3833a55 cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:36901/mod
==================
WARNING: DATA RACE
Read at 0x000001427d60 by goroutine 275:
  cmd/go/internal/base.AtExit()
      /tmp/workdir/go/src/cmd/go/internal/base/base.go:110 +0x5d0
  cmd/go/internal/work.(*Builder).Init()
      /tmp/workdir/go/src/cmd/go/internal/work/action.go:273 +0x54c
  cmd/go_test.(*testScript).cmdCc()
      /tmp/workdir/go/src/cmd/go/script_test.go:560 +0x12f
...
--- FAIL: TestScript (0.04s)
    --- FAIL: TestScript/doc (2.67s)
        script_test.go:270: 
            # (2022-08-08T17:13:12Z)
            # go doc --help (0.149s)
            # go help doc (0.087s)
            # go doc <pkg> (0.309s)
            # go doc <pkg> <sym> (0.315s)
            # go doc <pkg> <sym> <method> (0.159s)
            # go doc <pkg>.<sym> (0.171s)
...
            # (2022-08-08T17:13:13Z)
            # Issue 35837: "go vet -<analyzer> <std package>" should use the requested
            # analyzers, not the default analyzers for 'go test'. (1.859s)
            # Issue 37030: "go vet <std package>" without other flags should disable the
            # unsafeptr check by default. (1.915s)
            # However, it should be enabled if requested explicitly. (1.683s)
            # -unreachable is disabled during test but on during plain vet. (1.801s)
            # A flag terminator should be allowed before the package list. (0.652s)

        testing.go:1319: race detected during execution of test
2022-08-09 18:32 linux-amd64-longtest go@beebd537 cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:33027/mod
--- FAIL: TestScript (0.01s)
    --- FAIL: TestScript/mod_replace_gopkgin (12.55s)
        script_test.go:270: 
            # (2022-08-09T18:56:44Z)
            # Regression test for golang.org/issue/34254:
            # a clone of gopkg.in/[…].vN should be replaceable by
            # a fork hosted at corp.example.com/[…]/vN,
            # even if there is an explicit go.mod file containing the
            # gopkg.in path. (0.000s)
...
            	18:56:48.755444 http.c:715              => Send header: content-length: 128
            	18:56:48.755447 http.c:715              => Send header:
            	18:56:48.755497 http.c:756              == Info: We are completely uploaded and fine
            	18:56:48.755704 http.c:756              == Info: HTTP/2 stream 0 was not closed cleanly: INTERNAL_ERROR (err 2)
            	18:56:48.755717 http.c:756              == Info: stopped the pause stream!
            	18:56:48.755726 http.c:756              == Info: Connection #0 to host go.googlesource.com left intact
            	error: RPC failed; curl 92 HTTP/2 stream 0 was not closed cleanly: INTERNAL_ERROR (err 2)
            	fatal: expected flush after ref listing
            [exit status 1]
            FAIL: testdata/script/mod_replace_gopkgin.txt:20: unexpected command failure
2022-08-22 18:57 linux-386-longtest go@a74e5f58 cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:37473/mod
--- FAIL: TestScript (0.01s)
    --- FAIL: TestScript/get_insecure_env (2.19s)
        script_test.go:282: 
            # (2022-08-22T19:30:35Z)
            # GOPATH: Set up (0.000s)
            # GOPATH: Try go get -d of HTTP-only repo (should fail). (0.084s)
            # GOPATH: Try again with invalid GOINSECURE (should fail). (0.052s)
            # GOPATH: Try with correct GOINSECURE (should succeed). (2.050s)
            > env GOINSECURE=insecure.go-get-issue-15410.appspot.com/pkg/p
            > go get -d insecure.go-get-issue-15410.appspot.com/pkg/p
            [stderr]
            package insecure.go-get-issue-15410.appspot.com/pkg/p: unrecognized import path "insecure.go-get-issue-15410.appspot.com/pkg/p": reading https://insecure.go-get-issue-15410.appspot.com/pkg?go-get=1: 503 Service Unavailable
            [exit status 1]
            FAIL: testdata/script/get_insecure_env.txt:16: unexpected command failure
2022-09-06 15:47 linux-amd64-longtest go@91a1b554 cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:42819/mod
--- FAIL: TestScript (0.01s)
    --- FAIL: TestScript/mod_init_glide (0.56s)
        script_test.go:282: 
            # (2022-09-06T16:13:29Z)
            # Regression test for golang.org/issue/32161:
            # 'go mod init' did not locate tags when resolving a commit to a pseudo-version. (0.560s)
            > go mod init x
            [stderr]
            go: creating new go.mod: module x
...
            	go mod tidy
            > cmpenv go.mod go.mod.out
            [diff -go.mod +go.mod.out]
             module x

             go 1.20
            +
            +require github.com/rsc/legacytest v1.1.0-pre.0.20180717164849-fb3c628075e3

            FAIL: testdata/script/mod_init_glide.txt:11: go.mod and go.mod.out differ

watchflakes

@bcmills
Copy link
Contributor

bcmills commented Sep 21, 2022

2022-08-08 16:57 freebsd-amd64-race go@c3833a55 cmd/go.TestScript (log)

That one is #54423, fixed at head and backported to 1.18 and 1.19. Not sure how to suppress watchflakes for it, or whether that's even necessary.

@bcmills
Copy link
Contributor

bcmills commented Sep 21, 2022

2022-08-09 18:32 linux-amd64-longtest go@beebd537 cmd/go.TestScript (log)

Looks like a server-side flake from go.googlesource.com, which would be a new failure mode for #50541.

@bcmills
Copy link
Contributor

bcmills commented Sep 21, 2022

2022-08-22 18:57 linux-386-longtest go@a74e5f58 cmd/go.TestScript (log)

That's #54608. I've attempted to add a watchflakes expression for it, but I'm not sure whether it will work. 🤔

@bcmills
Copy link
Contributor

bcmills commented Sep 21, 2022

2022-09-06 15:47 linux-amd64-longtest go@91a1b554 cmd/go.TestScript (log)

That's #52820.

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "cmd/go" && test == "TestScript"
2022-09-23 15:14 darwin-amd64-11_0 go@68fa3696 cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:54034/mod
--- FAIL: TestScript (0.03s)
    --- FAIL: TestScript/mod_require_exclude (1.19s)
        script_test.go:282: 
            # (2022-09-23T15:30:03Z)
            # build with no newer version to satisfy exclude (0.010s)
            # With the selected version excluded, commands that query that version without
            # updating go.mod should fail. (0.097s)
            # The failure message should be clear when -mod=vendor is implicit. (0.139s)
            # With the selected version excluded, commands that load only modules should
            # drop the excluded module. (0.035s)
            # With the latest version excluded, 'go list' should resolve needed packages
            # from the next-highest version. (0.457s)
            # build with newer version available (0.343s)
            # build with excluded newer version (0.045s)
            > cp go.mod3 go.mod
            > go list -mod=mod -f '{{with .Module}}{{.Path}} {{.Version}}{{end}}' all
            [stderr]
            go: errors parsing go.mod:
            $WORK/gopath/src/go.mod:1: unexpected input character '\x00'
            [exit status 1]
            FAIL: testdata/script/mod_require_exclude.txt:58: unexpected command failure

watchflakes

@bcmills
Copy link
Contributor

bcmills commented Sep 23, 2022

#52493

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "cmd/go" && test == "TestScript"
2022-09-27 21:00 linux-amd64-longtest go@871a3a40 cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:46033/mod
--- FAIL: TestScript (0.01s)
    --- FAIL: TestScript/mod_replace_gopkgin (33.32s)
        script_test.go:282: 
            # (2022-09-27T21:29:37Z)
            # Regression test for golang.org/issue/34254:
            # a clone of gopkg.in/[…].vN should be replaceable by
            # a fork hosted at corp.example.com/[…]/vN,
            # even if there is an explicit go.mod file containing the
            # gopkg.in path. (0.000s)
...
            	21:29:44.841637 http.c:715              <= Recv header: date: Tue, 27 Sep 2022 21:29:44 GMT
            	21:29:44.841641 http.c:703              <= Recv header, 0000000002 bytes (0x00000002)
            	21:29:44.841645 http.c:715              <= Recv header:
            	21:30:06.089255 http.c:756              == Info: Connection #0 to host go.googlesource.com left intact
            	error: 10224 bytes of body are still expected
            	fetch-pack: unexpected disconnect while reading sideband packet
            	fatal: early EOF
            	fatal: index-pack failed
            [exit status 1]
            FAIL: testdata/script/mod_replace_gopkgin.txt:20: unexpected command failure

watchflakes

@bcmills
Copy link
Contributor

bcmills commented Sep 28, 2022

#52490

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "cmd/go" && test == "TestScript"
2022-09-30 23:00 linux-386-longtest go@76c1a501 cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:38617/mod
--- FAIL: TestScript (0.01s)
    --- FAIL: TestScript/cover_atomic_pkgall (6.28s)
        script_test.go:302: 
            # (2022-09-30T23:33:47Z)
            > env GO111MODULE=off
            > [short] skip
            > go test -coverpkg=all -covermode=atomic x
            [stdout]
            PASS
            panic: runtime error: invalid memory address or nil pointer dereference
            [signal SIGSEGV: segmentation violation code=0x1 addr=0x0 pc=0x80a8991]

            goroutine 1 [running]:
            panic({0x81f4880, 0x8334110})
            	/workdir/go/src/runtime/panic.go:987 +0x673 fp=0x88558b0 sp=0x8855854 pc=0x80b0f73
            runtime.panicmem()
            	/workdir/go/src/runtime/panic.go:260 +0x7e fp=0x88558c0 sp=0x88558b0 pc=0x80ae67e
            runtime.sigpanic()
            	/workdir/go/src/runtime/signal_unix.go:837 +0x270 fp=0x88558d8 sp=0x88558c0 pc=0x80d9ec0
...
            testing.(*M).Run.func4()
            	/workdir/go/src/testing/testing.go:1775 +0x27 fp=0x8855e84 sp=0x8855e7c pc=0x81c05e7
            testing.(*M).Run(0x886e0a0)
            	/workdir/go/src/testing/testing.go:1822 +0xbbc fp=0x8855f6c sp=0x8855e84 pc=0x81c023c
            main.main()
            	_testmain.go:78 +0x105 fp=0x8855fc4 sp=0x8855f6c pc=0x81df725
            runtime.main()
            	/workdir/go/src/runtime/proc.go:250 +0x3c8 fp=0x8855ff0 sp=0x8855fc4 pc=0x80b5f68
            runtime.goexit()
            	/workdir/go/src/runtime/asm_386.s:1326 +0x1 fp=0x8855ff4 sp=0x8855ff0 pc=0x8108dd1
2022-09-30 23:03 linux-386-longtest go@aeab76f0 cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:46409/mod
--- FAIL: TestScript (0.01s)
    --- FAIL: TestScript/cover_atomic_pkgall (8.22s)
        script_test.go:302: 
            # (2022-09-30T23:35:17Z)
            > env GO111MODULE=off
            > [short] skip
            > go test -coverpkg=all -covermode=atomic x
            [stdout]
            PASS
            panic: runtime error: invalid memory address or nil pointer dereference
            [signal SIGSEGV: segmentation violation code=0x1 addr=0x0 pc=0x80a8991]

            goroutine 1 [running]:
            panic({0x81f4880, 0x8334110})
            	/workdir/go/src/runtime/panic.go:987 +0x673 fp=0x88558b0 sp=0x8855854 pc=0x80b0f73
            runtime.panicmem()
            	/workdir/go/src/runtime/panic.go:260 +0x7e fp=0x88558c0 sp=0x88558b0 pc=0x80ae67e
            runtime.sigpanic()
            	/workdir/go/src/runtime/signal_unix.go:837 +0x270 fp=0x88558d8 sp=0x88558c0 pc=0x80d9ec0
...
            testing.(*M).Run.func4()
            	/workdir/go/src/testing/testing.go:1775 +0x27 fp=0x8855e84 sp=0x8855e7c pc=0x81c05e7
            testing.(*M).Run(0x88660a0)
            	/workdir/go/src/testing/testing.go:1822 +0xbbc fp=0x8855f6c sp=0x8855e84 pc=0x81c023c
            main.main()
            	_testmain.go:78 +0x105 fp=0x8855fc4 sp=0x8855f6c pc=0x81df725
            runtime.main()
            	/workdir/go/src/runtime/proc.go:250 +0x3c8 fp=0x8855ff0 sp=0x8855fc4 pc=0x80b5f68
            runtime.goexit()
            	/workdir/go/src/runtime/asm_386.s:1326 +0x1 fp=0x8855ff4 sp=0x8855ff0 pc=0x8108dd1

watchflakes

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "cmd/go" && test == "TestScript"
2022-10-13 18:21 netbsd-amd64-9_0 go@1ef685fb cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:54465/mod
--- FAIL: TestScript (0.01s)
    --- FAIL: TestScript/vet (484.75s)
        script_test.go:302: 
            # (2022-10-13T18:32:13Z)
            # Package with external tests (1.867s)
            # With tags (482.876s)
            > ! go vet -tags tagtest m/vetpkg
            [stderr]
            SIGQUIT: quit
...
            r13    0x19
            r14    0xc000583380
            r15    0xc00057a400
            rip    0x46d563
            rflags 0x203
            cs     0x47
            fs     0x0
            gs     0x0
            [context deadline exceeded]
            FAIL: testdata/script/vet.txt:6: test timed out while running command
2022-10-14 08:14 netbsd-amd64-9_0 go@76e4833b cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:54282/mod
--- FAIL: TestScript (0.01s)
    --- FAIL: TestScript/test_match_only_subtests (481.12s)
        script_test.go:302: 
            # (2022-10-14T08:24:45Z)
            # Matches only subtests (481.114s)
            > go test -run Test/Sub standalone_sub_test.go
            [stderr]
            SIGQUIT: quit
            PC=0x46d563 m=13 sigcode=0
...
            r13    0x5
            r14    0xc00055a1a0
            r15    0x7f7ff77e5000
            rip    0x46d563
            rflags 0x203
            cs     0x47
            fs     0x0
            gs     0x0
            [context deadline exceeded]
            FAIL: testdata/script/test_match_only_subtests.txt:2: test timed out while running command

watchflakes

@bcmills
Copy link
Contributor

bcmills commented Oct 17, 2022

2022-09-30 23:00 linux-386-longtest go@76c1a501 cmd/go.TestScript

That's #56044.

@bcmills
Copy link
Contributor

bcmills commented Oct 17, 2022

2022-10-13 18:21 netbsd-amd64-9_0 go@1ef685fb cmd/go.TestScript

#56180

@bcmills bcmills closed this as completed Oct 17, 2022
@gopherbot gopherbot reopened this Oct 25, 2022
@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript"
2022-10-17 18:38 linux-386-longtest go@b65e259e cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:34013/mod
--- FAIL: TestScript (0.01s)
    --- FAIL: TestScript/test_fuzz_mutate_crash (2.97s)
        script_test.go:302: 
            # (2022-10-17T19:12:37Z)
            # Tests that a crash caused by a mutator-discovered input writes the bad input
            # to testdata, and fails+reports correctly. This tests the end-to-end behavior
            # of the mutator finding a crash while fuzzing, adding it as a regression test
            # to the seed corpus in testdata, and failing the next time the test is run. (0.000s)
            # Running the seed corpus for all of the targets should pass the first
...
            --- FAIL: FuzzWithBug (0.18s)
                --- FAIL: FuzzWithBug (0.00s)
                    testing.go:1411: panic: this input caused a crash!
                        goroutine 36 [running]:
                        runtime/debug.Stack()
                        	/workdir/go/src/runtime/debug/stack.go:24 +0x83
                        testing.tRunner.func1()
                        	/workdir/go/src/testing/testing.go:1411 +0x27a
                        panic({0x81b9a60, 0x8213f90})
                        	/workdir/go/src/runtime/panic.go:884 +0x1c0
...
            FAIL	m	0.183s
            [exit status 1]
            > stdout 'testdata[/\\]fuzz[/\\]FuzzWithBug[/\\]'
            > stdout 'this input caused a crash!'
            > go run check_testdata.go FuzzWithBug
            [stderr]
            hash of bytes "582528ddfad69eb57775199a43e0f9fd5c94bba343ce7bb6724d4ebafe311ed4" does not match filename "582528ddfad69eb5"
            exit status 1
            [exit status 1]
            FAIL: testdata/script/test_fuzz_mutate_crash.txt:18: unexpected command failure
2022-10-17 18:38 linux-amd64-longtest go@b65e259e cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:39275/mod
--- FAIL: TestScript (0.01s)
    --- FAIL: TestScript/test_fuzz_mutate_crash (4.72s)
        script_test.go:302: 
            # (2022-10-17T19:08:34Z)
            # Tests that a crash caused by a mutator-discovered input writes the bad input
            # to testdata, and fails+reports correctly. This tests the end-to-end behavior
            # of the mutator finding a crash while fuzzing, adding it as a regression test
            # to the seed corpus in testdata, and failing the next time the test is run. (0.000s)
            # Running the seed corpus for all of the targets should pass the first
...
            --- FAIL: FuzzWithBug (0.13s)
                --- FAIL: FuzzWithBug (0.00s)
                    testing.go:1411: panic: this input caused a crash!
                        goroutine 16 [running]:
                        runtime/debug.Stack()
                        	/workdir/go/src/runtime/debug/stack.go:24 +0x9e
                        testing.tRunner.func1()
                        	/workdir/go/src/testing/testing.go:1411 +0x1f6
                        panic({0x5b8780, 0x623d58})
                        	/workdir/go/src/runtime/panic.go:884 +0x213
...
            FAIL	m	0.137s
            [exit status 1]
            > stdout 'testdata[/\\]fuzz[/\\]FuzzWithBug[/\\]'
            > stdout 'this input caused a crash!'
            > go run check_testdata.go FuzzWithBug
            [stderr]
            hash of bytes "582528ddfad69eb57775199a43e0f9fd5c94bba343ce7bb6724d4ebafe311ed4" does not match filename "582528ddfad69eb5"
            exit status 1
            [exit status 1]
            FAIL: testdata/script/test_fuzz_mutate_crash.txt:18: unexpected command failure
2022-10-17 18:38 windows-amd64-longtest go@b65e259e cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:53915/mod
--- FAIL: TestScript (0.02s)
    --- FAIL: TestScript/test_fuzz_mutate_crash (7.97s)
        script_test.go:302: 
            # (2022-10-17T19:09:55Z)
            # Tests that a crash caused by a mutator-discovered input writes the bad input
            # to testdata, and fails+reports correctly. This tests the end-to-end behavior
            # of the mutator finding a crash while fuzzing, adding it as a regression test
            # to the seed corpus in testdata, and failing the next time the test is run. (0.000s)
            # Running the seed corpus for all of the targets should pass the first
...
            --- FAIL: FuzzWithBug (0.46s)
                --- FAIL: FuzzWithBug (0.00s)
                    testing.go:1411: panic: this input caused a crash!
                        goroutine 13 [running]:
                        runtime/debug.Stack()
                        	C:/workdir/go/src/runtime/debug/stack.go:24 +0x9e
                        testing.tRunner.func1()
                        	C:/workdir/go/src/testing/testing.go:1411 +0x1f6
                        panic({0x13734a0, 0x13e6908})
                        	C:/workdir/go/src/runtime/panic.go:884 +0x213
...
            FAIL	m	0.527s
            [exit status 1]
            > stdout 'testdata[/\\]fuzz[/\\]FuzzWithBug[/\\]'
            > stdout 'this input caused a crash!'
            > go run check_testdata.go FuzzWithBug
            [stderr]
            hash of bytes "582528ddfad69eb57775199a43e0f9fd5c94bba343ce7bb6724d4ebafe311ed4" does not match filename "582528ddfad69eb5"
            exit status 1
            [exit status 1]
            FAIL: testdata\script\test_fuzz_mutate_crash.txt:18: unexpected command failure
2022-10-17 19:46 linux-386-longtest go@07c57aff cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:37879/mod
--- FAIL: TestScript (0.01s)
    --- FAIL: TestScript/test_fuzz_mutate_crash (3.64s)
        script_test.go:302: 
            # (2022-10-17T20:19:31Z)
            # Tests that a crash caused by a mutator-discovered input writes the bad input
            # to testdata, and fails+reports correctly. This tests the end-to-end behavior
            # of the mutator finding a crash while fuzzing, adding it as a regression test
            # to the seed corpus in testdata, and failing the next time the test is run. (0.000s)
            # Running the seed corpus for all of the targets should pass the first
...
            --- FAIL: FuzzWithBug (0.21s)
                --- FAIL: FuzzWithBug (0.00s)
                    testing.go:1411: panic: this input caused a crash!
                        goroutine 61 [running]:
                        runtime/debug.Stack()
                        	/workdir/go/src/runtime/debug/stack.go:24 +0x83
                        testing.tRunner.func1()
                        	/workdir/go/src/testing/testing.go:1411 +0x27a
                        panic({0x81b9a60, 0x8213f90})
                        	/workdir/go/src/runtime/panic.go:884 +0x1c0
...
            FAIL	m	0.223s
            [exit status 1]
            > stdout 'testdata[/\\]fuzz[/\\]FuzzWithBug[/\\]'
            > stdout 'this input caused a crash!'
            > go run check_testdata.go FuzzWithBug
            [stderr]
            hash of bytes "caf81e9797b19c76c1fc4dbf537d4d81f389524539f402d13aa01f93a65ac7e9" does not match filename "caf81e9797b19c76"
            exit status 1
            [exit status 1]
            FAIL: testdata/script/test_fuzz_mutate_crash.txt:18: unexpected command failure
2022-10-17 19:46 linux-amd64-longtest go@07c57aff cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:36615/mod
--- FAIL: TestScript (0.01s)
    --- FAIL: TestScript/test_fuzz_mutate_crash (16.23s)
        script_test.go:302: 
            # (2022-10-17T20:11:21Z)
            # Tests that a crash caused by a mutator-discovered input writes the bad input
            # to testdata, and fails+reports correctly. This tests the end-to-end behavior
            # of the mutator finding a crash while fuzzing, adding it as a regression test
            # to the seed corpus in testdata, and failing the next time the test is run. (0.000s)
            # Running the seed corpus for all of the targets should pass the first
...
            --- FAIL: FuzzWithBug (0.15s)
                --- FAIL: FuzzWithBug (0.00s)
                    testing.go:1411: panic: this input caused a crash!
                        goroutine 53 [running]:
                        runtime/debug.Stack()
                        	/workdir/go/src/runtime/debug/stack.go:24 +0x9e
                        testing.tRunner.func1()
                        	/workdir/go/src/testing/testing.go:1411 +0x1f6
                        panic({0x5b8780, 0x623d58})
                        	/workdir/go/src/runtime/panic.go:884 +0x213
...
            FAIL	m	0.171s
            [exit status 1]
            > stdout 'testdata[/\\]fuzz[/\\]FuzzWithBug[/\\]'
            > stdout 'this input caused a crash!'
            > go run check_testdata.go FuzzWithBug
            [stderr]
            hash of bytes "582528ddfad69eb57775199a43e0f9fd5c94bba343ce7bb6724d4ebafe311ed4" does not match filename "582528ddfad69eb5"
            exit status 1
            [exit status 1]
            FAIL: testdata/script/test_fuzz_mutate_crash.txt:18: unexpected command failure
2022-10-17 19:46 windows-amd64-longtest go@07c57aff cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:53996/mod
--- FAIL: TestScript (0.01s)
    --- FAIL: TestScript/test_fuzz_mutate_crash (31.54s)
        script_test.go:302: 
            # (2022-10-17T20:12:10Z)
            # Tests that a crash caused by a mutator-discovered input writes the bad input
            # to testdata, and fails+reports correctly. This tests the end-to-end behavior
            # of the mutator finding a crash while fuzzing, adding it as a regression test
            # to the seed corpus in testdata, and failing the next time the test is run. (0.000s)
            # Running the seed corpus for all of the targets should pass the first
...
            --- FAIL: FuzzWithBug (2.13s)
                --- FAIL: FuzzWithBug (0.00s)
                    testing.go:1411: panic: this input caused a crash!
                        goroutine 22 [running]:
                        runtime/debug.Stack()
                        	C:/workdir/go/src/runtime/debug/stack.go:24 +0x9e
                        testing.tRunner.func1()
                        	C:/workdir/go/src/testing/testing.go:1411 +0x1f6
                        panic({0xc234a0, 0xc96908})
                        	C:/workdir/go/src/runtime/panic.go:884 +0x213
...
            FAIL	m	2.264s
            [exit status 1]
            > stdout 'testdata[/\\]fuzz[/\\]FuzzWithBug[/\\]'
            > stdout 'this input caused a crash!'
            > go run check_testdata.go FuzzWithBug
            [stderr]
            hash of bytes "582528ddfad69eb57775199a43e0f9fd5c94bba343ce7bb6724d4ebafe311ed4" does not match filename "582528ddfad69eb5"
            exit status 1
            [exit status 1]
            FAIL: testdata\script\test_fuzz_mutate_crash.txt:18: unexpected command failure

watchflakes

@bcmills
Copy link
Contributor

bcmills commented Oct 25, 2022

The above six failures were fallout from https://go.dev/cl/443335, fixed in https://go.dev/cl/443381.

@bcmills bcmills closed this as completed Oct 25, 2022
@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript"
2022-11-19 16:45 linux-386-longtest go@5f60f844 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:45181
https://vcs-test.golang.org rerouted to https://127.0.0.1:43235
go test proxy running at GOPROXY=http://127.0.0.1:45355/mod
2022/11/19 17:09:12 http: TLS handshake error from 127.0.0.1:50944: EOF
2022/11/19 17:09:12 http: TLS handshake error from 127.0.0.1:50950: EOF
2022/11/19 17:09:12 http: TLS handshake error from 127.0.0.1:50986: read tcp 127.0.0.1:43235->127.0.0.1:50986: read: connection reset by peer
2022/11/19 17:09:12 http: TLS handshake error from 127.0.0.1:51002: read tcp 127.0.0.1:43235->127.0.0.1:51002: read: connection reset by peer
2022/11/19 17:09:12 http: TLS handshake error from 127.0.0.1:51072: EOF
--- FAIL: TestScript (0.04s)
    --- FAIL: TestScript/test2json_interrupt (2.39s)
...
            {"Time":"2022-11-19T17:08:51.664561426Z","Action":"output","Package":"example","Test":"FuzzInterrupt","Output":"    Failing input written to testdata/fuzz/FuzzInterrupt/110c93a88fa18c31\n"}
            {"Time":"2022-11-19T17:08:51.664565615Z","Action":"output","Package":"example","Test":"FuzzInterrupt","Output":"    To re-run:\n"}
            {"Time":"2022-11-19T17:08:51.664569895Z","Action":"output","Package":"example","Test":"FuzzInterrupt","Output":"    go test -run=FuzzInterrupt/110c93a88fa18c31\n"}
            {"Time":"2022-11-19T17:08:51.664575115Z","Action":"fail","Package":"example","Test":"FuzzInterrupt","Elapsed":0}
            {"Time":"2022-11-19T17:08:51.664581566Z","Action":"fail","Package":"example","Test":"FuzzInterrupt","Elapsed":0.07}
            {"Time":"2022-11-19T17:08:51.664591635Z","Action":"output","Package":"example","Output":"FAIL\n"}
            {"Time":"2022-11-19T17:08:51.665402725Z","Action":"fail","Package":"example","Elapsed":0.103}
            [exit status 1]
            > stdout -count=1 '"Action":"pass","Package":"example","Test":"FuzzInterrupt"'
        script_test.go:154: FAIL: test2json_interrupt.txt:12: stdout -count=1 "Action":"pass","Package":"example","Test":"FuzzInterrupt": found 0 matches for `(?m)"Action":"pass","Package":"example","Test":"FuzzInterrupt"` in stdout
2022-11-19 16:50 windows-amd64-longtest go@58a2db18 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:54031
https://vcs-test.golang.org rerouted to https://127.0.0.1:54032
go test proxy running at GOPROXY=http://127.0.0.1:54033/mod
--- FAIL: TestScript (0.15s)
    --- FAIL: TestScript/test_fuzz_mutator (2472.10s)
        script_test.go:134: 2022-11-19T18:29:47Z
        script_test.go:136: $WORK=C:\Users\gopher\AppData\Local\Temp\1\cmd-go-test-545967725\tmpdir2602773748\test_fuzz_mutator3453940057
        script_test.go:154: 
            > [!fuzz] skip
            [condition not met]
...
            fuzz: elapsed: 40m45s, execs: 110 (0/sec), new interesting: 0 (total: 1)
            fuzz: elapsed: 40m48s, execs: 110 (0/sec), new interesting: 0 (total: 1)
            fuzz: elapsed: 40m51s, execs: 110 (0/sec), new interesting: 0 (total: 1)
            fuzz: elapsed: 40m54s, execs: 110 (0/sec), new interesting: 0 (total: 1)
            fuzz: elapsed: 40m57s, execs: 110 (0/sec), new interesting: 0 (total: 1)
            fuzz: elapsed: 41m0s, execs: 110 (0/sec), new interesting: 0 (total: 1)
            fuzz: elapsed: 41m3s, execs: 110 (0/sec), new interesting: 0 (total: 1)
            fuzz: elapsed: 41m6s, execs: 110 (0/sec), new interesting: 0 (total: 1)
        script_test.go:154: FAIL: test_fuzz_mutator.txt:12: go test -fuzz=FuzzA -fuzztime=100x -parallel=1 -log=fuzz: exit status 1
2022/11/19 19:11:19 unexpected files left in tmpdir: [tmpdir2602773748]

watchflakes

@gopherbot gopherbot reopened this Dec 7, 2022
@bcmills
Copy link
Contributor

bcmills commented Dec 7, 2022

The TestScript/test_fuzz_mutator failure is #51484; I've added a watchflakes pattern for that.

@bcmills
Copy link
Contributor

bcmills commented Dec 7, 2022

The TestScript/test2json_interrupt failure is plausibly #56083 or related to it.

@bcmills bcmills closed this as completed Dec 8, 2022
@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript"
2022-12-08 03:52 windows-amd64-longtest go@c8313d4f cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:54134
https://vcs-test.golang.org rerouted to https://127.0.0.1:54135
go test proxy running at GOPROXY=http://127.0.0.1:54136/mod
--- FAIL: TestScript (0.15s)
    --- FAIL: TestScript/mod_sumdb_file_path (22.96s)
        script_test.go:134: 2022-12-08T04:28:41Z
        script_test.go:136: $WORK=C:\Users\gopher\AppData\Local\Temp\1\cmd-go-test-214507307\tmpdir129735765\mod_sumdb_file_path3753446665
        script_test.go:154: 
            > [!net] skip
            [condition not met]
            > env GO111MODULE=on
            > env GOSUMDB=
            > env GOPATH=$WORK/gopath1
            # With a file-based proxy with an empty checksum directory,
            # downloading a new module should fail, even if a subsequent
            # proxy contains a more complete mirror of the sum database.
            #
            # TODO(bcmills): The error message here is a bit redundant.
            # It comes from the sumweb package, which isn't yet producing structured errors. (0.814s)
            # If the proxy does not claim to support the database,
            # checksum verification should fall through to the next proxy,
            # and downloading should succeed. (21.926s)
            > [GOOS:windows] env GOPROXY=file:///$WORK/emptyproxy,https://proxy.golang.org
            > [!GOOS:windows] env GOPROXY=file://$WORK/emptyproxy,https://proxy.golang.org
            [condition not met]
            > go get golang.org/x/text@v0.3.2
            [stderr]
            go: downloading golang.org/x/text v0.3.2
            go: golang.org/x/text@v0.3.2: verifying module: golang.org/x/text@v0.3.2: Get "https://sum.golang.org/lookup/golang.org/x/text@v0.3.2": dial tcp 142.250.125.141:443: connectex: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond.
        script_test.go:154: FAIL: mod_sumdb_file_path.txt:23: go get golang.org/x/text@v0.3.2: exit status 1
2022-12-08 18:29 linux-arm64-longtest go@7973b0e5 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:33643
https://vcs-test.golang.org rerouted to https://127.0.0.1:35117
go test proxy running at GOPROXY=http://127.0.0.1:41799/mod
2022/12/08 18:52:28 http: TLS handshake error from 127.0.0.1:57922: EOF
2022/12/08 18:52:28 http: TLS handshake error from 127.0.0.1:57966: EOF
2022/12/08 18:52:30 http: TLS handshake error from 127.0.0.1:58092: EOF
2022/12/08 18:52:30 http: TLS handshake error from 127.0.0.1:58140: read tcp 127.0.0.1:35117->127.0.0.1:58140: read: connection reset by peer
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/get_issue11307 (0.56s)
        script_test.go:134: 2022-12-08T18:51:27Z
...
            18:51:27.544040 http.c:715              => Send header: accept-encoding: deflate, gzip, br
            18:51:27.544041 http.c:715              => Send header: pragma: no-cache
            18:51:27.544043 http.c:715              => Send header: git-protocol: version=2
            18:51:27.544045 http.c:715              => Send header:
            18:51:27.566239 http.c:756              == Info: http2 error: Remote peer returned unexpected data while we expected SETTINGS frame.  Perhaps, peer does not support HTTP/2 properly.
            18:51:27.566300 http.c:756              == Info: Empty reply from server
            18:51:27.566320 http.c:756              == Info: Closing connection 0
            fatal: unable to access 'https://github.com/rsc/go-get-issue-11307/': Empty reply from server
            package github.com/rsc/go-get-issue-11307: exit status 1
        script_test.go:154: FAIL: get_issue11307.txt:9: go get -u github.com/rsc/go-get-issue-11307: exit status 1

watchflakes

@gopherbot gopherbot reopened this Dec 17, 2022
@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript"
2022-11-07 15:19 linux-amd64-longtest-race go@390abbbb cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:46845
https://vcs-test.golang.org rerouted to https://127.0.0.1:39043
go test proxy running at GOPROXY=http://127.0.0.1:32867/mod
2022/11/10 17:32:30 http: TLS handshake error from 127.0.0.1:45898: read tcp 127.0.0.1:39043->127.0.0.1:45898: read: connection reset by peer
2022/11/10 17:32:40 http: TLS handshake error from 127.0.0.1:34216: EOF
2022/11/10 17:32:40 http: TLS handshake error from 127.0.0.1:34224: EOF
==================
WARNING: DATA RACE
Write at 0x00c00035c888 by goroutine 11951:
  cmd/go/internal/vcweb.(*Server).HandleScript.func1()
...
--- FAIL: TestScript (0.12s)
    --- FAIL: TestScript/mod_ambiguous_import (0.20s)
        script_test.go:134: 2022-11-10T17:32:40Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-2986908354/tmpdir3992809775/mod_ambiguous_import966979513
        script_test.go:154: 
            > env GO111MODULE=on
            > cd $WORK
            # An import provided by two different modules should be flagged as an error. (0.025s)
            # However, it should not be an error if that import is unused. (0.099s)
            # An import provided by both the main module and the vendor directory
...
            > env GOPROXY=direct
            > env GOSUMDB=off
            # Regression test for golang.org/issue/27171: after resolving an older
            # pseudo-version of a commit, future resolution of that commit by hash should
            # choose the highest appropriate pseudo-version instead of the cached one. (43.965s)
            # If GOPROXY is 'off', lookups should use whatever pseudo-version is available. (0.058s)
            # If we can re-resolve the commit to a pseudo-version, fetching the commit by
            # hash should use the highest such pseudo-version appropriate to the commit. (47.186s)
            # If GOPROXY is 'off', lookups should use the highest pseudo-version in the cache. (0.113s)
        testing.go:1441: race detected during execution of test
2022-11-07 19:47 linux-amd64-longtest-race go@72ce9ba9 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:39865
https://vcs-test.golang.org rerouted to https://127.0.0.1:32827
go test proxy running at GOPROXY=http://127.0.0.1:33411/mod
==================
WARNING: DATA RACE
Read at 0x00c00024c118 by goroutine 8502:
  cmd/go/internal/vcweb.(*Server).overview.func1()
      /workdir/go/src/cmd/go/internal/vcweb/vcweb.go:386 +0x1c5
  path/filepath.walkDir()
      /workdir/go/src/path/filepath/path.go:404 +0x90
...
--- FAIL: TestScript (0.17s)
    --- FAIL: TestScript/build_single_error (0.75s)
        script_test.go:134: 2022-11-10T19:23:45Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-3792726609/tmpdir1270439544/build_single_error2517059131
        script_test.go:154: 
            # go test ./... with a bad package should report the error once (#44624). (0.745s)
        testing.go:1441: race detected during execution of test
    --- FAIL: TestScript/mod_sumdb_file_path (58.95s)
        script_test.go:134: 2022-11-10T19:22:47Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-3792726609/tmpdir1270439544/mod_sumdb_file_path3659871536
...
            > env GOPROXY=direct
            > env GOSUMDB=off
            # Regression test for golang.org/issue/27171: after resolving an older
            # pseudo-version of a commit, future resolution of that commit by hash should
            # choose the highest appropriate pseudo-version instead of the cached one. (72.128s)
            # If GOPROXY is 'off', lookups should use whatever pseudo-version is available. (0.078s)
            # If we can re-resolve the commit to a pseudo-version, fetching the commit by
            # hash should use the highest such pseudo-version appropriate to the commit. (64.251s)
            # If GOPROXY is 'off', lookups should use the highest pseudo-version in the cache. (0.071s)
        testing.go:1441: race detected during execution of test
2022-11-07 21:28 linux-amd64-longtest-race go@b417f62b cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:41365
https://vcs-test.golang.org rerouted to https://127.0.0.1:40659
go test proxy running at GOPROXY=http://127.0.0.1:34829/mod
2022/11/10 17:48:37 http: TLS handshake error from 127.0.0.1:45416: EOF
==================
WARNING: DATA RACE
Write at 0x00c0002513e8 by goroutine 13622:
  cmd/go/internal/vcweb.(*Server).HandleScript.func1()
      /workdir/go/src/cmd/go/internal/vcweb/vcweb.go:326 +0x17a
  cmd/go/internal/vcweb.(*Server).HandleScript()
...
--- FAIL: TestScript (0.16s)
    --- FAIL: TestScript/mod_download_insecure_redirect (1.81s)
        script_test.go:134: 2022-11-10T17:48:37Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-2791995636/tmpdir2302056343/mod_download_insecure_redirect2993231490
        script_test.go:154: 
            # golang.org/issue/29591: 'go get' was following plain-HTTP redirects even without -insecure (now replaced by GOINSECURE). (0.080s)
            # insecure host (0.746s)
            # insecure glob host (0.499s)
            # insecure multiple host (0.360s)
            # different insecure host does not fetch (0.120s)
...
            > env GOPROXY=direct
            > env GOSUMDB=off
            # Regression test for golang.org/issue/27171: after resolving an older
            # pseudo-version of a commit, future resolution of that commit by hash should
            # choose the highest appropriate pseudo-version instead of the cached one. (65.793s)
            # If GOPROXY is 'off', lookups should use whatever pseudo-version is available. (0.076s)
            # If we can re-resolve the commit to a pseudo-version, fetching the commit by
            # hash should use the highest such pseudo-version appropriate to the commit. (70.334s)
            # If GOPROXY is 'off', lookups should use the highest pseudo-version in the cache. (0.089s)
        testing.go:1441: race detected during execution of test
2022-11-08 14:52 linux-amd64-longtest-race go@a1c31d68 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:33107
https://vcs-test.golang.org rerouted to https://127.0.0.1:37849
go test proxy running at GOPROXY=http://127.0.0.1:32797/mod
2022/11/10 19:18:37 http: TLS handshake error from 127.0.0.1:32940: EOF
==================
WARNING: DATA RACE
Read at 0x00c0001d4118 by goroutine 12220:
  cmd/go/internal/vcweb.(*Server).overview.func1()
      /workdir/go/src/cmd/go/internal/vcweb/vcweb.go:386 +0x1c5
  path/filepath.walkDir()
...
--- FAIL: TestScript (0.15s)
    --- FAIL: TestScript/mod_get_errors (0.34s)
        script_test.go:134: 2022-11-10T19:18:47Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-2190745356/tmpdir3811982126/mod_get_errors3176792427
        script_test.go:154: 
            > cp go.mod go.mod.orig
            # 'go get' should fail, without updating go.mod, if the transitive dependencies
            # of the requested package (by default, the package in the current directory)
            # cannot be resolved. (0.143s)
            # A syntax error in a dependency prevents the compiler from needing that
...
            > env GOPROXY=direct
            > env GOSUMDB=off
            # Regression test for golang.org/issue/27171: after resolving an older
            # pseudo-version of a commit, future resolution of that commit by hash should
            # choose the highest appropriate pseudo-version instead of the cached one. (63.761s)
            # If GOPROXY is 'off', lookups should use whatever pseudo-version is available. (0.061s)
            # If we can re-resolve the commit to a pseudo-version, fetching the commit by
            # hash should use the highest such pseudo-version appropriate to the commit. (61.590s)
            # If GOPROXY is 'off', lookups should use the highest pseudo-version in the cache. (0.043s)
        testing.go:1441: race detected during execution of test
2022-11-08 21:50 linux-386-longtest go@8befe0e4 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:35807
https://vcs-test.golang.org rerouted to https://127.0.0.1:37133
go test proxy running at GOPROXY=http://127.0.0.1:37109/mod
2022/11/08 22:23:51 http: TLS handshake error from 127.0.0.1:36298: EOF
2022/11/08 22:24:09 http: TLS handshake error from 127.0.0.1:53718: read tcp 127.0.0.1:37133->127.0.0.1:53718: read: connection reset by peer
2022/11/08 22:24:09 http: TLS handshake error from 127.0.0.1:53696: read tcp 127.0.0.1:37133->127.0.0.1:53696: read: connection reset by peer
2022/11/08 22:24:09 http: TLS handshake error from 127.0.0.1:53748: EOF
2022/11/08 22:24:09 http: TLS handshake error from 127.0.0.1:53730: EOF
2022/11/08 22:24:09 http: TLS handshake error from 127.0.0.1:53774: read tcp 127.0.0.1:37133->127.0.0.1:53774: read: connection reset by peer
2022/11/08 22:24:09 http: TLS handshake error from 127.0.0.1:53790: EOF
...
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/mod_replace_gopkgin (307.18s)
        script_test.go:134: 2022-11-08T22:23:50Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-2798095010/tmpdir3967962862/mod_replace_gopkgin1947823528
        script_test.go:154: 
            # Regression test for golang.org/issue/34254:
            # a clone of gopkg.in/[…].vN should be replaceable by
            # a fork hosted at corp.example.com/[…]/vN,
            # even if there is an explicit go.mod file containing the
            # gopkg.in path. (0.000s)
...
            	22:23:53.535422 http.c:756              == Info:   Trying 185.125.188.113:443...
            	22:24:44.072600 http.c:756              == Info: Connected to gopkg.in (185.125.188.113) port 443 (#0)
            	22:24:44.195603 http.c:756              == Info: found 387 certificates in /etc/ssl/certs
            	22:24:44.195789 http.c:756              == Info: ALPN, offering h2
            	22:24:44.195833 http.c:756              == Info: ALPN, offering http/1.1
            	22:28:53.571966 http.c:756              == Info: Operation timed out after 300045 milliseconds with 0 out of 0 bytes received
            	U
            	22:28:53.572034 http.c:756              == Info: Closing connection 0
            	fatal: unable to access 'https://gopkg.in/src-d/go-git-fixtures.v3/': Operation timed out after 300045 milliseconds with 0 out of 0 bytes received
        script_test.go:154: FAIL: mod_replace_gopkgin.txt:20: go list -m gopkg.in/src-d/go-git.v4: exit status 1
2022-11-09 18:43 linux-amd64-longtest-race go@a2335d05 cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:39171/mod
--- FAIL: TestScript (0.04s)
    --- FAIL: TestScript/mod_get_pseudo_other_branch (171.80s)
        script_test.go:270: 
            # (2022-11-10T17:45:59Z)
            # Testing that a pseudo-version is based on the semantically-latest
            # tag that appears in any commit that is a (transitive) parent of the commit
            # supplied to 'go get', regardless of branches (0.000s)
            # For this test repository:
            #  tag v0.2.1 is most recent tag on master itself
...
    --- FAIL: TestScript/mod_missing_repo (197.51s)
        script_test.go:270: 
            # (2022-11-10T17:45:49Z)
            # Regression test for golang.org/issue/34094: modules hosted within gitlab.com
            # subgroups could not be fetched because the server returned bogus go-import
            # tags for prefixes of the module path. (197.503s)
            > [!net] skip
            > [!exec:git] skip
            > env GO111MODULE=on
            > env GOPROXY=direct
...
            # will output with error:
            # go list -m: vcs-test.golang.org/git/gitrepo1.git@v1.2.3: invalid version: unknown revision v1.2.3
            # See golang/go#51312. (454.553s)
            > go list -m vcs-test.golang.org/git/gitrepo1.git@v1.2.3
            [stderr]
            go: vcs-test.golang.org/git/gitrepo1.git@v1.2.3: invalid version: git ls-remote -q origin in $WORK/gopath/pkg/mod/cache/vcs/08c5f0142cd2ee461695f026a59d62c44cb2a80cbae41f419679e3a566f62bca: exit status 128:
            	fatal: unable to connect to vcs-test.golang.org:
            	vcs-test.golang.org[0: 34.110.184.62]: errno=Connection timed out
            [exit status 1]
            FAIL: testdata/script/mod_download_git_decorate_full.txt:23: unexpected command failure
2022-11-09 18:44 linux-amd64-longtest-race go@ec651088 cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:37901/mod
--- FAIL: TestScript (0.04s)
    --- FAIL: TestScript/mod_download_insecure_redirect (155.60s)
        script_test.go:270: 
            # (2022-11-10T17:46:11Z)
            # golang.org/issue/29591: 'go get' was following plain-HTTP redirects even without -insecure (now replaced by GOINSECURE). (0.313s)
            # insecure host (155.284s)
            > env GOINSECURE=vcs-test.golang.org
            > go clean -modcache
            > go mod download vcs-test.golang.org/insecure/go/insecure@latest
...
    --- FAIL: TestScript/mod_get_pseudo_other_branch (157.13s)
        script_test.go:270: 
            # (2022-11-10T17:46:17Z)
            # Testing that a pseudo-version is based on the semantically-latest
            # tag that appears in any commit that is a (transitive) parent of the commit
            # supplied to 'go get', regardless of branches (0.000s)
            # For this test repository:
            #  tag v0.2.1 is most recent tag on master itself
            #  tag v0.2.2 is on branch2, which was then merged to master
            #  master is a merge commit with both tags as parents
...
            # will output with error:
            # go list -m: vcs-test.golang.org/git/gitrepo1.git@v1.2.3: invalid version: unknown revision v1.2.3
            # See golang/go#51312. (452.023s)
            > go list -m vcs-test.golang.org/git/gitrepo1.git@v1.2.3
            [stderr]
            go: vcs-test.golang.org/git/gitrepo1.git@v1.2.3: invalid version: git ls-remote -q origin in $WORK/gopath/pkg/mod/cache/vcs/08c5f0142cd2ee461695f026a59d62c44cb2a80cbae41f419679e3a566f62bca: exit status 128:
            	fatal: unable to connect to vcs-test.golang.org:
            	vcs-test.golang.org[0: 34.110.184.62]: errno=Connection timed out
            [exit status 1]
            FAIL: testdata/script/mod_download_git_decorate_full.txt:23: unexpected command failure
2022-11-14 05:09 linux-386-longtest go@d092f597 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:42761
https://vcs-test.golang.org rerouted to https://127.0.0.1:33981
go test proxy running at GOPROXY=http://127.0.0.1:35837/mod
2022/11/14 05:47:56 http: TLS handshake error from 127.0.0.1:38986: EOF
2022/11/14 05:48:27 http: TLS handshake error from 127.0.0.1:37450: EOF
2022/11/14 05:48:27 http: TLS handshake error from 127.0.0.1:37412: EOF
2022/11/14 05:48:28 http: TLS handshake error from 127.0.0.1:37428: EOF
2022/11/14 05:48:28 http: TLS handshake error from 127.0.0.1:37466: EOF
2022/11/14 05:48:28 http: TLS handshake error from 127.0.0.1:37474: EOF
2022/11/14 05:48:28 http: TLS handshake error from 127.0.0.1:37490: read tcp 127.0.0.1:33981->127.0.0.1:37490: read: connection reset by peer
...
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/mod_replace_gopkgin (1484.23s)
        script_test.go:134: 2022-11-14T05:47:55Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-4269327661/tmpdir3674476494/mod_replace_gopkgin1245759689
        script_test.go:154: 
            # Regression test for golang.org/issue/34254:
            # a clone of gopkg.in/[…].vN should be replaceable by
            # a fork hosted at corp.example.com/[…]/vN,
            # even if there is an explicit go.mod file containing the
            # gopkg.in path. (0.000s)
...
            # as replacements for gopkg.in/[…].v4.
            # As a special case, we continue to accept those. (2.154s)
            # A mismatched gopkg.in path should not be able to replace a different major version. (1025.958s)
            > cd ../3-to-gomod-4
            > ! go list -m gopkg.in/src-d/go-git.v3
            [stderr]
            go: gopkg.in/src-d/go-git.v3@v3.2.0: unrecognized import path "gopkg.in/src-d/go-git.v3": https fetch: Get "https://gopkg.in/src-d/go-git.v3?go-get=1": read tcp 10.128.0.91:53628->185.125.188.236:443: read: connection timed out
            [exit status 1]
            > stderr '^go: gopkg\.in/src-d/go-git\.v3@v3\.2\.0 \(replaced by gopkg\.in/src-d/go-git\.v3@v3\.0\.0-20190801152248-0d1a009cbb60\): version "v3\.0\.0-20190801152248-0d1a009cbb60" invalid: go\.mod has non-\.\.\.\.v3 module path "gopkg\.in/src-d/go-git\.v4" at revision 0d1a009cbb60$'
        script_test.go:154: FAIL: mod_replace_gopkgin.txt:38: stderr '^go: gopkg\.in/src-d/go-git\.v3@v3\.2\.0 \(replaced by gopkg\.in/src-d/go-git\.v3@v3\.0\.0-20190801152248-0d1a009cbb60\): version "v3\.0\.0-20190801152248-0d1a009cbb60" invalid: go\.mod has non-\.\.\.\.v3 module path "gopkg\.in/src-d/go-git\.v4" at revision 0d1a009cbb60$': no match for `(?m)^go: gopkg\.in/src-d/go-git\.v3@v3\.2\.0 \(replaced by gopkg\.in/src-d/go-git\.v3@v3\.0\.0-20190801152248-0d1a009cbb60\): version "v3\.0\.0-20190801152248-0d1a009cbb60" invalid: go\.mod has non-\.\.\.\.v3 module path "gopkg\.in/src-d/go-git\.v4" at revision 0d1a009cbb60$` in stderr
2022-11-14 05:09 linux-amd64-longtest-race go@d092f597 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:35029
https://vcs-test.golang.org rerouted to https://127.0.0.1:41913
go test proxy running at GOPROXY=http://127.0.0.1:42403/mod
2022/11/14 06:23:36 http: TLS handshake error from 127.0.0.1:39924: EOF
--- FAIL: TestScript (0.16s)
    --- FAIL: TestScript/mod_replace_gopkgin (32.86s)
        script_test.go:134: 2022-11-14T06:23:28Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-2370016582/tmpdir3745845637/mod_replace_gopkgin3595297536
        script_test.go:154: 
            # Regression test for golang.org/issue/34254:
            # a clone of gopkg.in/[…].vN should be replaceable by
            # a fork hosted at corp.example.com/[…]/vN,
            # even if there is an explicit go.mod file containing the
            # gopkg.in path. (0.000s)
            # Replacing gopkg.in/[…].vN with a repository with a root go.mod file
            # specifying […].vN and a compatible version should succeed, even if
            # the replacement path is not a gopkg.in path. (32.573s)
            > cd 4-to-4
            > go list -m gopkg.in/src-d/go-git.v4
            [stderr]
            go: gopkg.in/src-d/go-git.v4@v4.13.1 requires
            	gopkg.in/check.v1@v1.0.0-20180628173108-788fd7840127: unrecognized import path "gopkg.in/check.v1": https fetch: Get "https://gopkg.in/check.v1?go-get=1": dial tcp 185.125.188.236:443: i/o timeout
        script_test.go:154: FAIL: mod_replace_gopkgin.txt:20: go list -m gopkg.in/src-d/go-git.v4: exit status 1
2022-11-14 05:30 linux-386-longtest go@c55d1841 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:35463
https://vcs-test.golang.org rerouted to https://127.0.0.1:35205
go test proxy running at GOPROXY=http://127.0.0.1:33381/mod
2022/11/14 06:04:51 http: TLS handshake error from 127.0.0.1:54034: read tcp 127.0.0.1:35205->127.0.0.1:54034: read: connection reset by peer
2022/11/14 06:04:51 http: TLS handshake error from 127.0.0.1:54032: EOF
2022/11/14 06:04:51 http: TLS handshake error from 127.0.0.1:54058: read tcp 127.0.0.1:35205->127.0.0.1:54058: read: connection reset by peer
2022/11/14 06:04:51 http: TLS handshake error from 127.0.0.1:54072: EOF
2022/11/14 06:04:52 http: TLS handshake error from 127.0.0.1:54110: EOF
2022/11/14 06:04:52 http: TLS handshake error from 127.0.0.1:54112: EOF
2022/11/14 06:04:53 http: TLS handshake error from 127.0.0.1:54246: read tcp 127.0.0.1:35205->127.0.0.1:54246: read: connection reset by peer
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/mod_replace_gopkgin (33.03s)
        script_test.go:134: 2022-11-14T06:04:32Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-3807699321/tmpdir4186387784/mod_replace_gopkgin876446953
        script_test.go:154: 
            # Regression test for golang.org/issue/34254:
            # a clone of gopkg.in/[…].vN should be replaceable by
            # a fork hosted at corp.example.com/[…]/vN,
            # even if there is an explicit go.mod file containing the
            # gopkg.in path. (0.000s)
            # Replacing gopkg.in/[…].vN with a repository with a root go.mod file
            # specifying […].vN and a compatible version should succeed, even if
            # the replacement path is not a gopkg.in path. (32.877s)
            > cd 4-to-4
            > go list -m gopkg.in/src-d/go-git.v4
            [stderr]
            go: gopkg.in/src-d/go-git.v4@v4.13.1 requires
            	gopkg.in/check.v1@v1.0.0-20180628173108-788fd7840127: unrecognized import path "gopkg.in/check.v1": https fetch: Get "https://gopkg.in/check.v1?go-get=1": dial tcp 185.125.188.113:443: i/o timeout
        script_test.go:154: FAIL: mod_replace_gopkgin.txt:20: go list -m gopkg.in/src-d/go-git.v4: exit status 1
2022-11-14 05:30 linux-amd64-longtest go@c55d1841 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:34377
https://vcs-test.golang.org rerouted to https://127.0.0.1:38467
go test proxy running at GOPROXY=http://127.0.0.1:37731/mod
2022/11/14 05:51:24 http: TLS handshake error from 127.0.0.1:46016: read tcp 127.0.0.1:38467->127.0.0.1:46016: read: connection reset by peer
2022/11/14 05:51:41 http: TLS handshake error from 127.0.0.1:51098: EOF
--- FAIL: TestScript (0.06s)
    --- FAIL: TestScript/mod_replace_gopkgin (1443.23s)
        script_test.go:134: 2022-11-14T05:51:22Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-4248399153/tmpdir2080099949/mod_replace_gopkgin626830238
        script_test.go:154: 
...
            	06:14:43.841084 http.c:756              == Info: After 84469ms connect time, move on!
            	06:14:43.841131 http.c:756              == Info: connect to 185.125.188.113 port 443 failed: Connection timed out
            	06:14:43.841156 http.c:756              == Info:   Trying 185.125.188.128:443...
            	06:15:26.084536 http.c:756              == Info: After 42202ms connect time, move on!
            	06:15:26.084579 http.c:756              == Info: connect to 185.125.188.128 port 443 failed: Connection timed out
            	06:15:26.084614 http.c:756              == Info: Failed to connect to gopkg.in port 443: Connection timed out
            	�
            	06:15:26.084626 http.c:756              == Info: Closing connection 0
            	fatal: unable to access 'https://gopkg.in/src-d/go-billy.v4/': Failed to connect to gopkg.in port 443: Connection timed out
        script_test.go:154: FAIL: mod_replace_gopkgin.txt:20: go list -m gopkg.in/src-d/go-git.v4: exit status 1
2022-11-14 17:37 linux-386-longtest go@a650e399 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:43971
https://vcs-test.golang.org rerouted to https://127.0.0.1:40327
go test proxy running at GOPROXY=http://127.0.0.1:46765/mod
2022/11/14 18:11:54 http: TLS handshake error from 127.0.0.1:42294: EOF
2022/11/14 18:11:54 http: TLS handshake error from 127.0.0.1:42286: EOF
2022/11/14 18:11:54 http: TLS handshake error from 127.0.0.1:42346: EOF
2022/11/14 18:11:54 http: TLS handshake error from 127.0.0.1:42356: EOF
2022/11/14 18:11:54 http: TLS handshake error from 127.0.0.1:42388: EOF
2022/11/14 18:11:54 http: TLS handshake error from 127.0.0.1:42372: read tcp 127.0.0.1:40327->127.0.0.1:42372: read: connection reset by peer
2022/11/14 18:11:55 http: TLS handshake error from 127.0.0.1:42464: EOF
...
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/mod_replace_gopkgin (34.44s)
        script_test.go:134: 2022-11-14T18:11:26Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-2719307603/tmpdir2032878856/mod_replace_gopkgin1885781532
        script_test.go:154: 
            # Regression test for golang.org/issue/34254:
            # a clone of gopkg.in/[…].vN should be replaceable by
            # a fork hosted at corp.example.com/[…]/vN,
            # even if there is an explicit go.mod file containing the
            # gopkg.in path. (0.000s)
            # Replacing gopkg.in/[…].vN with a repository with a root go.mod file
            # specifying […].vN and a compatible version should succeed, even if
            # the replacement path is not a gopkg.in path. (34.136s)
            > cd 4-to-4
            > go list -m gopkg.in/src-d/go-git.v4
            [stderr]
            go: gopkg.in/src-d/go-git.v4@v4.13.1 requires
            	gopkg.in/check.v1@v1.0.0-20180628173108-788fd7840127: unrecognized import path "gopkg.in/check.v1": https fetch: Get "https://gopkg.in/check.v1?go-get=1": dial tcp 185.125.188.128:443: i/o timeout
        script_test.go:154: FAIL: mod_replace_gopkgin.txt:20: go list -m gopkg.in/src-d/go-git.v4: exit status 1
2022-11-14 17:37 linux-amd64-longtest go@a650e399 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:38669
https://vcs-test.golang.org rerouted to https://127.0.0.1:34291
go test proxy running at GOPROXY=http://127.0.0.1:44025/mod
2022/11/14 18:04:36 http: TLS handshake error from 127.0.0.1:57426: EOF
2022/11/14 18:04:43 http: TLS handshake error from 127.0.0.1:37696: read tcp 127.0.0.1:34291->127.0.0.1:37696: read: connection reset by peer
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/mod_replace_gopkgin (225.76s)
        script_test.go:134: 2022-11-14T18:04:20Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-3074528927/tmpdir2164484381/mod_replace_gopkgin4100494167
        script_test.go:154: 
            # Regression test for golang.org/issue/34254:
            # a clone of gopkg.in/[…].vN should be replaceable by
            # a fork hosted at corp.example.com/[…]/vN,
            # even if there is an explicit go.mod file containing the
            # gopkg.in path. (0.000s)
            # Replacing gopkg.in/[…].vN with a repository with a root go.mod file
            # specifying […].vN and a compatible version should succeed, even if
            # the replacement path is not a gopkg.in path. (225.692s)
            > cd 4-to-4
            > go list -m gopkg.in/src-d/go-git.v4
            [stderr]
            go: gopkg.in/src-d/go-git.v4@v4.13.1 requires
            	gopkg.in/check.v1@v1.0.0-20180628173108-788fd7840127: unrecognized import path "gopkg.in/check.v1": https fetch: Get "https://gopkg.in/check.v1?go-get=1": dial tcp 185.125.188.128:443: i/o timeout
        script_test.go:154: FAIL: mod_replace_gopkgin.txt:20: go list -m gopkg.in/src-d/go-git.v4: exit status 1
2022-11-14 18:35 linux-amd64-longtest go@74b6a220 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:44011
https://vcs-test.golang.org rerouted to https://127.0.0.1:42077
go test proxy running at GOPROXY=http://127.0.0.1:37365/mod
2022/11/14 19:09:24 http: TLS handshake error from 127.0.0.1:39558: EOF
2022/11/14 19:09:24 http: TLS handshake error from 127.0.0.1:39594: EOF
2022/11/14 19:09:24 http: TLS handshake error from 127.0.0.1:39584: read tcp 127.0.0.1:42077->127.0.0.1:39584: read: connection reset by peer
2022/11/14 19:09:24 http: TLS handshake error from 127.0.0.1:39582: read tcp 127.0.0.1:42077->127.0.0.1:39582: read: connection reset by peer
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/get_dash_t (120.81s)
        script_test.go:134: 2022-11-14T19:09:55Z
...
            19:11:56.041921 http.c:715              <= Recv header: x-xss-protection: 0
            19:11:56.041924 http.c:703              <= Recv header, 0000000037 bytes (0x00000025)
            19:11:56.041926 http.c:715              <= Recv header: date: Mon, 14 Nov 2022 19:11:56 GMT
            19:11:56.041931 http.c:703              <= Recv header, 0000000002 bytes (0x00000002)
            19:11:56.041933 http.c:715              <= Recv header:
            19:11:56.041986 http.c:756              == Info: Connection #0 to host go.googlesource.com left intact
            error: RPC failed; HTTP 502 curl 22 The requested URL returned error: 502
            fatal: error reading section header 'shallow-info'
            package golang.org/x/build/gerrit: exit status 128
        script_test.go:154: FAIL: get_dash_t.txt:7: go get -v -t github.com/rsc/go-get-issue-8181/a github.com/rsc/go-get-issue-8181/b: exit status 1
2022-11-17 18:31 linux-amd64-longtest go@249e51e5 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:40311
https://vcs-test.golang.org rerouted to https://127.0.0.1:40423
go test proxy running at GOPROXY=http://127.0.0.1:34411/mod
2022/11/17 18:59:00 http: TLS handshake error from 127.0.0.1:40988: EOF
2022/11/17 18:59:00 http: TLS handshake error from 127.0.0.1:41002: EOF
2022/11/17 18:59:02 http: TLS handshake error from 127.0.0.1:48258: read tcp 127.0.0.1:40423->127.0.0.1:48258: read: connection reset by peer
2022/11/17 18:59:02 http: TLS handshake error from 127.0.0.1:48252: read tcp 127.0.0.1:40423->127.0.0.1:48252: read: connection reset by peer
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/get_404_meta (83.54s)
        script_test.go:134: 2022-11-17T18:59:31Z
...
            	19:00:53.397903 http.c:715              <= Recv header: date: Thu, 17 Nov 2022 19:00:53 GMT
            	19:00:53.397906 http.c:703              <= Recv header, 0000000091 bytes (0x0000005b)
            	19:00:53.397910 http.c:715              <= Recv header: cross-origin-opener-policy-report-only: same-origin; report-to="gfe-default_product_name"
            	19:00:53.397914 http.c:703              <= Recv header, 0000000153 bytes (0x00000099)
            	19:00:53.397918 http.c:715              <= Recv header: report-to: {"group":"gfe-default_product_name","max_age":2592000,"endpoints":[{"url":"https://csp.withgoogle.com/csp/report-to/default_product_name"}]}
            	19:00:53.397922 http.c:703              <= Recv header, 0000000002 bytes (0x00000002)
            	19:00:53.397924 http.c:715              <= Recv header:
            	19:00:53.397966 http.c:756              == Info: Connection #0 to host go.googlesource.com left intact
            	fatal: unable to access 'https://go.googlesource.com/sync/': The requested URL returned error: 502
        script_test.go:154: FAIL: get_404_meta.txt:12: go get bazil.org/fuse/fs/fstestutil: exit status 1
2022-12-01 19:24 linux-386-longtest go@e24380b6 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:36255
https://vcs-test.golang.org rerouted to https://127.0.0.1:40325
go test proxy running at GOPROXY=http://127.0.0.1:37143/mod
2022/12/01 19:59:22 http: TLS handshake error from 127.0.0.1:54892: EOF
2022/12/01 19:59:45 http: TLS handshake error from 127.0.0.1:36556: EOF
2022/12/01 19:59:45 http: TLS handshake error from 127.0.0.1:36582: EOF
2022/12/01 19:59:45 http: TLS handshake error from 127.0.0.1:36570: EOF
2022/12/01 19:59:45 http: TLS handshake error from 127.0.0.1:36590: EOF
2022/12/01 19:59:45 http: TLS handshake error from 127.0.0.1:36584: EOF
2022/12/01 19:59:45 http: TLS handshake error from 127.0.0.1:36592: EOF
...
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/version_buildvcs_git_gpg (0.03s)
        script_test.go:134: 2022-12-01T19:58:51Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-4159795061/tmpdir2340526604/version_buildvcs_git_gpg2465035849
        script_test.go:154: 
            # This test checks that VCS information is stamped into Go binaries even when
            # the current commit is signed and the use has configured git to display commit
            # signatures. (0.001s)
            # Create GPG key (0.026s)
            > exec gpg --batch --passphrase '' --quick-generate-key --verbose gopher@golang.org
            [stderr]
            gpg: keybox '/workdir/tmp/cmd-go-test-4159795061/tmpdir2340526604/version_buildvcs_git_gpg2465035849/.gpupg/pubring.kbx' created
            gpg: no running gpg-agent - starting '/usr/bin/gpg-agent'
            gpg: error running '/usr/bin/gpg-agent': exit status 2
            gpg: failed to start agent '/usr/bin/gpg-agent': General error
            gpg: can't connect to the agent: General error
            gpg: agent_genkey failed: No agent running
            gpg: key generation failed: No agent running
        script_test.go:154: FAIL: version_buildvcs_git_gpg.txt:14: exec gpg --batch --passphrase  --quick-generate-key --verbose gopher@golang.org: exit status 2
2022-12-01 19:24 linux-amd64-longtest go@e24380b6 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:43775
https://vcs-test.golang.org rerouted to https://127.0.0.1:42849
go test proxy running at GOPROXY=http://127.0.0.1:44291/mod
2022/12/01 19:52:17 http: TLS handshake error from 127.0.0.1:57342: EOF
2022/12/01 19:52:17 http: TLS handshake error from 127.0.0.1:57320: EOF
2022/12/01 19:52:17 http: TLS handshake error from 127.0.0.1:57356: EOF
2022/12/01 19:52:17 http: TLS handshake error from 127.0.0.1:57384: EOF
2022/12/01 19:52:17 http: TLS handshake error from 127.0.0.1:57374: EOF
2022/12/01 19:52:17 http: TLS handshake error from 127.0.0.1:57418: read tcp 127.0.0.1:42849->127.0.0.1:57418: read: connection reset by peer
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/version_buildvcs_git_gpg (0.03s)
        script_test.go:134: 2022-12-01T19:51:21Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-2135136982/tmpdir1740308461/version_buildvcs_git_gpg3145242155
        script_test.go:154: 
            # This test checks that VCS information is stamped into Go binaries even when
            # the current commit is signed and the use has configured git to display commit
            # signatures. (0.000s)
            # Create GPG key (0.031s)
            > exec gpg --batch --passphrase '' --quick-generate-key --verbose gopher@golang.org
            [stderr]
            gpg: keybox '/workdir/tmp/cmd-go-test-2135136982/tmpdir1740308461/version_buildvcs_git_gpg3145242155/.gpupg/pubring.kbx' created
            gpg: no running gpg-agent - starting '/usr/bin/gpg-agent'
            gpg: error running '/usr/bin/gpg-agent': exit status 2
            gpg: failed to start agent '/usr/bin/gpg-agent': General error
            gpg: can't connect to the agent: General error
            gpg: agent_genkey failed: No agent running
            gpg: key generation failed: No agent running
        script_test.go:154: FAIL: version_buildvcs_git_gpg.txt:14: exec gpg --batch --passphrase  --quick-generate-key --verbose gopher@golang.org: exit status 2
2022-12-01 19:24 linux-amd64-longtest-race go@e24380b6 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:38509
https://vcs-test.golang.org rerouted to https://127.0.0.1:38397
go test proxy running at GOPROXY=http://127.0.0.1:43657/mod
2022/12/01 20:21:26 http: TLS handshake error from 127.0.0.1:38386: EOF
2022/12/01 20:23:15 http: TLS handshake error from 127.0.0.1:49126: EOF
--- FAIL: TestScript (0.17s)
    --- FAIL: TestScript/version_buildvcs_git_gpg (0.02s)
        script_test.go:134: 2022-12-01T20:21:48Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-2738129303/tmpdir570846408/version_buildvcs_git_gpg1679299920
        script_test.go:154: 
            # This test checks that VCS information is stamped into Go binaries even when
            # the current commit is signed and the use has configured git to display commit
            # signatures. (0.001s)
            # Create GPG key (0.018s)
            > exec gpg --batch --passphrase '' --quick-generate-key --verbose gopher@golang.org
            [stderr]
            gpg: keybox '/workdir/tmp/cmd-go-test-2738129303/tmpdir570846408/version_buildvcs_git_gpg1679299920/.gpupg/pubring.kbx' created
            gpg: no running gpg-agent - starting '/usr/bin/gpg-agent'
            gpg: error running '/usr/bin/gpg-agent': exit status 2
            gpg: failed to start agent '/usr/bin/gpg-agent': General error
            gpg: can't connect to the agent: General error
            gpg: agent_genkey failed: No agent running
            gpg: key generation failed: No agent running
        script_test.go:154: FAIL: version_buildvcs_git_gpg.txt:14: exec gpg --batch --passphrase  --quick-generate-key --verbose gopher@golang.org: exit status 2
2022-12-01 19:48 linux-386-longtest go@8fd2875c cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:43819
https://vcs-test.golang.org rerouted to https://127.0.0.1:39777
go test proxy running at GOPROXY=http://127.0.0.1:45511/mod
2022/12/01 20:25:34 http: TLS handshake error from 127.0.0.1:45182: read tcp 127.0.0.1:39777->127.0.0.1:45182: read: connection reset by peer
2022/12/01 20:25:34 http: TLS handshake error from 127.0.0.1:45194: EOF
2022/12/01 20:25:34 http: TLS handshake error from 127.0.0.1:45184: EOF
2022/12/01 20:25:34 http: TLS handshake error from 127.0.0.1:45196: EOF
2022/12/01 20:25:35 http: TLS handshake error from 127.0.0.1:45222: EOF
2022/12/01 20:25:35 http: TLS handshake error from 127.0.0.1:45238: EOF
2022/12/01 20:25:36 http: TLS handshake error from 127.0.0.1:45270: read tcp 127.0.0.1:39777->127.0.0.1:45270: read: connection reset by peer
...
--- FAIL: TestScript (0.09s)
    --- FAIL: TestScript/version_buildvcs_git_gpg (0.03s)
        script_test.go:134: 2022-12-01T20:26:11Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-1166463358/tmpdir2550198611/version_buildvcs_git_gpg3687303389
        script_test.go:154: 
            # This test checks that VCS information is stamped into Go binaries even when
            # the current commit is signed and the use has configured git to display commit
            # signatures. (0.000s)
            # Create GPG key (0.016s)
            > exec gpg --batch --passphrase '' --quick-generate-key --verbose gopher@golang.org
            [stderr]
            gpg: keybox '/workdir/tmp/cmd-go-test-1166463358/tmpdir2550198611/version_buildvcs_git_gpg3687303389/.gpupg/pubring.kbx' created
            gpg: no running gpg-agent - starting '/usr/bin/gpg-agent'
            gpg: error running '/usr/bin/gpg-agent': exit status 2
            gpg: failed to start agent '/usr/bin/gpg-agent': General error
            gpg: can't connect to the agent: General error
            gpg: agent_genkey failed: No agent running
            gpg: key generation failed: No agent running
        script_test.go:154: FAIL: version_buildvcs_git_gpg.txt:14: exec gpg --batch --passphrase  --quick-generate-key --verbose gopher@golang.org: exit status 2
2022-12-01 19:48 linux-amd64-longtest-race go@8fd2875c cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:42359
https://vcs-test.golang.org rerouted to https://127.0.0.1:33351
go test proxy running at GOPROXY=http://127.0.0.1:43593/mod
2022/12/01 20:44:56 http: TLS handshake error from 127.0.0.1:39628: EOF
2022/12/01 20:45:28 http: TLS handshake error from 127.0.0.1:57604: EOF
2022/12/01 20:45:28 http: TLS handshake error from 127.0.0.1:57676: read tcp 127.0.0.1:33351->127.0.0.1:57676: read: connection reset by peer
2022/12/01 20:45:36 http: TLS handshake error from 127.0.0.1:52274: EOF
2022/12/01 20:45:36 http: TLS handshake error from 127.0.0.1:52288: EOF
--- FAIL: TestScript (0.16s)
    --- FAIL: TestScript/version_buildvcs_git_gpg (0.03s)
        script_test.go:134: 2022-12-01T20:43:39Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-1873116863/tmpdir1791269166/version_buildvcs_git_gpg1680741642
        script_test.go:154: 
            # This test checks that VCS information is stamped into Go binaries even when
            # the current commit is signed and the use has configured git to display commit
            # signatures. (0.000s)
            # Create GPG key (0.028s)
            > exec gpg --batch --passphrase '' --quick-generate-key --verbose gopher@golang.org
            [stderr]
            gpg: keybox '/workdir/tmp/cmd-go-test-1873116863/tmpdir1791269166/version_buildvcs_git_gpg1680741642/.gpupg/pubring.kbx' created
            gpg: no running gpg-agent - starting '/usr/bin/gpg-agent'
            gpg: error running '/usr/bin/gpg-agent': exit status 2
            gpg: failed to start agent '/usr/bin/gpg-agent': General error
            gpg: can't connect to the agent: General error
            gpg: agent_genkey failed: No agent running
            gpg: key generation failed: No agent running
        script_test.go:154: FAIL: version_buildvcs_git_gpg.txt:14: exec gpg --batch --passphrase  --quick-generate-key --verbose gopher@golang.org: exit status 2
2022-12-06 19:00 darwin-amd64-race go@7013a4f5 cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:53047/mod
--- FAIL: TestScript (0.21s)
    --- FAIL: TestScript/test_shuffle (53.41s)
        script_test.go:259: 
            # Shuffle order of tests and benchmarks
            # Run tests (41.864s)
            # Run tests and benchmarks (11.527s)
            > go test -v -bench=. foo_test.go
            [stdout]
            === RUN   TestOne
...
panic: test timed out after 9m0s

goroutine 12976 [running]:
panic({0x198ebc0, 0xc00005c000})
	/tmp/buildlet/go/src/runtime/panic.go:941 +0x397 fp=0xc000708f88 sp=0xc000708ec8 pc=0x1078317
testing.(*M).startAlarm.func1()
	/tmp/buildlet/go/src/testing/testing.go:2029 +0xbb fp=0xc000708fe0 sp=0xc000708f88 pc=0x11c25fb
runtime.goexit()
	/tmp/buildlet/go/src/runtime/asm_amd64.s:1571 +0x1 fp=0xc000708fe8 sp=0xc000708fe0 pc=0x10ade01
created by time.goFunc
	/tmp/buildlet/go/src/time/sleep.go:176 +0x48

goroutine 106 [running]:
	goroutine running on other thread; stack unavailable
created by runtime.gcBgMarkStartWorkers
	/tmp/buildlet/go/src/runtime/mgc.go:1131 +0x25
2022-12-09 21:38 netbsd-386-9_3 go@e8f78cb6 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:53798
https://vcs-test.golang.org rerouted to https://127.0.0.1:53797
go test proxy running at GOPROXY=http://127.0.0.1:53796/mod
panic: test timed out after 9m0s
running tests:
	TestScript (9m0s)
	TestScript/mod_get_upgrade (8m39s)

syscall.Syscall6(0x1, 0x704, 0x9907b1c, 0x10020, 0x0, 0x0, 0x0)
	/tmp/workdir/go/src/syscall/asm_unix_386.s:43 +0x5 fp=0x9907af4 sp=0x9907af0 pc=0x80c53d5
...
	/tmp/workdir/go/src/cmd/go/internal/script/engine.go:590 +0x2cc fp=0x9907c48 sp=0x9907bd0 pc=0x863437c
cmd/go/internal/script.(*Engine).Execute(0x9365020, 0x910abd0, {0x93d1870, 0x13}, 0x9907e54, {0x885913c, 0x95d0170})
	/tmp/workdir/go/src/cmd/go/internal/script/engine.go:292 +0x9bb fp=0x9907db0 sp=0x9907c48 pc=0x8631d4b
cmd/go/internal/script/scripttest.Run.func1({0x885d76c, 0x9559b30}, 0x910abd0, 0x9365020, {0x93d1870, 0x13}, {0x88583cc, 0x955a120})
	/tmp/workdir/go/src/cmd/go/internal/script/scripttest/scripttest.go:89 +0x4fd fp=0x9907e88 sp=0x9907db0 pc=0x86473dd
cmd/go/internal/script/scripttest.Run({0x885d76c, 0x9559b30}, 0x9365020, 0x910abd0, {0x93d1870, 0x13}, {0x88583cc, 0x955a120})
	/tmp/workdir/go/src/cmd/go/internal/script/scripttest/scripttest.go:90 +0x67 fp=0x9907ecc sp=0x9907e88 pc=0x8646cf7
cmd/go_test.TestScript.func3(0x9559b30)
	/tmp/workdir/go/src/cmd/go/script_test.go:154 +0x66c fp=0x9907f9c sp=0x9907ecc pc=0x8664e5c
testing.tRunner(0x9559b30, 0x94fb7a0)

watchflakes

@bcmills
Copy link
Contributor

bcmills commented Jan 9, 2023

The last two failures are interesting. I will follow up on them separately.

@bcmills bcmills closed this as not planned Won't fix, can't repro, duplicate, stale Jan 9, 2023
@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-05-31 16:25 darwin-amd64-race go@30f8d5b6 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:54297
https://vcs-test.golang.org rerouted to https://127.0.0.1:54298
go test proxy running at GOPROXY=http://127.0.0.1:54299/mod
--- FAIL: TestScript (1.34s)
    --- FAIL: TestScript/goroot_executable (0.20s)
        script_test.go:134: 2023-05-31T19:05:32Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-1984852397/tmpdir4269609969/goroot_executable1550204641
        script_test.go:158: 
        script_test.go:158: FAIL: testdata/script/goroot_executable.txt:0: context deadline exceeded
    --- FAIL: TestScript/list_ambiguous_path (4.69s)
...
    --- FAIL: TestScript/build_gcflags_order (1.96s)
        script_test.go:134: 2023-05-31T19:05:30Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-1984852397/tmpdir4269609969/build_gcflags_order2023995028
        script_test.go:158: 
            # Tests golang.org/issue/47682
            # Flags specified with -gcflags should appear after other flags generated by cmd/go. (1.874s)
            > cd m
            > go build -n -gcflags=-lang=go1.17
            [stderr]

...
    --- FAIL: TestScript/build_arm (0.06s)
        script_test.go:134: 2023-05-31T19:05:33Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-1984852397/tmpdir4269609969/build_arm3914672212
        script_test.go:158: 
        script_test.go:158: FAIL: testdata/script/build_arm.txt:0: context deadline exceeded
    --- FAIL: TestScript/badgo (0.05s)
        script_test.go:134: 2023-05-31T19:05:33Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-1984852397/tmpdir4269609969/badgo2302897337
        script_test.go:158: 
        script_test.go:158: FAIL: testdata/script/badgo.txt:0: context deadline exceeded

watchflakes

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-05-31 21:29 darwin-amd64-race go@0b9348fc cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:55328
https://vcs-test.golang.org rerouted to https://127.0.0.1:55329
go test proxy running at GOPROXY=http://127.0.0.1:55330/mod
--- FAIL: TestScript (1.31s)
    --- FAIL: TestScript/gotoolchain_local (71.02s)
        script_test.go:134: 2023-05-31T22:23:24Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-3991259240/tmpdir829840289/gotoolchain_local882804609
        script_test.go:158: 
            # This test uses the fake toolchain switch support in cmd/go/internal/toolchain.Switch
            # to exercise all the version selection logic without needing actual toolchains.
...
            r12    0xc0000a5708
            r13    0x21c9240
            r14    0x0
            r15    0x7ff7bfeff5e0
            rip    0x7ff809a022be
            rflags 0x247
            cs     0x7
            fs     0x0
            gs     0x0
        script_test.go:158: FAIL: testdata/script/gotoolchain_local.txt:186: go version: exit status 2
2023-05-31 21:57 darwin-amd64-race go@fdd9911d cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:54891
https://vcs-test.golang.org rerouted to https://127.0.0.1:54892
go test proxy running at GOPROXY=http://127.0.0.1:54893/mod
--- FAIL: TestScript (1.46s)
    --- FAIL: TestScript/mod_update_sum_readonly (4.76s)
        script_test.go:134: 2023-06-01T01:48:29Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-4175414613/tmpdir2095561834/mod_update_sum_readonly78060812
        script_test.go:158: 
            # When finding the latest version of a module, we should not download version
            # contents. Previously, we downloaded .zip files to determine whether a real
...
    --- FAIL: TestScript/build_cache_disabled (5.77s)
        script_test.go:134: 2023-06-01T01:48:28Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-4175414613/tmpdir2095561834/build_cache_disabled2889250361
        script_test.go:158: 
            # The build cache is required to build anything. It also may be needed to
            # initialize the build system, which is needed for commands like 'go env'.
            # However, there are lots of commands the cache is not needed for, and we
            # shouldn't require it when it won't be used.
            #
            # TODO(golang.org/issue/39882): commands below should work, too.
...
            r12    0x16
            r13    0x0
            r14    0x440100004500
            r15    0x2f00
            rip    0x7ff81bbd73ea
            rflags 0x247
            cs     0x7
            fs     0x0
            gs     0x0
        script_test.go:158: FAIL: testdata/script/build_GOTMPDIR.txt:9: go build -x hello.go: exit status 2
2023-05-31 22:48 darwin-amd64-race go@1079a5c0 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:53788
https://vcs-test.golang.org rerouted to https://127.0.0.1:53789
go test proxy running at GOPROXY=http://127.0.0.1:53790/mod
--- FAIL: TestScript (1.31s)
    --- FAIL: TestScript/old_tidy_toolchain (6.46s)
        script_test.go:134: 2023-05-31T23:40:01Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-2314541232/tmpdir3704353699/old_tidy_toolchain3422106904
        script_test.go:158: 
            # Commands in an old module with no go line and no toolchain line,
            # or with only a go line, should succeed.
...
    --- FAIL: TestScript/mod_init_empty (0.88s)
        script_test.go:134: 2023-05-31T23:40:07Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-2314541232/tmpdir3704353699/mod_init_empty913740083
        script_test.go:158: 
            > env GO111MODULE=on
            > env GOPATH=$WORK${/}invalid-gopath
            > go list -m
            [stdout]
            example.com
            [stderr]
...
    --- FAIL: TestScript/mod_proxy_invalid (0.02s)
        script_test.go:134: 2023-05-31T23:40:08Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-2314541232/tmpdir3704353699/mod_proxy_invalid1527414002
        script_test.go:158: 
        script_test.go:158: FAIL: testdata/script/mod_proxy_invalid.txt:0: context deadline exceeded
    --- FAIL: TestScript/mod_sum_replaced (0.02s)
        script_test.go:134: 2023-05-31T23:40:08Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-2314541232/tmpdir3704353699/mod_sum_replaced4108862055
        script_test.go:158: 
        script_test.go:158: FAIL: testdata/script/mod_sum_replaced.txt:0: context deadline exceeded

watchflakes

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-05-31 19:08 darwin-amd64-race go@481665ec cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:54577
https://vcs-test.golang.org rerouted to https://127.0.0.1:54578
go test proxy running at GOPROXY=http://127.0.0.1:54579/mod
--- FAIL: TestScript (1.37s)
    --- FAIL: TestScript/gotoolchain_local (83.10s)
        script_test.go:134: 2023-06-01T11:24:39Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-2639580163/tmpdir503810164/gotoolchain_local961544854
        script_test.go:158: 
            # This test uses the fake toolchain switch support in cmd/go/internal/toolchain.Switch
            # to exercise all the version selection logic without needing actual toolchains.
...
            r12    0xc000141708
            r13    0x21c9240
            r14    0x0
            r15    0x7ff7bfeff5e0
            rip    0x7ff814e3f2be
            rflags 0x247
            cs     0x7
            fs     0x0
            gs     0x0
        script_test.go:158: FAIL: testdata/script/gotoolchain_local.txt:199: go version: exit status 2

watchflakes

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-05-31 15:33 darwin-amd64-race go@b71d4329 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:54448
https://vcs-test.golang.org rerouted to https://127.0.0.1:54449
go test proxy running at GOPROXY=http://127.0.0.1:54450/mod
--- FAIL: TestScript (1.32s)
    --- FAIL: TestScript/autocgo (1.29s)
        script_test.go:134: 2023-06-01T12:37:49Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-1448868090/tmpdir2748061331/autocgo1903365667
        script_test.go:158: 
            # Test automatic setting of CGO_ENABLED based on $CC and what's in $PATH. (0.000s)
            # Assume we're on a system that can enable cgo normally. (1.183s)
...
    --- FAIL: TestScript/mod_init_invalid_major (1.62s)
        script_test.go:134: 2023-06-01T12:37:49Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-1448868090/tmpdir2748061331/mod_init_invalid_major1672246316
        script_test.go:158: 
            > env GO111MODULE=on
            > env GOFLAGS=-mod=mod
            > ! go mod init example.com/user/repo/v0
            [stderr]
            go: invalid module path "example.com/user/repo/v0": major version suffixes must be in the form of /vN and are only allowed for v2 or later:
            	go mod init example.com/user/repo/v2
...
            r12    0x16
            r13    0x0
            r14    0x80100000900
            r15    0x800
            rip    0x7ff803c333ea
            rflags 0x247
            cs     0x7
            fs     0x0
            gs     0x0
        script_test.go:158: FAIL: testdata/script/build_GOTMPDIR.txt:9: go build -x hello.go: exit status 2

watchflakes

@bcmills
Copy link
Contributor

bcmills commented Jun 1, 2023

Looks like the darwin-amd64-race builder is just running too slowly. Nothing seems to be hung — it's just spending too much aggregate time in the parallel TestScript subtests.

@gopherbot
Copy link
Contributor Author

Change https://go.dev/cl/500015 mentions this issue: dashboard: set a GO_TEST_TIMEOUT_SCALE for darwin-amd64-race

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-06-01 18:30 darwin-amd64-race go@c2e0bf0a cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:54297
https://vcs-test.golang.org rerouted to https://127.0.0.1:54298
go test proxy running at GOPROXY=http://127.0.0.1:54299/mod
--- FAIL: TestScript (1.40s)
    --- FAIL: TestScript/mod_tidy_convergence_loop (20.78s)
        script_test.go:134: 2023-06-01T19:23:10Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-3989045930/tmpdir1341028222/mod_tidy_convergence_loop438095017
        script_test.go:158: 
            # This test demonstrates a simple case in which 'go mod tidy' may resolve a
            # missing package, only to remove that package when resolving its dependencies.
...
            r12    0x20ad92b
            r13    0x9
            r14    0x21c9f80
            r15    0x1
            rip    0x10aa9e5
            rflags 0x10202
            cs     0x2b
            fs     0x0
            gs     0x0
        script_test.go:158: FAIL: testdata/script/mod_tidy_convergence_loop.txt:150: go mod tidy -e: exit status 2

watchflakes

gopherbot pushed a commit to golang/build that referenced this issue Jun 1, 2023
For golang/go#55311.

Change-Id: Ic708e37a15bc4f4afc3e80fa3c3d544c421f70d3
Reviewed-on: https://go-review.googlesource.com/c/build/+/500015
Reviewed-by: Michael Knyszek <mknyszek@google.com>
Auto-Submit: Bryan Mills <bcmills@google.com>
TryBot-Result: Gopher Robot <gobot@golang.org>
Run-TryBot: Bryan Mills <bcmills@google.com>
@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-06-01 21:12 darwin-amd64-race go@459cca5c cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:53914
https://vcs-test.golang.org rerouted to https://127.0.0.1:53915
go test proxy running at GOPROXY=http://127.0.0.1:53916/mod
--- FAIL: TestScript (1.43s)
    --- FAIL: TestScript/gotoolchain_local (78.74s)
        script_test.go:134: 2023-06-01T21:55:45Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-2361753261/tmpdir925950850/gotoolchain_local2668578490
        script_test.go:158: 
            # This test uses the fake toolchain switch support in cmd/go/internal/toolchain.Switch
            # to exercise all the version selection logic without needing actual toolchains.
...
    --- FAIL: TestScript/work_init_path (0.05s)
        script_test.go:134: 2023-06-01T21:57:04Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-2361753261/tmpdir925950850/work_init_path1920241360
        script_test.go:158: 
        script_test.go:158: FAIL: testdata/script/work_init_path.txt:0: context deadline exceeded
    --- FAIL: TestScript/build_import_comment (5.67s)
        script_test.go:134: 2023-06-01T21:56:58Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-2361753261/tmpdir925950850/build_import_comment1140798218
        script_test.go:158: 
            # Test in GOPATH mode first. (0.000s)
...
    --- FAIL: TestScript/bug (0.11s)
        script_test.go:134: 2023-06-01T21:57:04Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-2361753261/tmpdir925950850/bug1264553030
        script_test.go:158: 
        script_test.go:158: FAIL: testdata/script/bug.txt:0: context deadline exceeded
    --- FAIL: TestScript/badgo (0.09s)
        script_test.go:134: 2023-06-01T21:57:04Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-2361753261/tmpdir925950850/badgo1349235223
        script_test.go:158: 
        script_test.go:158: FAIL: testdata/script/badgo.txt:0: context deadline exceeded

watchflakes

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-06-01 22:32 darwin-amd64-race go@7183e77c cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:55017
https://vcs-test.golang.org rerouted to https://127.0.0.1:55018
go test proxy running at GOPROXY=http://127.0.0.1:55019/mod
--- FAIL: TestScript (1.29s)
    --- FAIL: TestScript/test_race_install_cgo (4.72s)
        script_test.go:134: 2023-06-01T23:47:41Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-1610893047/tmpdir3780688752/test_race_install_cgo4160555547
        script_test.go:158: 
            # Tests Issue #10500 (4.550s)
            > [!race] skip
...
    --- FAIL: TestScript/mod_sumdb_golang (3.40s)
        script_test.go:134: 2023-06-01T23:47:43Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-1610893047/tmpdir3780688752/mod_sumdb_golang2870893587
        script_test.go:158: 
            # Test default GOPROXY and GOSUMDB (3.264s)
            > env GOPROXY=
            > env GOSUMDB=
            > go env GOPROXY
            [stdout]
            https://proxy.golang.org,direct
...
            r12    0xc000126db0
            r13    0x21c8240
            r14    0x111757e
            r15    0x10
            rip    0x10053f1
            rflags 0x10206
            cs     0x2b
            fs     0x0
            gs     0x0
        script_test.go:158: FAIL: testdata/script/build_GOTMPDIR.txt:9: go build -x hello.go: exit status 2

watchflakes

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-06-01 18:30 darwin-amd64-race go@563556cc cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:54001
https://vcs-test.golang.org rerouted to https://127.0.0.1:54002
go test proxy running at GOPROXY=http://127.0.0.1:54003/mod
--- FAIL: TestScript (1.37s)
    --- FAIL: TestScript/embed (19.06s)
        script_test.go:134: 2023-06-02T10:53:13Z
        script_test.go:136: $WORK=/tmp/buildlet/tmp/cmd-go-test-2172884436/tmpdir2261717149/embed443444201
        script_test.go:158: 
            # go list shows patterns and files (8.085s)
            # build embeds x.txt (2.020s)
...
            r12    0x70000bafa688
            r13    0x0
            r14    0x0
            r15    0x70000bafadd0
            rip    0x7ff81ada02be
            rflags 0x247
            cs     0x7
            fs     0x0
            gs     0x0
        script_test.go:158: FAIL: testdata/script/embed.txt:56: go build -x: exit status 2

watchflakes

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-06-06 17:46 linux-386-longtest go@7fe60b5d cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:41195/mod
--- FAIL: TestScript (0.01s)
    --- FAIL: TestScript/reuse_git (52.61s)
        script_test.go:271: 
            # (2023-06-06T18:14:41Z)
            # go mod download with the pseudo-version should invoke git but not have a TagSum or Ref. (9.594s)
            # go mod download vcstest/hello should invoke git, print origin info (0.621s)
            # pseudo-version again should not invoke git fetch (it has the version from the @latest query)
            # but still be careful not to include a TagSum or a Ref, especially not Ref set to HEAD,
            # which is easy to do when reusing the cached version from the @latest query. (0.018s)
...
    --- FAIL: TestScript/mod_get_major (401.93s)
        script_test.go:271: 
            # (2023-06-06T18:14:56Z)
            # golang.org/issue/34383: if a module path ends in a major-version suffix,
            # ensure that 'direct' mode can resolve the package to a module. (401.932s)
            > go get vcs-test.golang.org/git/v3pkg.git/v3@v3.0.0
            [stderr]
            go: downloading vcs-test.golang.org/git/v3pkg.git/v3 v3.0.0
            go: added vcs-test.golang.org/git/v3pkg.git/v3 v3.0.0
            > go list -m vcs-test.golang.org/git/v3pkg.git/v3
            [stdout]
            vcs-test.golang.org/git/v3pkg.git/v3 v3.0.0
            > stdout '^vcs-test.golang.org/git/v3pkg.git/v3 v3.0.0$'
            > go get vcs-test.golang.org/git/empty-v2-without-v1.git/v2@v2.0.0
            [stderr]
            go: vcs-test.golang.org/git/empty-v2-without-v1.git/v2@v2.0.0: invalid version: git ls-remote -q origin in $WORK/gopath/pkg/mod/cache/vcs/b33130d0163a055cc673528c56def6d79769cd8850cfaec1f2af480a21497de5: exit status 128:
            	fatal: unable to connect to vcs-test.golang.org:
            	vcs-test.golang.org[0: 34.110.184.62]: errno=Connection timed out
            [exit status 1]
            FAIL: testdata/script/mod_get_major.txt:16: unexpected command failure
2023-06-07 14:58 linux-arm64-longtest go@b52950f6 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:43155
https://vcs-test.golang.org rerouted to https://127.0.0.1:35379
go test proxy running at GOPROXY=http://127.0.0.1:35619/mod
2023/06/07 15:20:55 http: TLS handshake error from 127.0.0.1:45972: EOF
2023/06/07 15:20:55 http: TLS handshake error from 127.0.0.1:45968: EOF
2023/06/07 15:20:55 http: TLS handshake error from 127.0.0.1:46042: EOF
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/get_with_git_trace (130.05s)
        script_test.go:134: 2023-06-07T15:21:04Z
        script_test.go:136: $WORK=/tmp/workdir/tmp/cmd-go-test-1354750971/tmpdir2773573436/get_with_git_trace570553531
...
    --- FAIL: TestScript/get_non_pkg (131.63s)
        script_test.go:134: 2023-06-07T15:21:06Z
        script_test.go:136: $WORK=/tmp/workdir/tmp/cmd-go-test-1354750971/tmpdir2773573436/get_non_pkg2573463841
        script_test.go:158: 
            > [!net:golang.org] skip
            [condition not met]
            > [!git] skip
            [condition not met]
            > env GOBIN=$WORK/tmp/gobin
            > env GO111MODULE=off
...
            15:21:06.841176 http.c:756              == Info: Immediate connect fail for 2607:f8b0:4001:c32::52: Cannot assign requested address
            15:23:18.214898 http.c:756              == Info: connect to 64.233.182.82 port 443 failed: Connection timed out
            15:23:18.214950 http.c:756              == Info: Failed to connect to go.googlesource.com port 443: Connection timed out
            � fm��
            15:23:18.214965 http.c:756              == Info: Closing connection 0
            fatal: unable to access 'https://go.googlesource.com/tools/': Failed to connect to go.googlesource.com port 443: Connection timed out
            package golang.org/x/tools: exit status 128
            [exit status 1]
            > stderr 'golang.org/x/tools: no Go files'
        script_test.go:158: FAIL: testdata/script/get_non_pkg.txt:8: stderr 'golang.org/x/tools: no Go files': no match for `(?m)golang.org/x/tools: no Go files` in stderr

watchflakes

@bcmills
Copy link
Contributor

bcmills commented Jun 7, 2023

@bcmills bcmills closed this as not planned Won't fix, can't repro, duplicate, stale Jun 7, 2023
@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-06-07 18:23 linux-amd64-longtest-race go@7f26e9e5 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:34733
https://vcs-test.golang.org rerouted to https://127.0.0.1:35241
go test proxy running at GOPROXY=http://127.0.0.1:33875/mod
2023/06/07 19:23:13 http: TLS handshake error from 127.0.0.1:36156: EOF
2023/06/07 19:23:13 http: TLS handshake error from 127.0.0.1:36142: EOF
--- FAIL: TestScript (1.16s)
    --- FAIL: TestScript/mod_invalid_version (186.73s)
        script_test.go:134: 2023-06-07T19:23:53Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-1615386285/tmpdir143359191/mod_invalid_version747819738
        script_test.go:158: 
...
    --- FAIL: TestScript/get_insecure_update (135.76s)
        script_test.go:134: 2023-06-07T19:27:44Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-1615386285/tmpdir143359191/get_insecure_update2239131472
        script_test.go:158: 
            > [!net:github.com] skip
            [condition not met]
            > [!git] skip
            [condition not met]
            > env GO111MODULE=off
            # Clone the repo via HTTP manually. (0.666s)
...
            19:27:48.509536 http.c:756              == Info:   Trying 209.85.234.82:443...
            19:27:48.710357 http.c:756              == Info:   Trying 2607:f8b0:4001:c17::52:443...
            19:27:48.710426 http.c:756              == Info: Immediate connect fail for 2607:f8b0:4001:c17::52: Network is unreachable
            19:30:00.528736 http.c:756              == Info: connect to 209.85.234.82 port 443 failed: Connection timed out
            19:30:00.528837 http.c:756              == Info: Failed to connect to go.googlesource.com port 443: Connection timed out
            GES/libc.mo
            19:30:00.528860 http.c:756              == Info: Closing connection 0
            fatal: unable to access 'https://go.googlesource.com/example/': Failed to connect to go.googlesource.com port 443: Connection timed out
            package golang.org/x/example/stringutil: exit status 128
        script_test.go:158: FAIL: testdata/script/get_insecure_update.txt:14: go get -d -u -f github.com/golang/example/hello: exit status 1

watchflakes

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-06-08 16:47 linux-amd64-longtest go@cc99093d cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:38471
https://vcs-test.golang.org rerouted to https://127.0.0.1:34645
go test proxy running at GOPROXY=http://127.0.0.1:38201/mod
2023/06/08 17:14:11 http: TLS handshake error from 127.0.0.1:59160: read tcp 127.0.0.1:34645->127.0.0.1:59160: read: connection reset by peer
--- FAIL: TestScript (0.06s)
    --- FAIL: TestScript/mod_invalid_version (47.34s)
        script_test.go:134: 2023-06-08T17:14:01Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-3507492194/tmpdir1689896238/mod_invalid_version551686597
        script_test.go:158: 
            > [!net:golang.org] skip
...
            > go mod edit -require golang.org/x/text@v0.2.1-0.20171213102548-c4d099d611ac
            > cd outside
            > ! go list -m golang.org/x/text
            [stderr]
            go: unrecognized import path "golang.org/x/text": https fetch: Get "https://golang.org/x/text?go-get=1": dial tcp 142.250.136.141:443: i/o timeout
            go: example.com@v0.0.0 requires
            	golang.org/x/text@v0.2.1-0.20171213102548-c4d099d611ac: unrecognized import path "golang.org/x/text": https fetch: Get "https://golang.org/x/text?go-get=1": dial tcp 142.250.136.141:443: i/o timeout
            [exit status 1]
            > stderr 'go: example.com@v0.0.0 requires\n\tgolang.org/x/text@v0.2.1-0.20171213102548-c4d099d611ac: invalid pseudo-version: tag \(v0.2.0\) found on revision c4d099d611ac is already canonical, so should not be replaced with a pseudo-version derived from that tag'
        script_test.go:158: FAIL: testdata/script/mod_invalid_version.txt:166: stderr 'go: example.com@v0.0.0 requires\n\tgolang.org/x/text@v0.2.1-0.20171213102548-c4d099d611ac: invalid pseudo-version: tag \(v0.2.0\) found on revision c4d099d611ac is already canonical, so should not be replaced with a pseudo-version derived from that tag': no match for `(?m)go: example.com@v0.0.0 requires\n\tgolang.org/x/text@v0.2.1-0.20171213102548-c4d099d611ac: invalid pseudo-version: tag \(v0.2.0\) found on revision c4d099d611ac is already canonical, so should not be replaced with a pseudo-version derived from that tag` in stderr

watchflakes

@gopherbot gopherbot reopened this Jun 8, 2023
@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-06-08 16:47 linux-amd64-longtest-race go@cc99093d cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:37155
https://vcs-test.golang.org rerouted to https://127.0.0.1:34157
go test proxy running at GOPROXY=http://127.0.0.1:34615/mod
2023/06/08 17:48:24 http: TLS handshake error from 127.0.0.1:59676: EOF
2023/06/08 17:50:30 http: TLS handshake error from 127.0.0.1:53544: read tcp 127.0.0.1:34157->127.0.0.1:53544: read: connection reset by peer
2023/06/08 17:50:30 http: TLS handshake error from 127.0.0.1:53522: EOF
2023/06/08 17:50:42 http: TLS handshake error from 127.0.0.1:37768: EOF
--- FAIL: TestScript (1.17s)
    --- FAIL: TestScript/mod_pseudo_cache (129.98s)
        script_test.go:134: 2023-06-08T17:48:20Z
...
    --- FAIL: TestScript/get_insecure_custom_domain (129.34s)
        script_test.go:134: 2023-06-08T17:51:29Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-1679683365/tmpdir3335611373/get_insecure_custom_domain1786550409
        script_test.go:158: 
            > [!net:insecure.go-get-issue-15410.appspot.com] skip
            [condition not met]
            > [!git] skip
            [condition not met]
            > env GO111MODULE=off
            > ! go get -d insecure.go-get-issue-15410.appspot.com/pkg/p
...
            17:51:29.468573 http.c:756              == Info:   Trying 108.177.120.153:80...
            17:51:29.669122 http.c:756              == Info:   Trying 2607:f8b0:4001:c18::99:80...
            17:51:29.669186 http.c:756              == Info: Immediate connect fail for 2607:f8b0:4001:c18::99: Network is unreachable
            17:53:38.585268 http.c:756              == Info: connect to 108.177.120.153 port 80 failed: Connection timed out
            17:53:38.585330 http.c:756              == Info: Failed to connect to insecure.go-get-issue-15410.appspot.com port 80: Connection timed out
            L��
            17:53:38.585348 http.c:756              == Info: Closing connection 0
            fatal: unable to access 'http://insecure.go-get-issue-15410.appspot.com/': Failed to connect to insecure.go-get-issue-15410.appspot.com port 80: Connection timed out
            package insecure.go-get-issue-15410.appspot.com/pkg/p: exit status 128
        script_test.go:158: FAIL: testdata/script/get_insecure_custom_domain.txt:8: go get -d insecure.go-get-issue-15410.appspot.com/pkg/p: exit status 1

watchflakes

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-06-08 17:09 linux-amd64-longtest go@bff4b0ed cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:44683
https://vcs-test.golang.org rerouted to https://127.0.0.1:33719
go test proxy running at GOPROXY=http://127.0.0.1:37785/mod
2023/06/08 17:35:08 http: TLS handshake error from 127.0.0.1:60304: EOF
2023/06/08 17:35:08 http: TLS handshake error from 127.0.0.1:60366: EOF
--- FAIL: TestScript (0.05s)
    --- FAIL: TestScript/mod_invalid_version (130.88s)
        script_test.go:134: 2023-06-08T17:34:46Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-1144109719/tmpdir2990056829/mod_invalid_version465232989
        script_test.go:158: 
...
    --- FAIL: TestScript/get_dash_t (132.70s)
        script_test.go:134: 2023-06-08T17:36:14Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-1144109719/tmpdir2990056829/get_dash_t1214013351
        script_test.go:158: 
            # Tests issue 8181 (132.702s)
            > [!net:github.com] skip
            [condition not met]
            > [!git] skip
            [condition not met]
            > env GO111MODULE=off
...
            17:36:16.018078 http.c:756              == Info:   Trying 172.253.119.82:443...
            17:36:16.218569 http.c:756              == Info:   Trying 2607:f8b0:4001:c1f::52:443...
            17:36:16.218623 http.c:756              == Info: Immediate connect fail for 2607:f8b0:4001:c1f::52: Network is unreachable
            17:38:27.396006 http.c:756              == Info: connect to 172.253.119.82 port 443 failed: Connection timed out
            17:38:27.396058 http.c:756              == Info: Failed to connect to go.googlesource.com port 443: Connection timed out
            GES/libc.mo
            17:38:27.396077 http.c:756              == Info: Closing connection 0
            fatal: unable to access 'https://go.googlesource.com/build/': Failed to connect to go.googlesource.com port 443: Connection timed out
            package golang.org/x/build/gerrit: exit status 128
        script_test.go:158: FAIL: testdata/script/get_dash_t.txt:7: go get -v -t github.com/rsc/go-get-issue-8181/a github.com/rsc/go-get-issue-8181/b: exit status 1

watchflakes

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-06-08 19:25 linux-amd64-longtest-race go@7b325ba2 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:38599
https://vcs-test.golang.org rerouted to https://127.0.0.1:38685
go test proxy running at GOPROXY=http://127.0.0.1:43103/mod
2023/06/08 20:25:20 http: TLS handshake error from 127.0.0.1:36972: read tcp 127.0.0.1:38685->127.0.0.1:36972: read: connection reset by peer
2023/06/08 20:27:11 http: TLS handshake error from 127.0.0.1:54636: EOF
2023/06/08 20:27:11 http: TLS handshake error from 127.0.0.1:54634: EOF
2023/06/08 20:27:11 http: TLS handshake error from 127.0.0.1:54612: EOF
2023/06/08 20:27:26 http: TLS handshake error from 127.0.0.1:60828: EOF
--- FAIL: TestScript (1.17s)
    --- FAIL: TestScript/mod_invalid_version (203.05s)
...
            	20:29:58.713223 http.c:756              == Info:   Trying 2607:f8b0:4001:c18::52:443...
            	20:29:58.713367 http.c:756              == Info: Immediate connect fail for 2607:f8b0:4001:c18::52: Network is unreachable
            	20:32:09.393352 http.c:756              == Info: connect to 108.177.120.82 port 443 failed: Connection timed out
            	20:32:09.393420 http.c:756              == Info: Failed to connect to go.googlesource.com port 443: Connection timed out
            	GES/libc.mo
            	20:32:09.393454 http.c:756              == Info: Closing connection 0
            	fatal: unable to access 'https://go.googlesource.com/text/': Failed to connect to go.googlesource.com port 443: Connection timed out
            [exit status 1]
            > stderr 'golang.org/x/text@v0.1.1-0.20170915032832-14c0d48ead0c\+incompatible: invalid version: \+incompatible suffix not allowed: major version v0 is compatible'
        script_test.go:158: FAIL: testdata/script/mod_invalid_version.txt:179: stderr 'golang.org/x/text@v0.1.1-0.20170915032832-14c0d48ead0c\+incompatible: invalid version: \+incompatible suffix not allowed: major version v0 is compatible': no match for `(?m)golang.org/x/text@v0.1.1-0.20170915032832-14c0d48ead0c\+incompatible: invalid version: \+incompatible suffix not allowed: major version v0 is compatible` in stderr

watchflakes

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-06-09 00:17 linux-amd64-longtest go@bd00528d cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:33977
https://vcs-test.golang.org rerouted to https://127.0.0.1:39941
go test proxy running at GOPROXY=http://127.0.0.1:33685/mod
2023/06/09 00:48:28 http: TLS handshake error from 127.0.0.1:44488: EOF
2023/06/09 00:48:28 http: TLS handshake error from 127.0.0.1:44472: EOF
--- FAIL: TestScript (0.06s)
    --- FAIL: TestScript/mod_invalid_version (63.76s)
        script_test.go:134: 2023-06-09T00:47:24Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-2197074842/tmpdir3122568122/mod_invalid_version1203386909
        script_test.go:158: 
...
    --- FAIL: TestScript/get_dash_t (30.93s)
        script_test.go:134: 2023-06-09T00:49:00Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-2197074842/tmpdir3122568122/get_dash_t3480053876
        script_test.go:158: 
            # Tests issue 8181 (30.929s)
            > [!net:github.com] skip
            [condition not met]
            > [!git] skip
            [condition not met]
            > env GO111MODULE=off
            > go get -v -t github.com/rsc/go-get-issue-8181/a github.com/rsc/go-get-issue-8181/b
            [stderr]
            github.com/rsc/go-get-issue-8181 (download)
            package golang.org/x/build/gerrit: unrecognized import path "golang.org/x/build/gerrit": https fetch: Get "https://golang.org/x/build/gerrit?go-get=1": dial tcp 142.250.152.141:443: i/o timeout
        script_test.go:158: FAIL: testdata/script/get_dash_t.txt:7: go get -v -t github.com/rsc/go-get-issue-8181/a github.com/rsc/go-get-issue-8181/b: exit status 1

watchflakes

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-06-06 17:11 darwin-amd64-nocgo go@c160b49b cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:52254/mod
--- FAIL: TestScript (0.03s)
    --- FAIL: TestScript/build_cwd_newline (0.60s)
        script_test.go:271: 
            # (2023-06-07T12:31:12Z)
            # If the directory path containing a package to be built includes a newline,
            # the go command should refuse to even try to build the package. (0.435s)
            > env DIR=$WORK${/}${newline}'package main'${newline}'func main() { panic("uh-oh")'${newline}'/*'
            > mkdir $DIR
            > cd $DIR
...
            [stderr]
            package example: invalid package directory "$WORK/\npackage main\nfunc main() { panic(\"uh-oh\")\n/*"
            [exit status 1]
            > stderr 'package example: invalid package directory .*uh-oh'
            > ! go build -o $devnull main.go
            [stderr]
            go: no Go source files
            [exit status 1]
            > stderr 'package command-line-arguments: invalid package directory .*uh-oh'
            FAIL: testdata/script/build_cwd_newline.txt:20: no match for `(?m)package command-line-arguments: invalid package directory .*uh-oh` found in stderr
2023-06-06 17:46 darwin-amd64-nocgo go@7fe60b5d cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:51589/mod
--- FAIL: TestScript (0.05s)
    --- FAIL: TestScript/build_cwd_newline (0.39s)
        script_test.go:271: 
            # (2023-06-06T18:20:14Z)
            # If the directory path containing a package to be built includes a newline,
            # the go command should refuse to even try to build the package. (0.374s)
            > env DIR=$WORK${/}${newline}'package main'${newline}'func main() { panic("uh-oh")'${newline}'/*'
            > mkdir $DIR
            > cd $DIR
...
            [stderr]
            package example: invalid package directory "$WORK/\npackage main\nfunc main() { panic(\"uh-oh\")\n/*"
            [exit status 1]
            > stderr 'package example: invalid package directory .*uh-oh'
            > ! go build -o $devnull main.go
            [stderr]
            go: no Go source files
            [exit status 1]
            > stderr 'package command-line-arguments: invalid package directory .*uh-oh'
            FAIL: testdata/script/build_cwd_newline.txt:20: no match for `(?m)package command-line-arguments: invalid package directory .*uh-oh` found in stderr
2023-06-06 21:43 darwin-amd64-nocgo go@3ba9c890 cmd/go.TestScript (log)
go test proxy running at GOPROXY=http://127.0.0.1:51711/mod
--- FAIL: TestScript (0.04s)
    --- FAIL: TestScript/build_cwd_newline (0.83s)
        script_test.go:271: 
            # (2023-06-07T05:02:01Z)
            # If the directory path containing a package to be built includes a newline,
            # the go command should refuse to even try to build the package. (0.778s)
            > env DIR=$WORK${/}${newline}'package main'${newline}'func main() { panic("uh-oh")'${newline}'/*'
            > mkdir $DIR
            > cd $DIR
...
            [stderr]
            package example: invalid package directory "$WORK/\npackage main\nfunc main() { panic(\"uh-oh\")\n/*"
            [exit status 1]
            > stderr 'package example: invalid package directory .*uh-oh'
            > ! go build -o $devnull main.go
            [stderr]
            go: no Go source files
            [exit status 1]
            > stderr 'package command-line-arguments: invalid package directory .*uh-oh'
            FAIL: testdata/script/build_cwd_newline.txt:20: no match for `(?m)package command-line-arguments: invalid package directory .*uh-oh` found in stderr

watchflakes

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-06-15 15:16 linux-arm64-longtest go@b7e74678 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:43655
https://vcs-test.golang.org rerouted to https://127.0.0.1:41779
go test proxy running at GOPROXY=http://127.0.0.1:38555/mod
2023/06/15 15:39:32 http: TLS handshake error from 127.0.0.1:56876: read tcp 127.0.0.1:41779->127.0.0.1:56876: read: connection reset by peer
2023/06/15 15:39:32 http: TLS handshake error from 127.0.0.1:56880: EOF
2023/06/15 15:39:32 http: TLS handshake error from 127.0.0.1:56886: EOF
2023/06/15 15:39:32 http: TLS handshake error from 127.0.0.1:56882: EOF
2023/06/15 15:39:32 http: TLS handshake error from 127.0.0.1:56920: EOF
2023/06/15 15:39:32 http: TLS handshake error from 127.0.0.1:56932: EOF
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/mod_invalid_version (37.55s)
        script_test.go:132: 2023-06-15T15:39:15Z
        script_test.go:134: $WORK=/tmp/workdir/tmp/cmd-go-test-2619552180/tmpdir711461935/mod_invalid_version452710507
        script_test.go:156: 
            > [!net:golang.org] skip
            [condition not met]
            > [!git] skip
            [condition not met]
            > env GO111MODULE=on
            > env GOPROXY=direct
...
            	go mod tidy
            > cmpenv go.mod go.mod.want
            > [!net:google.golang.org] skip
            [condition not met]
            > [!net:cloud.google.com] skip
            [condition not met]
            > go mod download github.com/fishy/gcsbucket@v0.0.0-20180217031846-618d60fe84e0
            [stderr]
            go: github.com/fishy/gcsbucket@v0.0.0-20180217031846-618d60fe84e0: verifying go.mod: github.com/fishy/gcsbucket@v0.0.0-20180217031846-618d60fe84e0/go.mod: Get "https://sum.golang.org/lookup/github.com/fishy/gcsbucket@v0.0.0-20180217031846-618d60fe84e0": dial tcp 64.233.183.141:443: i/o timeout
        script_test.go:156: FAIL: testdata/script/mod_convert.txt:20: go mod download github.com/fishy/gcsbucket@v0.0.0-20180217031846-618d60fe84e0: exit status 1

watchflakes

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-06-16 20:01 linux-amd64-longtest-race go@60876717 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:40393
https://vcs-test.golang.org rerouted to https://127.0.0.1:34343
go test proxy running at GOPROXY=http://127.0.0.1:41281/mod
2023/06/16 21:14:58 http: TLS handshake error from 127.0.0.1:44120: EOF
2023/06/16 21:14:58 http: TLS handshake error from 127.0.0.1:44102: read tcp 127.0.0.1:34343->127.0.0.1:44102: read: connection reset by peer
2023/06/16 21:15:18 http: TLS handshake error from 127.0.0.1:51128: EOF
2023/06/16 21:15:18 http: TLS handshake error from 127.0.0.1:51088: EOF
--- FAIL: TestScript (1.15s)
    --- FAIL: TestScript/mod_get_hash (159.64s)
        script_test.go:132: 2023-06-16T21:14:52Z
...
            	21:14:52.858740 http.c:756              == Info: Couldn't find host go.googlesource.com in the .netrc file; using defaults
            	21:14:52.860212 http.c:756              == Info:   Trying 142.251.161.82:443...
            	21:14:53.060582 http.c:756              == Info:   Trying 2607:f8b0:4001:c5c::52:443...
            	21:14:53.060630 http.c:756              == Info: Immediate connect fail for 2607:f8b0:4001:c5c::52: Network is unreachable
            	21:17:02.267677 http.c:756              == Info: connect to 142.251.161.82 port 443 failed: Connection timed out
            	21:17:02.267723 http.c:756              == Info: Failed to connect to go.googlesource.com port 443: Connection timed out
            	GES/libc.mo
            	21:17:02.267743 http.c:756              == Info: Closing connection 0
            	fatal: unable to access 'https://go.googlesource.com/time/': Failed to connect to go.googlesource.com port 443: Connection timed out
        script_test.go:156: FAIL: testdata/script/mod_get_hash.txt:8: go list -m golang.org/x/time@8be79e1e0910c292df4e79c241bb7e8f7e725959: exit status 1

watchflakes

@gopherbot
Copy link
Contributor Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/go" && test == "TestScript" && builder != "illumos-amd64"
2023-06-17 19:02 linux-386-longtest go@261e2676 cmd/go.TestScript (log)
vcs-test.golang.org rerouted to http://127.0.0.1:34741
https://vcs-test.golang.org rerouted to https://127.0.0.1:33167
go test proxy running at GOPROXY=http://127.0.0.1:46017/mod
2023/06/17 19:36:28 http: TLS handshake error from 127.0.0.1:54364: EOF
2023/06/17 19:36:59 http: TLS handshake error from 127.0.0.1:40492: read tcp 127.0.0.1:33167->127.0.0.1:40492: read: connection reset by peer
2023/06/17 19:37:00 http: TLS handshake error from 127.0.0.1:40530: read tcp 127.0.0.1:33167->127.0.0.1:40530: read: connection reset by peer
2023/06/17 19:37:00 http: TLS handshake error from 127.0.0.1:40536: read tcp 127.0.0.1:33167->127.0.0.1:40536: read: connection reset by peer
2023/06/17 19:37:00 http: TLS handshake error from 127.0.0.1:40572: read tcp 127.0.0.1:33167->127.0.0.1:40572: read: connection reset by peer
2023/06/17 19:37:00 http: TLS handshake error from 127.0.0.1:40542: read tcp 127.0.0.1:33167->127.0.0.1:40542: read: connection reset by peer
2023/06/17 19:37:00 http: TLS handshake error from 127.0.0.1:40560: EOF
...
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/get_update (21.01s)
        script_test.go:132: 2023-06-17T19:35:26Z
        script_test.go:134: $WORK=/workdir/tmp/cmd-go-test-1939711100/tmpdir2905680494/get_update2244394697
        script_test.go:156: 
            # Tests Issue #9224
            # The recursive updating was trying to walk to
            # former dependencies, not current ones. (0.000s)
            # Rewind (3.445s)
            # Run get (17.560s)
...
            19:35:47.961919 http.c:715              <= Recv header: x-frame-options: DENY
            19:35:47.961923 http.c:703              <= Recv header, 0000000057 bytes (0x00000039)
            19:35:47.961926 http.c:715              <= Recv header: x-github-request-id: 3818:A8D9:8D00B83:8F11FC9:648E0B03
            19:35:47.961930 http.c:703              <= Recv header, 0000000002 bytes (0x00000002)
            19:35:47.961933 http.c:715              <= Recv header:
            19:35:47.961967 http.c:756              == Info: Connection #0 to host github.com left intact
            remote: Internal Server Error
            fatal: unable to access 'https://github.com/rsc/go-get-issue-9224-lib/': The requested URL returned error: 500
            package github.com/rsc/go-get-issue-9224-lib/liba: exit status 1
        script_test.go:156: FAIL: testdata/script/get_update.txt:16: go get -u github.com/rsc/go-get-issue-9224-cmd: exit status 1

watchflakes

@bcmills
Copy link
Contributor

bcmills commented Jun 20, 2023

Updated #55164.

I don't think this issue itself is pulling its weight as a catchall; I would rather have new issues filed for new failure modes, when we can then close as duplicates. I'm going to close this issue and remove it from the Test Flakes project.

@bcmills bcmills closed this as not planned Won't fix, can't repro, duplicate, stale Jun 20, 2023
@bcmills
Copy link
Contributor

bcmills commented Jun 20, 2023

Filed #60899 to catch new failures so that it can use default instead of post, since post tends to massively over-post. (Perhaps watchflakes should create new issues with default instead of post in the first place? 🤔)

@golang golang locked and limited conversation to collaborators Jun 19, 2024
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Labels
FrozenDueToAge NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one.
Projects
None yet
Development

No branches or pull requests

2 participants