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

net/http: TestIssue4191_InfiniteGetTimeout failures #56274

Open
gopherbot opened this issue Oct 17, 2022 · 12 comments
Open

net/http: TestIssue4191_InfiniteGetTimeout failures #56274

gopherbot opened this issue Oct 17, 2022 · 12 comments
Assignees
Labels
NeedsFix The path to resolution is known, but the work has not been done. Testing An issue that has been verified to require only test changes, not just a test failure.
Milestone

Comments

@gopherbot
Copy link

#!watchflakes
post <- pkg == "net/http" && test == "TestIssue4191_InfiniteGetTimeout"

Issue created automatically to collect these failures.

Example (log):

2022/10/14 13:21:56 http: TLS handshake error from 127.0.0.1:34378: EOF
2022/10/14 13:21:56 http: TLS handshake error from 127.0.0.1:58818: EOF
2022/10/14 13:22:06 http: TLS handshake error from 127.0.0.1:46802: EOF
2022/10/14 13:22:08 http: TLS handshake error from 127.0.0.1:37914: write tcp 127.0.0.1:33957->127.0.0.1:37914: use of closed network connection
2022/10/14 13:22:08 http: TLS handshake error from 127.0.0.1:55294: read tcp 127.0.0.1:32777->127.0.0.1:55294: use of closed network connection
2022/10/14 13:22:08 http: TLS handshake error from 127.0.0.1:55306: read tcp 127.0.0.1:32777->127.0.0.1:55306: use of closed network connection
2022/10/14 13:22:08 http: TLS handshake error from 127.0.0.1:55308: read tcp 127.0.0.1:32777->127.0.0.1:55308: use of closed network connection
2022/10/14 13:22:08 http: TLS handshake error from 127.0.0.1:55290: read tcp 127.0.0.1:32777->127.0.0.1:55290: use of closed network connection
2022/10/14 13:22:08 http: TLS handshake error from 127.0.0.1:55292: read tcp 127.0.0.1:32777->127.0.0.1:55292: use of closed network connection
2022/10/14 13:22:08 http: TLS handshake error from 127.0.0.1:55300: write tcp 127.0.0.1:32777->127.0.0.1:55300: use of closed network connection
...
--- FAIL: TestIssue4191_InfiniteGetTimeout (0.00s)
    --- FAIL: TestIssue4191_InfiniteGetTimeout/h2 (1.72s)
        transport_test.go:2167: increasing timeout
        transport_test.go:2172: Error issuing GET: Get "https://127.0.0.1:33957/get": read tcp 127.0.0.1:37936->127.0.0.1:33957: i/o timeout
2022/10/14 13:22:10 http: TLS handshake error from 127.0.0.1:43514: read tcp 127.0.0.1:32901->127.0.0.1:43514: use of closed network connection
2022/10/14 13:22:10 http: TLS handshake error from 127.0.0.1:43516: read tcp 127.0.0.1:32901->127.0.0.1:43516: use of closed network connection
2022/10/14 13:22:10 http: TLS handshake error from 127.0.0.1:43520: read tcp 127.0.0.1:32901->127.0.0.1:43520: use of closed network connection
2022/10/14 13:22:10 http: TLS handshake error from 127.0.0.1:43528: read tcp 127.0.0.1:32901->127.0.0.1:43528: use of closed network connection
2022/10/14 13:22:10 http: TLS handshake error from 127.0.0.1:43522: write tcp 127.0.0.1:32901->127.0.0.1:43522: use of closed network connection
2022/10/14 13:22:10 http: TLS handshake error from 127.0.0.1:43512: write tcp 127.0.0.1:32901->127.0.0.1:43512: use of closed network connection
...
2022/10/14 13:22:39 http: TLS handshake error from 127.0.0.1:54936: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:55624: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:55618: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:55620: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:54942: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:54946: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:54948: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:55630: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:55634: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:54952: EOF

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 Oct 17, 2022
@gopherbot
Copy link
Author

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "net/http" && test == "TestIssue4191_InfiniteGetTimeout"
2022-10-14 08:14 linux-mipsle-rtrk go@76e4833b net/http.TestIssue4191_InfiniteGetTimeout (log)
2022/10/14 13:21:56 http: TLS handshake error from 127.0.0.1:34378: EOF
2022/10/14 13:21:56 http: TLS handshake error from 127.0.0.1:58818: EOF
2022/10/14 13:22:06 http: TLS handshake error from 127.0.0.1:46802: EOF
2022/10/14 13:22:08 http: TLS handshake error from 127.0.0.1:37914: write tcp 127.0.0.1:33957->127.0.0.1:37914: use of closed network connection
2022/10/14 13:22:08 http: TLS handshake error from 127.0.0.1:55294: read tcp 127.0.0.1:32777->127.0.0.1:55294: use of closed network connection
2022/10/14 13:22:08 http: TLS handshake error from 127.0.0.1:55306: read tcp 127.0.0.1:32777->127.0.0.1:55306: use of closed network connection
2022/10/14 13:22:08 http: TLS handshake error from 127.0.0.1:55308: read tcp 127.0.0.1:32777->127.0.0.1:55308: use of closed network connection
2022/10/14 13:22:08 http: TLS handshake error from 127.0.0.1:55290: read tcp 127.0.0.1:32777->127.0.0.1:55290: use of closed network connection
2022/10/14 13:22:08 http: TLS handshake error from 127.0.0.1:55292: read tcp 127.0.0.1:32777->127.0.0.1:55292: use of closed network connection
2022/10/14 13:22:08 http: TLS handshake error from 127.0.0.1:55300: write tcp 127.0.0.1:32777->127.0.0.1:55300: use of closed network connection
...
--- FAIL: TestIssue4191_InfiniteGetTimeout (0.00s)
    --- FAIL: TestIssue4191_InfiniteGetTimeout/h2 (1.72s)
        transport_test.go:2167: increasing timeout
        transport_test.go:2172: Error issuing GET: Get "https://127.0.0.1:33957/get": read tcp 127.0.0.1:37936->127.0.0.1:33957: i/o timeout
2022/10/14 13:22:10 http: TLS handshake error from 127.0.0.1:43514: read tcp 127.0.0.1:32901->127.0.0.1:43514: use of closed network connection
2022/10/14 13:22:10 http: TLS handshake error from 127.0.0.1:43516: read tcp 127.0.0.1:32901->127.0.0.1:43516: use of closed network connection
2022/10/14 13:22:10 http: TLS handshake error from 127.0.0.1:43520: read tcp 127.0.0.1:32901->127.0.0.1:43520: use of closed network connection
2022/10/14 13:22:10 http: TLS handshake error from 127.0.0.1:43528: read tcp 127.0.0.1:32901->127.0.0.1:43528: use of closed network connection
2022/10/14 13:22:10 http: TLS handshake error from 127.0.0.1:43522: write tcp 127.0.0.1:32901->127.0.0.1:43522: use of closed network connection
2022/10/14 13:22:10 http: TLS handshake error from 127.0.0.1:43512: write tcp 127.0.0.1:32901->127.0.0.1:43512: use of closed network connection
...
2022/10/14 13:22:39 http: TLS handshake error from 127.0.0.1:54936: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:55624: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:55618: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:55620: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:54942: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:54946: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:54948: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:55630: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:55634: EOF
2022/10/14 13:22:40 http: TLS handshake error from 127.0.0.1:54952: EOF

watchflakes

@bcmills
Copy link
Member

bcmills commented Oct 17, 2022

See previously #4852.

@bcmills
Copy link
Member

bcmills commented Oct 17, 2022

This is probably a preexisting HTTP/2 bug exposed by https://go.dev/cl/438137 (attn @neild).

@gopherbot
Copy link
Author

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "net/http" && test == "TestIssue4191_InfiniteGetTimeout"
2022-10-26 16:24 linux-mipsle-rtrk go@3afba312 net/http.TestIssue4191_InfiniteGetTimeout (log)
2022/10/30 23:02:09 http: TLS handshake error from 127.0.0.1:36966: EOF
2022/10/30 23:02:09 http: TLS handshake error from 127.0.0.1:37310: EOF
2022/10/30 23:02:22 http: TLS handshake error from 127.0.0.1:39346: read tcp 127.0.0.1:44883->127.0.0.1:39346: use of closed network connection
2022/10/30 23:02:22 http: TLS handshake error from 127.0.0.1:39332: read tcp 127.0.0.1:44883->127.0.0.1:39332: use of closed network connection
2022/10/30 23:02:22 http: TLS handshake error from 127.0.0.1:39340: read tcp 127.0.0.1:44883->127.0.0.1:39340: use of closed network connection
2022/10/30 23:02:22 http: TLS handshake error from 127.0.0.1:39342: write tcp 127.0.0.1:44883->127.0.0.1:39342: use of closed network connection
2022/10/30 23:02:22 http: TLS handshake error from 127.0.0.1:39348: write tcp 127.0.0.1:44883->127.0.0.1:39348: use of closed network connection
2022/10/30 23:02:23 http: TLS handshake error from 127.0.0.1:59896: write tcp 127.0.0.1:36001->127.0.0.1:59896: use of closed network connection
2022/10/30 23:02:23 http: TLS handshake error from 127.0.0.1:59898: write tcp 127.0.0.1:36001->127.0.0.1:59898: use of closed network connection
2022/10/30 23:02:24 http: TLS handshake error from 127.0.0.1:47022: read tcp 127.0.0.1:36801->127.0.0.1:47022: use of closed network connection
...
--- FAIL: TestIssue4191_InfiniteGetTimeout (0.00s)
    --- FAIL: TestIssue4191_InfiniteGetTimeout/h1 (1.51s)
        transport_test.go:2170: increasing timeout
        transport_test.go:2175: Error issuing GET: Get "https://127.0.0.1:36001/get": read tcp 127.0.0.1:59898->127.0.0.1:36001: i/o timeout
2022/10/30 23:02:28 http2: server: error reading preface from client 127.0.0.1:49910: bogus greeting "CONNECT golang.fake.tld:"
2022/10/30 23:02:28 http2: server: error reading preface from client 127.0.0.1:41464: bogus greeting "CONNECT golang.fake.tld:"
2022/10/30 23:02:38 http: TLS handshake error from 127.0.0.1:54178: write tcp 127.0.0.1:45295->127.0.0.1:54178: i/o timeout
2022/10/30 23:02:38 http: TLS handshake error from 127.0.0.1:37352: write tcp 127.0.0.1:40741->127.0.0.1:37352: i/o timeout
2022/10/30 23:02:38 http: TLS handshake error from 127.0.0.1:38348: write tcp 127.0.0.1:38709->127.0.0.1:38348: i/o timeout
2022/10/30 23:02:39 http: TLS handshake error from 127.0.0.1:45438: write tcp 127.0.0.1:40589->127.0.0.1:45438: i/o timeout
...
2022/10/30 23:02:52 http: TLS handshake error from 127.0.0.1:36326: EOF
2022/10/30 23:02:52 http: TLS handshake error from 127.0.0.1:36330: EOF
2022/10/30 23:02:52 http: TLS handshake error from 127.0.0.1:38312: EOF
2022/10/30 23:02:52 http: TLS handshake error from 127.0.0.1:36334: EOF
2022/10/30 23:02:52 http: TLS handshake error from 127.0.0.1:36336: EOF
2022/10/30 23:02:52 http: TLS handshake error from 127.0.0.1:38306: EOF
2022/10/30 23:02:52 http: TLS handshake error from 127.0.0.1:38304: EOF
2022/10/30 23:02:52 http: TLS handshake error from 127.0.0.1:36344: EOF
2022/10/30 23:02:52 http: TLS handshake error from 127.0.0.1:38314: EOF
2022/10/30 23:02:52 http: TLS handshake error from 127.0.0.1:38316: EOF
2022-10-26 19:11 linux-mipsle-rtrk go@f9ee5614 net/http.TestIssue4191_InfiniteGetTimeout (log)
2022/10/30 17:43:14 http: TLS handshake error from 127.0.0.1:34680: EOF
2022/10/30 17:43:14 http: TLS handshake error from 127.0.0.1:41720: EOF
2022/10/30 17:43:20 http: TLS handshake error from 127.0.0.1:34156: read tcp 127.0.0.1:42031->127.0.0.1:34156: use of closed network connection
2022/10/30 17:43:20 http: TLS handshake error from 127.0.0.1:34158: read tcp 127.0.0.1:42031->127.0.0.1:34158: use of closed network connection
2022/10/30 17:43:20 http: TLS handshake error from 127.0.0.1:34166: read tcp 127.0.0.1:42031->127.0.0.1:34166: use of closed network connection
2022/10/30 17:43:20 http: TLS handshake error from 127.0.0.1:34172: read tcp 127.0.0.1:42031->127.0.0.1:34172: use of closed network connection
2022/10/30 17:43:20 http: TLS handshake error from 127.0.0.1:34170: write tcp 127.0.0.1:42031->127.0.0.1:34170: use of closed network connection
2022/10/30 17:43:21 http: TLS handshake error from 127.0.0.1:34154: write tcp 127.0.0.1:42031->127.0.0.1:34154: use of closed network connection
2022/10/30 17:43:22 http: TLS handshake error from 127.0.0.1:34744: read tcp 127.0.0.1:46581->127.0.0.1:34744: use of closed network connection
2022/10/30 17:43:22 http: TLS handshake error from 127.0.0.1:34732: read tcp 127.0.0.1:46581->127.0.0.1:34732: use of closed network connection
...
2022/10/30 17:43:47 http: TLS handshake error from 127.0.0.1:38820: write tcp 127.0.0.1:33397->127.0.0.1:38820: i/o timeout
2022/10/30 17:43:47 http: TLS handshake error from 127.0.0.1:55542: write tcp 127.0.0.1:35815->127.0.0.1:55542: i/o timeout
2022/10/30 17:43:47 http: TLS handshake error from 127.0.0.1:49296: write tcp 127.0.0.1:36149->127.0.0.1:49296: i/o timeout
2022/10/30 17:43:50 http: TLS handshake error from 127.0.0.1:46374: write tcp 127.0.0.1:35385->127.0.0.1:46374: use of closed network connection
2022/10/30 17:43:51 http: TLS handshake error from 127.0.0.1:46372: write tcp 127.0.0.1:35385->127.0.0.1:46372: use of closed network connection
2022/10/30 17:43:51 http: TLS handshake error from 127.0.0.1:35970: EOF
--- FAIL: TestIssue4191_InfiniteGetTimeout (0.00s)
    --- FAIL: TestIssue4191_InfiniteGetTimeout/h1 (1.87s)
        transport_test.go:2170: increasing timeout
        transport_test.go:2175: Error issuing GET: Get "https://127.0.0.1:35385/get": read tcp 127.0.0.1:46374->127.0.0.1:35385: i/o timeout
2022-10-28 23:50 netbsd-arm-bsiegert go@91a1f0d9 net/http.TestIssue4191_InfiniteGetTimeout (log)
2022/10/29 01:41:05 http: TLS handshake error from 127.0.0.1:58444: EOF
2022/10/29 01:41:05 http: TLS handshake error from 127.0.0.1:58443: EOF
2022/10/29 01:41:05 http: TLS handshake error from 127.0.0.1:58439: EOF
2022/10/29 01:41:05 http: TLS handshake error from 127.0.0.1:58434: EOF
2022/10/29 01:41:05 http: TLS handshake error from 127.0.0.1:58436: EOF
2022/10/29 01:41:05 http: TLS handshake error from 127.0.0.1:58433: EOF
2022/10/29 01:41:05 http: TLS handshake error from 127.0.0.1:58432: EOF
2022/10/29 01:41:07 http: TLS handshake error from 127.0.0.1:58348: EOF
--- FAIL: TestIssue4191_InfiniteGetTimeout (0.01s)
    --- FAIL: TestIssue4191_InfiniteGetTimeout/h1 (1.11s)
...
2022/10/29 01:41:20 http: TLS handshake error from 127.0.0.1:57909: write tcp 127.0.0.1:57913->127.0.0.1:57909: use of closed network connection
2022/10/29 01:41:20 http: TLS handshake error from 127.0.0.1:57906: read tcp 127.0.0.1:57913->127.0.0.1:57906: use of closed network connection
2022/10/29 01:41:20 http: TLS handshake error from 127.0.0.1:57905: read tcp 127.0.0.1:57913->127.0.0.1:57905: use of closed network connection
2022/10/29 01:41:20 http: TLS handshake error from 127.0.0.1:57904: read tcp 127.0.0.1:57913->127.0.0.1:57904: use of closed network connection
2022/10/29 01:41:20 http: TLS handshake error from 127.0.0.1:57908: write tcp 127.0.0.1:57913->127.0.0.1:57908: use of closed network connection
2022/10/29 01:41:20 http: TLS handshake error from 127.0.0.1:57907: write tcp 127.0.0.1:57913->127.0.0.1:57907: use of closed network connection
2022/10/29 01:41:21 http: TLS handshake error from 127.0.0.1:57898: write tcp 127.0.0.1:57900->127.0.0.1:57898: use of closed network connection
2022/10/29 01:41:21 http: TLS handshake error from 127.0.0.1:57896: read tcp 127.0.0.1:57900->127.0.0.1:57896: use of closed network connection
2022/10/29 01:41:21 http: TLS handshake error from 127.0.0.1:57894: read tcp 127.0.0.1:57900->127.0.0.1:57894: use of closed network connection
2022/10/29 01:41:21 http: TLS handshake error from 127.0.0.1:57891: write tcp 127.0.0.1:57900->127.0.0.1:57891: use of closed network connection

watchflakes

@gopherbot
Copy link
Author

Change https://go.dev/cl/452175 mentions this issue: net/http: deflake TestIssue4191_InfiniteGetTimeout

@dmitshur dmitshur modified the milestones: Backlog, Go1.20 Nov 19, 2022
@dmitshur dmitshur added NeedsFix The path to resolution is known, but the work has not been done. Testing An issue that has been verified to require only test changes, not just a test failure. and removed NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one. labels Nov 19, 2022
@gopherbot gopherbot reopened this Dec 5, 2022
@gopherbot
Copy link
Author

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "net/http" && test == "TestIssue4191_InfiniteGetTimeout"
2022-11-17 17:02 freebsd-riscv64-unmatched go@cafb49ac net/http.TestIssue4191_InfiniteGetTimeout (log)
2022/12/01 01:24:07 http2: server: error reading preface from client 127.0.0.1:23223: bogus greeting "CONNECT golang.fake.tld:"
2022/12/01 01:24:07 http2: server: error reading preface from client 127.0.0.1:23227: bogus greeting "CONNECT golang.fake.tld:"
2022/12/01 01:24:12 http: TLS handshake error from 127.0.0.1:23452: write tcp 127.0.0.1:23451->127.0.0.1:23452: i/o timeout
2022/12/01 01:24:12 http: TLS handshake error from 127.0.0.1:23453: write tcp 127.0.0.1:23450->127.0.0.1:23453: i/o timeout
2022/12/01 01:24:12 http: TLS handshake error from 127.0.0.1:23456: write tcp 127.0.0.1:23455->127.0.0.1:23456: i/o timeout
2022/12/01 01:24:12 http: TLS handshake error from 127.0.0.1:23457: read tcp 127.0.0.1:23454->127.0.0.1:23457: i/o timeout
2022/12/01 01:24:16 http: TLS handshake error from 127.0.0.1:23592: read tcp 127.0.0.1:23591->127.0.0.1:23592: read: connection reset by peer
2022/12/01 01:24:16 http: TLS handshake error from 127.0.0.1:23601: EOF
2022/12/01 01:24:18 http: TLS handshake error from 127.0.0.1:23603: write tcp 127.0.0.1:23600->127.0.0.1:23603: use of closed network connection
--- FAIL: TestIssue4191_InfiniteGetTimeout (0.00s)
    --- FAIL: TestIssue4191_InfiniteGetTimeout/h2 (1.33s)
        transport_test.go:2262: increasing timeout
        transport_test.go:2267: Error issuing GET: Get "https://127.0.0.1:23600/get": read tcp 127.0.0.1:23603->127.0.0.1:23600: i/o timeout
1 second passes in backend, proxygone= false
1 second passes in backend, proxygone= false
2022/12/01 01:24:22 http: TLS handshake error from 127.0.0.1:23687: EOF
2022/12/01 01:24:22 http: TLS handshake error from 127.0.0.1:23689: EOF
2022/12/01 01:24:29 http: TLS handshake error from 127.0.0.1:23735: read tcp 127.0.0.1:23727->127.0.0.1:23735: use of closed network connection
2022/12/01 01:24:29 http: TLS handshake error from 127.0.0.1:23736: read tcp 127.0.0.1:23727->127.0.0.1:23736: use of closed network connection
2022/12/01 01:24:29 http: TLS handshake error from 127.0.0.1:23728: write tcp 127.0.0.1:23727->127.0.0.1:23728: use of closed network connection
...
2022/12/01 01:24:29 http: TLS handshake error from 127.0.0.1:23741: write tcp 127.0.0.1:23738->127.0.0.1:23741: use of closed network connection
2022/12/01 01:24:29 http: TLS handshake error from 127.0.0.1:23748: write tcp 127.0.0.1:23738->127.0.0.1:23748: use of closed network connection
2022/12/01 01:24:41 http: TLS handshake error from 127.0.0.1:23983: read tcp 127.0.0.1:23979->127.0.0.1:23983: use of closed network connection
2022/12/01 01:24:46 http: TLS handshake error from 127.0.0.1:24089: EOF
2022/12/01 01:24:46 http: TLS handshake error from 127.0.0.1:24090: EOF
2022/12/01 01:24:46 http: TLS handshake error from 127.0.0.1:24091: EOF
2022/12/01 01:24:46 http: TLS handshake error from 127.0.0.1:24092: read tcp 127.0.0.1:24088->127.0.0.1:24092: read: connection reset by peer
2022/12/01 01:24:47 http: TLS handshake error from 127.0.0.1:24097: read tcp 127.0.0.1:24096->127.0.0.1:24097: read: connection reset by peer
2022/12/01 01:24:47 http: TLS handshake error from 127.0.0.1:24098: read tcp 127.0.0.1:24096->127.0.0.1:24098: read: connection reset by peer
2022/12/01 01:24:47 http: TLS handshake error from 127.0.0.1:24099: read tcp 127.0.0.1:24096->127.0.0.1:24099: read: connection reset by peer

watchflakes

@neild
Copy link
Contributor

neild commented Dec 15, 2022

cafb49a is prior to the fix CL.

@neild neild closed this as completed Dec 15, 2022
@gopherbot
Copy link
Author

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "net/http" && test == "TestIssue4191_InfiniteGetTimeout"
2022-11-18 14:08 netbsd-arm-bsiegert go@349d398e net/http.TestIssue4191_InfiniteGetTimeout (log)
2023/01/09 11:08:19 http2: server: error reading preface from client 127.0.0.1:56682: bogus greeting "CONNECT golang.fake.tld:"
2023/01/09 11:08:19 http2: server: error reading preface from client 127.0.0.1:56695: bogus greeting "CONNECT golang.fake.tld:"
2023/01/09 11:08:32 http: TLS handshake error from 127.0.0.1:55705: EOF
2023/01/09 11:08:33 http: TLS handshake error from 127.0.0.1:55704: EOF
--- FAIL: TestIssue4191_InfiniteGetTimeout (0.00s)
    --- FAIL: TestIssue4191_InfiniteGetTimeout/h1 (1.13s)
        transport_test.go:2262: increasing timeout
        transport_test.go:2267: Error issuing GET: Get "https://127.0.0.1:55706/get": read tcp 127.0.0.1:55704->127.0.0.1:55706: i/o timeout
2023/01/09 11:08:34 http: TLS handshake error from 127.0.0.1:55680: write tcp 127.0.0.1:55681->127.0.0.1:55680: i/o timeout
2023/01/09 11:08:35 http: TLS handshake error from 127.0.0.1:55676: write tcp 127.0.0.1:55677->127.0.0.1:55676: i/o timeout
...
2023/01/09 11:08:45 http: TLS handshake error from 127.0.0.1:55469: write tcp 127.0.0.1:55479->127.0.0.1:55469: use of closed network connection
2023/01/09 11:08:45 http: TLS handshake error from 127.0.0.1:55470: write tcp 127.0.0.1:55479->127.0.0.1:55470: use of closed network connection
2023/01/09 11:08:57 http: TLS handshake error from 127.0.0.1:55040: EOF
2023/01/09 11:08:57 http: TLS handshake error from 127.0.0.1:55037: EOF
2023/01/09 11:08:57 http: TLS handshake error from 127.0.0.1:55036: EOF
2023/01/09 11:08:57 http: TLS handshake error from 127.0.0.1:55031: EOF
2023/01/09 11:08:57 http: TLS handshake error from 127.0.0.1:55035: EOF
2023/01/09 11:08:57 http: TLS handshake error from 127.0.0.1:55030: EOF
2023/01/09 11:08:57 http: TLS handshake error from 127.0.0.1:55029: EOF
2023/01/09 11:08:57 http: TLS handshake error from 127.0.0.1:55027: EOF
2022-11-18 15:33 netbsd-arm-bsiegert go@e18d07dd net/http.TestIssue4191_InfiniteGetTimeout (log)
2023/01/09 05:37:00 http: TLS handshake error from 127.0.0.1:55298: write tcp 127.0.0.1:55299->127.0.0.1:55298: i/o timeout
2023/01/09 05:37:00 http: TLS handshake error from 127.0.0.1:55296: write tcp 127.0.0.1:55297->127.0.0.1:55296: i/o timeout
2023/01/09 05:37:00 http: TLS handshake error from 127.0.0.1:55294: write tcp 127.0.0.1:55295->127.0.0.1:55294: i/o timeout
2023/01/09 05:37:01 http: TLS handshake error from 127.0.0.1:55285: write tcp 127.0.0.1:55286->127.0.0.1:55285: i/o timeout
2023/01/09 05:37:05 http: TLS handshake error from 127.0.0.1:55205: EOF
2023/01/09 05:37:06 http: TLS handshake error from 127.0.0.1:55199: EOF
2023/01/09 05:37:06 http: TLS handshake error from 127.0.0.1:55198: write tcp 127.0.0.1:55200->127.0.0.1:55198: use of closed network connection
--- FAIL: TestIssue4191_InfiniteGetTimeout (0.00s)
    --- FAIL: TestIssue4191_InfiniteGetTimeout/h2 (1.22s)
        transport_test.go:2262: increasing timeout
...
2023/01/09 05:37:30 http: TLS handshake error from 127.0.0.1:54674: EOF
2023/01/09 05:37:31 http: TLS handshake error from 127.0.0.1:54673: read tcp 127.0.0.1:54675->127.0.0.1:54673: read: connection reset by peer
2023/01/09 05:37:31 http: TLS handshake error from 127.0.0.1:54668: EOF
2023/01/09 05:37:31 http: TLS handshake error from 127.0.0.1:54667: EOF
2023/01/09 05:37:31 http: TLS handshake error from 127.0.0.1:54665: EOF
2023/01/09 05:37:31 http: TLS handshake error from 127.0.0.1:54661: EOF
2023/01/09 05:37:31 http: TLS handshake error from 127.0.0.1:54660: EOF
2023/01/09 05:37:31 http: TLS handshake error from 127.0.0.1:54657: EOF
2023/01/09 05:37:32 http2: server: error reading preface from client 127.0.0.1:54602: bogus greeting "CONNECT golang.fake.tld:"
2023/01/09 05:37:32 http2: server: error reading preface from client 127.0.0.1:54598: bogus greeting "CONNECT golang.fake.tld:"

watchflakes

@gopherbot gopherbot reopened this Jan 16, 2023
@bcmills
Copy link
Member

bcmills commented Jan 17, 2023

The above failures are also prior to the fix (see #57632).

@bcmills bcmills closed this as completed Jan 17, 2023
@gopherbot
Copy link
Author

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "net/http" && test == "TestIssue4191_InfiniteGetTimeout"
2023-02-01 19:47 linux-amd64-race go@ab0f0459 net/http.TestIssue4191_InfiniteGetTimeout (log)
==================
WARNING: DATA RACE
Write at 0x00c0013e2000 by goroutine 1926:
  runtime.slicecopy()
      /workdir/go/src/runtime/slice.go:310 +0x0
  net/http.(*http2dataBuffer).Read()
      /workdir/go/src/net/http/h2_bundle.go:1105 +0x284
  net/http.(*http2pipe).Read()
      /workdir/go/src/net/http/h2_bundle.go:3678 +0x36f
  net/http.http2transportResponseBody.Read()
...
  net/http.http2ConfigureServer.func1()
      /workdir/go/src/net/http/h2_bundle.go:4060 +0x11e
  net/http.(*conn).serve()
      /workdir/go/src/net/http/server.go:1892 +0x1c01
  net/http.(*Server).Serve.func3()
      /workdir/go/src/net/http/server.go:3078 +0x4f
==================
--- FAIL: TestIssue4191_InfiniteGetTimeout (0.00s)
    --- FAIL: TestIssue4191_InfiniteGetTimeout/h2 (0.04s)
        testing.go:1446: race detected during execution of test
2023-02-01 21:30 darwin-amd64-race go@4b7f7eef net/http.TestIssue4191_InfiniteGetTimeout (log)
==================
WARNING: DATA RACE
Write at 0x00c001880000 by goroutine 1910:
  runtime.slicecopy()
      /tmp/buildlet/go/src/runtime/slice.go:310 +0x0
  net/http.(*http2dataBuffer).Read()
      /tmp/buildlet/go/src/net/http/h2_bundle.go:1105 +0x284
  net/http.(*http2pipe).Read()
      /tmp/buildlet/go/src/net/http/h2_bundle.go:3678 +0x36f
  net/http.http2transportResponseBody.Read()
...
  net/http.http2ConfigureServer.func1()
      /tmp/buildlet/go/src/net/http/h2_bundle.go:4060 +0x11e
  net/http.(*conn).serve()
      /tmp/buildlet/go/src/net/http/server.go:1892 +0x1c01
  net/http.(*Server).Serve.func3()
      /tmp/buildlet/go/src/net/http/server.go:3078 +0x4f
==================
--- FAIL: TestIssue4191_InfiniteGetTimeout (0.02s)
    --- FAIL: TestIssue4191_InfiniteGetTimeout/h1 (0.07s)
        testing.go:1446: race detected during execution of test

watchflakes

@gopherbot gopherbot reopened this Feb 2, 2023
@bcmills
Copy link
Member

bcmills commented Feb 2, 2023

The above are #58168

@bcmills bcmills closed this as completed Feb 2, 2023
@gopherbot
Copy link
Author

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "net/http" && test == "TestIssue4191_InfiniteGetTimeout"
2023-02-06 12:49 darwin-amd64-race go@a432d891 net/http.TestIssue4191_InfiniteGetTimeout (log)
==================
WARNING: DATA RACE
Write at 0x00c0035e0000 by goroutine 2188:
  runtime.slicecopy()
      /tmp/buildlet/go/src/runtime/slice.go:310 +0x0
  net/http.(*http2dataBuffer).Read()
      /tmp/buildlet/go/src/net/http/h2_bundle.go:1105 +0x284
  net/http.(*http2pipe).Read()
      /tmp/buildlet/go/src/net/http/h2_bundle.go:3678 +0x36f
  net/http.http2transportResponseBody.Read()
...
  net/http.http2ConfigureServer.func1()
      /tmp/buildlet/go/src/net/http/h2_bundle.go:4060 +0x11e
  net/http.(*conn).serve()
      /tmp/buildlet/go/src/net/http/server.go:1892 +0x1c01
  net/http.(*Server).Serve.func3()
      /tmp/buildlet/go/src/net/http/server.go:3078 +0x4f
==================
--- FAIL: TestIssue4191_InfiniteGetTimeout (0.01s)
    --- FAIL: TestIssue4191_InfiniteGetTimeout/h1 (0.09s)
        testing.go:1446: race detected during execution of test
2023-02-06 13:49 darwin-amd64-race go@bf8d142b net/http.TestIssue4191_InfiniteGetTimeout (log)
--- FAIL: TestIssue4191_InfiniteGetTimeout (0.00s)
    --- FAIL: TestIssue4191_InfiniteGetTimeout/h2 (0.14s)
        testing.go:1446: race detected during execution of test
    --- FAIL: TestIssue4191_InfiniteGetTimeout/h1 (0.19s)
        testing.go:1446: race detected during execution of test

watchflakes

@gopherbot gopherbot reopened this Feb 6, 2023
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
NeedsFix The path to resolution is known, but the work has not been done. Testing An issue that has been verified to require only test changes, not just a test failure.
Projects
Status: Done
Development

No branches or pull requests

4 participants