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.

Sign up for GitHub

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

net: TestDialTimeout failures #62540

Closed
gopherbot opened this issue Sep 8, 2023 · 3 comments
Closed

net: TestDialTimeout failures #62540

gopherbot opened this issue Sep 8, 2023 · 3 comments
Assignees
@bcmills
Labels
NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one. Testing An issue that has been verified to require only test changes, not just a test failure.
Milestone
Go1.22

Comments

@gopherbot
Copy link

gopherbot commented Sep 8, 2023 •
edited by bcmills 

#!watchflakes
default <- pkg == "net" && test == "TestDialTimeout" && date < "2023-10-01"

Issue created automatically to collect these failures.

Example (log):

--- FAIL: TestDialTimeout (0.01s)
    --- FAIL: TestDialTimeout/0s/1ms (0.01s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 125µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 62.5µs
...
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 15ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 7ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 3ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:95: can't reduce Timeout or Deadline

— 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 8, 2023
@gopherbot
Copy link
Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "net" && test == "TestDialTimeout"
2023-08-31 00:34 windows-386-2016 go@71a0beb6 net.TestDialTimeout (log) 
--- FAIL: TestDialTimeout (0.01s)
    --- FAIL: TestDialTimeout/0s/1ms (0.01s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 125µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 62.5µs
...
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 15ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 7ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 3ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:95: can't reduce Timeout or Deadline
2023-08-31 00:34 windows-amd64-2016 go@71a0beb6 net.TestDialTimeout (log) 
--- FAIL: TestDialTimeout (0.01s)
    --- FAIL: TestDialTimeout/1ms/5s (0.01s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 2.5s
        timeout_test.go:103: reducing Timeout to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1.25s
        timeout_test.go:103: reducing Timeout to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 625ms
...
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 9ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 4ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 2ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:95: can't reduce Timeout or Deadline
2023-08-31 16:31 windows-arm64-11 go@cab416f3 net.TestDialTimeout (log) 
--- FAIL: TestDialTimeout (0.02s)
    --- FAIL: TestDialTimeout/0s/1ms (0.01s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 125µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 62.5µs
...
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 15ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 7ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 3ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:95: can't reduce Timeout or Deadline
2023-08-31 16:59 windows-amd64-2016 go@9dea791a net.TestDialTimeout (log) 
--- FAIL: TestDialTimeout (0.01s)
    --- FAIL: TestDialTimeout/0s/1ms (0.00s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 125µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 62.5µs
...
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 15ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 7ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 3ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:95: can't reduce Timeout or Deadline
2023-08-31 16:59 windows-amd64-race go@9dea791a net.TestDialTimeout (log) 
--- FAIL: TestDialTimeout (0.01s)
    --- FAIL: TestDialTimeout/0s/1ms (0.01s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 125µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 62.5µs
...
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 15ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 7ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 3ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:95: can't reduce Timeout or Deadline
2023-08-31 16:59 windows-arm64-11 go@9dea791a net.TestDialTimeout (log) 
--- FAIL: TestDialTimeout (0.03s)
    --- FAIL: TestDialTimeout/1ms/0s (0.00s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:103: reducing Timeout to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:103: reducing Timeout to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:103: reducing Timeout to 125µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:103: reducing Timeout to 62.5µs
...
    --- FAIL: TestDialTimeout/0s/1ms (0.01s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 125µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 62.5µs
        timeout_test.go:91: closing spurious connection from Dial
...
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 9ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 4ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 2ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:95: can't reduce Timeout or Deadline
2023-08-31 23:57 windows-arm64-11 go@7a4787d1 net.TestDialTimeout (log) 
--- FAIL: TestDialTimeout (0.01s)
    --- FAIL: TestDialTimeout/0s/1ms (0.01s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 125µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 62.5µs
...
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 15ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 7ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 3ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:95: can't reduce Timeout or Deadline
2023-09-01 04:29 windows-amd64-race go@62fb281c net.TestDialTimeout (log) 
--- FAIL: TestDialTimeout (0.01s)
    --- FAIL: TestDialTimeout/1ms/5s (0.01s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 2.5s
        timeout_test.go:103: reducing Timeout to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1.25s
        timeout_test.go:103: reducing Timeout to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 625ms
...
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 9ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 4ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 2ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:95: can't reduce Timeout or Deadline
2023-09-01 14:55 windows-386-2016 go@660feea7 net.TestDialTimeout (log) 
--- FAIL: TestDialTimeout (0.01s)
    --- FAIL: TestDialTimeout/0s/1ms (0.00s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 125µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 62.5µs
...
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 15ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 7ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 3ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:95: can't reduce Timeout or Deadline
2023-09-01 15:06 windows-amd64-race go@e802f211 net.TestDialTimeout (log) 
--- FAIL: TestDialTimeout (0.01s)
    --- FAIL: TestDialTimeout/0s/1ms (0.01s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 125µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 62.5µs
...
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 15ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 7ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 3ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:95: can't reduce Timeout or Deadline
2023-09-01 15:07 windows-amd64-2016 go@6b52bfa3 net.TestDialTimeout (log) 
--- FAIL: TestDialTimeout (0.01s)
    --- FAIL: TestDialTimeout/1ms/5s (0.01s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 2.5s
        timeout_test.go:103: reducing Timeout to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1.25s
        timeout_test.go:103: reducing Timeout to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 625ms
...
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 9ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 4ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 2ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:95: can't reduce Timeout or Deadline
2023-09-01 15:07 windows-arm64-11 go@6b52bfa3 net.TestDialTimeout (log) 
--- FAIL: TestDialTimeout (0.02s)
    --- FAIL: TestDialTimeout/1ms/5s (0.01s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 2.5s
        timeout_test.go:103: reducing Timeout to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1.25s
        timeout_test.go:103: reducing Timeout to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 625ms
...
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 9ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 4ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 2ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:95: can't reduce Timeout or Deadline
2023-09-01 15:07 windows-arm64-11 go@bb74a53c net.TestDialTimeout (log) 
--- FAIL: TestDialTimeout (0.02s)
    --- FAIL: TestDialTimeout/1ms/0s (0.00s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:103: reducing Timeout to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:103: reducing Timeout to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:103: reducing Timeout to 125µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:103: reducing Timeout to 62.5µs
...
    --- FAIL: TestDialTimeout/0s/1ms (0.01s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 125µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 62.5µs
        timeout_test.go:91: closing spurious connection from Dial
...
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 9ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 4ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 2ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:95: can't reduce Timeout or Deadline
2023-09-01 15:08 windows-arm64-11 go@b4c889b5 net.TestDialTimeout (log) 
--- FAIL: TestDialTimeout (0.02s)
    --- FAIL: TestDialTimeout/1ms/0s (0.00s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:103: reducing Timeout to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:103: reducing Timeout to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:103: reducing Timeout to 125µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:103: reducing Timeout to 62.5µs
...
    --- FAIL: TestDialTimeout/1ms/5s (0.01s)
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 2.5s
        timeout_test.go:103: reducing Timeout to 500µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1.25s
        timeout_test.go:103: reducing Timeout to 250µs
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 625ms
        timeout_test.go:103: reducing Timeout to 125µs
...
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 9ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 4ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 2ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:99: reducing Deadline delta to 1ns
        timeout_test.go:91: closing spurious connection from Dial
        timeout_test.go:95: can't reduce Timeout or Deadline

— watchflakes

@bcmills bcmills self-assigned this Sep 13, 2023
@bcmills bcmills added this to the Go1.22 milestone Sep 13, 2023
@bcmills
Copy link
Contributor

bcmills commented Sep 13, 2023 •
edited

Fixed by https://go.dev/cl/524936.

@bcmills bcmills closed this as completed Sep 13, 2023
@bcmills bcmills added the Testing An issue that has been verified to require only test changes, not just a test failure. label Sep 13, 2023
@gopherbot gopherbot reopened this Jan 22, 2024
@gopherbot
Copy link
Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "net" && test == "TestDialTimeout"
2024-01-22 16:30 windows-amd64-race go@41c05ea4 net.TestDialTimeout (log) 
--- FAIL: TestDialTimeout (0.01s)
    --- FAIL: TestDialTimeout/0s/-5s (0.00s)
        testing.go:1398: race detected during execution of test

— watchflakes

@bcmills bcmills closed this as completed Feb 1, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@bcmills bcmills

Labels
NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one. Testing An issue that has been verified to require only test changes, not just a test failure.
Projects
Test Flakes
Status: Done
Milestone
Go1.22
Development

No branches or pull requests
2 participants
@bcmills @gopherbot