[I2025-03-10T08:36:49.068938-07:00 54689 0 sink.go:277] SinkServer: warm-up started [I2025-03-10T08:36:49.069026-07:00 54689 0 sink.go:350] SinkServer: starting HTTP server... [I2025-03-10T08:36:49.071513-07:00 54689 0 sink.go:282] SinkServer: warm-up ended [I2025-03-10T08:36:49.073553-07:00 54689 0 cmd_stream.go:492] rdb-stream: starting the test command - ["/home/swarming/.swarming/w/ir/cache/tools/bin/result_adapter" "go" "-v=false" "-dump-json" "/home/swarming/.swarming/w/ir/x/w/go.testjson" "--" "/home/swarming/.swarming/w/ir/x/w/goroot/bin/go" "test" "-json" "-short" "./..."] ok golang.org/x/tools/gopls/internal/analysis/deprecated 7.610s ok golang.org/x/tools/gopls/internal/analysis/embeddirective 5.192s ok golang.org/x/tools/gopls/internal/analysis/fillreturns 8.335s ok golang.org/x/tools/gopls/internal/analysis/fillstruct 6.151s ok golang.org/x/tools/gopls/internal/analysis/fillswitch 3.346s ok golang.org/x/tools/gopls/internal/analysis/gofix 10.188s ok golang.org/x/tools/gopls/internal/analysis/hostport 7.293s ok golang.org/x/tools/gopls/internal/analysis/infertypeargs 3.058s ok golang.org/x/tools/gopls/internal/analysis/modernize 7.978s ok golang.org/x/tools/gopls/internal/analysis/nonewvars 5.271s ok golang.org/x/tools/gopls/internal/analysis/noresultvalues 3.354s ok golang.org/x/tools/gopls/internal/analysis/simplifycompositelit 3.212s ok golang.org/x/tools/gopls/internal/analysis/simplifyrange 6.218s ok golang.org/x/tools/gopls/internal/analysis/simplifyslice 3.603s ok golang.org/x/tools/gopls/internal/analysis/unusedfunc 2.353s ok golang.org/x/tools/gopls/internal/analysis/unusedparams 8.257s ok golang.org/x/tools/gopls/internal/analysis/unusedvariable 4.478s ok golang.org/x/tools/gopls/internal/analysis/yield 4.578s ok golang.org/x/tools/gopls/internal/bloom 0.074s ok golang.org/x/tools/gopls/internal/cache 7.976s ok golang.org/x/tools/gopls/internal/cache/metadata 0.163s ok golang.org/x/tools/gopls/internal/cache/parsego 9.376s ok golang.org/x/tools/gopls/internal/cache/typerefs 0.189s ok golang.org/x/tools/gopls/internal/clonetest 0.158s ok golang.org/x/tools/gopls/internal/cmd 52.415s ok golang.org/x/tools/gopls/internal/debug 6.374s ok golang.org/x/tools/gopls/internal/doc/generate 22.649s ok golang.org/x/tools/gopls/internal/filecache 1.066s ok golang.org/x/tools/gopls/internal/fuzzy 0.198s ok golang.org/x/tools/gopls/internal/golang 10.618s ok golang.org/x/tools/gopls/internal/golang/completion 1.433s ok golang.org/x/tools/gopls/internal/golang/completion/snippet 0.252s ok golang.org/x/tools/gopls/internal/licenses 0.241s ok golang.org/x/tools/gopls/internal/lsprpc 12.993s ok golang.org/x/tools/gopls/internal/progress 0.509s ok golang.org/x/tools/gopls/internal/protocol 3.281s ok golang.org/x/tools/gopls/internal/protocol/command 14.276s ok golang.org/x/tools/gopls/internal/protocol/generate 0.376s ok golang.org/x/tools/gopls/internal/server 0.579s ok golang.org/x/tools/gopls/internal/settings 3.234s ok golang.org/x/tools/gopls/internal/telemetry 0.134s ok golang.org/x/tools/gopls/internal/template 0.554s ok golang.org/x/tools/gopls/internal/test/compare 0.141s ok golang.org/x/tools/gopls/internal/test/integration 0.885s ok golang.org/x/tools/gopls/internal/test/integration/bench 0.781s ok golang.org/x/tools/gopls/internal/test/integration/codelens 193.806s postfix_snippet_test.go:746: context deadline exceeded testing.go:1682: test executed panic(nil) or runtime.Goexit: subtest may have called FailNow on a parent test --- FAIL: TestPostfixSnippetCompletion/default/string_split (0.70s) #### Start Gopls Test Logs for "TestPostfixSnippetCompletion/default" [Trace - 08:48:03.990 AM] Sending request 'initialize - (1)'. Params: {"processId":0,"clientInfo":{"name":"fake.Editor","version":"v1.0.0"},"rootUri":"","capabilities":{"workspace":{"workspaceEdit":{"resourceOperations":["rename"]},"didChangeConfiguration":{},"didChangeWatchedFiles":{"dynamicRegistration":true},"configuration":true},"textDocument":{"completion":{"completionItem":{"snippetSupport":true,"tagSupport":{"valueSet":[1]},"insertReplaceSupport":true}},"documentSymbol":{"hierarchicalDocumentSymbolSupport":true},"codeAction":{"codeActionLiteralSupport":{"codeActionKind":{"valueSet":[""]}},"dataSupport":true,"resolveSupport":{"properties":["edit"]}},"publishDiagnostics":{},"semanticTokens":{"requests":{"full":true},"tokenTypes":["namespace","type","class","enum","interface","struct","typeParameter","parameter","variable","property","enumMember","event","function","method","macro","keyword","modifier","comment","string","number","regexp","operator","label"],"tokenModifiers":["declaration","definition","readonly","static","deprecated","abstract","async","modification","documentation","defaultLibrary","interface","struct","signature","pointer","array","map","slice","chan","string","number","bool","invalid"],"formats":null}},"window":{"workDoneProgress":true,"showDocument":{"support":true}}},"initializationOptions":{"completionBudget":"0s","diagnosticsDelay":"10ms","env":{"GO111MODULE":"","GOMODCACHE":"","GOPACKAGESDRIVER":"off","GOPATH":"/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/gopath","GOPROXY":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/proxy","GOSUMDB":"off","GOTOOLCHAIN":"local"},"experimentalPostfixCompletions":true,"verboseWorkDoneProgress":true},"trace":"messages","workspaceFolders":[{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work","name":"work"}]} [Trace - 08:48:03.994 AM] Received response 'initialize - (1)' in 4ms. Result: {"capabilities":{"textDocumentSync":{"openClose":true,"change":2,"save":{}},"completionProvider":{"triggerCharacters":["."]},"hoverProvider":true,"signatureHelpProvider":{"triggerCharacters":["(",","]},"definitionProvider":true,"typeDefinitionProvider":true,"implementationProvider":true,"referencesProvider":true,"documentHighlightProvider":true,"documentSymbolProvider":true,"codeActionProvider":{"codeActionKinds":["gopls.doc.features","quickfix","refactor.extract.constant","refactor.extract.constant-all","refactor.extract.function","refactor.extract.method","refactor.extract.toNewFile","refactor.extract.variable","refactor.extract.variable-all","refactor.inline.call","refactor.rewrite.changeQuote","refactor.rewrite.fillStruct","refactor.rewrite.fillSwitch","refactor.rewrite.invertIf","refactor.rewrite.joinLines","refactor.rewrite.removeUnusedParam","refactor.rewrite.splitLines","source.assembly","source.doc","source.fixAll","source.freesymbols","source.organizeImports"],"resolveProvider":true},"codeLensProvider":{},"documentLinkProvider":{},"workspaceSymbolProvider":true,"documentFormattingProvider":true,"renameProvider":true,"foldingRangeProvider":true,"selectionRangeProvider":true,"executeCommandProvider":{"commands":["gopls.add_dependency","gopls.add_import","gopls.add_telemetry_counters","gopls.add_test","gopls.apply_fix","gopls.assembly","gopls.change_signature","gopls.check_upgrades","gopls.client_open_url","gopls.diagnose_files","gopls.doc","gopls.edit_go_directive","gopls.extract_to_new_file","gopls.fetch_vulncheck_result","gopls.free_symbols","gopls.gc_details","gopls.generate","gopls.go_get_package","gopls.list_imports","gopls.list_known_packages","gopls.maybe_prompt_for_telemetry","gopls.mem_stats","gopls.modules","gopls.package_symbols","gopls.packages","gopls.regenerate_cgo","gopls.remove_dependency","gopls.reset_go_mod_diagnostics","gopls.run_go_work_command","gopls.run_govulncheck","gopls.run_tests","gopls.scan_imports","gopls.start_debugging","gopls.start_profile","gopls.stop_profile","gopls.tidy","gopls.update_go_sum","gopls.upgrade_dependency","gopls.vendor","gopls.views","gopls.vulncheck","gopls.workspace_stats"]},"callHierarchyProvider":true,"semanticTokensProvider":{"legend":{"tokenTypes":["namespace","type","typeParameter","parameter","variable","function","method","macro","keyword","comment","string","number","operator","label"],"tokenModifiers":["definition","readonly","defaultLibrary","array","bool","chan","format","interface","map","number","pointer","signature","slice","string","struct"]},"range":true,"full":true},"inlayHintProvider":{},"workspace":{"workspaceFolders":{"supported":true,"changeNotifications":"workspace/didChangeWorkspaceFolders"}}},"serverInfo":{"name":"gopls","version":"{\"GoVersion\":\"devel 22d5d09f1e39bf0ef77bfcf80388c676e7e91574\",\"Path\":\"golang.org/x/tools/gopls/internal/test/integration/completion.test\",\"Main\":{\"Path\":\"golang.org/x/tools/gopls\",\"Version\":\"(devel)\",\"Sum\":\"\",\"Replace\":null},\"Deps\":null,\"Settings\":[{\"Key\":\"-buildmode\",\"Value\":\"exe\"},{\"Key\":\"-compiler\",\"Value\":\"gc\"},{\"Key\":\"CGO_ENABLED\",\"Value\":\"1\"},{\"Key\":\"CGO_CFLAGS\",\"Value\":\"\"},{\"Key\":\"CGO_CPPFLAGS\",\"Value\":\"\"},{\"Key\":\"CGO_CXXFLAGS\",\"Value\":\"\"},{\"Key\":\"CGO_LDFLAGS\",\"Value\":\"\"},{\"Key\":\"GOARCH\",\"Value\":\"amd64\"},{\"Key\":\"GOOS\",\"Value\":\"openbsd\"},{\"Key\":\"GOAMD64\",\"Value\":\"v1\"}],\"Version\":\"(devel)\"}"}} [Trace - 08:48:03.996 AM] Sending notification 'initialized'. Params: {} [Trace - 08:48:03.996 AM] Received request 'window/workDoneProgress/create - (1)'. Params: {"token":"5412863278605038723"} [Trace - 08:48:03.996 AM] Sending response 'window/workDoneProgress/create - (1)' in 0ms. Result: [Trace - 08:48:03.997 AM] Received notification '$/progress'. Params: {"token":"5412863278605038723","value":{"kind":"begin","title":"diagnosing initial workspace load","message":"Calculating diagnostics for initial workspace load...","percentage":0}} [Trace - 08:48:03.997 AM] Received request 'window/workDoneProgress/create - (2)'. Params: {"token":"8194935161929383328"} [Trace - 08:48:03.997 AM] Sending response 'window/workDoneProgress/create - (2)' in 0ms. Result: [Trace - 08:48:03.998 AM] Received notification '$/progress'. Params: {"token":"8194935161929383328","value":{"kind":"begin","title":"Setting up workspace","message":"Loading packages...","percentage":0}} [Trace - 08:48:03.998 AM] Received request 'workspace/configuration - (3)'. Params: {"items":[{"scopeUri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work","section":"gopls"}]} [Trace - 08:48:03.999 AM] Sending response 'workspace/configuration - (3)' in 0ms. Result: [{"completionBudget":"0s","diagnosticsDelay":"10ms","env":{"GO111MODULE":"","GOMODCACHE":"","GOPACKAGESDRIVER":"off","GOPATH":"/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/gopath","GOPROXY":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/proxy","GOSUMDB":"off","GOTOOLCHAIN":"local"},"experimentalPostfixCompletions":true,"verboseWorkDoneProgress":true}] [Trace - 08:48:04.447 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:04 Created View (#42)\n\tdirectory=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work\n\tview_type=\"GoMod\"\n\troot_dir=\"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work\"\n\tgo_version=\"go version devel 22d5d09f1e39bf0ef77bfcf80388c676e7e91574 openbsd/amd64\"\n\tbuild_flags=[]\n\tenv={GOOS:openbsd GOARCH:amd64 GOCACHE:/home/swarming/.swarming/w/ir/x/w/gocache GOMODCACHE:/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/gopath/pkg/mod GOPATH:/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/gopath GOPRIVATE: GOFLAGS: GO111MODULE: GOTOOLCHAIN:local GOROOT:/home/swarming/.swarming/w/ir/x/w/goroot GoVersion:25 GoVersionOutput:go version devel 22d5d09f1e39bf0ef77bfcf80388c676e7e91574 openbsd/amd64\n ExplicitGOWORK: EffectiveGOPACKAGESDRIVER:}\n\tenv_overlay=[]\n"} [Trace - 08:48:04.994 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:04 go/packages.Load #94\n\tview_id=\"42\"\n\tsnapshot=0\n\tdirectory=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work\n\tquery=[/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/... builtin]\n\tpackages=1\n\tduration=545.536394ms\n"} [Trace - 08:48:04.996 AM] Received notification '$/progress'. Params: {"token":"8194935161929383328","value":{"kind":"end","message":"Finished loading packages."}} [Trace - 08:48:04.997 AM] Received request 'client/registerCapability - (4)'. Params: {"registrations":[{"id":"workspace/didChangeWatchedFiles-0","method":"workspace/didChangeWatchedFiles","registerOptions":{"watchers":[{"globPattern":"/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/**/*.{go,mod,sum,work}","kind":7},{"globPattern":"**/*.{mod,work}","kind":7}]}}]} [Trace - 08:48:04.998 AM] Sending response 'client/registerCapability - (4)' in 0ms. Result: [Trace - 08:48:04.998 AM] Received notification '$/progress'. Params: {"token":"5412863278605038723","value":{"kind":"end","message":"Done."}} [Trace - 08:48:04.999 AM] Received request 'window/workDoneProgress/create - (5)'. Params: {"token":"8367346110931987996"} [Trace - 08:48:04.999 AM] Sending response 'window/workDoneProgress/create - (5)' in 0ms. Result: [Trace - 08:48:05.000 AM] Received notification '$/progress'. Params: {"token":"8367346110931987996","value":{"kind":"begin","title":"Checking telemetry prompt","message":"Checking if gopls should prompt about telemetry...","percentage":0}} [Trace - 08:48:05.000 AM] Sending notification 'textDocument/didOpen'. Params: {"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","languageId":"go","version":1,"text":""}} [Trace - 08:48:05.001 AM] Received request 'window/workDoneProgress/create - (6)'. Params: {"token":"182550156649488841"} [Trace - 08:48:05.001 AM] Received notification '$/progress'. Params: {"token":"8367346110931987996","value":{"kind":"end","message":"Done."}} [Trace - 08:48:05.002 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":2,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar foo []int\n\tfoo.sort\n}"}]} [Trace - 08:48:05.002 AM] Sending response 'window/workDoneProgress/create - (6)' in 1ms. Result: [Trace - 08:48:05.002 AM] Sending request 'textDocument/completion - (2)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":4,"character":9}} [Trace - 08:48:05.002 AM] Received notification '$/progress'. Params: {"token":"182550156649488841","value":{"kind":"begin","title":"diagnosing opened files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:05.061 AM] Received request 'window/workDoneProgress/create - (7)'. Params: {"token":"3056208744967259902"} [Trace - 08:48:05.062 AM] Sending response 'window/workDoneProgress/create - (7)' in 0ms. Result: [Trace - 08:48:05.062 AM] Received notification '$/progress'. Params: {"token":"3056208744967259902","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:05.513 AM] Received notification '$/progress'. Params: {"token":"182550156649488841","value":{"kind":"end","message":"Done."}} [Trace - 08:48:06.139 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:06 go/packages.Load #97\n\tview_id=\"42\"\n\tsnapshot=2\n\tdirectory=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work\n\tquery=[file=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go]\n\tpackages=1\n\tduration=1.044562469s\n"} [Trace - 08:48:06.145 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:06 go/packages.Load #96\n\tview_id=\"42\"\n\tsnapshot=2\n\tdirectory=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work\n\tquery=[file=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go]\n\tpackages=1\n\tduration=1.051292853s\n"} [Trace - 08:48:06.150 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":2,"diagnostics":[{"range":{"start":{"line":4,"character":5},"end":{"line":4,"character":9}},"severity":1,"code":"MissingFieldOrMethod","codeDescription":{"href":"https://pkg.go.dev/golang.org/x/tools/internal/typesinternal#MissingFieldOrMethod"},"source":"compiler","message":"foo.sort undefined (type []int has no field or method sort)"}]} [Trace - 08:48:06.152 AM] Received response 'textDocument/completion - (2)' in 1149ms. Result: {"isIncomplete":true,"items":[{"label":"sort!","kind":15,"detail":"sort.Slice()","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"sort.Slice(foo, func(i, j int) bool {\n\t$0\n})","insert":{"start":{"line":4,"character":5},"end":{"line":4,"character":9}},"replace":{"start":{"line":4,"character":5},"end":{"line":4,"character":9}}},"additionalTextEdits":[{"range":{"start":{"line":4,"character":1},"end":{"line":4,"character":5}},"newText":""},{"range":{"start":{"line":1,"character":0},"end":{"line":1,"character":0}},"newText":"\nimport \"sort\"\n"}]}]} [Trace - 08:48:06.153 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":3,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nimport \"sort\"\n\nfunc _() {\n\tvar foo []int\n\tsort.Slice(foo, func(i, j int) bool {\n\t$0\n})\n}"}]} [Trace - 08:48:06.153 AM] Received request 'window/workDoneProgress/create - (8)'. Params: {"token":"1232363247757563073"} [Trace - 08:48:06.154 AM] Sending response 'window/workDoneProgress/create - (8)' in 0ms. Result: [Trace - 08:48:06.154 AM] Received notification '$/progress'. Params: {"token":"1232363247757563073","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:06.155 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":4,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nimport blahsort \"sort\"\n\nvar j int\n\nfunc _() {\n\tvar foo []int\n\tfoo.sort\n}"}]} [Trace - 08:48:06.156 AM] Sending request 'textDocument/completion - (3)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":8,"character":9}} [Trace - 08:48:06.156 AM] Received request 'window/workDoneProgress/create - (9)'. Params: {"token":"4956141053156202736"} [Trace - 08:48:06.157 AM] Received notification '$/progress'. Params: {"token":"3056208744967259902","value":{"kind":"end","message":"Done."}} [Trace - 08:48:06.157 AM] Sending response 'window/workDoneProgress/create - (9)' in 0ms. Result: [Trace - 08:48:06.157 AM] Received notification '$/progress'. Params: {"token":"4956141053156202736","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:06.562 AM] Received notification '$/progress'. Params: {"token":"1232363247757563073","value":{"kind":"end","message":"Done."}} [Trace - 08:48:07.783 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:07 go/packages.Load #99\n\tview_id=\"42\"\n\tsnapshot=4\n\tdirectory=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work\n\tquery=[file=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go]\n\tpackages=1\n\tduration=1.605872698s\n"} [Trace - 08:48:07.861 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:07 go/packages.Load #100\n\tview_id=\"42\"\n\tsnapshot=4\n\tdirectory=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work\n\tquery=[file=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go]\n\tpackages=1\n\tduration=1.649744519s\n"} [Trace - 08:48:07.926 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":4,"diagnostics":[{"range":{"start":{"line":2,"character":7},"end":{"line":2,"character":15}},"severity":1,"code":"UnusedImport","codeDescription":{"href":"https://pkg.go.dev/golang.org/x/tools/internal/typesinternal#UnusedImport"},"source":"compiler","message":"\"sort\" imported as blahsort and not used","tags":[1]},{"range":{"start":{"line":8,"character":5},"end":{"line":8,"character":9}},"severity":1,"code":"MissingFieldOrMethod","codeDescription":{"href":"https://pkg.go.dev/golang.org/x/tools/internal/typesinternal#MissingFieldOrMethod"},"source":"compiler","message":"foo.sort undefined (type []int has no field or method sort)"}]} [Trace - 08:48:07.927 AM] Received response 'textDocument/completion - (3)' in 1771ms. Result: {"isIncomplete":true,"items":[{"label":"sort!","kind":15,"detail":"sort.Slice()","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"blahsort.Slice(foo, func(i, j2 int) bool {\n\t$0\n})","insert":{"start":{"line":8,"character":5},"end":{"line":8,"character":9}},"replace":{"start":{"line":8,"character":5},"end":{"line":8,"character":9}}},"additionalTextEdits":[{"range":{"start":{"line":8,"character":1},"end":{"line":8,"character":5}},"newText":""}]}]} [Trace - 08:48:07.929 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":5,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nimport blahsort \"sort\"\n\nvar j int\n\nfunc _() {\n\tvar foo []int\n\tblahsort.Slice(foo, func(i, j2 int) bool {\n\t$0\n})\n}"}]} [Trace - 08:48:07.929 AM] Received request 'window/workDoneProgress/create - (10)'. Params: {"token":"8222340274760964681"} [Trace - 08:48:07.930 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":6,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar s struct { i []int }\n\ts.i.last\n}"}]} [Trace - 08:48:07.930 AM] Sending response 'window/workDoneProgress/create - (10)' in 0ms. Result: [Trace - 08:48:07.930 AM] Received notification '$/progress'. Params: {"token":"8222340274760964681","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:07.930 AM] Sending request 'textDocument/completion - (4)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":4,"character":9}} [Trace - 08:48:07.932 AM] Received request 'window/workDoneProgress/create - (11)'. Params: {"token":"3729896971314886227"} [Trace - 08:48:07.932 AM] Received notification '$/progress'. Params: {"token":"4956141053156202736","value":{"kind":"end","message":"Done."}} [Trace - 08:48:07.933 AM] Sending response 'window/workDoneProgress/create - (11)' in 1ms. Result: [Trace - 08:48:07.933 AM] Received notification '$/progress'. Params: {"token":"3729896971314886227","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:07.933 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":5,"diagnostics":[{"range":{"start":{"line":9,"character":1},"end":{"line":9,"character":1}},"severity":1,"source":"syntax","message":"expected statement, found 'ILLEGAL'"}]} [Trace - 08:48:07.936 AM] Received notification '$/progress'. Params: {"token":"8222340274760964681","value":{"kind":"end","message":"Done."}} [Trace - 08:48:08.842 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:08 go/packages.Load #102\n\tview_id=\"42\"\n\tsnapshot=6\n\tdirectory=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work\n\tquery=[file=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go]\n\tpackages=1\n\tduration=900.947491ms\n"} [Trace - 08:48:08.884 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:08 go/packages.Load #101\n\tview_id=\"42\"\n\tsnapshot=6\n\tdirectory=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work\n\tquery=[file=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go]\n\tpackages=1\n\tduration=946.269116ms\n"} [Trace - 08:48:08.889 AM] Received response 'textDocument/completion - (4)' in 958ms. Result: {"isIncomplete":true,"items":[{"label":"last!","kind":15,"detail":"s[len(s)-1]","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"s.i[len(s.i)-1]","insert":{"start":{"line":4,"character":5},"end":{"line":4,"character":9}},"replace":{"start":{"line":4,"character":5},"end":{"line":4,"character":9}}},"additionalTextEdits":[{"range":{"start":{"line":4,"character":1},"end":{"line":4,"character":5}},"newText":""}]}]} [Trace - 08:48:08.904 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":6,"diagnostics":[{"range":{"start":{"line":4,"character":5},"end":{"line":4,"character":9}},"severity":1,"code":"MissingFieldOrMethod","codeDescription":{"href":"https://pkg.go.dev/golang.org/x/tools/internal/typesinternal#MissingFieldOrMethod"},"source":"compiler","message":"s.i.last undefined (type []int has no field or method last)"}]} [Trace - 08:48:08.904 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":7,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar s struct { i []int }\n\ts.i[len(s.i)-1]\n}"}]} [Trace - 08:48:08.906 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":8,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar foo []int\n\tfoo.reverse\n}"}]} [Trace - 08:48:08.906 AM] Received request 'window/workDoneProgress/create - (12)'. Params: {"token":"5568957959295478467"} [Trace - 08:48:08.907 AM] Sending request 'textDocument/completion - (5)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":4,"character":12}} [Trace - 08:48:08.907 AM] Sending response 'window/workDoneProgress/create - (12)' in 0ms. Result: [Trace - 08:48:08.907 AM] Received notification '$/progress'. Params: {"token":"5568957959295478467","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:08.935 AM] Received request 'window/workDoneProgress/create - (13)'. Params: {"token":"1979831940177457995"} [Trace - 08:48:08.935 AM] Received notification '$/progress'. Params: {"token":"3729896971314886227","value":{"kind":"end","message":"Done."}} [Trace - 08:48:08.936 AM] Sending response 'window/workDoneProgress/create - (13)' in 0ms. Result: [Trace - 08:48:08.936 AM] Received notification '$/progress'. Params: {"token":"1979831940177457995","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:08.937 AM] Received notification '$/progress'. Params: {"token":"5568957959295478467","value":{"kind":"end","message":"Done."}} [Trace - 08:48:08.940 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":8,"diagnostics":[{"range":{"start":{"line":4,"character":5},"end":{"line":4,"character":12}},"severity":1,"code":"MissingFieldOrMethod","codeDescription":{"href":"https://pkg.go.dev/golang.org/x/tools/internal/typesinternal#MissingFieldOrMethod"},"source":"compiler","message":"foo.reverse undefined (type []int has no field or method reverse)"}]} [Trace - 08:48:08.942 AM] Received response 'textDocument/completion - (5)' in 35ms. Result: {"isIncomplete":true,"items":[{"label":"reverse!","kind":15,"detail":"reverse slice","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"slices.Reverse(foo)","insert":{"start":{"line":4,"character":5},"end":{"line":4,"character":12}},"replace":{"start":{"line":4,"character":5},"end":{"line":4,"character":12}}},"additionalTextEdits":[{"range":{"start":{"line":4,"character":1},"end":{"line":4,"character":5}},"newText":""},{"range":{"start":{"line":1,"character":0},"end":{"line":1,"character":0}},"newText":"\nimport \"slices\"\n"}]}]} [Trace - 08:48:08.942 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":9,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nimport \"slices\"\n\nfunc _() {\n\tvar foo []int\n\tslices.Reverse(foo)\n}"}]} [Trace - 08:48:08.943 AM] Received request 'window/workDoneProgress/create - (14)'. Params: {"token":"8902985930714667929"} [Trace - 08:48:08.943 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":10,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\ttype myThing struct{}\n\tvar foo []myThing\n\tfoo.range\n}"}]} [Trace - 08:48:08.944 AM] Sending response 'window/workDoneProgress/create - (14)' in 0ms. Result: [Trace - 08:48:08.944 AM] Received notification '$/progress'. Params: {"token":"8902985930714667929","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:08.944 AM] Sending request 'textDocument/completion - (6)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":5,"character":10}} [Trace - 08:48:08.987 AM] Received request 'window/workDoneProgress/create - (15)'. Params: {"token":"8417292760628125979"} [Trace - 08:48:08.988 AM] Sending response 'window/workDoneProgress/create - (15)' in 0ms. Result: [Trace - 08:48:08.989 AM] Received notification '$/progress'. Params: {"token":"1979831940177457995","value":{"kind":"end","message":"Done."}} [Trace - 08:48:08.989 AM] Received notification '$/progress'. Params: {"token":"8417292760628125979","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:09.411 AM] Received notification '$/progress'. Params: {"token":"8902985930714667929","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.125 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:10 go/packages.Load #105\n\tview_id=\"42\"\n\tsnapshot=10\n\tdirectory=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work\n\tquery=[file=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go]\n\tpackages=1\n\tduration=1.087650774s\n"} [Trace - 08:48:10.167 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":10,"diagnostics":[{"range":{"start":{"line":5,"character":5},"end":{"line":5,"character":5}},"severity":1,"source":"syntax","message":"expected selector or type assertion, found 'range'"}]} [Trace - 08:48:10.175 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:10 go/packages.Load #104\n\tview_id=\"42\"\n\tsnapshot=10\n\tdirectory=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work\n\tquery=[file=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go]\n\tpackages=1\n\tduration=1.13844457s\n"} [Trace - 08:48:10.180 AM] Received response 'textDocument/completion - (6)' in 1235ms. Result: {"isIncomplete":true,"items":[{"label":"range!","kind":15,"detail":"range over slice","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"for ${1:}, ${2:} := range foo {\n\t$0\n}","insert":{"start":{"line":5,"character":5},"end":{"line":5,"character":10}},"replace":{"start":{"line":5,"character":5},"end":{"line":5,"character":10}}},"additionalTextEdits":[{"range":{"start":{"line":5,"character":1},"end":{"line":5,"character":5}},"newText":""}]}]} [Trace - 08:48:10.181 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":11,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\ttype myThing struct{}\n\tvar foo []myThing\n\tfor ${1:}, ${2:} := range foo {\n\t$0\n}\n}"}]} [Trace - 08:48:10.183 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":12,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar foo []int\n\tfoo.append\n}"}]} [Trace - 08:48:10.183 AM] Received request 'window/workDoneProgress/create - (16)'. Params: {"token":"5624970753723653219"} [Trace - 08:48:10.186 AM] Sending request 'textDocument/completion - (7)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":4,"character":11}} [Trace - 08:48:10.186 AM] Sending response 'window/workDoneProgress/create - (16)' in 2ms. Result: [Trace - 08:48:10.186 AM] Received notification '$/progress'. Params: {"token":"5624970753723653219","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.189 AM] Received request 'window/workDoneProgress/create - (17)'. Params: {"token":"5784962310166833822"} [Trace - 08:48:10.190 AM] Sending response 'window/workDoneProgress/create - (17)' in 0ms. Result: [Trace - 08:48:10.191 AM] Received notification '$/progress'. Params: {"token":"5784962310166833822","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.196 AM] Received notification '$/progress'. Params: {"token":"5624970753723653219","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.197 AM] Received notification '$/progress'. Params: {"token":"8417292760628125979","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.201 AM] Received response 'textDocument/completion - (7)' in 15ms. Result: {"isIncomplete":true,"items":[{"label":"append!","kind":15,"detail":"append and re-assign slice","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"foo = append(foo, $0)","insert":{"start":{"line":4,"character":5},"end":{"line":4,"character":11}},"replace":{"start":{"line":4,"character":5},"end":{"line":4,"character":11}}},"additionalTextEdits":[{"range":{"start":{"line":4,"character":1},"end":{"line":4,"character":5}},"newText":""}]}]} [Trace - 08:48:10.201 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":13,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar foo []int\n\tfoo = append(foo, $0)\n}"}]} [Trace - 08:48:10.202 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":14,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar foo []int\n\tvar _ []int = foo.append\n}"}]} [Trace - 08:48:10.202 AM] Received request 'window/workDoneProgress/create - (18)'. Params: {"token":"5120209151536054268"} [Trace - 08:48:10.202 AM] Sending request 'textDocument/completion - (8)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":4,"character":25}} [Trace - 08:48:10.203 AM] Sending response 'window/workDoneProgress/create - (18)' in 0ms. Result: [Trace - 08:48:10.203 AM] Received notification '$/progress'. Params: {"token":"5120209151536054268","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.211 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":12,"diagnostics":[{"range":{"start":{"line":4,"character":5},"end":{"line":4,"character":11}},"severity":1,"code":"MissingFieldOrMethod","codeDescription":{"href":"https://pkg.go.dev/golang.org/x/tools/internal/typesinternal#MissingFieldOrMethod"},"source":"compiler","message":"foo.append undefined (type []int has no field or method append)"}]} [Trace - 08:48:10.212 AM] Received notification '$/progress'. Params: {"token":"5784962310166833822","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.213 AM] Received request 'window/workDoneProgress/create - (19)'. Params: {"token":"5122560161910215634"} [Trace - 08:48:10.213 AM] Sending response 'window/workDoneProgress/create - (19)' in 0ms. Result: [Trace - 08:48:10.213 AM] Received notification '$/progress'. Params: {"token":"5122560161910215634","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.220 AM] Received notification '$/progress'. Params: {"token":"5120209151536054268","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.223 AM] Received response 'textDocument/completion - (8)' in 20ms. Result: {"isIncomplete":true,"items":[{"label":"append!","kind":15,"detail":"append to slice","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"append(foo, $0)","insert":{"start":{"line":4,"character":19},"end":{"line":4,"character":25}},"replace":{"start":{"line":4,"character":19},"end":{"line":4,"character":25}}},"additionalTextEdits":[{"range":{"start":{"line":4,"character":15},"end":{"line":4,"character":19}},"newText":""}]}]} [Trace - 08:48:10.223 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":15,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar foo []int\n\tvar _ []int = append(foo, $0)\n}"}]} [Trace - 08:48:10.224 AM] Received request 'window/workDoneProgress/create - (20)'. Params: {"token":"5413697746449161557"} [Trace - 08:48:10.224 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":16,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar foo []int\n\tfoo.copy\n}"}]} [Trace - 08:48:10.225 AM] Sending response 'window/workDoneProgress/create - (20)' in 0ms. Result: [Trace - 08:48:10.225 AM] Sending request 'textDocument/completion - (9)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":4,"character":9}} [Trace - 08:48:10.225 AM] Received notification '$/progress'. Params: {"token":"5413697746449161557","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.244 AM] Received request 'window/workDoneProgress/create - (21)'. Params: {"token":"1295752333496752090"} [Trace - 08:48:10.244 AM] Sending response 'window/workDoneProgress/create - (21)' in 0ms. Result: [Trace - 08:48:10.264 AM] Received notification '$/progress'. Params: {"token":"1295752333496752090","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.265 AM] Received notification '$/progress'. Params: {"token":"5122560161910215634","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.268 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":15,"diagnostics":[{"range":{"start":{"line":4,"character":27},"end":{"line":4,"character":27}},"severity":1,"source":"syntax","message":"expected operand, found 'ILLEGAL'"}]} [Trace - 08:48:10.269 AM] Received notification '$/progress'. Params: {"token":"5413697746449161557","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.273 AM] Received response 'textDocument/completion - (9)' in 48ms. Result: {"isIncomplete":true,"items":[{"label":"copy!","kind":15,"detail":"duplicate slice","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"fooCopy := make([]int, len(foo))\ncopy(fooCopy, foo)\n","insert":{"start":{"line":4,"character":5},"end":{"line":4,"character":9}},"replace":{"start":{"line":4,"character":5},"end":{"line":4,"character":9}}},"additionalTextEdits":[{"range":{"start":{"line":4,"character":1},"end":{"line":4,"character":5}},"newText":""}]}]} [Trace - 08:48:10.274 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":17,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar foo []int\n\tfooCopy := make([]int, len(foo))\ncopy(fooCopy, foo)\n\n}"}]} [Trace - 08:48:10.274 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":16,"diagnostics":[{"range":{"start":{"line":4,"character":5},"end":{"line":4,"character":9}},"severity":1,"code":"MissingFieldOrMethod","codeDescription":{"href":"https://pkg.go.dev/golang.org/x/tools/internal/typesinternal#MissingFieldOrMethod"},"source":"compiler","message":"foo.copy undefined (type []int has no field or method copy)"}]} [Trace - 08:48:10.275 AM] Received request 'window/workDoneProgress/create - (22)'. Params: {"token":"8861805084040475493"} [Trace - 08:48:10.275 AM] Sending response 'window/workDoneProgress/create - (22)' in 0ms. Result: [Trace - 08:48:10.276 AM] Received notification '$/progress'. Params: {"token":"8861805084040475493","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.276 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":18,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar foo map[string]int\n\tfoo.range\n}"}]} [Trace - 08:48:10.277 AM] Sending request 'textDocument/completion - (10)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":4,"character":10}} [Trace - 08:48:10.279 AM] Received notification '$/progress'. Params: {"token":"1295752333496752090","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.279 AM] Received request 'window/workDoneProgress/create - (23)'. Params: {"token":"8662615798987282342"} [Trace - 08:48:10.287 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":17,"diagnostics":[]} [Trace - 08:48:10.288 AM] Sending response 'window/workDoneProgress/create - (23)' in 9ms. Result: [Trace - 08:48:10.289 AM] Received notification '$/progress'. Params: {"token":"8662615798987282342","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.291 AM] Received notification '$/progress'. Params: {"token":"8861805084040475493","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.299 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":18,"diagnostics":[{"range":{"start":{"line":4,"character":5},"end":{"line":4,"character":5}},"severity":1,"source":"syntax","message":"expected selector or type assertion, found 'range'"}]} [Trace - 08:48:10.300 AM] Received response 'textDocument/completion - (10)' in 22ms. Result: {"isIncomplete":true,"items":[{"label":"range!","kind":15,"detail":"range over map","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"for ${1:}, ${2:} := range foo {\n\t$0\n}","insert":{"start":{"line":4,"character":5},"end":{"line":4,"character":10}},"replace":{"start":{"line":4,"character":5},"end":{"line":4,"character":10}}},"additionalTextEdits":[{"range":{"start":{"line":4,"character":1},"end":{"line":4,"character":5}},"newText":""}]}]} [Trace - 08:48:10.300 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":19,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar foo map[string]int\n\tfor ${1:}, ${2:} := range foo {\n\t$0\n}\n}"}]} [Trace - 08:48:10.301 AM] Received request 'window/workDoneProgress/create - (24)'. Params: {"token":"2429342200369804697"} [Trace - 08:48:10.301 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":20,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar foo map[string]int\n\tfoo.clear\n}"}]} [Trace - 08:48:10.301 AM] Sending response 'window/workDoneProgress/create - (24)' in 0ms. Result: [Trace - 08:48:10.301 AM] Sending request 'textDocument/completion - (11)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":4,"character":10}} [Trace - 08:48:10.302 AM] Received notification '$/progress'. Params: {"token":"2429342200369804697","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.327 AM] Received request 'window/workDoneProgress/create - (25)'. Params: {"token":"665576512885486905"} [Trace - 08:48:10.328 AM] Received notification '$/progress'. Params: {"token":"8662615798987282342","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.328 AM] Sending response 'window/workDoneProgress/create - (25)' in 0ms. Result: [Trace - 08:48:10.328 AM] Received notification '$/progress'. Params: {"token":"665576512885486905","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.330 AM] Received notification '$/progress'. Params: {"token":"2429342200369804697","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.332 AM] Received response 'textDocument/completion - (11)' in 31ms. Result: {"isIncomplete":true,"items":[{"label":"clear!","kind":15,"detail":"clear map contents","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"for k := range foo {\n\tdelete(foo, k)\n}\n","insert":{"start":{"line":4,"character":5},"end":{"line":4,"character":10}},"replace":{"start":{"line":4,"character":5},"end":{"line":4,"character":10}}},"additionalTextEdits":[{"range":{"start":{"line":4,"character":1},"end":{"line":4,"character":5}},"newText":""}]}]} [Trace - 08:48:10.333 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":21,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar foo map[string]int\n\tfor k := range foo {\n\tdelete(foo, k)\n}\n\n}"}]} [Trace - 08:48:10.334 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":22,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar foo map[string]int\n\tfoo.keys\n}"}]} [Trace - 08:48:10.334 AM] Received request 'window/workDoneProgress/create - (26)'. Params: {"token":"4799600941293306399"} [Trace - 08:48:10.334 AM] Sending response 'window/workDoneProgress/create - (26)' in 0ms. Result: [Trace - 08:48:10.334 AM] Sending request 'textDocument/completion - (12)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":4,"character":9}} [Trace - 08:48:10.335 AM] Received notification '$/progress'. Params: {"token":"4799600941293306399","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.358 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":20,"diagnostics":[{"range":{"start":{"line":4,"character":5},"end":{"line":4,"character":10}},"severity":1,"code":"MissingFieldOrMethod","codeDescription":{"href":"https://pkg.go.dev/golang.org/x/tools/internal/typesinternal#MissingFieldOrMethod"},"source":"compiler","message":"foo.clear undefined (type map[string]int has no field or method clear)"}]} [Trace - 08:48:10.359 AM] Received request 'window/workDoneProgress/create - (27)'. Params: {"token":"2497444312472345062"} [Trace - 08:48:10.359 AM] Received notification '$/progress'. Params: {"token":"665576512885486905","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.359 AM] Sending response 'window/workDoneProgress/create - (27)' in 0ms. Result: [Trace - 08:48:10.360 AM] Received notification '$/progress'. Params: {"token":"2497444312472345062","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.386 AM] Received notification '$/progress'. Params: {"token":"4799600941293306399","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.388 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":22,"diagnostics":[{"range":{"start":{"line":4,"character":5},"end":{"line":4,"character":9}},"severity":1,"code":"MissingFieldOrMethod","codeDescription":{"href":"https://pkg.go.dev/golang.org/x/tools/internal/typesinternal#MissingFieldOrMethod"},"source":"compiler","message":"foo.keys undefined (type map[string]int has no field or method keys)"}]} [Trace - 08:48:10.389 AM] Received response 'textDocument/completion - (12)' in 54ms. Result: {"isIncomplete":true,"items":[{"label":"keys!","kind":15,"detail":"create slice of keys","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"keys := make([]string, 0, len(foo))\nfor k := range foo {\n\tkeys = append(keys, k)\n}\n","insert":{"start":{"line":4,"character":5},"end":{"line":4,"character":9}},"replace":{"start":{"line":4,"character":5},"end":{"line":4,"character":9}}},"additionalTextEdits":[{"range":{"start":{"line":4,"character":1},"end":{"line":4,"character":5}},"newText":""}]}]} [Trace - 08:48:10.390 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":23,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar foo map[string]int\n\tkeys := make([]string, 0, len(foo))\nfor k := range foo {\n\tkeys = append(keys, k)\n}\n\n}"}]} [Trace - 08:48:10.390 AM] Received request 'window/workDoneProgress/create - (28)'. Params: {"token":"6371319811033769387"} [Trace - 08:48:10.390 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":24,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tfoo := make(chan int)\n\tfoo.range\n}"}]} [Trace - 08:48:10.391 AM] Sending response 'window/workDoneProgress/create - (28)' in 0ms. Result: [Trace - 08:48:10.391 AM] Received notification '$/progress'. Params: {"token":"6371319811033769387","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.392 AM] Sending request 'textDocument/completion - (13)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":4,"character":10}} [Trace - 08:48:10.393 AM] Received notification '$/progress'. Params: {"token":"2497444312472345062","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.393 AM] Received request 'window/workDoneProgress/create - (29)'. Params: {"token":"2851674428211665625"} [Trace - 08:48:10.394 AM] Sending response 'window/workDoneProgress/create - (29)' in 0ms. Result: [Trace - 08:48:10.394 AM] Received notification '$/progress'. Params: {"token":"2851674428211665625","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.395 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":23,"diagnostics":[]} [Trace - 08:48:10.396 AM] Received notification '$/progress'. Params: {"token":"6371319811033769387","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.398 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":24,"diagnostics":[{"range":{"start":{"line":4,"character":5},"end":{"line":4,"character":5}},"severity":1,"source":"syntax","message":"expected selector or type assertion, found 'range'"}]} [Trace - 08:48:10.399 AM] Received response 'textDocument/completion - (13)' in 7ms. Result: {"isIncomplete":true,"items":[{"label":"range!","kind":15,"detail":"range over channel","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"for ${1:} := range foo {\n\t$0\n}","insert":{"start":{"line":4,"character":5},"end":{"line":4,"character":10}},"replace":{"start":{"line":4,"character":5},"end":{"line":4,"character":10}}},"additionalTextEdits":[{"range":{"start":{"line":4,"character":1},"end":{"line":4,"character":5}},"newText":""}]}]} [Trace - 08:48:10.400 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":25,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tfoo := make(chan int)\n\tfor ${1:} := range foo {\n\t$0\n}\n}"}]} [Trace - 08:48:10.401 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":26,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc foo() (int, error) { return 0, nil }\n\nfunc _() {\n\tfoo().var\n}"}]} [Trace - 08:48:10.401 AM] Received request 'window/workDoneProgress/create - (30)'. Params: {"token":"9206828273988868237"} [Trace - 08:48:10.401 AM] Sending response 'window/workDoneProgress/create - (30)' in 0ms. Result: [Trace - 08:48:10.402 AM] Sending request 'textDocument/completion - (14)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":5,"character":10}} [Trace - 08:48:10.402 AM] Received notification '$/progress'. Params: {"token":"9206828273988868237","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.418 AM] Received notification '$/progress'. Params: {"token":"2851674428211665625","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.418 AM] Received request 'window/workDoneProgress/create - (31)'. Params: {"token":"7565559121410573712"} [Trace - 08:48:10.419 AM] Sending response 'window/workDoneProgress/create - (31)' in 0ms. Result: [Trace - 08:48:10.420 AM] Received notification '$/progress'. Params: {"token":"7565559121410573712","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.420 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":25,"diagnostics":[{"range":{"start":{"line":4,"character":5},"end":{"line":4,"character":5}},"severity":1,"source":"syntax","message":"expected operand, found 'ILLEGAL'"}]} [Trace - 08:48:10.421 AM] Received notification '$/progress'. Params: {"token":"9206828273988868237","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.424 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":26,"diagnostics":[{"range":{"start":{"line":5,"character":7},"end":{"line":5,"character":7}},"severity":1,"source":"syntax","message":"expected selector or type assertion, found 'var'"}]} [Trace - 08:48:10.428 AM] Received response 'textDocument/completion - (14)' in 25ms. Result: {"isIncomplete":true,"items":[{"label":"var!","kind":15,"detail":"assign to variables","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"${1:}, ${2:} := foo()","insert":{"start":{"line":5,"character":7},"end":{"line":5,"character":10}},"replace":{"start":{"line":5,"character":7},"end":{"line":5,"character":10}}},"additionalTextEdits":[{"range":{"start":{"line":5,"character":1},"end":{"line":5,"character":7}},"newText":""}]},{"label":"variferr!","kind":15,"detail":"assign variables and check error","documentation":{"kind":"markdown","value":""},"sortText":"00001","insertTextFormat":2,"textEdit":{"newText":"${1:}, ${2:} := foo()\nif ${2:} != nil {\n\treturn \n}\n","insert":{"start":{"line":5,"character":7},"end":{"line":5,"character":10}},"replace":{"start":{"line":5,"character":7},"end":{"line":5,"character":10}}},"additionalTextEdits":[{"range":{"start":{"line":5,"character":1},"end":{"line":5,"character":7}},"newText":""}]}]} [Trace - 08:48:10.428 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":27,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc foo() (int, error) { return 0, nil }\n\nfunc _() {\n\t${1:}, ${2:} := foo()\n}"}]} [Trace - 08:48:10.429 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":28,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc foo() error { return nil }\n\nfunc _() {\n\tfoo().var\n}"}]} [Trace - 08:48:10.429 AM] Received request 'window/workDoneProgress/create - (32)'. Params: {"token":"8238254164383587673"} [Trace - 08:48:10.429 AM] Sending response 'window/workDoneProgress/create - (32)' in 0ms. Result: [Trace - 08:48:10.429 AM] Sending request 'textDocument/completion - (15)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":5,"character":10}} [Trace - 08:48:10.429 AM] Received notification '$/progress'. Params: {"token":"8238254164383587673","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.431 AM] Received notification '$/progress'. Params: {"token":"7565559121410573712","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.431 AM] Received request 'window/workDoneProgress/create - (33)'. Params: {"token":"7611686008735343448"} [Trace - 08:48:10.433 AM] Sending response 'window/workDoneProgress/create - (33)' in 2ms. Result: [Trace - 08:48:10.433 AM] Received notification '$/progress'. Params: {"token":"7611686008735343448","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.467 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":27,"diagnostics":[{"range":{"start":{"line":5,"character":1},"end":{"line":5,"character":1}},"severity":1,"source":"syntax","message":"expected statement, found 'ILLEGAL'"}]} [Trace - 08:48:10.469 AM] Received notification '$/progress'. Params: {"token":"8238254164383587673","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.476 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":28,"diagnostics":[{"range":{"start":{"line":5,"character":7},"end":{"line":5,"character":7}},"severity":1,"source":"syntax","message":"expected selector or type assertion, found 'var'"}]} [Trace - 08:48:10.476 AM] Received response 'textDocument/completion - (15)' in 47ms. Result: {"isIncomplete":true,"items":[{"label":"var!","kind":15,"detail":"assign to variable","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"${1:} := foo()","insert":{"start":{"line":5,"character":7},"end":{"line":5,"character":10}},"replace":{"start":{"line":5,"character":7},"end":{"line":5,"character":10}}},"additionalTextEdits":[{"range":{"start":{"line":5,"character":1},"end":{"line":5,"character":7}},"newText":""}]},{"label":"variferr!","kind":15,"detail":"assign variables and check error","documentation":{"kind":"markdown","value":""},"sortText":"00001","insertTextFormat":2,"textEdit":{"newText":"${1:} := foo()\nif ${1:} != nil {\n\treturn \n}\n","insert":{"start":{"line":5,"character":7},"end":{"line":5,"character":10}},"replace":{"start":{"line":5,"character":7},"end":{"line":5,"character":10}}},"additionalTextEdits":[{"range":{"start":{"line":5,"character":1},"end":{"line":5,"character":7}},"newText":""}]}]} [Trace - 08:48:10.477 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":29,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc foo() error { return nil }\n\nfunc _() {\n\t${1:} := foo()\n}"}]} [Trace - 08:48:10.477 AM] Received request 'window/workDoneProgress/create - (34)'. Params: {"token":"4218823582031641921"} [Trace - 08:48:10.478 AM] Sending response 'window/workDoneProgress/create - (34)' in 0ms. Result: [Trace - 08:48:10.478 AM] Received notification '$/progress'. Params: {"token":"4218823582031641921","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.478 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":30,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc foo() (int, int) { return 0, 0 }\n\nfunc _() {\n\tfoo().var\n}"}]} [Trace - 08:48:10.479 AM] Sending request 'textDocument/completion - (16)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":5,"character":10}} [Trace - 08:48:10.480 AM] Received notification '$/progress'. Params: {"token":"7611686008735343448","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.480 AM] Received request 'window/workDoneProgress/create - (35)'. Params: {"token":"5833555523281879191"} [Trace - 08:48:10.481 AM] Sending response 'window/workDoneProgress/create - (35)' in 0ms. Result: [Trace - 08:48:10.481 AM] Received notification '$/progress'. Params: {"token":"5833555523281879191","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.483 AM] Received notification '$/progress'. Params: {"token":"4218823582031641921","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.488 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":30,"diagnostics":[{"range":{"start":{"line":5,"character":7},"end":{"line":5,"character":7}},"severity":1,"source":"syntax","message":"expected selector or type assertion, found 'var'"}]} [Trace - 08:48:10.490 AM] Received response 'textDocument/completion - (16)' in 10ms. Result: {"isIncomplete":true,"items":[{"label":"var!","kind":15,"detail":"assign to variables","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"${1:}, ${2:} := foo()","insert":{"start":{"line":5,"character":7},"end":{"line":5,"character":10}},"replace":{"start":{"line":5,"character":7},"end":{"line":5,"character":10}}},"additionalTextEdits":[{"range":{"start":{"line":5,"character":1},"end":{"line":5,"character":7}},"newText":""}]}]} [Trace - 08:48:10.491 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":31,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc foo() (int, int) { return 0, 0 }\n\nfunc _() {\n\t${1:}, ${2:} := foo()\n}"}]} [Trace - 08:48:10.492 AM] Received request 'window/workDoneProgress/create - (36)'. Params: {"token":"4866881885806355139"} [Trace - 08:48:10.492 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":32,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc _() {\n\tvar foo int\n\tfoo.print\n}"}]} [Trace - 08:48:10.492 AM] Sending response 'window/workDoneProgress/create - (36)' in 0ms. Result: [Trace - 08:48:10.492 AM] Sending request 'textDocument/completion - (17)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":4,"character":10}} [Trace - 08:48:10.492 AM] Received notification '$/progress'. Params: {"token":"4866881885806355139","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.495 AM] Received notification '$/progress'. Params: {"token":"5833555523281879191","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.496 AM] Received request 'window/workDoneProgress/create - (37)'. Params: {"token":"7423665681517402044"} [Trace - 08:48:10.496 AM] Sending response 'window/workDoneProgress/create - (37)' in 0ms. Result: [Trace - 08:48:10.496 AM] Received notification '$/progress'. Params: {"token":"7423665681517402044","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.511 AM] Received notification '$/progress'. Params: {"token":"4866881885806355139","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.539 AM] Received response 'textDocument/completion - (17)' in 46ms. Result: {"isIncomplete":true,"items":[{"label":"print!","kind":15,"detail":"print to stdout","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"fmt.Printf(\"foo: %v\\n\", foo)","insert":{"start":{"line":4,"character":5},"end":{"line":4,"character":10}},"replace":{"start":{"line":4,"character":5},"end":{"line":4,"character":10}}},"additionalTextEdits":[{"range":{"start":{"line":4,"character":1},"end":{"line":4,"character":5}},"newText":""},{"range":{"start":{"line":1,"character":0},"end":{"line":1,"character":0}},"newText":"\nimport \"fmt\"\n"}]}]} [Trace - 08:48:10.539 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":33,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nimport \"fmt\"\n\nfunc _() {\n\tvar foo int\n\tfmt.Printf(\"foo: %v\\n\", foo)\n}"}]} [Trace - 08:48:10.540 AM] Received request 'window/workDoneProgress/create - (38)'. Params: {"token":"1566266872339510544"} [Trace - 08:48:10.540 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":34,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc foo() (int, error) { return 0, nil }\n\nfunc _() {\n\tfoo().print\n}"}]} [Trace - 08:48:10.541 AM] Sending request 'textDocument/completion - (18)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":5,"character":12}} [Trace - 08:48:10.541 AM] Sending response 'window/workDoneProgress/create - (38)' in 1ms. Result: [Trace - 08:48:10.541 AM] Received notification '$/progress'. Params: {"token":"1566266872339510544","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.542 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go","version":32,"diagnostics":[{"range":{"start":{"line":4,"character":5},"end":{"line":4,"character":10}},"severity":1,"code":"MissingFieldOrMethod","codeDescription":{"href":"https://pkg.go.dev/golang.org/x/tools/internal/typesinternal#MissingFieldOrMethod"},"source":"compiler","message":"foo.print undefined (type int has no field or method print)"}]} [Trace - 08:48:10.544 AM] Received notification '$/progress'. Params: {"token":"7423665681517402044","value":{"kind":"end","message":"Done."}} [Trace - 08:48:10.544 AM] Received request 'window/workDoneProgress/create - (39)'. Params: {"token":"6616957959374158670"} [Trace - 08:48:10.545 AM] Sending response 'window/workDoneProgress/create - (39)' in 0ms. Result: [Trace - 08:48:10.545 AM] Received notification '$/progress'. Params: {"token":"6616957959374158670","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:10.953 AM] Received notification '$/progress'. Params: {"token":"1566266872339510544","value":{"kind":"end","message":"Done."}} [Trace - 08:48:11.674 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:11 go/packages.Load #108\n\tview_id=\"42\"\n\tsnapshot=34\n\tdirectory=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work\n\tquery=[file=/home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go]\n\tpackages=1\n\tduration=1.118594661s\n"} [Trace - 08:48:11.687 AM] Received response 'textDocument/completion - (18)' in 1146ms. Result: {"isIncomplete":true,"items":[{"label":"print!","kind":15,"detail":"print to stdout","documentation":{"kind":"markdown","value":""},"preselect":true,"sortText":"00000","insertTextFormat":2,"textEdit":{"newText":"fmt.Println(foo())","insert":{"start":{"line":5,"character":7},"end":{"line":5,"character":12}},"replace":{"start":{"line":5,"character":7},"end":{"line":5,"character":12}}},"additionalTextEdits":[{"range":{"start":{"line":5,"character":1},"end":{"line":5,"character":7}},"newText":""},{"range":{"start":{"line":1,"character":0},"end":{"line":1,"character":0}},"newText":"\nimport \"fmt\"\n"}]}]} [Trace - 08:48:11.689 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":35,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nimport \"fmt\"\n\nfunc foo() (int, error) { return 0, nil }\n\nfunc _() {\n\tfmt.Println(foo())\n}"}]} [Trace - 08:48:11.691 AM] Received request 'window/workDoneProgress/create - (40)'. Params: {"token":"498166997495113847"} [Trace - 08:48:11.692 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":36,"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"contentChanges":[{"rangeLength":0,"text":"package foo\n\nfunc foo() []string {\n\tx := \"test\"\n\treturn x.split\n}"}]} [Trace - 08:48:11.692 AM] Sending response 'window/workDoneProgress/create - (40)' in 1ms. Result: [Trace - 08:48:11.693 AM] Received notification '$/progress'. Params: {"token":"498166997495113847","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:11.693 AM] Sending request 'textDocument/completion - (19)'. Params: {"context":{"triggerKind":0},"textDocument":{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-2122419438/TestPostfixSnippetCompletion/default/work/foo.go"},"position":{"line":4,"character":15}} [Trace - 08:48:11.698 AM] Received request 'window/workDoneProgress/create - (41)'. Params: {"token":"1945632317029801418"} [Trace - 08:48:11.699 AM] Sending response 'window/workDoneProgress/create - (41)' in 0ms. Result: [Trace - 08:48:11.699 AM] Received notification '$/progress'. Params: {"token":"1945632317029801418","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:11.704 AM] Received notification '$/progress'. Params: {"token":"6616957959374158670","value":{"kind":"end","message":"Done."}} [Trace - 08:48:12.112 AM] Received notification '$/progress'. Params: {"token":"498166997495113847","value":{"kind":"end","message":"Done."}} [Trace - 08:48:12.392 AM] Sending notification '$/cancelRequest'. Params: {"id":19} #### End Gopls Test Logs for "TestPostfixSnippetCompletion/default" panic: test timed out after 10m0s running tests: TestPostfixSnippetCompletion (9s) TestPostfixSnippetCompletion/default (9s) goroutine 33643 [running]: testing.(*M).startAlarm.func1() /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2490 +0x394 created by time.goFunc /home/swarming/.swarming/w/ir/x/w/goroot/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive]: testing.(*T).Run(0xc000102fc0, {0x127bf02?, 0xc00004da40?}, 0x13e4958) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1865 +0x431 testing.runTests.func1(0xc000102fc0) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2285 +0x37 testing.tRunner(0xc000102fc0, 0xc00004db80) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1798 +0xea testing.runTests(0xc0001c7a88, {0x1d509c0, 0x1f, 0x1f}, {0xc0000e6540?, 0xc00004dbf8?, 0x1d6da40?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2283 +0x4b4 testing.(*M).Run(0xc00059b360) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2148 +0x63a golang.org/x/tools/gopls/internal/test/integration.Main(0xc00059b360) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/regtest.go:197 +0x412 golang.org/x/tools/gopls/internal/test/integration/completion.TestMain(0x1d91bc0?) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/completion/completion_test.go:28 +0x1a main.main() _testmain.go:107 +0xa8 goroutine 181 [chan receive]: golang.org/x/tools/gopls/internal/protocol.LogEvent.func1.1() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/context.go:47 +0x47 created by golang.org/x/tools/gopls/internal/protocol.LogEvent.func1 in goroutine 165 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/context.go:46 +0x1a goroutine 196 [sleep, 5 minutes]: time.Sleep(0x45d964b800) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/time.go:338 +0x154 golang.org/x/tools/gopls/internal/filecache.gc({0xc000416640, 0x32}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/filecache/filecache.go:457 +0x134 created by golang.org/x/tools/gopls/internal/filecache.getCacheDir.func1 in goroutine 195 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/filecache/filecache.go:365 +0x1af goroutine 33641 [IO wait]: internal/poll.runtime_pollWait(0x2e31cbde8, 0x72) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc00726c540?, 0xc016192c00?, 0x1) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc00726c540, {0xc016192c00, 0x200, 0x200}) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_unix.go:165 +0x279 os.(*File).read(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file_posix.go:29 os.(*File).Read(0xc019c36db0, {0xc016192c00?, 0x0?, 0xc01ec30f50?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file.go:125 +0x4f bytes.(*Buffer).ReadFrom(0xc01626d140, {0x15500e0, 0xc01d8b8aa8}) /home/swarming/.swarming/w/ir/x/w/goroot/src/bytes/buffer.go:211 +0x98 io.copyBuffer({0x1550620, 0xc01626d140}, {0x15500e0, 0xc01d8b8aa8}, {0x0, 0x0, 0x0}) /home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go:415 +0x151 io.Copy(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go:388 os.genericWriteTo(0x480600?, {0x1550620, 0xc01626d140}) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file.go:276 +0x4f os.(*File).WriteTo(0x1d2d9b0?, {0x1550620?, 0xc01626d140?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file.go:254 +0x49 io.copyBuffer({0x1550620, 0xc01626d140}, {0x15501c0, 0xc019c36db0}, {0x0, 0x0, 0x0}) /home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go:411 +0x9d io.Copy(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go:388 os/exec.(*Cmd).writerDescriptor.func1() /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec/exec.go:596 +0x34 os/exec.(*Cmd).Start.func2(0x13e5650?) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec/exec.go:749 +0x2c created by os/exec.(*Cmd).Start in goroutine 31743 /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec/exec.go:748 +0x935 goroutine 31742 [chan receive]: testing.(*T).Run(0xc018332c40, {0x1228406?, 0x124e550?}, 0xc01e988640) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1865 +0x431 golang.org/x/tools/gopls/internal/test/integration.(*Runner).Run(0xc0000e25a0, 0xc018332c40, {0x12917ee, 0x26}, 0xc01e9b01c0, {0xc0173eff50, 0x1, 0xc01e9b01c0?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/runner.go:158 +0x445 golang.org/x/tools/gopls/internal/test/integration.configuredRunner.Run({{0xc0173eff50?, 0xc01e99bd40?, 0x1280eed?}}, 0xc018332c40, {0x12917ee, 0x26}, 0xc01e9b01c0) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/regtest.go:77 +0x147 golang.org/x/tools/gopls/internal/test/integration/completion.TestPostfixSnippetCompletion(0xc018332c40) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/completion/postfix_snippet_test.go:735 +0x13e testing.tRunner(0xc018332c40, 0x13e4958) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1798 +0xea created by testing.(*T).Run in goroutine 1 /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1857 +0x413 goroutine 33642 [syscall]: syscall.syscall6(0x49dda0, 0x24d5, 0xc006bd9ebc, 0x0, 0xc007a4be60, 0x0, 0x0) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/sys_openbsd3.go:54 +0x35 syscall.wait4(0xc006bd9ec0?, 0x41b385?, 0x90?, 0x1201fc0?) /home/swarming/.swarming/w/ir/x/w/goroot/src/syscall/zsyscall_openbsd_amd64.go:43 +0x45 syscall.Wait4(0x13?, 0xc006bd9ef4, 0xc00ea60360?, 0xc006bd9f60?) /home/swarming/.swarming/w/ir/x/w/goroot/src/syscall/syscall_bsd.go:144 +0x25 os.(*Process).pidWait.func1(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec_unix.go:64 os.ignoringEINTR2[...](...) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file_posix.go:261 os.(*Process).pidWait(0xc017f2f740) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec_unix.go:63 +0x85 os.(*Process).wait(0xc006bd9fc0?) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec_unix.go:28 +0x1a os.(*Process).Wait(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec.go:340 os/exec.(*Cmd).Wait(0xc00002e180) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec/exec.go:922 +0x45 golang.org/x/tools/internal/gocommand.runCmdContext.func4() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:378 +0x25 created by golang.org/x/tools/internal/gocommand.runCmdContext in goroutine 31743 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:377 +0x349 goroutine 31743 [select]: golang.org/x/tools/internal/gocommand.runCmdContext({0x155a658, 0x1d91bc0}, 0xc00002e180) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:387 +0x44d golang.org/x/tools/internal/gocommand.(*Invocation).run(0xc01e9a54e0, {0x155a658, 0x1d91bc0}, {0x1550620, 0xc01626d110}, {0x1550620, 0xc01626d140}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:285 +0xdfa golang.org/x/tools/internal/gocommand.(*Invocation).runWithFriendlyError(0xc01e9a54a8?, {0x155a658, 0x1d91bc0}, {0x1550620?, 0xc01626d110?}, {0x1550620, 0xc01626d140}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:187 +0x4f golang.org/x/tools/internal/gocommand.(*Runner).runConcurrent(0xc01e63b358, {0x155a658, 0x1d91bc0}, {{0x1223cd7, 0x5}, {0xc011c07500, 0x1, 0x1}, {0x0, 0x0, ...}, ...}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:124 +0x15f golang.org/x/tools/internal/gocommand.(*Runner).RunRaw(0xc01e63b358, {0x155a658, 0x1d91bc0}, {{0x1223cd7, 0x5}, {0xc011c07500, 0x1, 0x1}, {0x0, 0x0, ...}, ...}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:98 +0x370 golang.org/x/tools/gopls/internal/test/integration/fake.(*Sandbox).RunGoCommand(0xc01e63b320, {0x155a658, 0x1d91bc0}, {0xc01d4e7e00, 0x5c}, {0x1223cd7, 0x5}, {0xc011c07500, 0x1, 0x1}, ...) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/fake/sandbox.go:249 +0x248 golang.org/x/tools/gopls/internal/test/integration/fake.(*Sandbox).Close(0xc01e63b320) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/fake/sandbox.go:279 +0xc5 golang.org/x/tools/gopls/internal/test/integration.(*Runner).Run.func1.1() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/runner.go:197 +0x34 runtime.Goexit() /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/panic.go:615 +0x5e testing.(*T).Run(0xc018332e00, {0x1239538?, 0x6?}, 0xc00aef03d8) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1868 +0x43d golang.org/x/tools/gopls/internal/test/integration/completion.TestPostfixSnippetCompletion.func1(0xc018332e00, 0xc01e80a640) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/completion/postfix_snippet_test.go:739 +0x85 golang.org/x/tools/gopls/internal/test/integration.(*Runner).Run.func1(0xc018332e00) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/runner.go:239 +0x8eb testing.tRunner(0xc018332e00, 0xc01e988640) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1798 +0xea created by testing.(*T).Run in goroutine 31742 /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1857 +0x413 FAIL golang.org/x/tools/gopls/internal/test/integration/completion 604.892s ok golang.org/x/tools/gopls/internal/test/integration/debug 10.477s runner.go:238: context deadline exceeded #### Start Gopls Test Logs for "TestCreateOnlyXTest/default" [Trace - 08:48:08.876 AM] Sending request 'initialize - (1)'. Params: {"processId":0,"clientInfo":{"name":"fake.Editor","version":"v1.0.0"},"rootUri":"","capabilities":{"workspace":{"workspaceEdit":{"resourceOperations":["rename"]},"didChangeConfiguration":{},"didChangeWatchedFiles":{"dynamicRegistration":true},"configuration":true},"textDocument":{"completion":{"completionItem":{"snippetSupport":true,"tagSupport":{"valueSet":[1]},"insertReplaceSupport":true}},"documentSymbol":{"hierarchicalDocumentSymbolSupport":true},"codeAction":{"codeActionLiteralSupport":{"codeActionKind":{"valueSet":[""]}},"dataSupport":true,"resolveSupport":{"properties":["edit"]}},"publishDiagnostics":{},"semanticTokens":{"requests":{"full":true},"tokenTypes":["namespace","type","class","enum","interface","struct","typeParameter","parameter","variable","property","enumMember","event","function","method","macro","keyword","modifier","comment","string","number","regexp","operator","label"],"tokenModifiers":["declaration","definition","readonly","static","deprecated","abstract","async","modification","documentation","defaultLibrary","interface","struct","signature","pointer","array","map","slice","chan","string","number","bool","invalid"],"formats":null}},"window":{"workDoneProgress":true,"showDocument":{"support":true}}},"initializationOptions":{"completionBudget":"0s","diagnosticsDelay":"10ms","env":{"GO111MODULE":"","GOMODCACHE":"","GOPACKAGESDRIVER":"off","GOPATH":"/home/swarming/.swarming/w/ir/x/t/gopls-test-4196324837/TestCreateOnlyXTest/default/gopath","GOPROXY":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-4196324837/TestCreateOnlyXTest/default/proxy","GOSUMDB":"off","GOTOOLCHAIN":"local"},"verboseWorkDoneProgress":true},"trace":"messages","workspaceFolders":[{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-4196324837/TestCreateOnlyXTest/default/work","name":"work"}]} [Trace - 08:48:08.880 AM] Received response 'initialize - (1)' in 4ms. Result: {"capabilities":{"textDocumentSync":{"openClose":true,"change":2,"save":{}},"completionProvider":{"triggerCharacters":["."]},"hoverProvider":true,"signatureHelpProvider":{"triggerCharacters":["(",","]},"definitionProvider":true,"typeDefinitionProvider":true,"implementationProvider":true,"referencesProvider":true,"documentHighlightProvider":true,"documentSymbolProvider":true,"codeActionProvider":{"codeActionKinds":["gopls.doc.features","quickfix","refactor.extract.constant","refactor.extract.constant-all","refactor.extract.function","refactor.extract.method","refactor.extract.toNewFile","refactor.extract.variable","refactor.extract.variable-all","refactor.inline.call","refactor.rewrite.changeQuote","refactor.rewrite.fillStruct","refactor.rewrite.fillSwitch","refactor.rewrite.invertIf","refactor.rewrite.joinLines","refactor.rewrite.removeUnusedParam","refactor.rewrite.splitLines","source.assembly","source.doc","source.fixAll","source.freesymbols","source.organizeImports"],"resolveProvider":true},"codeLensProvider":{},"documentLinkProvider":{},"workspaceSymbolProvider":true,"documentFormattingProvider":true,"renameProvider":true,"foldingRangeProvider":true,"selectionRangeProvider":true,"executeCommandProvider":{"commands":["gopls.add_dependency","gopls.add_import","gopls.add_telemetry_counters","gopls.add_test","gopls.apply_fix","gopls.assembly","gopls.change_signature","gopls.check_upgrades","gopls.client_open_url","gopls.diagnose_files","gopls.doc","gopls.edit_go_directive","gopls.extract_to_new_file","gopls.fetch_vulncheck_result","gopls.free_symbols","gopls.gc_details","gopls.generate","gopls.go_get_package","gopls.list_imports","gopls.list_known_packages","gopls.maybe_prompt_for_telemetry","gopls.mem_stats","gopls.modules","gopls.package_symbols","gopls.packages","gopls.regenerate_cgo","gopls.remove_dependency","gopls.reset_go_mod_diagnostics","gopls.run_go_work_command","gopls.run_govulncheck","gopls.run_tests","gopls.scan_imports","gopls.start_debugging","gopls.start_profile","gopls.stop_profile","gopls.tidy","gopls.update_go_sum","gopls.upgrade_dependency","gopls.vendor","gopls.views","gopls.vulncheck","gopls.workspace_stats"]},"callHierarchyProvider":true,"semanticTokensProvider":{"legend":{"tokenTypes":["namespace","type","typeParameter","parameter","variable","function","method","macro","keyword","comment","string","number","operator","label"],"tokenModifiers":["definition","readonly","defaultLibrary","array","bool","chan","format","interface","map","number","pointer","signature","slice","string","struct"]},"range":true,"full":true},"inlayHintProvider":{},"workspace":{"workspaceFolders":{"supported":true,"changeNotifications":"workspace/didChangeWorkspaceFolders"}}},"serverInfo":{"name":"gopls","version":"{\"GoVersion\":\"devel 22d5d09f1e39bf0ef77bfcf80388c676e7e91574\",\"Path\":\"golang.org/x/tools/gopls/internal/test/integration/diagnostics.test\",\"Main\":{\"Path\":\"golang.org/x/tools/gopls\",\"Version\":\"(devel)\",\"Sum\":\"\",\"Replace\":null},\"Deps\":null,\"Settings\":[{\"Key\":\"-buildmode\",\"Value\":\"exe\"},{\"Key\":\"-compiler\",\"Value\":\"gc\"},{\"Key\":\"CGO_ENABLED\",\"Value\":\"1\"},{\"Key\":\"CGO_CFLAGS\",\"Value\":\"\"},{\"Key\":\"CGO_CPPFLAGS\",\"Value\":\"\"},{\"Key\":\"CGO_CXXFLAGS\",\"Value\":\"\"},{\"Key\":\"CGO_LDFLAGS\",\"Value\":\"\"},{\"Key\":\"GOARCH\",\"Value\":\"amd64\"},{\"Key\":\"GOOS\",\"Value\":\"openbsd\"},{\"Key\":\"GOAMD64\",\"Value\":\"v1\"}],\"Version\":\"(devel)\"}"}} [Trace - 08:48:08.882 AM] Sending notification 'initialized'. Params: {} [Trace - 08:48:08.882 AM] Received request 'window/workDoneProgress/create - (1)'. Params: {"token":"8060369036146829772"} [Trace - 08:48:08.882 AM] Sending response 'window/workDoneProgress/create - (1)' in 0ms. Result: [Trace - 08:48:08.883 AM] Received notification '$/progress'. Params: {"token":"8060369036146829772","value":{"kind":"begin","title":"diagnosing initial workspace load","message":"Calculating diagnostics for initial workspace load...","percentage":0}} [Trace - 08:48:08.883 AM] Received request 'window/workDoneProgress/create - (2)'. Params: {"token":"4716013188444409669"} [Trace - 08:48:08.884 AM] Sending response 'window/workDoneProgress/create - (2)' in 0ms. Result: [Trace - 08:48:08.884 AM] Received notification '$/progress'. Params: {"token":"4716013188444409669","value":{"kind":"begin","title":"Setting up workspace","message":"Loading packages...","percentage":0}} [Trace - 08:48:08.884 AM] Received request 'workspace/configuration - (3)'. Params: {"items":[{"scopeUri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-4196324837/TestCreateOnlyXTest/default/work","section":"gopls"}]} [Trace - 08:48:08.885 AM] Sending response 'workspace/configuration - (3)' in 0ms. Result: [{"completionBudget":"0s","diagnosticsDelay":"10ms","env":{"GO111MODULE":"","GOMODCACHE":"","GOPACKAGESDRIVER":"off","GOPATH":"/home/swarming/.swarming/w/ir/x/t/gopls-test-4196324837/TestCreateOnlyXTest/default/gopath","GOPROXY":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-4196324837/TestCreateOnlyXTest/default/proxy","GOSUMDB":"off","GOTOOLCHAIN":"local"},"verboseWorkDoneProgress":true}] [Trace - 08:48:09.734 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:09 Created View (#42)\n\tdirectory=/home/swarming/.swarming/w/ir/x/t/gopls-test-4196324837/TestCreateOnlyXTest/default/work\n\tview_type=\"GoMod\"\n\troot_dir=\"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-4196324837/TestCreateOnlyXTest/default/work\"\n\tgo_version=\"go version devel 22d5d09f1e39bf0ef77bfcf80388c676e7e91574 openbsd/amd64\"\n\tbuild_flags=[]\n\tenv={GOOS:openbsd GOARCH:amd64 GOCACHE:/home/swarming/.swarming/w/ir/x/w/gocache GOMODCACHE:/home/swarming/.swarming/w/ir/x/t/gopls-test-4196324837/TestCreateOnlyXTest/default/gopath/pkg/mod GOPATH:/home/swarming/.swarming/w/ir/x/t/gopls-test-4196324837/TestCreateOnlyXTest/default/gopath GOPRIVATE: GOFLAGS: GO111MODULE: GOTOOLCHAIN:local GOROOT:/home/swarming/.swarming/w/ir/x/w/goroot GoVersion:25 GoVersionOutput:go version devel 22d5d09f1e39bf0ef77bfcf80388c676e7e91574 openbsd/amd64\n ExplicitGOWORK: EffectiveGOPACKAGESDRIVER:}\n\tenv_overlay=[]\n"} #### End Gopls Test Logs for "TestCreateOnlyXTest/default" panic: test timed out after 10m0s running tests: TestCreateOnlyXTest (2s) TestCreateOnlyXTest/default (2s) goroutine 42371 [running]: testing.(*M).startAlarm.func1() /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2490 +0x394 created by time.goFunc /home/swarming/.swarming/w/ir/x/w/goroot/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive]: testing.(*T).Run(0xc000498fc0, {0x124ea68?, 0xc00004da40?}, 0x13d54e8) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1865 +0x431 testing.runTests.func1(0xc000498fc0) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2285 +0x37 testing.tRunner(0xc000498fc0, 0xc00004db80) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1798 +0xea testing.runTests(0xc00011c1f8, {0x1d412c0, 0x49, 0x49}, {0xc0001926c0?, 0xc00004dbf8?, 0x1d59d20?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2283 +0x4b4 testing.(*M).Run(0xc0000ccb40) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2148 +0x63a golang.org/x/tools/gopls/internal/test/integration.Main(0xc0000ccb40) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/regtest.go:197 +0x412 golang.org/x/tools/gopls/internal/test/integration/diagnostics.TestMain(0x1d7dea0?) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/diagnostics/diagnostics_test.go:24 +0x1a main.main() _testmain.go:191 +0xa8 goroutine 41845 [chan receive]: golang.org/x/tools/gopls/internal/lsprpc.(*streamServer).ServeStream(0xc00f7ac9f0, {0x1548020, 0xc011cec390}, {0x154c6a8, 0xc0001c67d0}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/lsprpc/lsprpc.go:93 +0x7d0 created by golang.org/x/tools/internal/jsonrpc2/servertest.(*PipeServer).Connect in goroutine 42239 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/servertest/servertest.go:84 +0x191 goroutine 42238 [chan receive]: testing.(*T).Run(0xc01414d880, {0x1219fa2?, 0x1240427?}, 0xc01b771e50) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1865 +0x431 golang.org/x/tools/gopls/internal/test/integration.(*Runner).Run(0xc0001c06c0, 0xc01414d880, {0x12a9ad2, 0x59}, 0x13d58c8, {0x0, 0x0, 0x4b1873?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/runner.go:158 +0x445 golang.org/x/tools/gopls/internal/test/integration.Run(...) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/regtest.go:53 golang.org/x/tools/gopls/internal/test/integration/diagnostics.TestCreateOnlyXTest(0xc01414d880?) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/diagnostics/diagnostics_test.go:885 +0x3d testing.tRunner(0xc01414d880, 0x13d54e8) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1798 +0xea created by testing.(*T).Run in goroutine 1 /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1857 +0x413 goroutine 42319 [sync.WaitGroup.Wait]: sync.runtime_SemacquireWaitGroup(0xc0179e80c0?) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/sema.go:110 +0x25 sync.(*WaitGroup).Wait(0xc00d0f1980?) /home/swarming/.swarming/w/ir/x/w/goroot/src/sync/waitgroup.go:118 +0x48 golang.org/x/tools/gopls/internal/server.(*server).addFolders(0xc00d0f1980, {0x1548020, 0xc012ac6780}, {0xc0170f72a0, 0x1, 0x4775b9?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/server/general.go:366 +0x8ca golang.org/x/tools/gopls/internal/server.(*server).Initialized(0xc00d0f1980, {0x1548020?, 0xc012ac66c0?}, 0x1015880?) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/server/general.go:218 +0x2b7 golang.org/x/tools/gopls/internal/protocol.serverDispatch({0x1548020, 0xc012ac66c0}, {0x1567180, 0xc00d0f1980}, 0xc012ac66f0, {0x2b08a74d8, 0xc00f7ad710}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/tsserver.go:275 +0x6f4 golang.org/x/tools/gopls/internal/lsprpc.(*streamServer).ServeStream.ServerHandler.func3({0x1548020, 0xc012ac66c0}, 0xc012ac66f0, {0x2b08a74d8, 0xc00f7ad710}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/protocol.go:160 +0x85 golang.org/x/tools/gopls/internal/lsprpc.(*streamServer).ServeStream.handshaker.func4({0x1548020, 0xc012ac66c0}, 0xc012ac66f0, {0x2b08a74d8, 0xc00f7ad710}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/lsprpc/lsprpc.go:509 +0x909 golang.org/x/tools/gopls/internal/protocol.Handlers.MustReplyHandler.func1({0x1548020, 0xc012ac66c0}, 0xc012d54618, {0x2b08a74d8, 0xc00f7ad710}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/handler.go:35 +0xc6 golang.org/x/tools/gopls/internal/protocol.Handlers.AsyncHandler.func2.2() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/handler.go:104 +0x96 created by golang.org/x/tools/gopls/internal/protocol.Handlers.AsyncHandler.func2 in goroutine 41952 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/handler.go:101 +0x22f goroutine 167 [chan receive]: golang.org/x/tools/gopls/internal/protocol.LogEvent.func1.1() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/context.go:47 +0x47 created by golang.org/x/tools/gopls/internal/protocol.LogEvent.func1 in goroutine 103 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/context.go:46 +0x1a goroutine 42239 [select]: golang.org/x/tools/internal/jsonrpc2.(*conn).Call(0xc0001c6820, {0x1548218, 0xc009e55430}, {0x121d28d, 0x8}, {0x0?, 0x0?}, {0x0, 0x0}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/conn.go:137 +0x8f6 golang.org/x/tools/gopls/internal/protocol.clientConn.Call({{0x154c6a8, 0xc0001c6820}}, {0x1548218, 0xc009e55430}, {0x121d28d?, 0xc0076c3000?}, {0x0?, 0x0?}, {0x0, 0x0}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/protocol.go:67 +0x75 golang.org/x/tools/gopls/internal/protocol.(*serverDispatcher).Shutdown(0x11f4ec0?, {0x1548218?, 0xc009e55430?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/tsserver.go:1002 +0x3d golang.org/x/tools/gopls/internal/test/integration/fake.(*Editor).Shutdown(0x1?, {0x1548218?, 0xc009e55430?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/fake/editor.go:206 +0x32 golang.org/x/tools/gopls/internal/test/integration/fake.(*Editor).Close(0xc0067ea588, {0x1548218, 0xc009e55430}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/fake/editor.go:228 +0x4c golang.org/x/tools/gopls/internal/test/integration.(*Runner).Run.func1.2() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/runner.go:233 +0x136 runtime.Goexit() /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/panic.go:615 +0x5e testing.(*common).FailNow(0xc01414da40) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1042 +0x4a testing.(*common).Fatal(0xc01414da40, {0xc009e553e0?, 0xc00f7ac780?, 0xc0005c08a0?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1119 +0x54 golang.org/x/tools/gopls/internal/test/integration.(*Env).Await(0xc0116a5180, {0xc01f40c4b0, 0x1, 0x1}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/env.go:316 +0xe9 golang.org/x/tools/gopls/internal/test/integration.(*Runner).Run.func1(0xc01414da40) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/runner.go:238 +0x8ce testing.tRunner(0xc01414da40, 0xc01b771e50) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1798 +0xea created by testing.(*T).Run in goroutine 42238 /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1857 +0x413 goroutine 712 [sleep, 5 minutes]: time.Sleep(0x45d964b800) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/time.go:338 +0x154 golang.org/x/tools/gopls/internal/filecache.gc({0xc00681afc0, 0x32}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/filecache/filecache.go:457 +0x134 created by golang.org/x/tools/gopls/internal/filecache.getCacheDir.func1 in goroutine 774 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/filecache/filecache.go:365 +0x1af goroutine 42343 [chan send]: golang.org/x/tools/go/packages.goListDriver.func1() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/golist.go:165 +0x205 created by golang.org/x/tools/go/packages.goListDriver in goroutine 42342 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/golist.go:161 +0x32e goroutine 41851 [select]: golang.org/x/tools/gopls/internal/cache.(*Snapshot).AwaitInitialized(0xc009b38b40, {0x1548020, 0xc012ac6780}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/snapshot.go:1190 +0x6b golang.org/x/tools/gopls/internal/server.(*server).addFolders.func2() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/server/general.go:348 +0x48 created by golang.org/x/tools/gopls/internal/server.(*server).addFolders in goroutine 42319 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/server/general.go:347 +0x805 goroutine 41850 [sync.WaitGroup.Wait]: sync.runtime_SemacquireWaitGroup(0xc01f40c750?) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/sema.go:110 +0x25 sync.(*WaitGroup).Wait(0x11c0080?) /home/swarming/.swarming/w/ir/x/w/goroot/src/sync/waitgroup.go:118 +0x48 golang.org/x/sync/errgroup.(*Group).Wait(0xc007f92980) /home/swarming/.swarming/w/ir/x/w/gopath/pkg/mod/golang.org/x/sync@v0.12.0/errgroup/errgroup.go:56 +0x1e golang.org/x/tools/go/packages.callDriverOnChunks(0xc017a33c60, 0xc0002356c8, {0xc01f40c738, 0x1, 0xc007cc94b0?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/packages.go:399 +0x1d7 golang.org/x/tools/go/packages.defaultDriver(0xc0002356c8, {0xc017a33b60?, 0xc007cc94f8?, 0x3f?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/packages.go:347 +0x167 golang.org/x/tools/go/packages.Load(0xc009b38b40?, {0xc017a33b60, 0x2, 0x2}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/packages.go:271 +0x51 golang.org/x/tools/gopls/internal/cache.(*Snapshot).load(0xc009b38b40, {0x1548058, 0xc0001c7810}, 0x1, {0xc017a33b40, 0x2, 0xc00006f908?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/load.go:130 +0xb5a golang.org/x/tools/gopls/internal/cache.(*Snapshot).initialize(0xc009b38b40, {0x1548058, 0xc0001c7810}, 0x1) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/view.go:700 +0x38c golang.org/x/tools/gopls/internal/cache.(*Session).createView.func3() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/session.go:300 +0x3e created by golang.org/x/tools/gopls/internal/cache.(*Session).createView in goroutine 42319 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/session.go:298 +0x1bf7 goroutine 42342 [select]: golang.org/x/tools/internal/gocommand.runCmdContext({0x1548020, 0xc00061af60}, 0xc007576d80) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:387 +0x44d golang.org/x/tools/internal/gocommand.(*Invocation).run(0xc00691cbc0, {0x1548020, 0xc00061af60}, {0x153dfe0, 0xc00061b080}, {0x153dfe0, 0xc00061b0b0}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:285 +0xdfa golang.org/x/tools/internal/gocommand.(*Invocation).runWithFriendlyError(0xc00691cb88?, {0x1548020, 0xc00061af60}, {0x153dfe0?, 0xc00061b080?}, {0x153dfe0, 0xc00061b0b0}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:187 +0x4f golang.org/x/tools/internal/gocommand.(*Runner).runConcurrent(0xc017a33c40, {0x1548020, 0xc00061af60}, {{0x1214928, 0x4}, {0xc0003acb60, 0xb, 0xe}, {0x0, 0x0, ...}, ...}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:124 +0x15f golang.org/x/tools/internal/gocommand.(*Runner).RunRaw(0xc017a33c40, {0x15480c8, 0xc009f9aa80}, {{0x1214928, 0x4}, {0xc0003acb60, 0xb, 0xe}, {0x0, 0x0, ...}, ...}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:98 +0x370 golang.org/x/tools/go/packages.(*golistState).invokeGo(0xc0002356c8?, {0x1214928?, 0x2?}, {0xc0003acb60, 0xb, 0xe?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/golist.go:872 +0x1db golang.org/x/tools/go/packages.(*golistState).createDriverResponse(0xc009fb0580, {0xc017a33c80, 0x2, 0x2}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/golist.go:388 +0xa7 golang.org/x/tools/go/packages.goListDriver(0xc0002356c8, 0xc017a33c40, {0x0, 0x0}, {0xc017a33b60, 0x2, 0xc00dcbbee8?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/golist.go:210 +0x7ff golang.org/x/tools/go/packages.defaultDriver.func1(0x1548058?, {0xc017a33b60?, 0xc0122a3c00?, 0x1d?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/packages.go:345 +0x36 golang.org/x/tools/go/packages.callDriverOnChunks.func1() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/packages.go:392 +0x5c golang.org/x/sync/errgroup.(*Group).Go.func1() /home/swarming/.swarming/w/ir/x/w/gopath/pkg/mod/golang.org/x/sync@v0.12.0/errgroup/errgroup.go:78 +0x50 created by golang.org/x/sync/errgroup.(*Group).Go in goroutine 41850 /home/swarming/.swarming/w/ir/x/w/gopath/pkg/mod/golang.org/x/sync@v0.12.0/errgroup/errgroup.go:75 +0x93 goroutine 42241 [IO wait]: internal/poll.runtime_pollWait(0x2dbcd2808, 0x72) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc009c42e70?, 0xc009b96e00?, 0x1) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc009c42e70, {0xc009b96e00, 0x200, 0x200}) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_unix.go:165 +0x279 os.(*File).read(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file_posix.go:29 os.(*File).Read(0xc018920368, {0xc009b96e00?, 0x200000003?, 0x0?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file.go:125 +0x4f bytes.(*Buffer).ReadFrom(0xc00061b0b0, {0x153da60, 0xc015c04950}) /home/swarming/.swarming/w/ir/x/w/goroot/src/bytes/buffer.go:211 +0x98 io.copyBuffer({0x153dfe0, 0xc00061b0b0}, {0x153da60, 0xc015c04950}, {0x0, 0x0, 0x0}) /home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go:415 +0x151 io.Copy(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go:388 os.genericWriteTo(0x2a?, {0x153dfe0, 0xc00061b0b0}) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file.go:276 +0x4f os.(*File).WriteTo(0x1d19940?, {0x153dfe0?, 0xc00061b0b0?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file.go:254 +0x49 io.copyBuffer({0x153dfe0, 0xc00061b0b0}, {0x153db40, 0xc018920368}, {0x0, 0x0, 0x0}) /home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go:411 +0x9d io.Copy(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go:388 os/exec.(*Cmd).writerDescriptor.func1() /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec/exec.go:596 +0x34 os/exec.(*Cmd).Start.func2(0x0?) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec/exec.go:749 +0x2c created by os/exec.(*Cmd).Start in goroutine 42342 /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec/exec.go:748 +0x935 goroutine 41951 [select]: golang.org/x/tools/gopls/internal/cache.(*parseCache).gc(0xc0116a4eb0) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/parse_cache.go:262 +0xb7 created by golang.org/x/tools/gopls/internal/cache.newParseCache in goroutine 41845 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/parse_cache.go:119 +0xc5 goroutine 41852 [select]: golang.org/x/tools/gopls/internal/cache.(*Snapshot).AwaitInitialized(0xc009b38b40, {0x1548020, 0xc0179e8330}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/snapshot.go:1190 +0x6b golang.org/x/tools/gopls/internal/cache.(*Snapshot).awaitLoaded(0xc009b38b40, {0x1548020, 0xc0179e8330}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/snapshot.go:1183 +0x25 golang.org/x/tools/gopls/internal/cache.(*Snapshot).WorkspaceMetadata(0xc009b38b40, {0x1548020?, 0xc0179e8330?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/snapshot.go:960 +0x65 golang.org/x/tools/gopls/internal/server.(*server).diagnose(0xc00d0f1980, {0x1548020, 0xc0179e8180}, 0xc009b38b40) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/server/diagnostics.go:381 +0x446 golang.org/x/tools/gopls/internal/server.(*server).diagnoseSnapshot(0xc00d0f1980, {0x1548058, 0xc0001c7720}, 0xc009b38b40, {0x0, 0x0, 0x0}, 0x0) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/server/diagnostics.go:233 +0x279 golang.org/x/tools/gopls/internal/server.(*server).addFolders.func3() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/server/general.go:357 +0x49 created by golang.org/x/tools/gopls/internal/server.(*server).addFolders in goroutine 42319 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/server/general.go:356 +0x8bb goroutine 42329 [chan receive]: golang.org/x/tools/gopls/internal/protocol.Handlers.AsyncHandler.func2.2() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/handler.go:102 +0x65 created by golang.org/x/tools/gopls/internal/protocol.Handlers.AsyncHandler.func2 in goroutine 41952 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/handler.go:101 +0x22f goroutine 42240 [IO wait]: internal/poll.runtime_pollWait(0x2dbcd2178, 0x72) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc009c42cb0?, 0xc009b96c00?, 0x1) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc009c42cb0, {0xc009b96c00, 0x200, 0x200}) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_unix.go:165 +0x279 os.(*File).read(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file_posix.go:29 os.(*File).Read(0xc018920338, {0xc009b96c00?, 0x1546fb0?, 0x1d2b4a0?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file.go:125 +0x4f bytes.(*Buffer).ReadFrom(0xc00061b080, {0x153da60, 0xc015c04948}) /home/swarming/.swarming/w/ir/x/w/goroot/src/bytes/buffer.go:211 +0x98 io.copyBuffer({0x153dfe0, 0xc00061b080}, {0x153da60, 0xc015c04948}, {0x0, 0x0, 0x0}) /home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go:415 +0x151 io.Copy(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go:388 os.genericWriteTo(0x0?, {0x153dfe0, 0xc00061b080}) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file.go:276 +0x4f os.(*File).WriteTo(0x1d19940?, {0x153dfe0?, 0xc00061b080?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file.go:254 +0x49 io.copyBuffer({0x153dfe0, 0xc00061b080}, {0x153db40, 0xc018920338}, {0x0, 0x0, 0x0}) /home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go:411 +0x9d io.Copy(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go:388 golang.org/x/tools/internal/gocommand.runCmdContext.func1() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:322 +0x45 created by golang.org/x/tools/internal/gocommand.runCmdContext in goroutine 42342 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:321 +0x17e goroutine 42370 [syscall]: syscall.syscall6(0x49dda0, 0xcef, 0xc00bc81ebc, 0x0, 0xc0190c57a0, 0x0, 0x0) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/sys_openbsd3.go:54 +0x35 syscall.wait4(0xc00bc81ec0?, 0x41b385?, 0x90?, 0x11f3fc0?) /home/swarming/.swarming/w/ir/x/w/goroot/src/syscall/zsyscall_openbsd_amd64.go:43 +0x45 syscall.Wait4(0x0?, 0xc00bc81ef4, 0x0?, 0x0?) /home/swarming/.swarming/w/ir/x/w/goroot/src/syscall/syscall_bsd.go:144 +0x25 os.(*Process).pidWait.func1(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec_unix.go:64 os.ignoringEINTR2[...](...) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file_posix.go:261 os.(*Process).pidWait(0xc00e239980) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec_unix.go:63 +0x85 os.(*Process).wait(0x1?) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec_unix.go:28 +0x1a os.(*Process).Wait(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec.go:340 os/exec.(*Cmd).Wait(0xc007576d80) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec/exec.go:922 +0x45 golang.org/x/tools/internal/gocommand.runCmdContext.func4() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:378 +0x25 created by golang.org/x/tools/internal/gocommand.runCmdContext in goroutine 42342 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:377 +0x349 goroutine 41952 [select]: net.(*pipe).read(0xc00f7c6b00, {0xc009e36000, 0xe00, 0x7db10f?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/pipe.go:159 +0x14d net.(*pipe).Read(0x1d7dea0?, {0xc009e36000?, 0x41a8f4?, 0x28?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/pipe.go:142 +0x1c encoding/json.(*Decoder).refill(0xc0065f4500) /home/swarming/.swarming/w/ir/x/w/goroot/src/encoding/json/stream.go:165 +0x188 encoding/json.(*Decoder).readValue(0xc0065f4500) /home/swarming/.swarming/w/ir/x/w/goroot/src/encoding/json/stream.go:140 +0x85 encoding/json.(*Decoder).Decode(0xc0065f4500, {0x10b84e0, 0xc01318c258}) /home/swarming/.swarming/w/ir/x/w/goroot/src/encoding/json/stream.go:63 +0x75 golang.org/x/tools/internal/jsonrpc2.(*rawStream).Read(0xc01617ea50, {0x1548020, 0xc011cec390}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/stream.go:61 +0x68 golang.org/x/tools/gopls/internal/protocol.(*loggingStream).Read(0xc00f7acb70, {0x1548020?, 0xc011cec390?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/log.go:30 +0x2a golang.org/x/tools/internal/jsonrpc2.(*conn).run(0xc0001c67d0, {0x1548020, 0xc011cec390}, 0xc01f40c048) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/conn.go:195 +0x9f created by golang.org/x/tools/internal/jsonrpc2.(*conn).Go in goroutine 41845 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/conn.go:188 +0x8f goroutine 41846 [select]: net.(*pipe).read(0xc00f7c6b80, {0xc009e51000, 0xe00, 0xc009e26c40?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/pipe.go:159 +0x14d net.(*pipe).Read(0xc01b361870?, {0xc009e51000?, 0x41a8f4?, 0x1548020?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/pipe.go:142 +0x1c encoding/json.(*Decoder).refill(0xc0065f4640) /home/swarming/.swarming/w/ir/x/w/goroot/src/encoding/json/stream.go:165 +0x188 encoding/json.(*Decoder).readValue(0xc0065f4640) /home/swarming/.swarming/w/ir/x/w/goroot/src/encoding/json/stream.go:140 +0x85 encoding/json.(*Decoder).Decode(0xc0065f4640, {0x10b84e0, 0xc0176cc588}) /home/swarming/.swarming/w/ir/x/w/goroot/src/encoding/json/stream.go:63 +0x75 golang.org/x/tools/internal/jsonrpc2.(*rawStream).Read(0xc01617ea68, {0x1548058, 0xc0001c6780}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/stream.go:61 +0x68 golang.org/x/tools/internal/jsonrpc2.(*conn).run(0xc0001c6820, {0x1548058, 0xc0001c6780}, 0xc01617eab0) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/conn.go:195 +0x9f created by golang.org/x/tools/internal/jsonrpc2.(*conn).Go in goroutine 42239 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/conn.go:188 +0x8f FAIL golang.org/x/tools/gopls/internal/test/integration/diagnostics 603.062s ok golang.org/x/tools/gopls/internal/test/integration/fake 0.691s ok golang.org/x/tools/gopls/internal/test/integration/fake/glob 0.209s ok golang.org/x/tools/gopls/internal/test/integration/inlayhints 9.442s generate_test.go:66: context deadline exceeded #### Start Gopls Test Logs for "TestGenerateProgress/default" [Trace - 08:48:14.931 AM] Sending request 'initialize - (1)'. Params: {"processId":0,"clientInfo":{"name":"fake.Editor","version":"v1.0.0"},"rootUri":"","capabilities":{"workspace":{"workspaceEdit":{"resourceOperations":["rename"]},"didChangeConfiguration":{},"didChangeWatchedFiles":{"dynamicRegistration":true},"configuration":true},"textDocument":{"completion":{"completionItem":{"snippetSupport":true,"tagSupport":{"valueSet":[1]},"insertReplaceSupport":true}},"documentSymbol":{"hierarchicalDocumentSymbolSupport":true},"codeAction":{"codeActionLiteralSupport":{"codeActionKind":{"valueSet":[""]}},"dataSupport":true,"resolveSupport":{"properties":["edit"]}},"publishDiagnostics":{},"semanticTokens":{"requests":{"full":true},"tokenTypes":["namespace","type","class","enum","interface","struct","typeParameter","parameter","variable","property","enumMember","event","function","method","macro","keyword","modifier","comment","string","number","regexp","operator","label"],"tokenModifiers":["declaration","definition","readonly","static","deprecated","abstract","async","modification","documentation","defaultLibrary","interface","struct","signature","pointer","array","map","slice","chan","string","number","bool","invalid"],"formats":null}},"window":{"workDoneProgress":true,"showDocument":{"support":true}}},"initializationOptions":{"completionBudget":"0s","diagnosticsDelay":"10ms","env":{"GO111MODULE":"","GOMODCACHE":"","GOPACKAGESDRIVER":"off","GOPATH":"/home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/gopath","GOPROXY":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/proxy","GOSUMDB":"off","GOTOOLCHAIN":"local"},"verboseWorkDoneProgress":true},"trace":"messages","workspaceFolders":[{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/work","name":"work"}]} [Trace - 08:48:14.934 AM] Received response 'initialize - (1)' in 3ms. Result: {"capabilities":{"textDocumentSync":{"openClose":true,"change":2,"save":{}},"completionProvider":{"triggerCharacters":["."]},"hoverProvider":true,"signatureHelpProvider":{"triggerCharacters":["(",","]},"definitionProvider":true,"typeDefinitionProvider":true,"implementationProvider":true,"referencesProvider":true,"documentHighlightProvider":true,"documentSymbolProvider":true,"codeActionProvider":{"codeActionKinds":["gopls.doc.features","quickfix","refactor.extract.constant","refactor.extract.constant-all","refactor.extract.function","refactor.extract.method","refactor.extract.toNewFile","refactor.extract.variable","refactor.extract.variable-all","refactor.inline.call","refactor.rewrite.changeQuote","refactor.rewrite.fillStruct","refactor.rewrite.fillSwitch","refactor.rewrite.invertIf","refactor.rewrite.joinLines","refactor.rewrite.removeUnusedParam","refactor.rewrite.splitLines","source.assembly","source.doc","source.fixAll","source.freesymbols","source.organizeImports"],"resolveProvider":true},"codeLensProvider":{},"documentLinkProvider":{},"workspaceSymbolProvider":true,"documentFormattingProvider":true,"renameProvider":true,"foldingRangeProvider":true,"selectionRangeProvider":true,"executeCommandProvider":{"commands":["gopls.add_dependency","gopls.add_import","gopls.add_telemetry_counters","gopls.add_test","gopls.apply_fix","gopls.assembly","gopls.change_signature","gopls.check_upgrades","gopls.client_open_url","gopls.diagnose_files","gopls.doc","gopls.edit_go_directive","gopls.extract_to_new_file","gopls.fetch_vulncheck_result","gopls.free_symbols","gopls.gc_details","gopls.generate","gopls.go_get_package","gopls.list_imports","gopls.list_known_packages","gopls.maybe_prompt_for_telemetry","gopls.mem_stats","gopls.modules","gopls.package_symbols","gopls.packages","gopls.regenerate_cgo","gopls.remove_dependency","gopls.reset_go_mod_diagnostics","gopls.run_go_work_command","gopls.run_govulncheck","gopls.run_tests","gopls.scan_imports","gopls.start_debugging","gopls.start_profile","gopls.stop_profile","gopls.tidy","gopls.update_go_sum","gopls.upgrade_dependency","gopls.vendor","gopls.views","gopls.vulncheck","gopls.workspace_stats"]},"callHierarchyProvider":true,"semanticTokensProvider":{"legend":{"tokenTypes":["namespace","type","typeParameter","parameter","variable","function","method","macro","keyword","comment","string","number","operator","label"],"tokenModifiers":["definition","readonly","defaultLibrary","array","bool","chan","format","interface","map","number","pointer","signature","slice","string","struct"]},"range":true,"full":true},"inlayHintProvider":{},"workspace":{"workspaceFolders":{"supported":true,"changeNotifications":"workspace/didChangeWorkspaceFolders"}}},"serverInfo":{"name":"gopls","version":"{\"GoVersion\":\"devel 22d5d09f1e39bf0ef77bfcf80388c676e7e91574\",\"Path\":\"golang.org/x/tools/gopls/internal/test/integration/misc.test\",\"Main\":{\"Path\":\"golang.org/x/tools/gopls\",\"Version\":\"(devel)\",\"Sum\":\"\",\"Replace\":null},\"Deps\":null,\"Settings\":[{\"Key\":\"-buildmode\",\"Value\":\"exe\"},{\"Key\":\"-compiler\",\"Value\":\"gc\"},{\"Key\":\"CGO_ENABLED\",\"Value\":\"1\"},{\"Key\":\"CGO_CFLAGS\",\"Value\":\"\"},{\"Key\":\"CGO_CPPFLAGS\",\"Value\":\"\"},{\"Key\":\"CGO_CXXFLAGS\",\"Value\":\"\"},{\"Key\":\"CGO_LDFLAGS\",\"Value\":\"\"},{\"Key\":\"GOARCH\",\"Value\":\"amd64\"},{\"Key\":\"GOOS\",\"Value\":\"openbsd\"},{\"Key\":\"GOAMD64\",\"Value\":\"v1\"}],\"Version\":\"(devel)\"}"}} [Trace - 08:48:14.936 AM] Sending notification 'initialized'. Params: {} [Trace - 08:48:14.937 AM] Received request 'window/workDoneProgress/create - (1)'. Params: {"token":"5406777173565339453"} [Trace - 08:48:14.937 AM] Sending response 'window/workDoneProgress/create - (1)' in 0ms. Result: [Trace - 08:48:14.937 AM] Received notification '$/progress'. Params: {"token":"5406777173565339453","value":{"kind":"begin","title":"diagnosing initial workspace load","message":"Calculating diagnostics for initial workspace load...","percentage":0}} [Trace - 08:48:14.938 AM] Received request 'window/workDoneProgress/create - (2)'. Params: {"token":"4423293618045051817"} [Trace - 08:48:14.938 AM] Sending response 'window/workDoneProgress/create - (2)' in 0ms. Result: [Trace - 08:48:14.938 AM] Received notification '$/progress'. Params: {"token":"4423293618045051817","value":{"kind":"begin","title":"Setting up workspace","message":"Loading packages...","percentage":0}} [Trace - 08:48:14.939 AM] Received request 'workspace/configuration - (3)'. Params: {"items":[{"scopeUri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/work","section":"gopls"}]} [Trace - 08:48:14.940 AM] Sending response 'workspace/configuration - (3)' in 0ms. Result: [{"completionBudget":"0s","diagnosticsDelay":"10ms","env":{"GO111MODULE":"","GOMODCACHE":"","GOPACKAGESDRIVER":"off","GOPATH":"/home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/gopath","GOPROXY":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/proxy","GOSUMDB":"off","GOTOOLCHAIN":"local"},"verboseWorkDoneProgress":true}] [Trace - 08:48:15.208 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:15 Created View (#60)\n\tdirectory=/home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/work\n\tview_type=\"GoMod\"\n\troot_dir=\"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/work\"\n\tgo_version=\"go version devel 22d5d09f1e39bf0ef77bfcf80388c676e7e91574 openbsd/amd64\"\n\tbuild_flags=[]\n\tenv={GOOS:openbsd GOARCH:amd64 GOCACHE:/home/swarming/.swarming/w/ir/x/w/gocache GOMODCACHE:/home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/gopath/pkg/mod GOPATH:/home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/gopath GOPRIVATE: GOFLAGS: GO111MODULE: GOTOOLCHAIN:local GOROOT:/home/swarming/.swarming/w/ir/x/w/goroot GoVersion:25 GoVersionOutput:go version devel 22d5d09f1e39bf0ef77bfcf80388c676e7e91574 openbsd/amd64\n ExplicitGOWORK: EffectiveGOPACKAGESDRIVER:}\n\tenv_overlay=[]\n"} [Trace - 08:48:15.920 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:15 go/packages.Load #73\n\tview_id=\"60\"\n\tsnapshot=0\n\tdirectory=/home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/work\n\tquery=[/home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/work/... builtin]\n\tpackages=4\n\tduration=709.050422ms\n"} [Trace - 08:48:15.923 AM] Received notification '$/progress'. Params: {"token":"4423293618045051817","value":{"kind":"end","message":"Finished loading packages."}} [Trace - 08:48:15.929 AM] Received request 'client/registerCapability - (4)'. Params: {"registrations":[{"id":"workspace/didChangeWatchedFiles-0","method":"workspace/didChangeWatchedFiles","registerOptions":{"watchers":[{"globPattern":"/home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/work/**/*.{go,mod,sum,work}","kind":7},{"globPattern":"**/*.{mod,work}","kind":7}]}}]} [Trace - 08:48:15.931 AM] Sending response 'client/registerCapability - (4)' in 2ms. Result: [Trace - 08:48:15.934 AM] Received request 'window/workDoneProgress/create - (5)'. Params: {"token":"4093682818450192711"} [Trace - 08:48:15.936 AM] Sending response 'window/workDoneProgress/create - (5)' in 1ms. Result: [Trace - 08:48:15.936 AM] Received notification '$/progress'. Params: {"token":"4093682818450192711","value":{"kind":"begin","title":"Checking telemetry prompt","message":"Checking if gopls should prompt about telemetry...","percentage":0}} [Trace - 08:48:15.962 AM] Received notification '$/progress'. Params: {"token":"4093682818450192711","value":{"kind":"end","message":"Done."}} [Trace - 08:48:16.043 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/work/main.go","version":0,"diagnostics":[{"range":{"start":{"line":8,"character":14},"end":{"line":8,"character":20}},"severity":1,"code":"UndeclaredImportedName","codeDescription":{"href":"https://pkg.go.dev/golang.org/x/tools/internal/typesinternal#UndeclaredImportedName"},"source":"compiler","message":"undefined: lib1.Answer"},{"range":{"start":{"line":8,"character":28},"end":{"line":8,"character":34}},"severity":1,"code":"UndeclaredImportedName","codeDescription":{"href":"https://pkg.go.dev/golang.org/x/tools/internal/typesinternal#UndeclaredImportedName"},"source":"compiler","message":"undefined: lib2.Answer"}]} [Trace - 08:48:16.044 AM] Received notification '$/progress'. Params: {"token":"5406777173565339453","value":{"kind":"end","message":"Done."}} [Trace - 08:48:16.046 AM] Sending request 'workspace/executeCommand - (2)'. Params: {"command":"gopls.generate","arguments":[{"Dir":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/work/lib1","Recursive":false}]} [Trace - 08:48:16.046 AM] Received request 'window/workDoneProgress/create - (6)'. Params: {"token":"5010047561752400536"} [Trace - 08:48:16.047 AM] Sending response 'window/workDoneProgress/create - (6)' in 0ms. Result: [Trace - 08:48:16.047 AM] Received notification '$/progress'. Params: {"token":"5010047561752400536","value":{"kind":"begin","title":"gopls.generate","message":"Verbose: running command...","percentage":0}} [Trace - 08:48:16.058 AM] Received request 'window/workDoneProgress/create - (7)'. Params: {"token":"6567128364695241297"} [Trace - 08:48:16.058 AM] Sending response 'window/workDoneProgress/create - (7)' in 0ms. Result: [Trace - 08:48:16.059 AM] Received notification '$/progress'. Params: {"token":"6567128364695241297","value":{"kind":"begin","title":"Running go generate .","cancellable":true,"message":"Running...","percentage":0}} [Trace - 08:48:16.139 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:16 go run ../generate.go lib1\n\n\toperation=\"generate\"\n"} [Trace - 08:48:16.140 AM] Received notification '$/progress'. Params: {"token":"6567128364695241297","value":{"kind":"report","cancellable":true,"message":"go run ../generate.go lib1","percentage":0}} [Trace - 08:48:17.098 AM] Received notification '$/progress'. Params: {"token":"6567128364695241297","value":{"kind":"end","message":"completed"}} [Trace - 08:48:17.099 AM] Received notification '$/progress'. Params: {"token":"5010047561752400536","value":{"kind":"end","message":"Done."}} [Trace - 08:48:17.099 AM] Received response 'workspace/executeCommand - (2)' in 1053ms. Result: null [Trace - 08:48:17.118 AM] Sending notification 'workspace/didChangeWatchedFiles'. Params: {"changes":[{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/work/lib1/generated.go","type":1}]} [Trace - 08:48:17.119 AM] Sending request 'workspace/executeCommand - (3)'. Params: {"command":"gopls.generate","arguments":[{"Dir":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/work/lib2","Recursive":false}]} [Trace - 08:48:17.119 AM] Received request 'window/workDoneProgress/create - (8)'. Params: {"token":"8000920059140713720"} [Trace - 08:48:17.119 AM] Sending response 'window/workDoneProgress/create - (8)' in 0ms. Result: [Trace - 08:48:17.119 AM] Received notification '$/progress'. Params: {"token":"8000920059140713720","value":{"kind":"begin","title":"diagnosing files changed on disk","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:17.121 AM] Received request 'window/workDoneProgress/create - (9)'. Params: {"token":"618981782896701230"} [Trace - 08:48:17.122 AM] Sending response 'window/workDoneProgress/create - (9)' in 0ms. Result: [Trace - 08:48:17.122 AM] Received notification '$/progress'. Params: {"token":"618981782896701230","value":{"kind":"begin","title":"gopls.generate","message":"Verbose: running command...","percentage":0}} [Trace - 08:48:17.123 AM] Received request 'window/workDoneProgress/create - (10)'. Params: {"token":"5395855275535578062"} [Trace - 08:48:17.123 AM] Sending response 'window/workDoneProgress/create - (10)' in 0ms. Result: [Trace - 08:48:17.124 AM] Received notification '$/progress'. Params: {"token":"5395855275535578062","value":{"kind":"begin","title":"Running go generate .","cancellable":true,"message":"Running...","percentage":0}} [Trace - 08:48:17.342 AM] Received notification '$/progress'. Params: {"token":"5395855275535578062","value":{"kind":"report","cancellable":true,"message":"go run ../generate.go lib2","percentage":0}} [Trace - 08:48:17.342 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:17 go run ../generate.go lib2\n\n\toperation=\"generate\"\n"} [Trace - 08:48:17.941 AM] Received notification '$/progress'. Params: {"token":"5395855275535578062","value":{"kind":"end","message":"completed"}} [Trace - 08:48:17.941 AM] Received notification '$/progress'. Params: {"token":"618981782896701230","value":{"kind":"end","message":"Done."}} [Trace - 08:48:17.942 AM] Received response 'workspace/executeCommand - (3)' in 822ms. Result: null [Trace - 08:48:17.954 AM] Sending notification 'workspace/didChangeWatchedFiles'. Params: {"changes":[{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/work/lib2/generated.go","type":1}]} [Trace - 08:48:17.954 AM] Received request 'window/workDoneProgress/create - (11)'. Params: {"token":"80521158234891633"} [Trace - 08:48:17.955 AM] Sending response 'window/workDoneProgress/create - (11)' in 0ms. Result: [Trace - 08:48:17.955 AM] Received notification '$/progress'. Params: {"token":"80521158234891633","value":{"kind":"begin","title":"diagnosing files changed on disk","message":"Calculating file diagnostics...","percentage":0}} [Trace - 08:48:17.994 AM] Received notification '$/progress'. Params: {"token":"8000920059140713720","value":{"kind":"end","message":"Done."}} [Trace - 08:48:18.654 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:18 go/packages.Load #75\n\tview_id=\"60\"\n\tsnapshot=2\n\tdirectory=/home/swarming/.swarming/w/ir/x/t/gopls-test-4107175463/TestGenerateProgress/default/work\n\tquery=[fake.test fake.test/lib1 fake.test/lib2]\n\tpackages=3\n\tduration=657.656952ms\n"} #### End Gopls Test Logs for "TestGenerateProgress/default" --- FAIL: TestGenerateProgress/default (4.05s) panic: test timed out after 10m0s running tests: TestGenerateProgress (4s) TestGenerateProgress/default (4s) goroutine 41962 [running]: testing.(*M).startAlarm.func1() /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2490 +0x394 created by time.goFunc /home/swarming/.swarming/w/ir/x/w/goroot/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive]: testing.(*T).Run(0xc0002d4c40, {0x12ce5d5?, 0xc00004ba10?}, 0x145a8e8) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1865 +0x431 testing.runTests.func1(0xc0002d4c40) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2285 +0x37 testing.tRunner(0xc0002d4c40, 0xc00004bb50) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1798 +0xea testing.runTests(0xc0003d5aa0, {0x1e0dd40, 0x9b, 0x9b}, {0xc000156600?, 0xc00004bbc8?, 0x1e229a0?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2283 +0x4b4 testing.(*M).Run(0xc00052dae0) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2148 +0x63a golang.org/x/tools/gopls/internal/test/integration.Main(0xc00052dae0) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/regtest.go:197 +0x412 golang.org/x/tools/gopls/internal/test/integration/misc.TestMain(0xc00052dae0) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/misc/misc_test.go:25 +0x51 main.main() _testmain.go:355 +0xa8 goroutine 126 [sleep, 4 minutes]: time.Sleep(0x45d964b800) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/time.go:338 +0x154 golang.org/x/tools/gopls/internal/filecache.gc({0xc0003ce440, 0x32}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/filecache/filecache.go:457 +0x134 created by golang.org/x/tools/gopls/internal/filecache.getCacheDir.func1 in goroutine 125 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/filecache/filecache.go:365 +0x1af goroutine 165 [chan receive]: golang.org/x/tools/gopls/internal/protocol.LogEvent.func1.1() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/context.go:47 +0x47 created by golang.org/x/tools/gopls/internal/protocol.LogEvent.func1 in goroutine 108 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/context.go:46 +0x1a goroutine 12145 [select, 8 minutes]: golang.org/x/tools/internal/jsonrpc2.Serve({0x15d91d0, 0xc011fbea50}, {0x15d84b0, 0xc00b694e40}, {0x15cf240, 0xc011fbeb40}, 0x0) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/serve.go:112 +0x31b created by golang.org/x/tools/internal/jsonrpc2/servertest.NewTCPServer in goroutine 12144 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/servertest/servertest.go:47 +0x10d goroutine 12291 [IO wait, 8 minutes]: internal/poll.runtime_pollWait(0x2d730e648, 0x72) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc011c16c80?, 0x50?, 0x0) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Accept(0xc011c16c80) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_unix.go:613 +0x28c net.(*netFD).accept(0xc011c16c80) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/fd_unix.go:172 +0x29 net.(*TCPListener).accept(0xc00dd1f500) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/tcpsock_posix.go:159 +0x1b net.(*TCPListener).Accept(0xc00dd1f500) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/tcpsock.go:380 +0x30 net/http.(*Server).Serve(0xc0115b3400, {0x15d84b0, 0xc00dd1f500}) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/http/server.go:3424 +0x30c net/http.Serve(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/http/server.go:2932 golang.org/x/tools/gopls/internal/debug.(*Instance).Serve.func1() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/debug/serve.go:471 +0xa51 created by golang.org/x/tools/gopls/internal/debug.(*Instance).Serve in goroutine 12241 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/debug/serve.go:432 +0x42e goroutine 12152 [IO wait, 8 minutes]: internal/poll.runtime_pollWait(0x2d730dea0, 0x72) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc011729700?, 0x50?, 0x0) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Accept(0xc011729700) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_unix.go:613 +0x28c net.(*netFD).accept(0xc011729700) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/fd_unix.go:172 +0x29 net.(*TCPListener).accept(0xc00b694e40) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/tcpsock_posix.go:159 +0x1b net.(*TCPListener).Accept(0xc00b694e40) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/tcpsock.go:380 +0x30 golang.org/x/tools/internal/jsonrpc2.Serve.func1() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/serve.go:76 +0x93 created by golang.org/x/tools/internal/jsonrpc2.Serve in goroutine 12145 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/serve.go:72 +0x158 goroutine 41648 [runnable]: testing.(*T).Run(0xc016b5fc00, {0x1294373?, 0x12baf10?}, 0xc016137c70) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1865 +0x431 golang.org/x/tools/gopls/internal/test/integration.(*Runner).Run(0xc00013e6c0, 0xc016b5fc00, {0x133e750, 0x1f0}, 0x145af18, {0x0, 0x0, 0x4b1873?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/runner.go:158 +0x445 golang.org/x/tools/gopls/internal/test/integration.Run(...) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/regtest.go:53 golang.org/x/tools/gopls/internal/test/integration/misc.TestGenerateProgress(0xc016b5fc00?) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/misc/generate_test.go:60 +0x3d testing.tRunner(0xc016b5fc00, 0x145a8e8) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1798 +0xea created by testing.(*T).Run in goroutine 1 /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1857 +0x413 goroutine 12175 [IO wait, 8 minutes]: internal/poll.runtime_pollWait(0x2d730dfb8, 0x72) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc011c16900?, 0x50?, 0x0) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Accept(0xc011c16900) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_unix.go:613 +0x28c net.(*netFD).accept(0xc011c16900) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/fd_unix.go:172 +0x29 net.(*TCPListener).accept(0xc00dd1ee80) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/tcpsock_posix.go:159 +0x1b net.(*TCPListener).Accept(0xc00dd1ee80) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/tcpsock.go:380 +0x30 net/http.(*Server).Serve(0xc0123e6100, {0x15d84b0, 0xc00dd1ee80}) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/http/server.go:3424 +0x30c net/http.Serve(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/http/server.go:2932 golang.org/x/tools/gopls/internal/debug.(*Instance).Serve.func1() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/debug/serve.go:471 +0xa51 created by golang.org/x/tools/gopls/internal/debug.(*Instance).Serve in goroutine 12260 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/debug/serve.go:432 +0x42e FAIL golang.org/x/tools/gopls/internal/test/integration/misc 606.222s ok golang.org/x/tools/gopls/internal/test/integration/modfile 583.467s ok golang.org/x/tools/gopls/internal/test/integration/template 29.555s ok golang.org/x/tools/gopls/internal/test/integration/watch 296.868s runner.go:238: context deadline exceeded #### Start Gopls Test Logs for "TestInnerGoWork/default" [Trace - 08:48:19.903 AM] Sending request 'initialize - (1)'. Params: {"processId":0,"clientInfo":{"name":"fake.Editor","version":"v1.0.0"},"rootUri":"","capabilities":{"workspace":{"workspaceEdit":{"resourceOperations":["rename"]},"didChangeConfiguration":{},"didChangeWatchedFiles":{"dynamicRegistration":true},"configuration":true},"textDocument":{"completion":{"completionItem":{"snippetSupport":true,"tagSupport":{"valueSet":[1]},"insertReplaceSupport":true}},"documentSymbol":{"hierarchicalDocumentSymbolSupport":true},"codeAction":{"codeActionLiteralSupport":{"codeActionKind":{"valueSet":[""]}},"dataSupport":true,"resolveSupport":{"properties":["edit"]}},"publishDiagnostics":{},"semanticTokens":{"requests":{"full":true},"tokenTypes":["namespace","type","class","enum","interface","struct","typeParameter","parameter","variable","property","enumMember","event","function","method","macro","keyword","modifier","comment","string","number","regexp","operator","label"],"tokenModifiers":["declaration","definition","readonly","static","deprecated","abstract","async","modification","documentation","defaultLibrary","interface","struct","signature","pointer","array","map","slice","chan","string","number","bool","invalid"],"formats":null}},"window":{"workDoneProgress":true,"showDocument":{"support":true}}},"initializationOptions":{"completionBudget":"0s","diagnosticsDelay":"10ms","env":{"GO111MODULE":"","GOMODCACHE":"","GOPACKAGESDRIVER":"off","GOPATH":"/home/swarming/.swarming/w/ir/x/t/gopls-test-660943524/TestInnerGoWork/default/gopath","GOPROXY":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-660943524/TestInnerGoWork/default/proxy","GOSUMDB":"off","GOTOOLCHAIN":"local"},"verboseWorkDoneProgress":true},"trace":"messages","workspaceFolders":[{"uri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-660943524/TestInnerGoWork/default/work/a","name":"a"}]} [Trace - 08:48:19.907 AM] Received response 'initialize - (1)' in 4ms. Result: {"capabilities":{"textDocumentSync":{"openClose":true,"change":2,"save":{}},"completionProvider":{"triggerCharacters":["."]},"hoverProvider":true,"signatureHelpProvider":{"triggerCharacters":["(",","]},"definitionProvider":true,"typeDefinitionProvider":true,"implementationProvider":true,"referencesProvider":true,"documentHighlightProvider":true,"documentSymbolProvider":true,"codeActionProvider":{"codeActionKinds":["gopls.doc.features","quickfix","refactor.extract.constant","refactor.extract.constant-all","refactor.extract.function","refactor.extract.method","refactor.extract.toNewFile","refactor.extract.variable","refactor.extract.variable-all","refactor.inline.call","refactor.rewrite.changeQuote","refactor.rewrite.fillStruct","refactor.rewrite.fillSwitch","refactor.rewrite.invertIf","refactor.rewrite.joinLines","refactor.rewrite.removeUnusedParam","refactor.rewrite.splitLines","source.assembly","source.doc","source.fixAll","source.freesymbols","source.organizeImports"],"resolveProvider":true},"codeLensProvider":{},"documentLinkProvider":{},"workspaceSymbolProvider":true,"documentFormattingProvider":true,"renameProvider":true,"foldingRangeProvider":true,"selectionRangeProvider":true,"executeCommandProvider":{"commands":["gopls.add_dependency","gopls.add_import","gopls.add_telemetry_counters","gopls.add_test","gopls.apply_fix","gopls.assembly","gopls.change_signature","gopls.check_upgrades","gopls.client_open_url","gopls.diagnose_files","gopls.doc","gopls.edit_go_directive","gopls.extract_to_new_file","gopls.fetch_vulncheck_result","gopls.free_symbols","gopls.gc_details","gopls.generate","gopls.go_get_package","gopls.list_imports","gopls.list_known_packages","gopls.maybe_prompt_for_telemetry","gopls.mem_stats","gopls.modules","gopls.package_symbols","gopls.packages","gopls.regenerate_cgo","gopls.remove_dependency","gopls.reset_go_mod_diagnostics","gopls.run_go_work_command","gopls.run_govulncheck","gopls.run_tests","gopls.scan_imports","gopls.start_debugging","gopls.start_profile","gopls.stop_profile","gopls.tidy","gopls.update_go_sum","gopls.upgrade_dependency","gopls.vendor","gopls.views","gopls.vulncheck","gopls.workspace_stats"]},"callHierarchyProvider":true,"semanticTokensProvider":{"legend":{"tokenTypes":["namespace","type","typeParameter","parameter","variable","function","method","macro","keyword","comment","string","number","operator","label"],"tokenModifiers":["definition","readonly","defaultLibrary","array","bool","chan","format","interface","map","number","pointer","signature","slice","string","struct"]},"range":true,"full":true},"inlayHintProvider":{},"workspace":{"workspaceFolders":{"supported":true,"changeNotifications":"workspace/didChangeWorkspaceFolders"}}},"serverInfo":{"name":"gopls","version":"{\"GoVersion\":\"devel 22d5d09f1e39bf0ef77bfcf80388c676e7e91574\",\"Path\":\"golang.org/x/tools/gopls/internal/test/integration/workspace.test\",\"Main\":{\"Path\":\"golang.org/x/tools/gopls\",\"Version\":\"(devel)\",\"Sum\":\"\",\"Replace\":null},\"Deps\":null,\"Settings\":[{\"Key\":\"-buildmode\",\"Value\":\"exe\"},{\"Key\":\"-compiler\",\"Value\":\"gc\"},{\"Key\":\"CGO_ENABLED\",\"Value\":\"1\"},{\"Key\":\"CGO_CFLAGS\",\"Value\":\"\"},{\"Key\":\"CGO_CPPFLAGS\",\"Value\":\"\"},{\"Key\":\"CGO_CXXFLAGS\",\"Value\":\"\"},{\"Key\":\"CGO_LDFLAGS\",\"Value\":\"\"},{\"Key\":\"GOARCH\",\"Value\":\"amd64\"},{\"Key\":\"GOOS\",\"Value\":\"openbsd\"},{\"Key\":\"GOAMD64\",\"Value\":\"v1\"}],\"Version\":\"(devel)\"}"}} [Trace - 08:48:19.908 AM] Sending notification 'initialized'. Params: {} [Trace - 08:48:19.909 AM] Received request 'window/workDoneProgress/create - (1)'. Params: {"token":"6699459313435056500"} [Trace - 08:48:19.909 AM] Sending response 'window/workDoneProgress/create - (1)' in 0ms. Result: [Trace - 08:48:19.909 AM] Received notification '$/progress'. Params: {"token":"6699459313435056500","value":{"kind":"begin","title":"diagnosing initial workspace load","message":"Calculating diagnostics for initial workspace load...","percentage":0}} [Trace - 08:48:19.910 AM] Received request 'window/workDoneProgress/create - (2)'. Params: {"token":"5606919694506927833"} [Trace - 08:48:19.910 AM] Sending response 'window/workDoneProgress/create - (2)' in 0ms. Result: [Trace - 08:48:19.910 AM] Received notification '$/progress'. Params: {"token":"5606919694506927833","value":{"kind":"begin","title":"Setting up workspace","message":"Loading packages...","percentage":0}} [Trace - 08:48:19.911 AM] Received request 'workspace/configuration - (3)'. Params: {"items":[{"scopeUri":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-660943524/TestInnerGoWork/default/work/a","section":"gopls"}]} [Trace - 08:48:19.911 AM] Sending response 'workspace/configuration - (3)' in 0ms. Result: [{"completionBudget":"0s","diagnosticsDelay":"10ms","env":{"GO111MODULE":"","GOMODCACHE":"","GOPACKAGESDRIVER":"off","GOPATH":"/home/swarming/.swarming/w/ir/x/t/gopls-test-660943524/TestInnerGoWork/default/gopath","GOPROXY":"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-660943524/TestInnerGoWork/default/proxy","GOSUMDB":"off","GOTOOLCHAIN":"local"},"verboseWorkDoneProgress":true}] [Trace - 08:48:20.058 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2025/03/10 08:48:20 Created View (#72)\n\tdirectory=/home/swarming/.swarming/w/ir/x/t/gopls-test-660943524/TestInnerGoWork/default/work/a\n\tview_type=\"GoWork\"\n\troot_dir=\"file:///home/swarming/.swarming/w/ir/x/t/gopls-test-660943524/TestInnerGoWork/default/work/a\"\n\tgo_version=\"go version devel 22d5d09f1e39bf0ef77bfcf80388c676e7e91574 openbsd/amd64\"\n\tbuild_flags=[]\n\tenv={GOOS:openbsd GOARCH:amd64 GOCACHE:/home/swarming/.swarming/w/ir/x/w/gocache GOMODCACHE:/home/swarming/.swarming/w/ir/x/t/gopls-test-660943524/TestInnerGoWork/default/gopath/pkg/mod GOPATH:/home/swarming/.swarming/w/ir/x/t/gopls-test-660943524/TestInnerGoWork/default/gopath GOPRIVATE: GOFLAGS: GO111MODULE: GOTOOLCHAIN:local GOROOT:/home/swarming/.swarming/w/ir/x/w/goroot GoVersion:25 GoVersionOutput:go version devel 22d5d09f1e39bf0ef77bfcf80388c676e7e91574 openbsd/amd64\n ExplicitGOWORK: EffectiveGOPACKAGESDRIVER:}\n\tenv_overlay=[]\n"} #### End Gopls Test Logs for "TestInnerGoWork/default" panic: test timed out after 10m0s running tests: TestInnerGoWork (0s) TestInnerGoWork/default (0s) goroutine 34469 [running]: testing.(*M).startAlarm.func1() /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2490 +0x394 created by time.goFunc /home/swarming/.swarming/w/ir/x/w/goroot/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive]: testing.(*T).Run(0xc000103180, {0x125e253?, 0xc000455a40?}, 0x13fb8c8) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1865 +0x431 testing.runTests.func1(0xc000103180) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2285 +0x37 testing.tRunner(0xc000103180, 0xc000455b80) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1798 +0xea testing.runTests(0xc000112228, {0x1d79340, 0x45, 0x45}, {0xc000248600?, 0xc000455bf8?, 0x1d91d40?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2283 +0x4b4 testing.(*M).Run(0xc0003a0be0) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2148 +0x63a golang.org/x/tools/gopls/internal/test/integration.Main(0xc0003a0be0) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/regtest.go:197 +0x412 golang.org/x/tools/gopls/internal/test/integration/workspace.TestMain(0x1db5ec0?) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/workspace/workspace_test.go:31 +0x1a main.main() _testmain.go:183 +0xa8 goroutine 164 [chan receive]: golang.org/x/tools/gopls/internal/protocol.LogEvent.func1.1() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/context.go:47 +0x47 created by golang.org/x/tools/gopls/internal/protocol.LogEvent.func1 in goroutine 94 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/context.go:46 +0x1a goroutine 195 [sleep, 6 minutes]: time.Sleep(0x45d964b800) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/time.go:338 +0x154 golang.org/x/tools/gopls/internal/filecache.gc({0xc0002be2c0, 0x32}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/filecache/filecache.go:457 +0x134 created by golang.org/x/tools/gopls/internal/filecache.getCacheDir.func1 in goroutine 194 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/filecache/filecache.go:365 +0x1af goroutine 34468 [chan receive]: golang.org/x/tools/gopls/internal/protocol.Handlers.AsyncHandler.func2.2() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/handler.go:102 +0x65 created by golang.org/x/tools/gopls/internal/protocol.Handlers.AsyncHandler.func2 in goroutine 34367 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/handler.go:101 +0x22f goroutine 34045 [select]: golang.org/x/tools/gopls/internal/cache.(*Snapshot).AwaitInitialized(0xc0089b2ea0, {0x1572800, 0xc017164f90}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/snapshot.go:1190 +0x6b golang.org/x/tools/gopls/internal/cache.(*Snapshot).awaitLoaded(0xc0089b2ea0, {0x1572800, 0xc017164f90}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/snapshot.go:1183 +0x25 golang.org/x/tools/gopls/internal/cache.(*Snapshot).WorkspaceMetadata(0xc0089b2ea0, {0x1572800?, 0xc017164f90?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/snapshot.go:960 +0x65 golang.org/x/tools/gopls/internal/server.(*server).diagnose(0xc007520780, {0x1572800, 0xc017164d50}, 0xc0089b2ea0) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/server/diagnostics.go:381 +0x446 golang.org/x/tools/gopls/internal/server.(*server).diagnoseSnapshot(0xc007520780, {0x1572838, 0xc009dfeb40}, 0xc0089b2ea0, {0x0, 0x0, 0x0}, 0x0) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/server/diagnostics.go:233 +0x279 golang.org/x/tools/gopls/internal/server.(*server).addFolders.func3() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/server/general.go:357 +0x49 created by golang.org/x/tools/gopls/internal/server.(*server).addFolders in goroutine 34368 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/server/general.go:356 +0x8bb goroutine 34200 [chan receive]: golang.org/x/tools/gopls/internal/lsprpc.(*streamServer).ServeStream(0xc016cb4e70, {0x1572800, 0xc017519170}, {0x1576f28, 0xc0153814a0}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/lsprpc/lsprpc.go:93 +0x7d0 created by golang.org/x/tools/internal/jsonrpc2/servertest.(*PipeServer).Connect in goroutine 34199 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/servertest/servertest.go:84 +0x191 goroutine 34366 [select]: golang.org/x/tools/gopls/internal/cache.(*parseCache).gc(0xc0136e8d70) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/parse_cache.go:262 +0xb7 created by golang.org/x/tools/gopls/internal/cache.newParseCache in goroutine 34200 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/parse_cache.go:119 +0xc5 goroutine 34044 [select]: golang.org/x/tools/gopls/internal/cache.(*Snapshot).AwaitInitialized(0xc0089b2ea0, {0x1572800, 0xc0177041b0}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/snapshot.go:1190 +0x6b golang.org/x/tools/gopls/internal/server.(*server).addFolders.func2() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/server/general.go:348 +0x48 created by golang.org/x/tools/gopls/internal/server.(*server).addFolders in goroutine 34368 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/server/general.go:347 +0x805 goroutine 34445 [select]: os/exec.(*Cmd).awaitGoroutines(0xc007520a80, 0xc00b6209a0) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec/exec.go:988 +0x148 os/exec.(*Cmd).Wait(0xc007520a80) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec/exec.go:940 +0x15f golang.org/x/tools/internal/gocommand.runCmdContext.func4() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:378 +0x25 created by golang.org/x/tools/internal/gocommand.runCmdContext in goroutine 34438 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:377 +0x349 goroutine 34198 [chan receive]: testing.(*T).Run(0xc010fe08c0, {0x123dee3?, 0x1263c7c?}, 0xc015381270) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1865 +0x431 golang.org/x/tools/gopls/internal/test/integration.(*Runner).Run(0xc0001903f0, 0xc010fe08c0, {0x12d67d3, 0xd5}, 0x13fbc50, {0xc01023df50, 0x1, 0xc014b99a60?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/runner.go:158 +0x445 golang.org/x/tools/gopls/internal/test/integration.configuredRunner.Run({{0xc01023df50?, 0xc01023def0?, 0xc01023df40?}}, 0xc010fe08c0, {0x12d67d3, 0xd5}, 0x13fbc50) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/regtest.go:77 +0x147 golang.org/x/tools/gopls/internal/test/integration/workspace.TestInnerGoWork(0xc010fe08c0) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/workspace/workspace_test.go:858 +0xbf testing.tRunner(0xc010fe08c0, 0x13fb8c8) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1798 +0xea created by testing.(*T).Run in goroutine 1 /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1857 +0x413 goroutine 34367 [select]: net.(*pipe).read(0xc007a2c600, {0xc007cd0000, 0xe00, 0x6b600f?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/pipe.go:159 +0x14d net.(*pipe).Read(0x1db5ec0?, {0xc007cd0000?, 0x41a8f4?, 0x28?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/pipe.go:142 +0x1c encoding/json.(*Decoder).refill(0xc01323f680) /home/swarming/.swarming/w/ir/x/w/goroot/src/encoding/json/stream.go:165 +0x188 encoding/json.(*Decoder).readValue(0xc01323f680) /home/swarming/.swarming/w/ir/x/w/goroot/src/encoding/json/stream.go:140 +0x85 encoding/json.(*Decoder).Decode(0xc01323f680, {0x10daa60, 0xc0132183f0}) /home/swarming/.swarming/w/ir/x/w/goroot/src/encoding/json/stream.go:63 +0x75 golang.org/x/tools/internal/jsonrpc2.(*rawStream).Read(0xc014aee318, {0x1572800, 0xc017519170}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/stream.go:61 +0x68 golang.org/x/tools/gopls/internal/protocol.(*loggingStream).Read(0xc016cb4ff0, {0x1572800?, 0xc017519170?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/log.go:30 +0x2a golang.org/x/tools/internal/jsonrpc2.(*conn).run(0xc0153814a0, {0x1572800, 0xc017519170}, 0xc00a744d50) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/conn.go:195 +0x9f created by golang.org/x/tools/internal/jsonrpc2.(*conn).Go in goroutine 34200 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/conn.go:188 +0x8f goroutine 34043 [sync.WaitGroup.Wait]: sync.runtime_SemacquireWaitGroup(0xc00a4946d8?) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/sema.go:110 +0x25 sync.(*WaitGroup).Wait(0x11e4500?) /home/swarming/.swarming/w/ir/x/w/goroot/src/sync/waitgroup.go:118 +0x48 golang.org/x/sync/errgroup.(*Group).Wait(0xc008858740) /home/swarming/.swarming/w/ir/x/w/gopath/pkg/mod/golang.org/x/sync@v0.12.0/errgroup/errgroup.go:56 +0x1e golang.org/x/tools/go/packages.callDriverOnChunks(0xc01618c4e0, 0xc006f93888, {0xc00a4946c0, 0x1, 0xc00699f4b0?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/packages.go:399 +0x1d7 golang.org/x/tools/go/packages.defaultDriver(0xc006f93888, {0xc0088586c0?, 0xc00699f4f8?, 0x3f?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/packages.go:347 +0x167 golang.org/x/tools/go/packages.Load(0xc0089b2ea0?, {0xc0088586c0, 0x3, 0x4}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/packages.go:271 +0x51 golang.org/x/tools/gopls/internal/cache.(*Snapshot).load(0xc0089b2ea0, {0x1572838, 0xc009dfec30}, 0x1, {0xc008858680, 0x3, 0xc00005aa08?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/load.go:130 +0xb5a golang.org/x/tools/gopls/internal/cache.(*Snapshot).initialize(0xc0089b2ea0, {0x1572838, 0xc009dfec30}, 0x1) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/view.go:700 +0x38c golang.org/x/tools/gopls/internal/cache.(*Session).createView.func3() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/session.go:300 +0x3e created by golang.org/x/tools/gopls/internal/cache.(*Session).createView in goroutine 34368 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/cache/session.go:298 +0x1bf7 goroutine 34199 [select]: golang.org/x/tools/internal/jsonrpc2.(*conn).Call(0xc0153814f0, {0x15729c0, 0xc00ac130d0}, {0x1240f2f, 0x8}, {0x0?, 0x0?}, {0x0, 0x0}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/conn.go:137 +0x8f6 golang.org/x/tools/gopls/internal/protocol.clientConn.Call({{0x1576f28, 0xc0153814f0}}, {0x15729c0, 0xc00ac130d0}, {0x1240f2f?, 0xc00bb5c000?}, {0x0?, 0x0?}, {0x0, 0x0}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/protocol.go:67 +0x75 golang.org/x/tools/gopls/internal/protocol.(*serverDispatcher).Shutdown(0x1218a40?, {0x15729c0?, 0xc00ac130d0?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/tsserver.go:1002 +0x3d golang.org/x/tools/gopls/internal/test/integration/fake.(*Editor).Shutdown(0x1?, {0x15729c0?, 0xc00ac130d0?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/fake/editor.go:206 +0x32 golang.org/x/tools/gopls/internal/test/integration/fake.(*Editor).Close(0xc007a362c8, {0x15729c0, 0xc00ac130d0}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/fake/editor.go:228 +0x4c golang.org/x/tools/gopls/internal/test/integration.(*Runner).Run.func1.2() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/runner.go:233 +0x136 runtime.Goexit() /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/panic.go:615 +0x5e testing.(*common).FailNow(0xc010fe0a80) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1042 +0x4a testing.(*common).Fatal(0xc010fe0a80, {0xc00ac13080?, 0xc016cb4b70?, 0xc0001f7860?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1119 +0x54 golang.org/x/tools/gopls/internal/test/integration.(*Env).Await(0xc015381860, {0xc014aee8a0, 0x1, 0x1}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/env.go:316 +0xe9 golang.org/x/tools/gopls/internal/test/integration.(*Runner).Run.func1(0xc010fe0a80) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/test/integration/runner.go:238 +0x8ce testing.tRunner(0xc010fe0a80, 0xc015381270) /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1798 +0xea created by testing.(*T).Run in goroutine 34198 /home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:1857 +0x413 goroutine 34439 [chan send]: golang.org/x/tools/go/packages.goListDriver.func1() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/golist.go:165 +0x205 created by golang.org/x/tools/go/packages.goListDriver in goroutine 34438 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/golist.go:161 +0x32e goroutine 34444 [IO wait]: internal/poll.runtime_pollWait(0x263f7a2f8, 0x72) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc00b6d33b0?, 0xc00047b000?, 0x1) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc00b6d33b0, {0xc00047b000, 0x200, 0x200}) /home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/fd_unix.go:165 +0x279 os.(*File).read(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file_posix.go:29 os.(*File).Read(0xc01650ceb0, {0xc00047b000?, 0xc007702fc0?, 0xc000056008?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file.go:125 +0x4f bytes.(*Buffer).ReadFrom(0xc017317080, {0x1568180, 0xc007734630}) /home/swarming/.swarming/w/ir/x/w/goroot/src/bytes/buffer.go:211 +0x98 io.copyBuffer({0x15686e0, 0xc017317080}, {0x1568180, 0xc007734630}, {0x0, 0x0, 0x0}) /home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go:415 +0x151 io.Copy(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go:388 os.genericWriteTo(0x4c0113?, {0x15686e0, 0xc017317080}) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file.go:276 +0x4f os.(*File).WriteTo(0x1d51960?, {0x15686e0?, 0xc017317080?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file.go:254 +0x49 io.copyBuffer({0x15686e0, 0xc017317080}, {0x1568260, 0xc01650ceb0}, {0x0, 0x0, 0x0}) /home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go:411 +0x9d io.Copy(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go:388 os/exec.(*Cmd).writerDescriptor.func1() /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec/exec.go:596 +0x34 os/exec.(*Cmd).Start.func2(0xc006b90fb8?) /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec/exec.go:749 +0x2c created by os/exec.(*Cmd).Start in goroutine 34438 /home/swarming/.swarming/w/ir/x/w/goroot/src/os/exec/exec.go:748 +0x935 goroutine 34201 [select]: net.(*pipe).read(0xc007a2c680, {0xc007da3000, 0xe00, 0xc007a3bea0?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/pipe.go:159 +0x14d net.(*pipe).Read(0xc0077344b0?, {0xc007da3000?, 0x41a8f4?, 0x1572800?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/net/pipe.go:142 +0x1c encoding/json.(*Decoder).refill(0xc01323f7c0) /home/swarming/.swarming/w/ir/x/w/goroot/src/encoding/json/stream.go:165 +0x188 encoding/json.(*Decoder).readValue(0xc01323f7c0) /home/swarming/.swarming/w/ir/x/w/goroot/src/encoding/json/stream.go:140 +0x85 encoding/json.(*Decoder).Decode(0xc01323f7c0, {0x10daa60, 0xc009fbbd88}) /home/swarming/.swarming/w/ir/x/w/goroot/src/encoding/json/stream.go:63 +0x75 golang.org/x/tools/internal/jsonrpc2.(*rawStream).Read(0xc014aee330, {0x1572838, 0xc015381450}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/stream.go:61 +0x68 golang.org/x/tools/internal/jsonrpc2.(*conn).run(0xc0153814f0, {0x1572838, 0xc015381450}, 0xc014aee378) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/conn.go:195 +0x9f created by golang.org/x/tools/internal/jsonrpc2.(*conn).Go in goroutine 34199 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/conn.go:188 +0x8f goroutine 34368 [sync.WaitGroup.Wait]: sync.runtime_SemacquireWaitGroup(0xc017164c90?) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/sema.go:110 +0x25 sync.(*WaitGroup).Wait(0xc007520780?) /home/swarming/.swarming/w/ir/x/w/goroot/src/sync/waitgroup.go:118 +0x48 golang.org/x/tools/gopls/internal/server.(*server).addFolders(0xc007520780, {0x1572800, 0xc0177041b0}, {0xc009721cc0, 0x1, 0x4775b9?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/server/general.go:366 +0x8ca golang.org/x/tools/gopls/internal/server.(*server).Initialized(0xc007520780, {0x1572800?, 0xc0177040f0?}, 0x10379e0?) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/server/general.go:218 +0x2b7 golang.org/x/tools/gopls/internal/protocol.serverDispatch({0x1572800, 0xc0177040f0}, {0x1591cd8, 0xc007520780}, 0xc017704120, {0x2663b61f8, 0xc0175199e0}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/tsserver.go:275 +0x6f4 golang.org/x/tools/gopls/internal/lsprpc.(*streamServer).ServeStream.ServerHandler.func3({0x1572800, 0xc0177040f0}, 0xc017704120, {0x2663b61f8, 0xc0175199e0}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/protocol/protocol.go:160 +0x85 golang.org/x/tools/gopls/internal/lsprpc.(*streamServer).ServeStream.handshaker.func4({0x1572800, 0xc0177040f0}, 0xc017704120, {0x2663b61f8, 0xc0175199e0}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/gopls/internal/lsprpc/lsprpc.go:509 +0x909 golang.org/x/tools/gopls/internal/protocol.Handlers.MustReplyHandler.func1({0x1572800, 0xc0177040f0}, 0xc00a745170, {0x2663b61f8, 0xc0175199e0}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/handler.go:35 +0xc6 golang.org/x/tools/gopls/internal/protocol.Handlers.AsyncHandler.func2.2() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/handler.go:104 +0x96 created by golang.org/x/tools/gopls/internal/protocol.Handlers.AsyncHandler.func2 in goroutine 34367 /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/jsonrpc2/handler.go:101 +0x22f goroutine 34438 [select]: golang.org/x/tools/internal/gocommand.runCmdContext({0x1572800, 0xc017316f30}, 0xc007520a80) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:387 +0x44d golang.org/x/tools/internal/gocommand.(*Invocation).run(0xc008eeabc0, {0x1572800, 0xc017316f30}, {0x15686e0, 0xc017317050}, {0x15686e0, 0xc017317080}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:285 +0xdfa golang.org/x/tools/internal/gocommand.(*Invocation).runWithFriendlyError(0xc008eeab88?, {0x1572800, 0xc017316f30}, {0x15686e0?, 0xc017317050?}, {0x15686e0, 0xc017317080}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:187 +0x4f golang.org/x/tools/internal/gocommand.(*Runner).runConcurrent(0xc01618c4c0, {0x1572800, 0xc017316f30}, {{0x1238457, 0x4}, {0xc006f93960, 0xc, 0xe}, {0x0, 0x0, ...}, ...}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:124 +0x15f golang.org/x/tools/internal/gocommand.(*Runner).RunRaw(0xc01618c4c0, {0x15728a8, 0xc00b6d2af0}, {{0x1238457, 0x4}, {0xc006f93960, 0xc, 0xe}, {0x0, 0x0, ...}, ...}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/internal/gocommand/invoke.go:98 +0x370 golang.org/x/tools/go/packages.(*golistState).invokeGo(0xc006f93888?, {0x1238457?, 0x3?}, {0xc006f93960, 0xc, 0xe?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/golist.go:872 +0x1db golang.org/x/tools/go/packages.(*golistState).createDriverResponse(0xc00b802b00, {0xc017316930, 0x3, 0x3}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/golist.go:388 +0xa7 golang.org/x/tools/go/packages.goListDriver(0xc006f93888, 0xc01618c4c0, {0x0, 0x0}, {0xc0088586c0, 0x3, 0xc015c4eee8?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/golist.go:210 +0x7ff golang.org/x/tools/go/packages.defaultDriver.func1(0x1572838?, {0xc0088586c0?, 0xc016d19c60?, 0x1d?}) /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/packages.go:345 +0x36 golang.org/x/tools/go/packages.callDriverOnChunks.func1() /home/swarming/.swarming/w/ir/x/w/targetrepo1489996876/go/packages/packages.go:392 +0x5c golang.org/x/sync/errgroup.(*Group).Go.func1() /home/swarming/.swarming/w/ir/x/w/gopath/pkg/mod/golang.org/x/sync@v0.12.0/errgroup/errgroup.go:78 +0x50 created by golang.org/x/sync/errgroup.(*Group).Go in goroutine 34043 /home/swarming/.swarming/w/ir/x/w/gopath/pkg/mod/golang.org/x/sync@v0.12.0/errgroup/errgroup.go:75 +0x93 FAIL golang.org/x/tools/gopls/internal/test/integration/workspace 602.601s ok golang.org/x/tools/gopls/internal/test/marker 546.424s ok golang.org/x/tools/gopls/internal/util/asm 0.366s ok golang.org/x/tools/gopls/internal/util/astutil 5.227s ok golang.org/x/tools/gopls/internal/util/bug 0.286s ok golang.org/x/tools/gopls/internal/util/fingerprint 4.867s ok golang.org/x/tools/gopls/internal/util/frob 0.162s ok golang.org/x/tools/gopls/internal/util/goversion 0.114s ok golang.org/x/tools/gopls/internal/util/lru 0.469s ok golang.org/x/tools/gopls/internal/util/persistent 0.849s ok golang.org/x/tools/gopls/internal/util/safetoken 23.015s ok golang.org/x/tools/gopls/internal/vulncheck/semver 0.278s ok golang.org/x/tools/gopls/internal/vulncheck/vulntest 0.805s Warning: no '=' in invocation-link-artifacts pair: "", ignoring [W2025-03-10T08:48:50.266608-07:00 54689 0 cmd_stream.go:504] rdb-stream: test process exited with error: exit status 1 [I2025-03-10T08:48:50.266682-07:00 54689 0 cmd_stream.go:488] rdb-stream: the test process terminated [I2025-03-10T08:48:50.268086-07:00 54689 0 sink.go:375] SinkServer: shutdown started [I2025-03-10T08:48:50.268174-07:00 54689 0 sink.go:353] SinkServer: HTTP server stopped with "http: Server closed" [I2025-03-10T08:48:50.268220-07:00 54689 0 sink_server.go:96] SinkServer: draining TestResult channel started [I2025-03-10T08:48:50.508714-07:00 54689 0 sink_server.go:98] SinkServer: draining TestResult channel ended [I2025-03-10T08:48:50.508767-07:00 54689 0 sink_server.go:100] SinkServer: draining Artifact channel started [I2025-03-10T08:48:50.892285-07:00 54689 0 sink_server.go:102] SinkServer: draining Artifact channel ended [I2025-03-10T08:48:50.892371-07:00 54689 0 sink.go:378] SinkServer: shutdown completed successfully [I2025-03-10T08:48:50.892429-07:00 54689 0 cmd_stream.go:420] rdb-stream: exiting with 1