net: TestVariousDeadlines failing on plan9/386 builder

[0intro]

TestVariousDeadlines is regularly failing on the plan9/386 builder.

--- FAIL: TestVariousDeadlines (6.21s)
    timeout_test.go:880: 1ns run 1/1
    timeout_test.go:905: for 1ns run 1/1, good client timeout after 81.296µs, reading 0 bytes
    timeout_test.go:915: for 1ns run 1/1, server in 3.50244ms wrote 458752: readfrom tcp 127.0.0.1:43229->127.0.0.1:62762: write tcp 127.0.0.1:43229->127.0.0.1:62762: write /net/tcp/166/data: Hangup
    timeout_test.go:880: 2ns run 1/1
    timeout_test.go:905: for 2ns run 1/1, good client timeout after 56.991µs, reading 0 bytes
    timeout_test.go:915: for 2ns run 1/1, server in 7.46919ms wrote 1048576: readfrom tcp 127.0.0.1:43229->127.0.0.1:38454: write tcp 127.0.0.1:43229->127.0.0.1:38454: write /net/tcp/167/data: Hangup
    timeout_test.go:880: 5ns run 1/1
    timeout_test.go:905: for 5ns run 1/1, good client timeout after 58.667µs, reading 0 bytes
    timeout_test.go:915: for 5ns run 1/1, server in 7.167473ms wrote 1048576: readfrom tcp 127.0.0.1:43229->127.0.0.1:59766: write tcp 127.0.0.1:43229->127.0.0.1:59766: write /net/tcp/168/data: Hangup
    timeout_test.go:880: 50ns run 1/1
    timeout_test.go:905: for 50ns run 1/1, good client timeout after 56.153µs, reading 0 bytes
    timeout_test.go:915: for 50ns run 1/1, server in 4.392508ms wrote 524288: readfrom tcp 127.0.0.1:43229->127.0.0.1:62830: write tcp 127.0.0.1:43229->127.0.0.1:62830: write /net/tcp/169/data: Hangup
    timeout_test.go:880: 100ns run 1/1
    timeout_test.go:905: for 100ns run 1/1, good client timeout after 56.153µs, reading 0 bytes
    timeout_test.go:915: for 100ns run 1/1, server in 4.517385ms wrote 622592: readfrom tcp 127.0.0.1:43229->127.0.0.1:50076: write tcp 127.0.0.1:43229->127.0.0.1:50076: write /net/tcp/170/data: Hangup
    timeout_test.go:880: 200ns run 1/1
    timeout_test.go:905: for 200ns run 1/1, good client timeout after 57.829µs, reading 0 bytes
    timeout_test.go:915: for 200ns run 1/1, server in 3.538478ms wrote 458752: readfrom tcp 127.0.0.1:43229->127.0.0.1:44015: write tcp 127.0.0.1:43229->127.0.0.1:44015: write /net/tcp/171/data: Hangup
    timeout_test.go:880: 500ns run 1/1
    timeout_test.go:905: for 500ns run 1/1, good client timeout after 64.534µs, reading 0 bytes
    timeout_test.go:915: for 500ns run 1/1, server in 3.929036ms wrote 524288: readfrom tcp 127.0.0.1:43229->127.0.0.1:50845: write tcp 127.0.0.1:43229->127.0.0.1:50845: write /net/tcp/172/data: Hangup
    timeout_test.go:880: 750ns run 1/1
    timeout_test.go:905: for 750ns run 1/1, good client timeout after 56.991µs, reading 0 bytes
    timeout_test.go:915: for 750ns run 1/1, server in 7.222787ms wrote 1048576: readfrom tcp 127.0.0.1:43229->127.0.0.1:54489: write tcp 127.0.0.1:43229->127.0.0.1:54489: write /net/tcp/173/data: Hangup
    timeout_test.go:880: 1µs run 1/1
    timeout_test.go:905: for 1µs run 1/1, good client timeout after 56.991µs, reading 0 bytes
    timeout_test.go:915: for 1µs run 1/1, server in 4.337192ms wrote 557056: readfrom tcp 127.0.0.1:43229->127.0.0.1:55578: write tcp 127.0.0.1:43229->127.0.0.1:55578: write /net/tcp/174/data: Hangup
    timeout_test.go:880: 5µs run 1/1
    timeout_test.go:905: for 5µs run 1/1, good client timeout after 59.506µs, reading 0 bytes
    timeout_test.go:915: for 5µs run 1/1, server in 3.603013ms wrote 458752: readfrom tcp 127.0.0.1:43229->127.0.0.1:35569: write tcp 127.0.0.1:43229->127.0.0.1:35569: write /net/tcp/175/data: Hangup
    timeout_test.go:880: 25µs run 1/1
    timeout_test.go:905: for 25µs run 1/1, good client timeout after 57.829µs, reading 0 bytes
    timeout_test.go:915: for 25µs run 1/1, server in 2.280483ms wrote 294912: readfrom tcp 127.0.0.1:43229->127.0.0.1:58149: write tcp 127.0.0.1:43229->127.0.0.1:58149: write /net/tcp/176/data: Hangup
    timeout_test.go:880: 250µs run 1/1
    timeout_test.go:910: for 250µs run 1/1, timeout (5s) waiting for client to timeout (250µs) reading
FAIL
FAIL	net	30.849s

See https://build.golang.org/log/4b9709667343395dcd2d93c1447461e4fb807a33

This is related to issue #19519.

@millerresearch

[gopherbot]

Change https://golang.org/cl/129098 mentions this issue: env: disable aux/timesync in Plan 9 image

[gopherbot]

Change https://golang.org/cl/130137 mentions this issue: dashboard: bump plan9-386-v5 image to v6

[dmitshur]

Re-opening so we can confirm that the flakiness gets fixed after the change is deployed.

Edit: The change from CL 129098 is deployed now.

[dmitshur]

An update here. I've looked over the plan9-386 failures over the last 2 days:

• https://build.golang.org/log/eb6fd6a9e522e232ede78ce0bd75fb114f84ecc3
• https://build.golang.org/log/4535350c4d4ada456799d694b22e6b01caa465ee
• https://build.golang.org/log/aad3df6203098849aee1d9ddd2c414a38c00c7b5
• https://build.golang.org/log/1b0397c388f1b47a4989f5b8bec6ce5ab2f2eea0
• https://build.golang.org/log/2403454e2cab9ba6454d5a9b96b9be4819c6a6d2
• https://build.golang.org/log/7359f2eca19de70865cdd9a567cea7e7c1eaac78
• https://build.golang.org/log/13695e48cb873b13cdcd1072e5715f53d71462fe
• https://build.golang.org/log/91d65a04e89b93cb6c8285d93c75f7ec56709b9c
• https://build.golang.org/log/edc7973e321a709f9c803f31c5682b673c9e90ec
• https://build.golang.org/log/0be11115a5b3086dcde291d7afc2a8a32c6d0ee4
• https://build.golang.org/log/a73d70779dbdcff7f12e1d3ff95354cb03a58649

Out of the 11 builds that failed, 5 were due to TestVariousDeadlines failing with the same "Hangup" errors. It seems this flakiness issue isn't completely resolved yet.

[gopherbot]

Change https://golang.org/cl/152721 mentions this issue: net: skip TestVariousDeadlines on Plan 9