x/build/internal/task: TestTagXRepos failures

[gopherbot]

#!watchflakes
post <- pkg == "golang.org/x/build/internal/task" && test == "TestTagXRepos"

Issue created automatically to collect these failures.

Example (log):

2022/10/14 09:11:08 extracted tarball into /Users/gopher/workdir/tmp/TestTagXRepos3413242940/001/linux-amd64-longtest/0: 1 files, 1 dirs (1.770237ms)
2022/10/14 09:11:08 extracted tarball into /Users/gopher/workdir/tmp/TestTagXRepos3413242940/001/linux-amd64-longtest/0/repo: 2 files, 1 dirs (1.167212ms)
--- FAIL: TestTagXRepos (10.01s)
    tagx_test.go:460: task Select repositories: started
    tagx_test.go:460: task Create plan: started
    tagx_test.go:460: task Select repositories: started
    tagx_test.go:482: task Select repositories: LOG: Examining repositories [go sys mod tools]
    tagx_test.go:482: task Select repositories: LOG: ignoring go: no semver tag
    tagx_test.go:464: task Select repositories: done: [{sys golang.org/x/sys [] } {mod golang.org/x/mod [] } {tools golang.org/x/tools [golang.org/x/mod golang.org/x/sys] }]
    tagx_test.go:460: task Create plan: started
...
    tagx_test.go:460: task mod: tag if appropriate: started
    tagx_test.go:464: task mod: tag if appropriate: done: {mod golang.org/x/mod [] v1.0.0}
    tagx_test.go:464: task sys: wait for green post-submit: done: sys~1
    tagx_test.go:460: task sys: tag if appropriate: started
    tagx_test.go:482: task sys: tag if appropriate: LOG: Waiting for approval to tag sys at sys~1 as v0.2.0
    tagx_test.go:464: task sys: tag if appropriate: done: {sys golang.org/x/sys [] v0.2.0}
    tagx_test.go:460: task tools: generate updated go.mod: started
    tagx_test.go:482: task tools: generate updated go.mod: LOG: task failed, will retry (1 of 3): command /Users/gopher/workdir/tmp/TestTagXRepos3413242940/001/linux-amd64-longtest/0/go/bin/go [get golang.org/x/mod@v1.0.0 golang.org/x/sys@v0.2.0] failed: signal: killed output: ""
    tagx_test.go:460: task tools: generate updated go.mod: started
    tagx_test.go:417: context deadline exceeded

— watchflakes

[gopherbot]

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "golang.org/x/build/internal/task" && test == "TestTagXRepos"

2022-10-14 14:19 darwin-amd64-12_0 build@3481d34e go@a8ca70ff x/build/internal/task.TestTagXRepos (log)

2022/10/14 09:11:08 extracted tarball into /Users/gopher/workdir/tmp/TestTagXRepos3413242940/001/linux-amd64-longtest/0: 1 files, 1 dirs (1.770237ms)
2022/10/14 09:11:08 extracted tarball into /Users/gopher/workdir/tmp/TestTagXRepos3413242940/001/linux-amd64-longtest/0/repo: 2 files, 1 dirs (1.167212ms)
--- FAIL: TestTagXRepos (10.01s)
    tagx_test.go:460: task Select repositories: started
    tagx_test.go:460: task Create plan: started
    tagx_test.go:460: task Select repositories: started
    tagx_test.go:482: task Select repositories: LOG: Examining repositories [go sys mod tools]
    tagx_test.go:482: task Select repositories: LOG: ignoring go: no semver tag
    tagx_test.go:464: task Select repositories: done: [{sys golang.org/x/sys [] } {mod golang.org/x/mod [] } {tools golang.org/x/tools [golang.org/x/mod golang.org/x/sys] }]
    tagx_test.go:460: task Create plan: started
...
    tagx_test.go:460: task mod: tag if appropriate: started
    tagx_test.go:464: task mod: tag if appropriate: done: {mod golang.org/x/mod [] v1.0.0}
    tagx_test.go:464: task sys: wait for green post-submit: done: sys~1
    tagx_test.go:460: task sys: tag if appropriate: started
    tagx_test.go:482: task sys: tag if appropriate: LOG: Waiting for approval to tag sys at sys~1 as v0.2.0
    tagx_test.go:464: task sys: tag if appropriate: done: {sys golang.org/x/sys [] v0.2.0}
    tagx_test.go:460: task tools: generate updated go.mod: started
    tagx_test.go:482: task tools: generate updated go.mod: LOG: task failed, will retry (1 of 3): command /Users/gopher/workdir/tmp/TestTagXRepos3413242940/001/linux-amd64-longtest/0/go/bin/go [get golang.org/x/mod@v1.0.0 golang.org/x/sys@v0.2.0] failed: signal: killed output: ""
    tagx_test.go:460: task tools: generate updated go.mod: started
    tagx_test.go:417: context deadline exceeded

— watchflakes

[heschi]

This test is entirely hermetic and runs in .074 seconds on my workstation, so I dunno what could've happened to cause it to take 100 times longer. If it happens again I suppose I can add timestamps to the log.

[gopherbot]

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "golang.org/x/build/internal/task" && test == "TestTagXRepos"

2022-10-14 16:33 darwin-amd64-11_0 build@209d9014 go@9ddb8ea7 x/build/internal/task.TestTagXRepos (log)

2022/10/14 14:10:29 extracted tarball into /Users/gopher/workdir/tmp/TestTagXRepos1387298848/001/linux-amd64-longtest/0: 1 files, 1 dirs (756.304µs)
2022/10/14 14:10:29 extracted tarball into /Users/gopher/workdir/tmp/TestTagXRepos1387298848/001/linux-amd64-longtest/0/repo: 2 files, 1 dirs (938.005µs)
--- FAIL: TestTagXRepos (10.01s)
    tagx_test.go:460: task Select repositories: started
    tagx_test.go:460: task Create plan: started
    tagx_test.go:460: task Select repositories: started
    tagx_test.go:482: task Select repositories: LOG: Examining repositories [go sys mod tools]
    tagx_test.go:482: task Select repositories: LOG: ignoring go: no semver tag
    tagx_test.go:464: task Select repositories: done: [{sys golang.org/x/sys [] } {mod golang.org/x/mod [] } {tools golang.org/x/tools [golang.org/x/mod golang.org/x/sys] }]
    tagx_test.go:460: task Create plan: started
...
    tagx_test.go:460: task mod: tag if appropriate: started
    tagx_test.go:464: task mod: tag if appropriate: done: {mod golang.org/x/mod [] v1.0.0}
    tagx_test.go:464: task sys: wait for green post-submit: done: sys~1
    tagx_test.go:460: task sys: tag if appropriate: started
    tagx_test.go:482: task sys: tag if appropriate: LOG: Waiting for approval to tag sys at sys~1 as v0.2.0
    tagx_test.go:464: task sys: tag if appropriate: done: {sys golang.org/x/sys [] v0.2.0}
    tagx_test.go:460: task tools: generate updated go.mod: started
    tagx_test.go:482: task tools: generate updated go.mod: LOG: task failed, will retry (1 of 3): command /Users/gopher/workdir/tmp/TestTagXRepos1387298848/001/linux-amd64-longtest/0/go/bin/go [get golang.org/x/mod@v1.0.0 golang.org/x/sys@v0.2.0] failed: signal: killed output: ""
    tagx_test.go:460: task tools: generate updated go.mod: started
    tagx_test.go:417: context deadline exceeded

— watchflakes

[gopherbot]

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "golang.org/x/build/internal/task" && test == "TestTagXRepos"

2022-10-14 22:12 darwin-amd64-12_0 build@1aa9c6a3 go@89566448 x/build/internal/task.TestTagXRepos (log)

2022/10/14 15:21:39 extracted tarball into /Users/gopher/workdir/tmp/TestTagXRepos4265959818/001/linux-amd64-longtest/0: 1 files, 1 dirs (789.312µs)
2022/10/14 15:21:39 extracted tarball into /Users/gopher/workdir/tmp/TestTagXRepos4265959818/001/linux-amd64-longtest/0/repo: 2 files, 1 dirs (908.147µs)
--- FAIL: TestTagXRepos (10.01s)
    tagx_test.go:460: task Create plan: started
    tagx_test.go:460: task Select repositories: started
    tagx_test.go:460: task Select repositories: started
    tagx_test.go:482: task Select repositories: LOG: Examining repositories [go sys mod tools]
    tagx_test.go:482: task Select repositories: LOG: ignoring go: no semver tag
    tagx_test.go:464: task Select repositories: done: [{sys golang.org/x/sys [] } {mod golang.org/x/mod [] } {tools golang.org/x/tools [golang.org/x/mod golang.org/x/sys] }]
    tagx_test.go:460: task Create plan: started
...
    tagx_test.go:460: task sys: tag if appropriate: started
    tagx_test.go:482: task sys: tag if appropriate: LOG: Waiting for approval to tag sys at sys~1 as v0.2.0
    tagx_test.go:464: task sys: tag if appropriate: done: {sys golang.org/x/sys [] v0.2.0}
    tagx_test.go:464: task mod: wait for green post-submit: done: mod~0
    tagx_test.go:460: task mod: tag if appropriate: started
    tagx_test.go:464: task mod: tag if appropriate: done: {mod golang.org/x/mod [] v1.0.0}
    tagx_test.go:460: task tools: generate updated go.mod: started
    tagx_test.go:482: task tools: generate updated go.mod: LOG: task failed, will retry (1 of 3): command /Users/gopher/workdir/tmp/TestTagXRepos4265959818/001/linux-amd64-longtest/0/go/bin/go [get golang.org/x/mod@v1.0.0 golang.org/x/sys@v0.2.0] failed: signal: killed output: ""
    tagx_test.go:460: task tools: generate updated go.mod: started
    tagx_test.go:417: context deadline exceeded

— watchflakes

[gopherbot]

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "golang.org/x/build/internal/task" && test == "TestTagXRepos"

2022-10-17 17:51 darwin-amd64-11_0 build@13478d3d go@da6042e8 x/build/internal/task.TestTagXRepos (log)

2022/10/17 10:54:00 extracted tarball into /Users/gopher/workdir/tmp/TestTagXRepos513414571/001/linux-amd64-longtest/0: 1 files, 1 dirs (1.000036ms)
2022/10/17 10:54:00 extracted tarball into /Users/gopher/workdir/tmp/TestTagXRepos513414571/001/linux-amd64-longtest/0/repo: 2 files, 1 dirs (953.872µs)
--- FAIL: TestTagXRepos (10.01s)
    tagx_test.go:460: task Select repositories: started
    tagx_test.go:460: task Create plan: started
    tagx_test.go:460: task Select repositories: started
    tagx_test.go:482: task Select repositories: LOG: Examining repositories [tools go sys mod]
    tagx_test.go:482: task Select repositories: LOG: ignoring go: no semver tag
    tagx_test.go:464: task Select repositories: done: [{tools golang.org/x/tools [golang.org/x/mod golang.org/x/sys] } {sys golang.org/x/sys [] } {mod golang.org/x/mod [] }]
    tagx_test.go:460: task Create plan: started
...
    tagx_test.go:460: task mod: tag if appropriate: started
    tagx_test.go:464: task mod: tag if appropriate: done: {mod golang.org/x/mod [] v1.0.0}
    tagx_test.go:464: task sys: wait for green post-submit: done: sys~1
    tagx_test.go:460: task sys: tag if appropriate: started
    tagx_test.go:482: task sys: tag if appropriate: LOG: Waiting for approval to tag sys at sys~1 as v0.2.0
    tagx_test.go:464: task sys: tag if appropriate: done: {sys golang.org/x/sys [] v0.2.0}
    tagx_test.go:460: task tools: generate updated go.mod: started
    tagx_test.go:482: task tools: generate updated go.mod: LOG: task failed, will retry (1 of 3): command /Users/gopher/workdir/tmp/TestTagXRepos513414571/001/linux-amd64-longtest/0/go/bin/go [get golang.org/x/mod@v1.0.0 golang.org/x/sys@v0.2.0] failed: signal: killed output: ""
    tagx_test.go:460: task tools: generate updated go.mod: started
    tagx_test.go:417: context deadline exceeded

— watchflakes

[gopherbot]

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "golang.org/x/build/internal/task" && test == "TestTagXRepos"

2022-10-17 20:08 darwin-amd64-10_15 build@16b8a7ce go@947091d3 x/build/internal/task.TestTagXRepos (log)

2022/10/17 16:13:01 extracted tarball into /var/folders/kh/5zzynz152r94t18yzstnrwx80000gn/T/workdir-host-darwin-amd64-10_15/tmp/TestTagXRepos769089925/001/linux-amd64-longtest/0: 1 files, 1 dirs (751.157µs)
2022/10/17 16:13:01 extracted tarball into /var/folders/kh/5zzynz152r94t18yzstnrwx80000gn/T/workdir-host-darwin-amd64-10_15/tmp/TestTagXRepos769089925/001/linux-amd64-longtest/0/repo: 2 files, 1 dirs (761.692µs)
--- FAIL: TestTagXRepos (10.01s)
    tagx_test.go:460: task Select repositories: started
    tagx_test.go:460: task Create plan: started
    tagx_test.go:460: task Select repositories: started
    tagx_test.go:482: task Select repositories: LOG: Examining repositories [go sys mod tools]
    tagx_test.go:482: task Select repositories: LOG: ignoring go: no semver tag
    tagx_test.go:464: task Select repositories: done: [{sys golang.org/x/sys [] } {mod golang.org/x/mod [] } {tools golang.org/x/tools [golang.org/x/mod golang.org/x/sys] }]
    tagx_test.go:460: task Create plan: started
...
    tagx_test.go:460: task mod: tag if appropriate: started
    tagx_test.go:464: task sys: wait for green post-submit: done: sys~1
    tagx_test.go:460: task sys: tag if appropriate: started
    tagx_test.go:482: task sys: tag if appropriate: LOG: Waiting for approval to tag sys at sys~1 as v0.2.0
    tagx_test.go:464: task sys: tag if appropriate: done: {sys golang.org/x/sys [] v0.2.0}
    tagx_test.go:464: task mod: tag if appropriate: done: {mod golang.org/x/mod [] v1.0.0}
    tagx_test.go:460: task tools: generate updated go.mod: started
    tagx_test.go:482: task tools: generate updated go.mod: LOG: task failed, will retry (1 of 3): command /var/folders/kh/5zzynz152r94t18yzstnrwx80000gn/T/workdir-host-darwin-amd64-10_15/tmp/TestTagXRepos769089925/001/linux-amd64-longtest/0/go/bin/go [get golang.org/x/mod@v1.0.0 golang.org/x/sys@v0.2.0] failed: signal: killed output: ""
    tagx_test.go:460: task tools: generate updated go.mod: started
    tagx_test.go:417: context deadline exceeded

— watchflakes

[gopherbot]

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "golang.org/x/build/internal/task" && test == "TestTagXRepos"

2022-10-17 20:08 darwin-amd64-10_15 build@16b8a7ce go@947091d3 x/build/internal/task.TestTagXRepos (log)

2022/10/17 16:31:14 extracted tarball into /var/folders/kh/5zzynz152r94t18yzstnrwx80000gn/T/workdir-host-darwin-amd64-10_15/tmp/TestTagXRepos1215779614/001/linux-amd64-longtest/0: 1 files, 1 dirs (779.39µs)
2022/10/17 16:31:14 extracted tarball into /var/folders/kh/5zzynz152r94t18yzstnrwx80000gn/T/workdir-host-darwin-amd64-10_15/tmp/TestTagXRepos1215779614/001/linux-amd64-longtest/0/repo: 2 files, 1 dirs (703.776µs)
--- FAIL: TestTagXRepos (10.01s)
    tagx_test.go:460: task Select repositories: started
    tagx_test.go:460: task Create plan: started
    tagx_test.go:460: task Select repositories: started
    tagx_test.go:482: task Select repositories: LOG: Examining repositories [mod tools go sys]
    tagx_test.go:482: task Select repositories: LOG: ignoring go: no semver tag
    tagx_test.go:464: task Select repositories: done: [{mod golang.org/x/mod [] } {tools golang.org/x/tools [golang.org/x/mod golang.org/x/sys] } {sys golang.org/x/sys [] }]
    tagx_test.go:460: task Create plan: started
...
    tagx_test.go:460: task mod: tag if appropriate: started
    tagx_test.go:464: task mod: tag if appropriate: done: {mod golang.org/x/mod [] v1.0.0}
    tagx_test.go:464: task sys: wait for green post-submit: done: sys~1
    tagx_test.go:460: task sys: tag if appropriate: started
    tagx_test.go:482: task sys: tag if appropriate: LOG: Waiting for approval to tag sys at sys~1 as v0.2.0
    tagx_test.go:464: task sys: tag if appropriate: done: {sys golang.org/x/sys [] v0.2.0}
    tagx_test.go:460: task tools: generate updated go.mod: started
    tagx_test.go:482: task tools: generate updated go.mod: LOG: task failed, will retry (1 of 3): command /var/folders/kh/5zzynz152r94t18yzstnrwx80000gn/T/workdir-host-darwin-amd64-10_15/tmp/TestTagXRepos1215779614/001/linux-amd64-longtest/0/go/bin/go [get golang.org/x/mod@v1.0.0 golang.org/x/sys@v0.2.0] failed: signal: killed output: ""
    tagx_test.go:460: task tools: generate updated go.mod: started
    tagx_test.go:417: context deadline exceeded

— watchflakes

[gopherbot]

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "golang.org/x/build/internal/task" && test == "TestTagXRepos"

2022-10-18 17:49 darwin-amd64-12_0 build@fa98444b go@947091d3 x/build/internal/task.TestTagXRepos (log)

2022/10/18 10:53:52 extracted tarball into /Users/gopher/workdir/tmp/TestTagXRepos2810739213/001/linux-amd64-longtest/0: 1 files, 1 dirs (813.54µs)
2022/10/18 10:53:52 extracted tarball into /Users/gopher/workdir/tmp/TestTagXRepos2810739213/001/linux-amd64-longtest/0/repo: 2 files, 1 dirs (753.878µs)
--- FAIL: TestTagXRepos (10.00s)
    tagx_test.go:480: task Select repositories: started
    tagx_test.go:480: task Create plan: started
    tagx_test.go:480: task Select repositories: started
    tagx_test.go:502: task Select repositories: LOG: Examining repositories [go sys mod tools]
    tagx_test.go:502: task Select repositories: LOG: ignoring go: no semver tag
    tagx_test.go:484: task Select repositories: done: [{sys golang.org/x/sys [] } {mod golang.org/x/mod [] } {tools golang.org/x/tools [golang.org/x/mod golang.org/x/sys] }]
    tagx_test.go:480: task Create plan: started
...
    tagx_test.go:480: task sys: tag if appropriate: started
    tagx_test.go:502: task sys: tag if appropriate: LOG: Waiting for approval to tag sys at sys~1 as v0.2.0
    tagx_test.go:484: task sys: tag if appropriate: done: {sys golang.org/x/sys [] v0.2.0}
    tagx_test.go:484: task mod: wait for green post-submit: done: mod~0
    tagx_test.go:480: task mod: tag if appropriate: started
    tagx_test.go:484: task mod: tag if appropriate: done: {mod golang.org/x/mod [] v1.0.0}
    tagx_test.go:480: task tools: generate updated go.mod: started
    tagx_test.go:502: task tools: generate updated go.mod: LOG: task failed, will retry (1 of 3): command /Users/gopher/workdir/tmp/TestTagXRepos2810739213/001/linux-amd64-longtest/0/go/bin/go [get golang.org/x/mod@v1.0.0 golang.org/x/sys@v0.2.0] failed: signal: killed output: ""
    tagx_test.go:480: task tools: generate updated go.mod: started
    tagx_test.go:437: context deadline exceeded

— watchflakes

[gopherbot]

Change https://go.dev/cl/444118 mentions this issue: internal/task: increase tagx test verbosity

[gopherbot]

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "golang.org/x/build/internal/task" && test == "TestTagXRepos"

2022-10-20 20:24 darwin-amd64-12_0 build@8be4277e go@2b21a27f x/build/internal/task.TestTagXRepos (log)

2022/10/21 08:38:46 extracted tarball into /Users/gopher/workdir/tmp/TestTagXRepos4074187584/001/linux-amd64-longtest/0: 1 files, 1 dirs (767.699µs)
2022/10/21 08:38:46 extracted tarball into /Users/gopher/workdir/tmp/TestTagXRepos4074187584/001/linux-amd64-longtest/0/repo: 4 files, 2 dirs (1.916662ms)
--- FAIL: TestTagXRepos (10.00s)
    tagx_test.go:489: task Select repositories: started
    tagx_test.go:489: task Create plan: started
    tagx_test.go:489: task Select repositories: started
    tagx_test.go:511: 2022-10-21 08:38:46.498281 -0700 PDT m=+0.058261223	task Select repositories: LOG: Examining repositories [go sys mod tools]
    tagx_test.go:511: 2022-10-21 08:38:46.498324 -0700 PDT m=+0.058303426	task Select repositories: LOG: ignoring go: no semver tag
    tagx_test.go:493: task Select repositories: done: [{sys golang.org/x/sys []  } {mod golang.org/x/mod []  } {tools golang.org/x/tools [golang.org/x/mod golang.org/x/sys] 1.16 }]
    tagx_test.go:489: task Create plan: started
...
    tagx_test.go:489: task mod: tag if appropriate: started
    tagx_test.go:493: task mod: tag if appropriate: done: {mod golang.org/x/mod []  v1.0.0}
    tagx_test.go:493: task sys: wait for green post-submit: done: sys~1
    tagx_test.go:489: task sys: tag if appropriate: started
    tagx_test.go:511: 2022-10-21 08:38:46.501611 -0700 PDT m=+0.061591353	task sys: tag if appropriate: LOG: Waiting for approval to tag sys at sys~1 as v0.2.0
    tagx_test.go:493: task sys: tag if appropriate: done: {sys golang.org/x/sys []  v0.2.0}
    tagx_test.go:489: task tools: generate updated go.mod: started
    tagx_test.go:511: 2022-10-21 08:38:56.498725 -0700 PDT m=+10.058704657	task tools: generate updated go.mod: LOG: task failed, will retry (1 of 3): command /Users/gopher/workdir/tmp/TestTagXRepos4074187584/001/linux-amd64-longtest/0/go/bin/go [get golang.org/x/mod@v1.0.0 golang.org/x/sys@v0.2.0] failed: signal: killed output: ""
    tagx_test.go:489: task tools: generate updated go.mod: started
    tagx_test.go:439: context deadline exceeded

— watchflakes