Created
June 19, 2020 16:09
-
-
Save julieqiu/c279934ee6054e7d5900274569c5c0d2 to your computer and use it in GitHub Desktop.
test output for https://go-review.googlesource.com/c/pkgsite/+/238978 - patchset 9
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
| ~/go/src/golang.org/x/pkgsite master ⇡1 ?1 ❯ ./all.bash ✘ INT 12:08:17 PM | |
| Checking staged files for license header | |
| Checking internal files for license header | |
| Checking for bad migrations | |
| $ staticcheck golang.org/x/pkgsite/cmd/frontend golang.org/x/pkgsite/cmd/prober golang.org/x/pkgsite/cmd/teeproxy golang.org/x/pkgsite/cmd/worker golang.org/x/pkgsite/content/sta... | |
| $ misspell cmd/frontend/main.go cmd/prober/main.go cmd/teeproxy/main.go cmd/worker/main.go cmd/**/*.sh internal/auth/auth.go internal/complete/completion.go internal/complete/com... | |
| $ unparam ./... | |
| $ prettier --write content/static/css/sidenav.css content/static/css/stylesheet.css | |
| content/static/css/sidenav.css 54ms | |
| content/static/css/stylesheet.css 192ms | |
| $ prettier --write content/static/js/analytics.js content/static/js/base.min.js content/static/js/completion.js content/static/js/completion.min.js content/static/js/jump.js cont... | |
| content/static/js/analytics.js 32ms | |
| content/static/js/completion.js 36ms | |
| content/static/js/jump.js 40ms | |
| content/static/js/site.js 8ms | |
| $ prettier --write doc/design.md doc/frontend.md doc/postgres.md doc/precommit.md doc/worker.md private/README.md | |
| doc/design.md 48ms | |
| doc/frontend.md 10ms | |
| doc/postgres.md 30ms | |
| doc/precommit.md 12ms | |
| doc/worker.md 8ms | |
| private/README.md 7ms | |
| $ go mod tidy | |
| $ go test ./... | |
| ? golang.org/x/pkgsite/cmd/frontend [no test files] | |
| ? golang.org/x/pkgsite/cmd/prober [no test files] | |
| ? golang.org/x/pkgsite/cmd/teeproxy [no test files] | |
| ? golang.org/x/pkgsite/cmd/worker [no test files] | |
| ? golang.org/x/pkgsite/content/static/css [no test files] | |
| ok golang.org/x/pkgsite/internal (cached) | |
| ? golang.org/x/pkgsite/internal/auth [no test files] | |
| ok golang.org/x/pkgsite/internal/complete (cached) | |
| ok golang.org/x/pkgsite/internal/config (cached) | |
| ok golang.org/x/pkgsite/internal/database (cached) | |
| ok golang.org/x/pkgsite/internal/dcensus (cached) | |
| ok golang.org/x/pkgsite/internal/derrors (cached) | |
| ok golang.org/x/pkgsite/internal/experiment (cached) | |
| ok golang.org/x/pkgsite/internal/fetch (cached) | |
| ok golang.org/x/pkgsite/internal/fetch/dochtml (cached) | |
| ok golang.org/x/pkgsite/internal/fetch/dochtml/internal/render (cached) | |
| ok golang.org/x/pkgsite/internal/fetch/internal/doc (cached) | |
| ok golang.org/x/pkgsite/internal/frontend (cached) | |
| ok golang.org/x/pkgsite/internal/index (cached) | |
| ok golang.org/x/pkgsite/internal/licenses (cached) | |
| ? golang.org/x/pkgsite/internal/log [no test files] | |
| ok golang.org/x/pkgsite/internal/middleware (cached) | |
| ok golang.org/x/pkgsite/internal/postgres (cached) | |
| ok golang.org/x/pkgsite/internal/proxy (cached) | |
| ok golang.org/x/pkgsite/internal/proxydatasource (cached) | |
| ok golang.org/x/pkgsite/internal/queue (cached) | |
| ok golang.org/x/pkgsite/internal/secrets (cached) | |
| ok golang.org/x/pkgsite/internal/source (cached) | |
| ok golang.org/x/pkgsite/internal/stdlib (cached) | |
| ok golang.org/x/pkgsite/internal/teeproxy (cached) | |
| ? golang.org/x/pkgsite/internal/testing/dbtest [no test files] | |
| ok golang.org/x/pkgsite/internal/testing/htmlcheck (cached) | |
| ok golang.org/x/pkgsite/internal/testing/integration (cached) | |
| ? golang.org/x/pkgsite/internal/testing/pagecheck [no test files] | |
| ? golang.org/x/pkgsite/internal/testing/sample [no test files] | |
| ? golang.org/x/pkgsite/internal/testing/testhelper [no test files] | |
| ok golang.org/x/pkgsite/internal/version (cached) | |
| 2020/06/19 12:08:28 Debug: locking github.com/something (6741443461166009828) ... | |
| 2020/06/19 12:08:28 Debug: locking github.com/something (6741443461166009828) succeeded | |
| 2020/06/19 12:08:28 Debug: locking github.com/something/else (-8153995561595371100) ... | |
| 2020/06/19 12:08:28 Debug: locking github.com/something/else (-8153995561595371100) succeeded | |
| 2020/06/19 12:08:28 Info: 1 total rows in search_documents match computed_imported_by_counts | |
| 2020/06/19 12:08:28 Info: 1 will change | |
| 2020/06/19 12:08:28 Info: 1 currently have a zero imported-by count | |
| 2020/06/19 12:08:28 Info: 0 of the non-zero rows will change by more than 5% | |
| 2020/06/19 12:08:28 Info: Writing completion data pipeline of size 9. | |
| 2020/06/19 12:08:28 Info: Renaming "completionsPopular-2020-06-19T12:08:28-04:00" to "completionsPopular" | |
| 2020/06/19 12:08:28 Info: Renaming "completionsRest-2020-06-19T12:08:28-04:00" to "completionsRest" | |
| 2020/06/19 12:08:28 Debug (map[fetch:github.com/take/down@v1.0.0]): memory after fetch of github.com/take/down@v1.0.0: 14M | |
| 2020/06/19 12:08:28 Info (map[fetch:github.com/take/down@v1.0.0]): fetch.FetchVersion succeeded for github.com/take/down@v1.0.0 | |
| 2020/06/19 12:08:28 Debug (map[fetch:github.com/take/down@v1.0.0]): locking github.com/take/down (2755050197703618584) ... | |
| 2020/06/19 12:08:28 Debug (map[fetch:github.com/take/down@v1.0.0]): locking github.com/take/down (2755050197703618584) succeeded | |
| 2020/06/19 12:08:28 Info (map[fetch:github.com/take/down@v1.0.0]): db.InsertModule succeeded for github.com/take/down@v1.0.0 | |
| 2020/06/19 12:08:28 Info (map[fetch:github.com/take/down@v1.0.0]): Updated module version state for github.com/take/down@v1.0.0: code=200, num_packages=1, err=<nil>; timings: db.InsertModule=0.010s, db.UpsertModuleVersionState=0.002s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.010s | |
| 2020/06/19 12:08:28 Debug (map[fetch:github.com/take/down@v1.0.0]): memory after fetch of github.com/take/down@v1.0.0: 14M | |
| 2020/06/19 12:08:28 Info (map[fetch:github.com/take/down@v1.0.0]): Error executing fetch: FetchModule("github.com/take/down", "v1.0.0"): proxy.Client.GetInfo("github.com/take/down", "v1.0.0"): Client.readBody("github.com/take/down", "v1.0.0", "info"): ctxhttp.Get(ctx, client, "https://127.0.0.1:50717/github.com/take/down/@v/v1.0.0.info"): not found (code 404) | |
| 2020/06/19 12:08:28 Info (map[fetch:github.com/take/down@v1.0.0]): github.com/take/down@v1.0.0: code=404, deleting | |
| 2020/06/19 12:08:28 Info (map[fetch:github.com/take/down@v1.0.0]): Updated module version state for github.com/take/down@v1.0.0: code=404, num_packages=0, err=fetchAndInsertModule("github.com/take/down", "v1.0.0"): FetchModule("github.com/take/down", "v1.0.0"): proxy.Client.GetInfo("github.com/take/down", "v1.0.0"): Client.readBody("github.com/take/down", "v1.0.0", "info"): ctxhttp.Get(ctx, client, "https://127.0.0.1:50717/github.com/take/down/@v/v1.0.0.info"): not found; timings: db.DeleteModule=0.024s, db.UpsertModuleVersionState=0.002s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.001s | |
| --- FAIL: TestFetchAndUpdateState_NotFound (0.15s) | |
| fetch_test.go:135: DB.GetVersionMap(ctx, tx, "github.com/take/down", "v1.0.0"): not found | |
| 2020/06/19 12:08:28 Info (map[fetch:github.com/my/module@v1.0.0]): path "github.com/my/module" matched excluded prefix "github.com/my/m" | |
| 2020/06/19 12:08:28 Info (map[fetch:github.com/my/module@v1.0.0]): github.com/my/module@v1.0.0: code=403, deleting | |
| 2020/06/19 12:08:28 Info (map[fetch:github.com/my/module@v1.0.0]): Updated module version state for github.com/my/module@v1.0.0: code=403, num_packages=0, err=fetchAndInsertModule("github.com/my/module", "v1.0.0"): excluded; timings: db.DeleteModule=0.002s, db.UpsertModuleVersionState=0.001s, db.UpsertVersionMap=0.002s | |
| --- FAIL: TestFetchAndUpdateState_Excluded (0.08s) | |
| fetch_test.go:186: DB.GetVersionMap(ctx, tx, "github.com/my/module", "v1.0.0"): not found | |
| 2020/06/19 12:08:28 Debug (map[fetch:build.constraints/module@badversion]): memory after fetch of build.constraints/module@badversion: 15M | |
| 2020/06/19 12:08:28 Info (map[fetch:build.constraints/module@badversion]): Error executing fetch: FetchModule("build.constraints/module", "badversion"): proxy.Client.GetInfo("build.constraints/module", "badversion"): Client.readBody("build.constraints/module", "badversion", "info"): ctxhttp.Get(ctx, client, "https://127.0.0.1:50721/build.constraints/module/@v/badversion.info"): not found (code 404) | |
| 2020/06/19 12:08:29 Info (map[fetch:build.constraints/module@v1.0.0]): error getting source info: source.LegacyModuleInfo(ctx, "build.constraints/module", "v1.0.0"): source.moduleInfoDynamic(ctx, client, "build.constraints/module", "v1.0.0"): fetchMeta(ctx, client, "build.constraints/module"): go-import and go-source meta tags not found: not found | |
| 2020/06/19 12:08:29 Debug (map[fetch:build.constraints/module@v1.0.0]): memory after fetch of build.constraints/module@v1.0.0: 17M | |
| 2020/06/19 12:08:29 Info (map[fetch:build.constraints/module@v1.0.0]): fetch.FetchVersion succeeded for build.constraints/module@v1.0.0 | |
| 2020/06/19 12:08:29 Debug (map[fetch:build.constraints/module@v1.0.0]): locking build.constraints/module (-739876647136779968) ... | |
| 2020/06/19 12:08:29 Debug (map[fetch:build.constraints/module@v1.0.0]): locking build.constraints/module (-739876647136779968) succeeded | |
| 2020/06/19 12:08:29 Info (map[fetch:build.constraints/module@v1.0.0]): db.InsertModule succeeded for build.constraints/module@v1.0.0 | |
| 2020/06/19 12:08:29 Info (map[fetch:build.constraints/module@v1.0.0]): Updated module version state for build.constraints/module@v1.0.0: code=290, num_packages=2, err=<nil>; timings: db.InsertModule=0.016s, db.UpsertModuleVersionState=0.003s, db.UpsertVersionMap=0.001s, fetch.FetchModule=1.080s | |
| 2020/06/19 12:08:29 Debug (map[fetch:github.com/mis/match@v1.0.0]): memory after fetch of github.com/mis/match@v1.0.0: 17M | |
| 2020/06/19 12:08:29 Info (map[fetch:github.com/mis/match@v1.0.0]): Error executing fetch: FetchModule("github.com/mis/match", "v1.0.0"): module path=github.com/mis/match, go.mod path=other: alternative module (code 491) | |
| 2020/06/19 12:08:29 Info (map[fetch:github.com/mis/match@v1.0.0]): github.com/mis/match@v1.0.0: code=491, deleting | |
| 2020/06/19 12:08:29 Info (map[fetch:github.com/mis/match@v1.0.0]): github.com/mis/match@v1.0.0: code=491, deleting older version from search | |
| 2020/06/19 12:08:29 Info (map[fetch:github.com/mis/match@v1.0.0]): Updated module version state for github.com/mis/match@v1.0.0: code=491, num_packages=0, err=fetchAndInsertModule("github.com/mis/match", "v1.0.0"): FetchModule("github.com/mis/match", "v1.0.0"): module path=github.com/mis/match, go.mod path=other: alternative module; timings: db.DeleteModule=0.002s, db.DeleteOlderVersionFromSearchDocuments=0.001s, db.UpsertModuleVersionState=0.002s, db.UpsertVersionMap=0.003s, fetch.FetchModule=0.002s | |
| --- FAIL: TestFetchAndUpdateState_Mismatch (0.10s) | |
| fetch_test.go:362: DB.GetVersionMap(ctx, tx, "github.com/mis/match", "v1.0.0"): not found | |
| 2020/06/19 12:08:30 Info (map[fetch:github.com/m@v0.9.0]): error getting source info: source.LegacyModuleInfo(ctx, "github.com/m", "v0.9.0"): source.moduleInfoDynamic(ctx, client, "github.com/m", "v0.9.0"): fetchMeta(ctx, client, "github.com/m"): go-import and go-source meta tags not found: not found | |
| 2020/06/19 12:08:30 Debug (map[fetch:github.com/m@v0.9.0]): memory after fetch of github.com/m@v0.9.0: 20M | |
| 2020/06/19 12:08:30 Info (map[fetch:github.com/m@v0.9.0]): fetch.FetchVersion succeeded for github.com/m@v0.9.0 | |
| 2020/06/19 12:08:30 Debug (map[fetch:github.com/m@v0.9.0]): locking github.com/m (2624847693534121557) ... | |
| 2020/06/19 12:08:30 Debug (map[fetch:github.com/m@v0.9.0]): locking github.com/m (2624847693534121557) succeeded | |
| 2020/06/19 12:08:30 Info (map[fetch:github.com/m@v0.9.0]): db.InsertModule succeeded for github.com/m@v0.9.0 | |
| 2020/06/19 12:08:30 Info (map[fetch:github.com/m@v0.9.0]): Updated module version state for github.com/m@v0.9.0: code=200, num_packages=1, err=<nil>; timings: db.InsertModule=0.012s, db.UpsertModuleVersionState=0.003s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.783s | |
| 2020/06/19 12:08:30 Debug (map[fetch:github.com/m@v1.0.0]): memory after fetch of github.com/m@v1.0.0: 21M | |
| 2020/06/19 12:08:30 Info (map[fetch:github.com/m@v1.0.0]): Error executing fetch: FetchModule("github.com/m", "v1.0.0"): module path=github.com/m, go.mod path=other: alternative module (code 491) | |
| 2020/06/19 12:08:30 Info (map[fetch:github.com/m@v1.0.0]): github.com/m@v1.0.0: code=491, deleting | |
| 2020/06/19 12:08:30 Info (map[fetch:github.com/m@v1.0.0]): github.com/m@v1.0.0: code=491, deleting older version from search | |
| 2020/06/19 12:08:30 Info (map[fetch:github.com/m@v1.0.0]): deleted 1 rows from search_documents | |
| 2020/06/19 12:08:30 Info (map[fetch:github.com/m@v1.0.0]): Updated module version state for github.com/m@v1.0.0: code=491, num_packages=0, err=fetchAndInsertModule("github.com/m", "v1.0.0"): FetchModule("github.com/m", "v1.0.0"): module path=github.com/m, go.mod path=other: alternative module; timings: db.DeleteModule=0.001s, db.DeleteOlderVersionFromSearchDocuments=0.001s, db.UpsertModuleVersionState=0.001s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.000s | |
| 2020/06/19 12:08:31 Debug (map[fetch:github.com/my/module@v1.0.0]): memory after fetch of github.com/my/module@v1.0.0: 77M | |
| 2020/06/19 12:08:31 Info (map[fetch:github.com/my/module@v1.0.0]): fetch.FetchVersion succeeded for github.com/my/module@v1.0.0 | |
| 2020/06/19 12:08:31 Debug (map[fetch:github.com/my/module@v1.0.0]): locking github.com/my/module (-3497685076882834591) ... | |
| 2020/06/19 12:08:31 Debug (map[fetch:github.com/my/module@v1.0.0]): locking github.com/my/module (-3497685076882834591) succeeded | |
| 2020/06/19 12:08:31 Info (map[fetch:github.com/my/module@v1.0.0]): db.InsertModule succeeded for github.com/my/module@v1.0.0 | |
| 2020/06/19 12:08:31 Info (map[fetch:github.com/my/module@v1.0.0]): Updated module version state for github.com/my/module@v1.0.0: code=290, num_packages=2, err=<nil>; timings: db.InsertModule=0.012s, db.UpsertModuleVersionState=0.002s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.004s | |
| 2020/06/19 12:08:31 Debug (map[fetch:github.com/my/module@v1.0.0]): memory after fetch of github.com/my/module@v1.0.0: 53M | |
| 2020/06/19 12:08:31 Info (map[fetch:github.com/my/module@v1.0.0]): fetch.FetchVersion succeeded for github.com/my/module@v1.0.0 | |
| 2020/06/19 12:08:31 Debug (map[fetch:github.com/my/module@v1.0.0]): locking github.com/my/module (-3497685076882834591) ... | |
| 2020/06/19 12:08:31 Debug (map[fetch:github.com/my/module@v1.0.0]): locking github.com/my/module (-3497685076882834591) succeeded | |
| 2020/06/19 12:08:31 Info (map[fetch:github.com/my/module@v1.0.0]): db.InsertModule succeeded for github.com/my/module@v1.0.0 | |
| 2020/06/19 12:08:31 Info (map[fetch:github.com/my/module@v1.0.0]): Updated module version state for github.com/my/module@v1.0.0: code=200, num_packages=3, err=<nil>; timings: db.InsertModule=0.014s, db.UpsertModuleVersionState=0.003s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.160s | |
| 2020/06/19 12:08:32 Info (map[fetch:my.mod/foo@v1.0.0]): error getting source info: source.LegacyModuleInfo(ctx, "my.mod/foo", "v1.0.0"): source.moduleInfoDynamic(ctx, client, "my.mod/foo", "v1.0.0"): fetchMeta(ctx, client, "my.mod/foo"): go-import and go-source meta tags not found: not found | |
| 2020/06/19 12:08:32 Debug (map[fetch:my.mod/foo@v1.0.0]): memory after fetch of my.mod/foo@v1.0.0: 55M | |
| 2020/06/19 12:08:32 Info (map[fetch:my.mod/foo@v1.0.0]): fetch.FetchVersion succeeded for my.mod/foo@v1.0.0 | |
| 2020/06/19 12:08:32 Debug (map[fetch:my.mod/foo@v1.0.0]): locking my.mod/foo (5799206896731396064) ... | |
| 2020/06/19 12:08:32 Debug (map[fetch:my.mod/foo@v1.0.0]): locking my.mod/foo (5799206896731396064) succeeded | |
| 2020/06/19 12:08:32 Info (map[fetch:my.mod/foo@v1.0.0]): db.InsertModule succeeded for my.mod/foo@v1.0.0 | |
| 2020/06/19 12:08:32 Info (map[fetch:my.mod/foo@v1.0.0]): Updated module version state for my.mod/foo@v1.0.0: code=200, num_packages=1, err=<nil>; timings: db.InsertModule=0.011s, db.UpsertModuleVersionState=0.002s, db.UpsertVersionMap=0.001s, fetch.FetchModule=1.078s | |
| 2020/06/19 12:08:32 Debug (map[fetch:github.com/my/module@v1.0.0]): memory after fetch of github.com/my/module@v1.0.0: 56M | |
| 2020/06/19 12:08:32 Info (map[fetch:github.com/my/module@v1.0.0]): fetch.FetchVersion succeeded for github.com/my/module@v1.0.0 | |
| 2020/06/19 12:08:32 Debug (map[fetch:github.com/my/module@v1.0.0]): locking github.com/my/module (-3497685076882834591) ... | |
| 2020/06/19 12:08:32 Debug (map[fetch:github.com/my/module@v1.0.0]): locking github.com/my/module (-3497685076882834591) succeeded | |
| 2020/06/19 12:08:32 Info (map[fetch:github.com/my/module@v1.0.0]): db.InsertModule succeeded for github.com/my/module@v1.0.0 | |
| 2020/06/19 12:08:32 Info (map[fetch:github.com/my/module@v1.0.0]): Updated module version state for github.com/my/module@v1.0.0: code=200, num_packages=1, err=<nil>; timings: db.InsertModule=0.012s, db.UpsertModuleVersionState=0.002s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.005s | |
| 2020/06/19 12:08:32 Debug (map[fetch:github.com/my/module@v1.0.0]): memory after fetch of github.com/my/module@v1.0.0: 58M | |
| 2020/06/19 12:08:32 Info (map[fetch:github.com/my/module@v1.0.0]): fetch.FetchVersion succeeded for github.com/my/module@v1.0.0 | |
| 2020/06/19 12:08:32 Debug (map[fetch:github.com/my/module@v1.0.0]): locking github.com/my/module (-3497685076882834591) ... | |
| 2020/06/19 12:08:32 Debug (map[fetch:github.com/my/module@v1.0.0]): locking github.com/my/module (-3497685076882834591) succeeded | |
| 2020/06/19 12:08:32 Info (map[fetch:github.com/my/module@v1.0.0]): db.InsertModule succeeded for github.com/my/module@v1.0.0 | |
| 2020/06/19 12:08:32 Info (map[fetch:github.com/my/module@v1.0.0]): Updated module version state for github.com/my/module@v1.0.0: code=200, num_packages=2, err=<nil>; timings: db.InsertModule=0.007s, db.UpsertModuleVersionState=0.001s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.003s | |
| 2020/06/19 12:08:32 Debug (map[fetch:github.com/my/module@v1.0.0]): memory after fetch of github.com/my/module@v1.0.0: 59M | |
| 2020/06/19 12:08:32 Info (map[fetch:github.com/my/module@v1.0.0]): fetch.FetchVersion succeeded for github.com/my/module@v1.0.0 | |
| 2020/06/19 12:08:32 Error (map[fetch:github.com/my/module@v1.0.0]): DB.InsertModule(ctx, Module("github.com/my/module", "v1.0.0")): comparePackages(ctx, "github.com/my/module", "v1.0.0"): expected package "github.com/my/module/bar" in module: db module insert invalid | |
| 2020/06/19 12:08:32 Info (map[fetch:github.com/my/module@v1.0.0]): github.com/my/module@v1.0.0: code=480, deleting | |
| 2020/06/19 12:08:32 Info (map[fetch:github.com/my/module@v1.0.0]): Updated module version state for github.com/my/module@v1.0.0: code=480, num_packages=1, err=fetchAndInsertModule("github.com/my/module", "v1.0.0"): DB.InsertModule(ctx, Module("github.com/my/module", "v1.0.0")): comparePackages(ctx, "github.com/my/module", "v1.0.0"): expected package "github.com/my/module/bar" in module: db module insert invalid; timings: db.DeleteModule=0.003s, db.InsertModule=0.001s, db.UpsertModuleVersionState=0.001s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.003s | |
| 2020/06/19 12:08:32 Debug (map[fetch:github.com/my/module@v1.0.0]): memory after fetch of github.com/my/module@v1.0.0: 60M | |
| 2020/06/19 12:08:32 Info (map[fetch:github.com/my/module@v1.0.0]): fetch.FetchVersion succeeded for github.com/my/module@v1.0.0 | |
| 2020/06/19 12:08:32 Debug (map[fetch:github.com/my/module@v1.0.0]): locking github.com/my/module (-3497685076882834591) ... | |
| 2020/06/19 12:08:32 Debug (map[fetch:github.com/my/module@v1.0.0]): locking github.com/my/module (-3497685076882834591) succeeded | |
| 2020/06/19 12:08:32 Info (map[fetch:github.com/my/module@v1.0.0]): db.InsertModule succeeded for github.com/my/module@v1.0.0 | |
| 2020/06/19 12:08:32 Info (map[fetch:github.com/my/module@v1.0.0]): Updated module version state for github.com/my/module@v1.0.0: code=200, num_packages=2, err=<nil>; timings: db.InsertModule=0.016s, db.UpsertModuleVersionState=0.003s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.006s | |
| 2020/06/19 12:08:33 Debug (map[fetch:github.com/my/module@latest]): memory after fetch of github.com/my/module@latest: 61M | |
| 2020/06/19 12:08:33 Info (map[fetch:github.com/my/module@latest]): fetch.FetchVersion succeeded for github.com/my/module@latest | |
| 2020/06/19 12:08:33 Debug (map[fetch:github.com/my/module@latest]): locking github.com/my/module (-3497685076882834591) ... | |
| 2020/06/19 12:08:33 Debug (map[fetch:github.com/my/module@latest]): locking github.com/my/module (-3497685076882834591) succeeded | |
| 2020/06/19 12:08:33 Info (map[fetch:github.com/my/module@latest]): db.InsertModule succeeded for github.com/my/module@latest | |
| 2020/06/19 12:08:33 Info (map[fetch:github.com/my/module@latest]): Updated module version state for github.com/my/module@v1.0.0: code=200, num_packages=2, err=<nil>; timings: db.InsertModule=0.016s, db.UpsertModuleVersionState=0.003s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.005s | |
| 2020/06/19 12:08:34 Info (map[fetch:nonredistributable.mod/module@v1.0.0]): error getting source info: source.LegacyModuleInfo(ctx, "nonredistributable.mod/module", "v1.0.0"): source.moduleInfoDynamic(ctx, client, "nonredistributable.mod/module", "v1.0.0"): fetchMeta(ctx, client, "nonredistributable.mod/module"): go-import and go-source meta tags not found: not found | |
| 2020/06/19 12:08:34 Info (map[fetch:nonredistributable.mod/module@v1.0.0]): nonredistributable.mod/module@v1.0.0/foo/LICENSE.md checker.Cover failed, skipping | |
| 2020/06/19 12:08:34 Debug (map[fetch:nonredistributable.mod/module@v1.0.0]): memory after fetch of nonredistributable.mod/module@v1.0.0: 62M | |
| 2020/06/19 12:08:34 Info (map[fetch:nonredistributable.mod/module@v1.0.0]): fetch.FetchVersion succeeded for nonredistributable.mod/module@v1.0.0 | |
| 2020/06/19 12:08:34 Debug (map[fetch:nonredistributable.mod/module@v1.0.0]): locking nonredistributable.mod/module (-7761895262062850408) ... | |
| 2020/06/19 12:08:34 Debug (map[fetch:nonredistributable.mod/module@v1.0.0]): locking nonredistributable.mod/module (-7761895262062850408) succeeded | |
| 2020/06/19 12:08:34 Info (map[fetch:nonredistributable.mod/module@v1.0.0]): db.InsertModule succeeded for nonredistributable.mod/module@v1.0.0 | |
| 2020/06/19 12:08:34 Info (map[fetch:nonredistributable.mod/module@v1.0.0]): Updated module version state for nonredistributable.mod/module@v1.0.0: code=200, num_packages=3, err=<nil>; timings: db.InsertModule=0.017s, db.UpsertModuleVersionState=0.003s, db.UpsertVersionMap=0.001s, fetch.FetchModule=1.086s | |
| 2020/06/19 12:08:35 Info (map[fetch:nonredistributable.mod/module@v1.0.0]): error getting source info: source.LegacyModuleInfo(ctx, "nonredistributable.mod/module", "v1.0.0"): source.moduleInfoDynamic(ctx, client, "nonredistributable.mod/module", "v1.0.0"): fetchMeta(ctx, client, "nonredistributable.mod/module"): go-import and go-source meta tags not found: not found | |
| 2020/06/19 12:08:35 Info (map[fetch:nonredistributable.mod/module@v1.0.0]): nonredistributable.mod/module@v1.0.0/foo/LICENSE.md checker.Cover failed, skipping | |
| 2020/06/19 12:08:35 Debug (map[fetch:nonredistributable.mod/module@v1.0.0]): memory after fetch of nonredistributable.mod/module@v1.0.0: 63M | |
| 2020/06/19 12:08:35 Info (map[fetch:nonredistributable.mod/module@v1.0.0]): fetch.FetchVersion succeeded for nonredistributable.mod/module@v1.0.0 | |
| 2020/06/19 12:08:35 Debug (map[fetch:nonredistributable.mod/module@v1.0.0]): locking nonredistributable.mod/module (-7761895262062850408) ... | |
| 2020/06/19 12:08:35 Debug (map[fetch:nonredistributable.mod/module@v1.0.0]): locking nonredistributable.mod/module (-7761895262062850408) succeeded | |
| 2020/06/19 12:08:35 Info (map[fetch:nonredistributable.mod/module@v1.0.0]): db.InsertModule succeeded for nonredistributable.mod/module@v1.0.0 | |
| 2020/06/19 12:08:35 Info (map[fetch:nonredistributable.mod/module@v1.0.0]): Updated module version state for nonredistributable.mod/module@v1.0.0: code=200, num_packages=3, err=<nil>; timings: db.InsertModule=0.016s, db.UpsertModuleVersionState=0.002s, db.UpsertVersionMap=0.001s, fetch.FetchModule=1.077s | |
| 2020/06/19 12:08:35 Debug (map[fetch:std@v1.12.5]): memory after fetch of std@v1.12.5: 82M | |
| 2020/06/19 12:08:35 Info (map[fetch:std@v1.12.5]): fetch.FetchVersion succeeded for std@v1.12.5 | |
| 2020/06/19 12:08:35 Debug (map[fetch:std@v1.12.5]): locking std (-2837360019337655052) ... | |
| 2020/06/19 12:08:35 Debug (map[fetch:std@v1.12.5]): locking std (-2837360019337655052) succeeded | |
| 2020/06/19 12:08:35 Info (map[fetch:std@v1.12.5]): db.InsertModule succeeded for std@v1.12.5 | |
| 2020/06/19 12:08:35 Info (map[fetch:std@v1.12.5]): Updated module version state for std@v1.12.5: code=200, num_packages=6, err=<nil>; timings: db.InsertModule=0.030s, db.UpsertModuleVersionState=0.003s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.073s | |
| 2020/06/19 12:08:35 Debug (map[fetch:std@v1.12.5]): memory after fetch of std@v1.12.5: 20M | |
| 2020/06/19 12:08:35 Info (map[fetch:std@v1.12.5]): fetch.FetchVersion succeeded for std@v1.12.5 | |
| 2020/06/19 12:08:35 Debug (map[fetch:std@v1.12.5]): locking std (-2837360019337655052) ... | |
| 2020/06/19 12:08:35 Debug (map[fetch:std@v1.12.5]): locking std (-2837360019337655052) succeeded | |
| 2020/06/19 12:08:35 Info (map[fetch:std@v1.12.5]): db.InsertModule succeeded for std@v1.12.5 | |
| 2020/06/19 12:08:35 Info (map[fetch:std@v1.12.5]): Updated module version state for std@v1.12.5: code=200, num_packages=6, err=<nil>; timings: db.InsertModule=0.032s, db.UpsertModuleVersionState=0.003s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.064s | |
| 2020/06/19 12:08:36 Debug (map[fetch:std@v1.12.5]): memory after fetch of std@v1.12.5: 20M | |
| 2020/06/19 12:08:36 Info (map[fetch:std@v1.12.5]): fetch.FetchVersion succeeded for std@v1.12.5 | |
| 2020/06/19 12:08:36 Debug (map[fetch:std@v1.12.5]): locking std (-2837360019337655052) ... | |
| 2020/06/19 12:08:36 Debug (map[fetch:std@v1.12.5]): locking std (-2837360019337655052) succeeded | |
| 2020/06/19 12:08:36 Info (map[fetch:std@v1.12.5]): db.InsertModule succeeded for std@v1.12.5 | |
| 2020/06/19 12:08:36 Info (map[fetch:std@v1.12.5]): Updated module version state for std@v1.12.5: code=200, num_packages=6, err=<nil>; timings: db.InsertModule=0.030s, db.UpsertModuleVersionState=0.003s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.070s | |
| 2020/06/19 12:08:37 Info (map[fetch:build.constraints/module@v1.0.0]): error getting source info: source.LegacyModuleInfo(ctx, "build.constraints/module", "v1.0.0"): source.moduleInfoDynamic(ctx, client, "build.constraints/module", "v1.0.0"): fetchMeta(ctx, client, "build.constraints/module"): go-import and go-source meta tags not found: not found | |
| 2020/06/19 12:08:37 Debug (map[fetch:build.constraints/module@v1.0.0]): memory after fetch of build.constraints/module@v1.0.0: 21M | |
| 2020/06/19 12:08:37 Info (map[fetch:build.constraints/module@v1.0.0]): fetch.FetchVersion succeeded for build.constraints/module@v1.0.0 | |
| 2020/06/19 12:08:37 Debug (map[fetch:build.constraints/module@v1.0.0]): locking build.constraints/module (-739876647136779968) ... | |
| 2020/06/19 12:08:37 Debug (map[fetch:build.constraints/module@v1.0.0]): locking build.constraints/module (-739876647136779968) succeeded | |
| 2020/06/19 12:08:37 Info (map[fetch:build.constraints/module@v1.0.0]): db.InsertModule succeeded for build.constraints/module@v1.0.0 | |
| 2020/06/19 12:08:37 Info (map[fetch:build.constraints/module@v1.0.0]): Updated module version state for build.constraints/module@v1.0.0: code=290, num_packages=2, err=<nil>; timings: db.InsertModule=0.012s, db.UpsertModuleVersionState=0.002s, db.UpsertVersionMap=0.001s, fetch.FetchModule=1.077s | |
| 2020/06/19 12:08:37 Error (map[fetch:my.mod/version@v1.0.0]): reading excluded_prefixes: context deadline exceeded | |
| 2020/06/19 12:08:37 Error (map[fetch:my.mod/version@v1.0.0]): updateVersionMapAndDeleteModulesWithErrors("my.mod/version", "v1.0.0", "v1.0.0", 500, fetchAndInsertModule("my.mod/version", "v1.0.0"): DB.IsExcluded(ctx, "my.mod/version"): context deadline exceeded): DB.UpsertVersionMap(ctx, tx, "my.mod/version", "v1.0.0", "v1.0.0"): context deadline exceeded | |
| 2020/06/19 12:08:37 Error (map[fetch:my.mod/version@v1.0.0]): UpsertModuleVersionState(ctx, "my.mod/version", "v1.0.0", "appVersionLabel", 0001-01-01 00:00:00 +0000 UTC, 500, "", updateVersionMapAndDeleteModulesWithErrors("my.mod/version", "v1.0.0", "v1.0.0", 500, fetchAndInsertModule("my.mod/version", "v1.0.0"): DB.IsExcluded(ctx, "my.mod/version"): context deadline exceeded): DB.UpsertVersionMap(ctx, tx, "my.mod/version", "v1.0.0", "v1.0.0"): context deadline exceeded: Transact(Default): db.BeginTx(): context deadline exceeded | |
| 2020/06/19 12:08:37 Error (map[fetch:my.mod/version@v1.0.0]): Failed to update module version state for my.mod/version@v1.0.0: code=500, num_packages=0, err=db.UpsertModuleVersionState: UpsertModuleVersionState(ctx, "my.mod/version", "v1.0.0", "appVersionLabel", 0001-01-01 00:00:00 +0000 UTC, 500, "", updateVersionMapAndDeleteModulesWithErrors("my.mod/version", "v1.0.0", "v1.0.0", 500, fetchAndInsertModule("my.mod/version", "v1.0.0"): DB.IsExcluded(ctx, "my.mod/version"): context deadline exceeded): DB.UpsertVersionMap(ctx, tx, "my.mod/version", "v1.0.0", "v1.0.0"): context deadline exceeded: Transact(Default): db.BeginTx(): context deadline exceeded, original error: updateVersionMapAndDeleteModulesWithErrors("my.mod/version", "v1.0.0", "v1.0.0", 500, fetchAndInsertModule("my.mod/version", "v1.0.0"): DB.IsExcluded(ctx, "my.mod/version"): context deadline exceeded): DB.UpsertVersionMap(ctx, tx, "my.mod/version", "v1.0.0", "v1.0.0"): context deadline exceeded; timings: db.UpsertModuleVersionState=0.000s, db.UpsertVersionMap=0.000s | |
| 2020/06/19 12:08:37 Info: Scheduling modules to be fetched: 2 new modules from index.golang.org | |
| 2020/06/19 12:08:37 Info: Successfully scheduled modules to be fetched: 2 new modules from index.golang.org | |
| 2020/06/19 12:08:37 Info: Fetch requested: "foo.com/bar" "v0.0.1" (workerCount = 10) | |
| 2020/06/19 12:08:37 Info: Fetch requested: "foo.com/foo" "v1.0.0" (workerCount = 10) | |
| 2020/06/19 12:08:37 Info (map[fetch:foo.com/foo@v1.0.0]): error getting source info: source.LegacyModuleInfo(ctx, "foo.com/foo", "v1.0.0"): source.moduleInfoDynamic(ctx, client, "foo.com/foo", "v1.0.0"): fetchMeta(ctx, client, "foo.com/foo"): go-import and go-source meta tags not found: not found | |
| 2020/06/19 12:08:37 Info (map[fetch:foo.com/bar@v0.0.1]): error getting source info: source.LegacyModuleInfo(ctx, "foo.com/bar", "v0.0.1"): source.moduleInfoDynamic(ctx, client, "foo.com/bar", "v0.0.1"): fetchMeta(ctx, client, "foo.com/bar"): go-import and go-source meta tags not found: not found | |
| 2020/06/19 12:08:37 Debug (map[fetch:foo.com/foo@v1.0.0]): memory after fetch of foo.com/foo@v1.0.0: 24M | |
| 2020/06/19 12:08:37 Info (map[fetch:foo.com/foo@v1.0.0]): fetch.FetchVersion succeeded for foo.com/foo@v1.0.0 | |
| 2020/06/19 12:08:37 Debug (map[fetch:foo.com/bar@v0.0.1]): memory after fetch of foo.com/bar@v0.0.1: 24M | |
| 2020/06/19 12:08:37 Info (map[fetch:foo.com/bar@v0.0.1]): fetch.FetchVersion succeeded for foo.com/bar@v0.0.1 | |
| 2020/06/19 12:08:37 Debug (map[fetch:foo.com/bar@v0.0.1]): locking foo.com/bar (5139751464559718596) ... | |
| 2020/06/19 12:08:37 Debug (map[fetch:foo.com/foo@v1.0.0]): locking foo.com/foo (5143421634373930111) ... | |
| 2020/06/19 12:08:37 Debug (map[fetch:foo.com/foo@v1.0.0]): locking foo.com/foo (5143421634373930111) succeeded | |
| 2020/06/19 12:08:37 Debug (map[fetch:foo.com/bar@v0.0.1]): locking foo.com/bar (5139751464559718596) succeeded | |
| 2020/06/19 12:08:37 Info (map[fetch:foo.com/bar@v0.0.1]): db.InsertModule succeeded for foo.com/bar@v0.0.1 | |
| 2020/06/19 12:08:37 Info (map[fetch:foo.com/foo@v1.0.0]): db.InsertModule succeeded for foo.com/foo@v1.0.0 | |
| 2020/06/19 12:08:37 Info (map[fetch:foo.com/bar@v0.0.1]): Updated module version state for foo.com/bar@v0.0.1: code=200, num_packages=1, err=<nil>; timings: db.InsertModule=0.009s, db.UpsertModuleVersionState=0.002s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.140s | |
| 2020/06/19 12:08:37 Info (map[fetch:foo.com/foo@v1.0.0]): Updated module version state for foo.com/foo@v1.0.0: code=200, num_packages=1, err=<nil>; timings: db.InsertModule=0.009s, db.UpsertModuleVersionState=0.002s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.140s | |
| 2020/06/19 12:08:37 Info: Scheduling modules to be fetched: 1 new modules from index.golang.org | |
| 2020/06/19 12:08:37 Info: Successfully scheduled modules to be fetched: 1 new modules from index.golang.org | |
| 2020/06/19 12:08:37 Info: Fetch requested: "foo.com/foo" "v1.0.0" (workerCount = 10) | |
| 2020/06/19 12:08:38 Info (map[fetch:foo.com/foo@v1.0.0]): error getting source info: source.LegacyModuleInfo(ctx, "foo.com/foo", "v1.0.0"): source.moduleInfoDynamic(ctx, client, "foo.com/foo", "v1.0.0"): fetchMeta(ctx, client, "foo.com/foo"): go-import and go-source meta tags not found: not found | |
| 2020/06/19 12:08:38 Debug (map[fetch:foo.com/foo@v1.0.0]): memory after fetch of foo.com/foo@v1.0.0: 25M | |
| 2020/06/19 12:08:38 Info (map[fetch:foo.com/foo@v1.0.0]): fetch.FetchVersion succeeded for foo.com/foo@v1.0.0 | |
| 2020/06/19 12:08:38 Debug (map[fetch:foo.com/foo@v1.0.0]): locking foo.com/foo (5143421634373930111) ... | |
| 2020/06/19 12:08:38 Debug (map[fetch:foo.com/foo@v1.0.0]): locking foo.com/foo (5143421634373930111) succeeded | |
| 2020/06/19 12:08:38 Info (map[fetch:foo.com/foo@v1.0.0]): db.InsertModule succeeded for foo.com/foo@v1.0.0 | |
| 2020/06/19 12:08:38 Info (map[fetch:foo.com/foo@v1.0.0]): Updated module version state for foo.com/foo@v1.0.0: code=200, num_packages=1, err=<nil>; timings: db.InsertModule=0.010s, db.UpsertModuleVersionState=0.002s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.882s | |
| 2020/06/19 12:08:38 Info: Scheduling modules to be fetched: 2 new modules from index.golang.org | |
| 2020/06/19 12:08:38 Info: Successfully scheduled modules to be fetched: 2 new modules from index.golang.org | |
| 2020/06/19 12:08:38 Info: Fetch requested: "foo.com/bar" "v0.0.1" (workerCount = 10) | |
| 2020/06/19 12:08:38 Info: Fetch requested: "foo.com/foo" "v1.0.0" (workerCount = 10) | |
| 2020/06/19 12:08:38 Debug (map[fetch:foo.com/bar@v0.0.1]): memory after fetch of foo.com/bar@v0.0.1: 27M | |
| 2020/06/19 12:08:38 Info (map[fetch:foo.com/bar@v0.0.1]): Error executing fetch: FetchModule("foo.com/bar", "v0.0.1"): proxy.Client.GetInfo("foo.com/bar", "v0.0.1"): Client.readBody("foo.com/bar", "v0.0.1", "info"): ctxhttp.Get(ctx, client, "https://127.0.0.1:50782/foo.com/bar/@v/v0.0.1.info"): not found (code 404) | |
| 2020/06/19 12:08:38 Info (map[fetch:foo.com/bar@v0.0.1]): foo.com/bar@v0.0.1: code=404, deleting | |
| 2020/06/19 12:08:38 Info (map[fetch:foo.com/bar@v0.0.1]): Updated module version state for foo.com/bar@v0.0.1: code=404, num_packages=0, err=fetchAndInsertModule("foo.com/bar", "v0.0.1"): FetchModule("foo.com/bar", "v0.0.1"): proxy.Client.GetInfo("foo.com/bar", "v0.0.1"): Client.readBody("foo.com/bar", "v0.0.1", "info"): ctxhttp.Get(ctx, client, "https://127.0.0.1:50782/foo.com/bar/@v/v0.0.1.info"): not found; timings: db.DeleteModule=0.003s, db.UpsertModuleVersionState=0.001s, db.UpsertVersionMap=0.002s, fetch.FetchModule=0.001s | |
| 2020/06/19 12:08:38 Error: FetchAndUpdateState("foo.com/bar", "v0.0.1"): fetchAndInsertModule("foo.com/bar", "v0.0.1"): FetchModule("foo.com/bar", "v0.0.1"): proxy.Client.GetInfo("foo.com/bar", "v0.0.1"): Client.readBody("foo.com/bar", "v0.0.1", "info"): ctxhttp.Get(ctx, client, "https://127.0.0.1:50782/foo.com/bar/@v/v0.0.1.info"): not found | |
| 2020/06/19 12:08:39 Info (map[fetch:foo.com/foo@v1.0.0]): error getting source info: source.LegacyModuleInfo(ctx, "foo.com/foo", "v1.0.0"): source.moduleInfoDynamic(ctx, client, "foo.com/foo", "v1.0.0"): fetchMeta(ctx, client, "foo.com/foo"): go-import and go-source meta tags not found: not found | |
| 2020/06/19 12:08:39 Debug (map[fetch:foo.com/foo@v1.0.0]): memory after fetch of foo.com/foo@v1.0.0: 27M | |
| 2020/06/19 12:08:39 Info (map[fetch:foo.com/foo@v1.0.0]): fetch.FetchVersion succeeded for foo.com/foo@v1.0.0 | |
| 2020/06/19 12:08:39 Debug (map[fetch:foo.com/foo@v1.0.0]): locking foo.com/foo (5143421634373930111) ... | |
| 2020/06/19 12:08:39 Debug (map[fetch:foo.com/foo@v1.0.0]): locking foo.com/foo (5143421634373930111) succeeded | |
| 2020/06/19 12:08:39 Info (map[fetch:foo.com/foo@v1.0.0]): db.InsertModule succeeded for foo.com/foo@v1.0.0 | |
| 2020/06/19 12:08:39 Info (map[fetch:foo.com/foo@v1.0.0]): Updated module version state for foo.com/foo@v1.0.0: code=200, num_packages=1, err=<nil>; timings: db.InsertModule=0.009s, db.UpsertModuleVersionState=0.002s, db.UpsertVersionMap=0.001s, fetch.FetchModule=0.882s | |
| 2020/06/19 12:08:39 Error: parsing query parameter "x": strconv.Atoi: parsing "bad": invalid syntax | |
| FAIL | |
| FAIL golang.org/x/pkgsite/internal/worker 12.092s | |
| ok golang.org/x/pkgsite/internal/xcontext (cached) | |
| FAIL | |
| command failed | |
| $ go test ./internal/secrets | |
| ok golang.org/x/pkgsite/internal/secrets (cached) | |
| FAILED; see errors above |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment