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

Jump to bottom

x/build: git errors with gnutls_handshake() failed: Error in the pull function. #52820

Open
bcmills opened this issue May 10, 2022 · 13 comments
Open

x/build: git errors with gnutls_handshake() failed: Error in the pull function. #52820

bcmills opened this issue May 10, 2022 · 13 comments
Labels
Builders x/build issues (builders, bots, dashboards) NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one.
Milestone
Unplanned

Comments

@bcmills
Copy link
Member

bcmills commented May 10, 2022 •
edited 

#!watchflakes
post <- pkg ~ `^cmd/go` && `Error in the pull function`

            > go get -d insecure.go-get-issue-15410.appspot.com/pkg/p
            [stderr]
            # cd $WORK/gopath/src/insecure.go-get-issue-15410.appspot.com; git clone -- http://insecure.go-get-issue-15410.appspot.com/ $WORK/gopath/src/insecure.go-get-issue-15410.appspot.com/pkg
            Cloning into '$WORK/gopath/src/insecure.go-get-issue-15410.appspot.com/pkg'...
            fatal: unable to access 'http://insecure.go-get-issue-15410.appspot.com/': gnutls_handshake() failed: Error in the pull function.
            package insecure.go-get-issue-15410.appspot.com/pkg/p: exit status 128

greplogs -l -e 'gnutls_handshake() failed: Error in the pull function.'`
2022-05-09T20:57:58-3f43096/linux-386-longtest
2021-12-13T16:44:13-acc65b4/linux-amd64-longtest
2021-11-08T14:49:56-ab31dbc/linux-386-longtest
2021-10-26T15:20:53-1b2362b/linux-386-longtest
2021-10-23T16:18:15-12e4404/linux-386-longtest
2021-10-20T20:39:29-1dff8f0/linux-386-longtest
2021-10-20T05:28:45-b091189/linux-386-longtest
2021-10-20T05:28:45-b091189/linux-amd64-longtest
2021-10-19T18:56:08-07e5527/linux-386-longtest
2021-10-19T18:43:08-1d63052/linux-386-longtest
2021-10-19T18:18:37-9820602/linux-386-longtest
2021-10-11T22:34:49-d90f0b9/linux-386-longtest
2021-09-08T11:57:03-9295723/linux-386-longtest
2021-04-11T15:56:20-189c694/linux-amd64-longtest
2021-04-06T21:25:55-0bc4605/linux-386-longtest
2021-04-03T21:06:18-9e7bc80/linux-386-longtest
2020-10-29T16:26:43-25d28ec/linux-386-longtest
@gopherbot gopherbot added the Builders x/build issues (builders, bots, dashboards) label May 10, 2022
@gopherbot gopherbot added this to the Unreleased milestone May 10, 2022
@bcmills
Copy link
Member Author

bcmills commented May 10, 2022

I suspect that this indicates some kind of network error or internal server error, but it would be nice to get a better error message out of it.

Some cursory searching suggests:

  • Upgrade git. (linux-386-longtest currently has git version 2.30.2, whereas the current release is 2.36.1)
  • Upgrade libcurl3-gnutls (linux-386-longtest has 7.74.0, current is 7.83.0)
  • Upgrade libgnutls30(linux-386-longtest has 3.7.1-5, current is 3.7.4-2)

@bcmills bcmills modified the milestones: Unreleased, Backlog, Unplanned May 10, 2022
@heschi heschi added the NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one. label May 10, 2022
@bcmills
Copy link
Member Author

bcmills commented Jun 14, 2022

greplogs -l -e 'gnutls_handshake\(\) failed: Error in the pull function\.' --since=2022-05-10
2022-06-07T18:31:49-ef2567c/linux-amd64-longtest

This one contains more logging due to the inclusion of CL 409575:

go test proxy running at GOPROXY=http://127.0.0.1:33855/mod
--- FAIL: TestScript (0.01s)
    --- FAIL: TestScript/gopath_moved_repo (1.00s)
        script_test.go:272: 
            # (2022-06-07T18:54:31Z)
            # Test that 'go get -u' reports packages whose VCS configurations do not
            # match their import paths. (0.000s)
            # We need to execute a custom Go program to break the config files.
            #
            # git will ask for a username and password when we run 'go get -d -f -u',
            # so we also need to set GIT_ASKPASS. Conveniently, a single binary can
            # perform both tasks! (0.555s)
            # Test that 'go get -u' reports moved git packages. (0.439s)
            > [exec:git] go get -d rsc.io/pdf
            [stderr]
            # cd $WORK/gopath/src/rsc.io; git clone -- https://github.com/rsc/pdf $WORK/gopath/src/rsc.io/pdf
            Cloning into '$WORK/gopath/src/rsc.io/pdf'...
            18:54:31.678227 http.c:756              == Info: Couldn't find host github.com in the .netrc file; using defaults
            18:54:31.681371 http.c:756              == Info:   Trying 140.82.114.3:443...
            18:54:31.706505 http.c:756              == Info: Connected to github.com (140.82.114.3) port 443 (#0)
            18:54:31.790700 http.c:756              == Info: found 387 certificates in /etc/ssl/certs
            18:54:31.790893 http.c:756              == Info: ALPN, offering h2
            18:54:31.790909 http.c:756              == Info: ALPN, offering http/1.1
            18:54:31.818991 http.c:756              == Info: SSL connection using TLS1.3 / ECDHE_RSA_AES_128_GCM_SHA256
            18:54:31.821982 http.c:756              == Info: 	 server certificate verification OK
            18:54:31.821996 http.c:756              == Info: 	 server certificate status verification SKIPPED
            18:54:31.822153 http.c:756              == Info: 	 common name: github.com (matched)
            18:54:31.822162 http.c:756              == Info: 	 server certificate expiration date OK
            18:54:31.822167 http.c:756              == Info: 	 server certificate activation date OK
            18:54:31.822180 http.c:756              == Info: 	 certificate public key: EC/ECDSA
            18:54:31.822185 http.c:756              == Info: 	 certificate version: #3
            18:54:31.822225 http.c:756              == Info: 	 subject: C=US,ST=California,L=San Francisco,O=GitHub\, Inc.,CN=github.com
            18:54:31.822235 http.c:756              == Info: 	 start date: Tue, 15 Mar 2022 00:00:00 GMT
            18:54:31.822242 http.c:756              == Info: 	 expire date: Wed, 15 Mar 2023 23:59:59 GMT
            18:54:31.822264 http.c:756              == Info: 	 issuer: C=US,O=DigiCert Inc,CN=DigiCert TLS Hybrid ECC SHA384 2020 CA1
            18:54:31.822283 http.c:756              == Info: ALPN, server accepted to use h2
            18:54:31.822323 http.c:756              == Info: Using HTTP2, server supports multi-use
            18:54:31.822329 http.c:756              == Info: Connection state changed (HTTP/2 confirmed)
            18:54:31.822336 http.c:756              == Info: Copying HTTP/2 data in stream buffer to connection buffer after upgrade: len=0
            18:54:31.822429 http.c:756              == Info: Using Stream ID: 1 (easy handle 0x564ed7140430)
            18:54:31.822470 http.c:703              => Send header, 0000000191 bytes (0x000000bf)
            18:54:31.822479 http.c:715              => Send header: GET /rsc/pdf/info/refs?service=git-upload-pack HTTP/2
            18:54:31.822483 http.c:715              => Send header: Host: github.com
            18:54:31.822486 http.c:715              => Send header: user-agent: git/2.30.2
            18:54:31.822488 http.c:715              => Send header: accept: */*
            18:54:31.822493 http.c:715              => Send header: accept-encoding: deflate, gzip, br
            18:54:31.822496 http.c:715              => Send header: pragma: no-cache
            18:54:31.822499 http.c:715              => Send header: git-protocol: version=2
            18:54:31.822502 http.c:715              => Send header:
            18:54:31.847592 http.c:756              == Info: Connection state changed (MAX_CONCURRENT_STREAMS == 100)!
            18:54:31.870791 http.c:703              <= Recv header, 0000000013 bytes (0x0000000d)
            18:54:31.870817 http.c:715              <= Recv header: HTTP/2 200
            18:54:31.870848 http.c:703              <= Recv header, 0000000026 bytes (0x0000001a)
            18:54:31.870853 http.c:715              <= Recv header: server: GitHub Babel 2.0
            18:54:31.870859 http.c:703              <= Recv header, 0000000059 bytes (0x0000003b)
            18:54:31.870863 http.c:715              <= Recv header: content-type: application/x-git-upload-pack-advertisement
            18:54:31.870867 http.c:703              <= Recv header, 0000000054 bytes (0x00000036)
            18:54:31.870870 http.c:715              <= Recv header: content-security-policy: default-src 'none'; sandbox
            18:54:31.870874 http.c:703              <= Recv header, 0000000040 bytes (0x00000028)
            18:54:31.870880 http.c:715              <= Recv header: expires: Fri, 01 Jan 1980 00:00:00 GMT
            18:54:31.870891 http.c:703              <= Recv header, 0000000018 bytes (0x00000012)
            18:54:31.870894 http.c:715              <= Recv header: pragma: no-cache
            18:54:31.870898 http.c:703              <= Recv header, 0000000053 bytes (0x00000035)
            18:54:31.870901 http.c:715              <= Recv header: cache-control: no-cache, max-age=0, must-revalidate
            18:54:31.870905 http.c:703              <= Recv header, 0000000023 bytes (0x00000017)
            18:54:31.870909 http.c:715              <= Recv header: vary: Accept-Encoding
            18:54:31.870913 http.c:703              <= Recv header, 0000000052 bytes (0x00000034)
            18:54:31.870917 http.c:715              <= Recv header: x-github-request-id: B303:511E:EDA6:1892A:629F9EE7
            18:54:31.870921 http.c:703              <= Recv header, 0000000023 bytes (0x00000017)
            18:54:31.870924 http.c:715              <= Recv header: x-frame-options: DENY
            18:54:31.870929 http.c:703              <= Recv header, 0000000002 bytes (0x00000002)
            18:54:31.870932 http.c:715              <= Recv header:
            18:54:31.885018 http.c:756              == Info: Closing connection 0
            18:54:31.889791 http.c:756              == Info: Couldn't find host github.com in the .netrc file; using defaults
            18:54:31.889821 http.c:756              == Info: Hostname github.com was found in DNS cache
            18:54:31.889849 http.c:756              == Info:   Trying 140.82.114.3:443...
            18:54:31.915207 http.c:756              == Info: Connected to github.com (140.82.114.3) port 443 (#1)
            18:54:31.994652 http.c:756              == Info: found 387 certificates in /etc/ssl/certs
            18:54:31.994958 http.c:756              == Info: ALPN, offering h2
            18:54:31.995010 http.c:756              == Info: ALPN, offering http/1.1
            18:54:31.995038 http.c:756              == Info: SSL re-using session ID
            18:54:32.024572 http.c:756              == Info: gnutls_handshake() failed: Error in the pull function.
            18:54:32.024663 http.c:756              == Info: Closing connection 1
            error: RPC failed; curl 35 gnutls_handshake() failed: Error in the pull function.
            fatal: expected flush after ref listing
            package rsc.io/pdf: exit status 128
            [exit status 1]
            FAIL: testdata/script/gopath_moved_repo.txt:21: unexpected command failure
            
FAIL
FAIL	cmd/go	177.649s

@mknyszek mknyszek mentioned this issue Sep 7, 2022
Closed
@bcmills
Copy link
Member Author

bcmills commented Sep 14, 2022 

--- 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: converting glide.lock: stat github.com/rsc/legacytest@fb3c628075e32f7f3c248a3abbdafd69ad6e21e1: github.com/rsc/legacytest@fb3c628075e32f7f3c248a3abbdafd69ad6e21e1: invalid version: git fetch -f origin refs/heads/*:refs/heads/* refs/tags/*:refs/tags/* in $WORK/gopath/pkg/mod/cache/vcs/02e2b870e781600b12f669a2429e9f625455accbfc13989fc24e53973cfac1fb: exit status 128:
            	16:13:30.239149 http.c:756              == Info: Couldn't find host github.com in the .netrc file; using defaults
            	16:13:30.244830 http.c:756              == Info:   Trying 140.82.114.4:443...
            	16:13:30.269528 http.c:756              == Info: Connected to github.com (140.82.114.4) port 443 (#0)
            	16:13:30.350695 http.c:756              == Info: found 387 certificates in /etc/ssl/certs
            	16:13:30.350876 http.c:756              == Info: ALPN, offering h2
            	16:13:30.350916 http.c:756              == Info: ALPN, offering http/1.1
            	16:13:30.376080 http.c:756              == Info: gnutls_handshake() failed: Error in the pull function.
            	16:13:30.376104 http.c:756              == Info: Closing connection 0
            	fatal: unable to access 'https://github.com/rsc/legacytest/': gnutls_handshake() failed: Error in the pull function.
            go: copying requirements from glide.lock
            go: to add module requirements and sums:
            	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
            
FAIL
FAIL	cmd/go	168.673s

@bcmills bcmills mentioned this issue Sep 14, 2022
Closed
@bcmills bcmills mentioned this issue Sep 21, 2022
Open
@gopherbot
Copy link

Found new dashboard test flakes for:

#!watchflakes
post <- pkg ~ `^cmd/go` && `Error in the pull function`
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

@gopherbot
Copy link

Found new dashboard test flakes for:

#!watchflakes
post <- pkg ~ `^cmd/go` && `Error in the pull function`
2022-11-14 05:09 linux-amd64-longtest go@d092f597 cmd/go.TestScript (log) 
vcs-test.golang.org rerouted to http://127.0.0.1:43633
https://vcs-test.golang.org rerouted to https://127.0.0.1:34697
go test proxy running at GOPROXY=http://127.0.0.1:43841/mod
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/mod_replace_gopkgin (305.55s)
        script_test.go:134: 2022-11-14T05:38:57Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-871364483/tmpdir2555465245/mod_replace_gopkgin3850149785
        script_test.go:154: 
            # Regression test for golang.org/issue/34254:
            # a clone of gopkg.in/[…].vN should be replaceable by
...
            	05:39:01.371605 http.c:756              == Info: Couldn't find host gopkg.in in the .netrc file; using defaults
            	05:39:01.386732 http.c:756              == Info:   Trying 185.125.188.236:443...
            	05:39:51.954926 http.c:756              == Info: Connected to gopkg.in (185.125.188.236) port 443 (#0)
            	05:39:52.038300 http.c:756              == Info: found 387 certificates in /etc/ssl/certs
            	05:39:52.038505 http.c:756              == Info: ALPN, offering h2
            	05:39:52.038545 http.c:756              == Info: ALPN, offering http/1.1
            	05:40:44.387269 http.c:756              == Info: gnutls_handshake() failed: Error in the pull function.
            	05:40:44.387313 http.c:756              == Info: Closing connection 0
            	fatal: unable to access 'https://gopkg.in/check.v1/': gnutls_handshake() failed: Error in the pull function.
        script_test.go:154: FAIL: mod_replace_gopkgin.txt:20: go list -m gopkg.in/src-d/go-git.v4: exit status 1

— watchflakes

@gopherbot
Copy link

Found new dashboard test flakes for:

#!watchflakes
post <- pkg ~ `^cmd/go` && `Error in the pull function`
2022-12-08 15:23 linux-amd64-longtest-race go@0aad4d32 cmd/go.TestScript (log) 
vcs-test.golang.org rerouted to http://127.0.0.1:37129
https://vcs-test.golang.org rerouted to https://127.0.0.1:40939
go test proxy running at GOPROXY=http://127.0.0.1:33323/mod
2022/12/08 17:49:57 http: TLS handshake error from 127.0.0.1:37638: EOF
2022/12/08 17:49:57 http: TLS handshake error from 127.0.0.1:37658: EOF
--- FAIL: TestScript (0.16s)
    --- FAIL: TestScript/get_insecure (4.02s)
        script_test.go:134: 2022-12-08T17:51:48Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-2813403435/tmpdir3979662260/get_insecure1065438113
        script_test.go:154: 
...
            	17:51:52.831320 http.c:756              == Info: Couldn't find host github.com in the .netrc file; using defaults
            	17:51:52.845959 http.c:756              == Info:   Trying 140.82.112.3:443...
            	17:51:52.871373 http.c:756              == Info: Connected to github.com (140.82.112.3) port 443 (#1)
            	17:51:52.951347 http.c:756              == Info: found 387 certificates in /etc/ssl/certs
            	17:51:52.951530 http.c:756              == Info: ALPN, offering h2
            	17:51:52.951570 http.c:756              == Info: ALPN, offering http/1.1
            	17:51:52.977305 http.c:756              == Info: gnutls_handshake() failed: Error in the pull function.
            	17:51:52.977339 http.c:756              == Info: Closing connection 1
            	fatal: unable to access 'http://insecure.go-get-issue-15410.appspot.com/': gnutls_handshake() failed: Error in the pull function.
        script_test.go:154: FAIL: get_insecure.txt:37: go get -d insecure.go-get-issue-15410.appspot.com/pkg/p: exit status 1

— watchflakes

@gopherbot
Copy link

Found new dashboard test flakes for:

#!watchflakes
post <- pkg ~ `^cmd/go` && `Error in the pull function`
2022-11-08 18:44 linux-386-longtest go@39ac1fbd cmd/go.TestScript (log) 
go test proxy running at GOPROXY=http://127.0.0.1:37835/mod
--- FAIL: TestScript (0.01s)
    --- FAIL: TestScript/mod_convert (92.54s)
        script_test.go:270: 
            # (2022-11-08T19:39:43Z)
            > [short] skip
            > [!net] skip
            > [!exec:git] skip
            > env GO111MODULE=on
            > env GOPROXY=
...
    --- FAIL: TestScript/mod_gopkg_unstable (412.57s)
        script_test.go:270: 
            # (2022-11-08T19:39:34Z)
            > env GO111MODULE=on
            > cp go.mod.empty go.mod
            > go get gopkg.in/dummy.v2-unstable
            [stderr]
            go: downloading gopkg.in/dummy.v2-unstable v2.0.0
            go: added gopkg.in/dummy.v2-unstable v2.0.0
            > cp x.go.txt x.go
...
            	19:46:08.024628 http.c:703              <= Recv header, 0000000028 bytes (0x0000001c)
            	19:46:08.024631 http.c:715              <= Recv header: Transfer-Encoding: chunked
            	19:46:08.024634 http.c:703              <= Recv header, 0000000002 bytes (0x00000002)
            	19:46:08.024636 http.c:715              <= Recv header:
            	19:48:33.748341 http.c:756              == Info: GnuTLS recv error (-54): Error in the pull function.
            	19:48:33.748398 http.c:756              == Info: Closing connection 0
            	fatal: unable to access 'https://gopkg.in/src-d/go-git.v3/': GnuTLS recv error (-54): Error in the pull function.
            [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$'
            FAIL: testdata/script/mod_replace_gopkgin.txt:38: 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$` found in stderr
2022-11-08 21:26 linux-386-longtest go@9944ba75 cmd/go.TestScript (log) 
vcs-test.golang.org rerouted to http://127.0.0.1:38627
https://vcs-test.golang.org rerouted to https://127.0.0.1:46399
go test proxy running at GOPROXY=http://127.0.0.1:45519/mod
2022/11/08 22:02:22 http: TLS handshake error from 127.0.0.1:49044: EOF
2022/11/08 22:02:39 http: TLS handshake error from 127.0.0.1:43136: EOF
2022/11/08 22:02:39 http: TLS handshake error from 127.0.0.1:43138: EOF
2022/11/08 22:02:39 http: TLS handshake error from 127.0.0.1:43162: EOF
2022/11/08 22:02:39 http: TLS handshake error from 127.0.0.1:43178: EOF
2022/11/08 22:02:39 http: TLS handshake error from 127.0.0.1:43182: EOF
2022/11/08 22:02:39 http: TLS handshake error from 127.0.0.1:43220: EOF
...
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/mod_replace_gopkgin (311.34s)
        script_test.go:134: 2022-11-08T22:02:16Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-2718943204/tmpdir1910364977/mod_replace_gopkgin324273820
        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:02:23.578377 http.c:756              == Info: Couldn't find host gopkg.in in the .netrc file; using defaults
            	22:02:23.605517 http.c:756              == Info:   Trying 185.125.188.113:443...
            	22:02:55.333090 http.c:756              == Info: Connected to gopkg.in (185.125.188.113) port 443 (#0)
            	22:02:55.430159 http.c:756              == Info: found 387 certificates in /etc/ssl/certs
            	22:02:55.430338 http.c:756              == Info: ALPN, offering h2
            	22:02:55.430379 http.c:756              == Info: ALPN, offering http/1.1
            	22:04:36.976603 http.c:756              == Info: gnutls_handshake() failed: Error in the pull function.
            	22:04:36.976656 http.c:756              == Info: Closing connection 0
            	fatal: unable to access 'https://gopkg.in/src-d/go-billy.v4/': gnutls_handshake() failed: Error in the pull function.
        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-18 02:33 linux-amd64-longtest-race go@0613418c cmd/go.TestScript (log) 
vcs-test.golang.org rerouted to http://127.0.0.1:39007
https://vcs-test.golang.org rerouted to https://127.0.0.1:43651
go test proxy running at GOPROXY=http://127.0.0.1:43487/mod
2022/11/18 03:44:22 http: TLS handshake error from 127.0.0.1:47470: EOF
2022/11/18 03:44:48 http: TLS handshake error from 127.0.0.1:33710: EOF
2022/11/18 03:44:48 http: TLS handshake error from 127.0.0.1:33686: EOF
2022/11/18 03:44:48 http: TLS handshake error from 127.0.0.1:33720: read tcp 127.0.0.1:43651->127.0.0.1:33720: read: connection reset by peer
--- FAIL: TestScript (0.16s)
    --- FAIL: TestScript/mod_get_direct (2.43s)
        script_test.go:134: 2022-11-18T03:44:49Z
...
            	03:44:50.668169 http.c:756              == Info: Couldn't find host github.com in the .netrc file; using defaults
            	03:44:50.698395 http.c:756              == Info:   Trying 140.82.113.3:443...
            	03:44:50.722593 http.c:756              == Info: Connected to github.com (140.82.113.3) port 443 (#0)
            	03:44:50.809930 http.c:756              == Info: found 387 certificates in /etc/ssl/certs
            	03:44:50.810445 http.c:756              == Info: ALPN, offering h2
            	03:44:50.810513 http.c:756              == Info: ALPN, offering http/1.1
            	03:44:50.835082 http.c:756              == Info: gnutls_handshake() failed: Error in the pull function.
            	03:44:50.835119 http.c:756              == Info: Closing connection 0
            	fatal: unable to access 'https://github.com/googleapis/google-cloud-go/': gnutls_handshake() failed: Error in the pull function.
        script_test.go:154: FAIL: mod_get_direct.txt:13: go list -m cloud.google.com/go@main: exit status 1

— watchflakes

@gopherbot
Copy link

Found new dashboard test flakes for:

#!watchflakes
post <- pkg ~ `^cmd/go` && `Error in the pull function`
2023-02-01 22:45 linux-amd64-longtest-race go@40a09869 cmd/go.TestScript (log) 
vcs-test.golang.org rerouted to http://127.0.0.1:39243
https://vcs-test.golang.org rerouted to https://127.0.0.1:36809
go test proxy running at GOPROXY=http://127.0.0.1:39663/mod
2023/02/01 23:38:10 http: TLS handshake error from 127.0.0.1:60680: EOF
--- FAIL: TestScript (0.16s)
    --- FAIL: TestScript/get_update_wildcard (7.34s)
        script_test.go:134: 2023-02-01T23:39:03Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-1437974583/tmpdir2393210824/get_update_wildcard1316862860
        script_test.go:154: 
            # Issue 14450: go get -u .../ tried to import not downloaded package (7.341s)
...
            23:39:11.237159 http.c:756              == Info: found 387 certificates in /etc/ssl/certs
            23:39:11.237320 http.c:756              == Info: ALPN, offering h2
            23:39:11.237372 http.c:756              == Info: ALPN, offering http/1.1
            23:39:11.263149 http.c:756              == Info: gnutls_handshake() failed: Error in the pull function.
            23:39:11.263180 http.c:756              == Info: Closing connection 0
            fatal: unable to access 'https://github.com/tmwh/go-get-issue-14450-b-dependency/': gnutls_handshake() failed: Error in the pull function.
            package github.com/tmwh/go-get-issue-14450-b-dependency/c: exit status 128
            [exit status 1]
            > stderr 'cannot find package.*d-dependency/e'
        script_test.go:154: FAIL: get_update_wildcard.txt:9: stderr 'cannot find package.*d-dependency/e': no match for `(?m)cannot find package.*d-dependency/e` in stderr

— watchflakes

@gopherbot
Copy link

Found new dashboard test flakes for:

#!watchflakes
post <- pkg ~ `^cmd/go` && `Error in the pull function`
2023-02-23 18:57 linux-arm64-longtest go@ebe49f98 cmd/go.TestScript (log) 
vcs-test.golang.org rerouted to http://127.0.0.1:33375
https://vcs-test.golang.org rerouted to https://127.0.0.1:37939
go test proxy running at GOPROXY=http://127.0.0.1:37707/mod
2023/02/23 19:17:05 http: TLS handshake error from 127.0.0.1:58464: read tcp 127.0.0.1:37939->127.0.0.1:58464: read: connection reset by peer
2023/02/23 19:17:06 http: TLS handshake error from 127.0.0.1:58496: EOF
2023/02/23 19:18:30 http: TLS handshake error from 127.0.0.1:57422: EOF
--- FAIL: TestScript (0.06s)
    --- FAIL: TestScript/get_legacy (192.47s)
        script_test.go:134: 2023-02-23T19:17:51Z
        script_test.go:136: $WORK=/tmp/workdir/tmp/cmd-go-test-1234399840/tmpdir3390613298/get_legacy472400688
...
            cannot find package "github.com/myitcv/vgo_example_compat/v2/sub" in any of:
            	/tmp/workdir/go/src/github.com/myitcv/vgo_example_compat/v2/sub (from $GOROOT)
            	/tmp/workdir/tmp/cmd-go-test-1234399840/tmpdir3390613298/get_legacy472400688/tmp/d2/src/github.com/myitcv/vgo_example_compat/v2/sub (from $GOPATH)
            cannot find package "github.com/myitcv/vgo_example_compat" in any of:
            	/tmp/workdir/go/src/github.com/myitcv/vgo_example_compat (from $GOROOT)
            	/tmp/workdir/tmp/cmd-go-test-1234399840/tmpdir3390613298/get_legacy472400688/tmp/d2/src/github.com/myitcv/vgo_example_compat (from $GOPATH)
            cannot find package "github.com/myitcv/vgo_example_compat/sub" in any of:
            	/tmp/workdir/go/src/github.com/myitcv/vgo_example_compat/sub (from $GOROOT)
            	/tmp/workdir/tmp/cmd-go-test-1234399840/tmpdir3390613298/get_legacy472400688/tmp/d2/src/github.com/myitcv/vgo_example_compat/sub (from $GOPATH)
        script_test.go:154: FAIL: get_legacy.txt:35: go get github.com/rsc/vgotest5 github.com/rsc/vgotest4: exit status 1

— watchflakes

@gopherbot
Copy link

Found new dashboard test flakes for:

#!watchflakes
post <- pkg ~ `^cmd/go` && `Error in the pull function`
2023-04-05 21:43 linux-amd64-longtest-race go@84eaceab cmd/go.TestIssue11457 (log) 
vcs-test.golang.org rerouted to http://127.0.0.1:41981
https://vcs-test.golang.org rerouted to https://127.0.0.1:39413
go test proxy running at GOPROXY=http://127.0.0.1:37115/mod
2023/04/05 22:38:16 http: TLS handshake error from 127.0.0.1:41422: EOF
2023/04/05 22:38:46 http: TLS handshake error from 127.0.0.1:33460: EOF
2023/04/05 22:39:45 http: TLS handshake error from 127.0.0.1:34972: EOF
2023/04/05 22:39:46 http: TLS handshake error from 127.0.0.1:35032: EOF
--- FAIL: TestIssue11457 (182.45s)
    go_test.go:1023: running testgo [get -d -u rsc.io/go-get-issue-11457]
    go_test.go:1023: standard error:
    go_test.go:1023: # cd /workdir/tmp/cmd-go-test-1517402474/gotest230454960/src/rsc.io; git clone -- https://github.com/rsc/go-get-issue-11457 /workdir/tmp/cmd-go-test-1517402474/gotest230454960/src/rsc.io/go-get-issue-11457
        Cloning into '/workdir/tmp/cmd-go-test-1517402474/gotest230454960/src/rsc.io/go-get-issue-11457'...
        22:41:49.704217 http.c:756              == Info: Couldn't find host github.com in the .netrc file; using defaults
        22:41:49.712135 http.c:756              == Info:   Trying 140.82.114.3:443...
        22:41:49.739805 http.c:756              == Info: Connected to github.com (140.82.114.3) port 443 (#0)
        22:41:49.826296 http.c:756              == Info: found 387 certificates in /etc/ssl/certs
        22:41:49.826505 http.c:756              == Info: ALPN, offering h2
        22:41:49.826551 http.c:756              == Info: ALPN, offering http/1.1
        22:44:51.779065 http.c:756              == Info: gnutls_handshake() failed: Error in the pull function.
        22:44:51.779117 http.c:756              == Info: Closing connection 0
        fatal: unable to access 'https://github.com/rsc/go-get-issue-11457/': gnutls_handshake() failed: Error in the pull function.
        package rsc.io/go-get-issue-11457: exit status 128

    go_test.go:1023: go [get -d -u rsc.io/go-get-issue-11457] failed unexpectedly in /workdir/go/src/cmd/go: exit status 1

— watchflakes

@gopherbot
Copy link

Found new dashboard test flakes for:

#!watchflakes
post <- pkg ~ `^cmd/go` && `Error in the pull function`
2023-04-24 14:06 linux-amd64-longtest go@ee42d468 cmd/go.TestScript (log) 
vcs-test.golang.org rerouted to http://127.0.0.1:36253
https://vcs-test.golang.org rerouted to https://127.0.0.1:41325
go test proxy running at GOPROXY=http://127.0.0.1:36227/mod
2023/04/24 14:35:17 http: TLS handshake error from 127.0.0.1:36524: EOF
2023/04/24 14:35:19 http: TLS handshake error from 127.0.0.1:36572: EOF
2023/04/24 14:35:19 http: TLS handshake error from 127.0.0.1:36622: read tcp 127.0.0.1:41325->127.0.0.1:36622: read: connection reset by peer
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/get_insecure (184.68s)
        script_test.go:134: 2023-04-24T14:35:57Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-2853058243/tmpdir3851920162/get_insecure898377911
...
            14:35:58.990073 http.c:756              == Info:   Trying 140.82.113.4:443...
            14:35:59.015296 http.c:756              == Info: Connected to github.com (140.82.113.4) port 443 (#1)
            14:35:59.153826 http.c:756              == Info: found 387 certificates in /etc/ssl/certs
            14:35:59.154035 http.c:756              == Info: ALPN, offering h2
            14:35:59.154095 http.c:756              == Info: ALPN, offering http/1.1
            14:39:02.648838 http.c:756              == Info: gnutls_handshake() failed: Error in the pull function.
            14:39:02.648899 http.c:756              == Info: Closing connection 1
            fatal: unable to access 'http://insecure.go-get-issue-15410.appspot.com/': gnutls_handshake() failed: Error in the pull function.
            package insecure.go-get-issue-15410.appspot.com/pkg/p: exit status 128
        script_test.go:154: FAIL: get_insecure.txt:17: go get -d insecure.go-get-issue-15410.appspot.com/pkg/p: exit status 1

— watchflakes

@gopherbot
Copy link

Found new dashboard test flakes for:

#!watchflakes
post <- pkg ~ `^cmd/go` && `Error in the pull function`
2023-05-04 16:22 linux-386-longtest go@ca49b8d7 cmd/go.TestScript (log) 
vcs-test.golang.org rerouted to http://127.0.0.1:35401
https://vcs-test.golang.org rerouted to https://127.0.0.1:35293
go test proxy running at GOPROXY=http://127.0.0.1:35847/mod
2023/05/04 16:58:57 http: TLS handshake error from 127.0.0.1:41712: EOF
2023/05/04 16:58:57 http: TLS handshake error from 127.0.0.1:41722: EOF
2023/05/04 16:59:15 http: TLS handshake error from 127.0.0.1:54342: EOF
2023/05/04 16:59:15 http: TLS handshake error from 127.0.0.1:54374: EOF
2023/05/04 16:59:15 http: TLS handshake error from 127.0.0.1:54432: EOF
2023/05/04 16:59:15 http: TLS handshake error from 127.0.0.1:54450: EOF
2023/05/04 16:59:15 http: TLS handshake error from 127.0.0.1:54418: EOF
...
--- FAIL: TestScript (0.06s)
    --- FAIL: TestScript/govcs (9.65s)
        script_test.go:134: 2023-05-04T17:00:41Z
        script_test.go:136: $WORK=/workdir/tmp/cmd-go-test-3443148756/tmpdir1896534065/govcs4243415820
        script_test.go:154: 
            > env GO111MODULE=on
            > env proxy=$GOPROXY
            > env GOPROXY=direct
            # GOVCS stops go get (0.059s)
            # public pattern works (0.010s)
...
            17:00:50.937413 http.c:756              == Info:   Trying 140.82.114.4:443...
            17:00:50.962032 http.c:756              == Info: Connected to github.com (140.82.114.4) port 443 (#0)
            17:00:51.005063 http.c:756              == Info: found 387 certificates in /etc/ssl/certs
            17:00:51.005147 http.c:756              == Info: ALPN, offering h2
            17:00:51.005152 http.c:756              == Info: ALPN, offering http/1.1
            17:00:51.029944 http.c:756              == Info: gnutls_handshake() failed: Error in the pull function.
            17:00:51.029975 http.c:756              == Info: Closing connection 0
            fatal: unable to access 'https://github.com/rsc/sampler/': gnutls_handshake() failed: Error in the pull function.
            package rsc.io/sampler: exit status 128
        script_test.go:154: FAIL: govcs.txt:153: go get rsc.io/sampler: exit status 1

— watchflakes

@gopherbot
Copy link

Found new dashboard test flakes for:

#!watchflakes
post <- pkg ~ `^cmd/go` && `Error in the pull function`
2023-05-10 22:56 linux-arm64-longtest go@b768a1fb cmd/go.TestScript (log) 
vcs-test.golang.org rerouted to http://127.0.0.1:42013
https://vcs-test.golang.org rerouted to https://127.0.0.1:40355
go test proxy running at GOPROXY=http://127.0.0.1:36417/mod
2023/05/10 23:18:56 http: TLS handshake error from 127.0.0.1:51530: EOF
2023/05/10 23:19:09 http: TLS handshake error from 127.0.0.1:53026: EOF
2023/05/10 23:19:09 http: TLS handshake error from 127.0.0.1:53004: EOF
2023/05/10 23:19:09 http: TLS handshake error from 127.0.0.1:53044: EOF
2023/05/10 23:19:09 http: TLS handshake error from 127.0.0.1:53056: EOF
2023/05/10 23:19:09 http: TLS handshake error from 127.0.0.1:53050: EOF
2023/05/10 23:19:09 http: TLS handshake error from 127.0.0.1:53096: read tcp 127.0.0.1:40355->127.0.0.1:53096: read: connection reset by peer
...
--- FAIL: TestScript (0.07s)
    --- FAIL: TestScript/get_insecure_env (1.44s)
        script_test.go:134: 2023-05-10T23:19:41Z
        script_test.go:136: $WORK=/tmp/workdir/tmp/cmd-go-test-2000183652/tmpdir492782984/get_insecure_env4061631678
        script_test.go:154: 
            > [!net:insecure.go-get-issue-15410.appspot.com] skip
            [condition not met]
            > [!git] skip
            [condition not met]
            # GOPATH: Set up (0.000s)
...
            23:19:43.059311 http.c:756              == Info:   Trying 140.82.113.4:443...
            23:19:43.084418 http.c:756              == Info: Connected to github.com (140.82.113.4) port 443 (#1)
            23:19:43.123692 http.c:756              == Info: found 387 certificates in /etc/ssl/certs
            23:19:43.123811 http.c:756              == Info: ALPN, offering h2
            23:19:43.123851 http.c:756              == Info: ALPN, offering http/1.1
            23:19:43.149351 http.c:756              == Info: gnutls_handshake() failed: Error in the pull function.
            23:19:43.149378 http.c:756              == Info: Closing connection 1
            fatal: unable to access 'http://insecure.go-get-issue-15410.appspot.com/': gnutls_handshake() failed: Error in the pull function.
            package insecure.go-get-issue-15410.appspot.com/pkg/p: exit status 1
        script_test.go:154: FAIL: get_insecure_env.txt:28: go get -d -u -f insecure.go-get-issue-15410.appspot.com/pkg/p: exit status 1

— watchflakes

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
Builders x/build issues (builders, bots, dashboards) NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one.
Projects
Test Flakes
Status: Active
Milestone
Unplanned
Development

No branches or pull requests
3 participants
@bcmills @gopherbot @heschi