Flaky test: TestRoomKeyIsCycledOnDeviceLogout/{js_hs1}|{js_hs1} #199
Description
https://github.com/matrix-org/matrix-js-sdk/actions/runs/16472923003/job/46566625891
client.go:718: [CSAPI] POST hs1/_matrix/client/v3/register => 200 OK (16.117048ms)
client.go:718: [CSAPI] POST hs1/_matrix/client/v3/register => 200 OK (11.548063ms)
client.go:718: [CSAPI] POST hs1/_matrix/client/v3/createRoom => 200 OK (100.28804ms)
client.go:718: [CSAPI] POST hs1/_matrix/client/v3/join/!xSTScwaoqKpJmevIkv:hs1 => 200 OK (22.612512ms)
client.go:718: [CSAPI] POST hs1/_matrix/client/v3/login => 200 OK (8.003551ms)
JS SDK listening on http://127.0.0.1:45491/
panic: test timed out after 15m0s
running tests:
TestRoomKeyIsCycledOnDeviceLogout (11m14s)
TestRoomKeyIsCycledOnDeviceLogout/{js_hs1}|{js_hs1} (11m14s)
goroutine 1520 [running]:
testing.(*M).startAlarm.func1()
/home/runner/go/pkg/mod/golang.org/[email protected]/src/testing/testing.go:2373 +0x385
created by time.goFunc
/home/runner/go/pkg/mod/golang.org/[email protected]/src/time/sleep.go:215 +0x2d
goroutine 1 [chan receive, 11 minutes]:
testing.(*T).Run(0xc0000d01a0, {0xb7a179?, 0x0?}, 0x13621e8)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/testing/testing.go:1751 +0x3ab
testing.runTests.func1(0xc0000d01a0)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/testing/testing.go:2168 +0x37
testing.tRunner(0xc0000d01a0, 0xc000e07a88)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/testing/testing.go:1690 +0xf4
testing.runTests(0xc000013aa0, {0x17f9160, 0x1c, 0x1c}, {0x10?, 0xc000e07b18?, 0x18013c0?})
/home/runner/go/pkg/mod/golang.org/[email protected]/src/testing/testing.go:2166 +0x43d
testing.(*M).Run(0xc0000a5a40)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/testing/testing.go:2034 +0x64a
github.com/matrix-org/complement.TestMain(0xc0000a5a40, {0xb5e2e6, 0x6}, {0xc0008f5df8, 0x1, 0x40f6c5?})
/home/runner/go/pkg/mod/github.com/matrix-org/[email protected]/test_main.go:79 +0x13c
github.com/matrix-org/complement-crypto/internal/cc.(*Instance).TestMain(0xc0000125e8, 0xc0000a5a40, {0xb5e2e6, 0x6})
/home/runner/work/matrix-js-sdk/matrix-js-sdk/complement-crypto/internal/cc/instance.go:42 +0x28f
github.com/matrix-org/complement-crypto/tests.TestMain(0xc0000a5a40)
/home/runner/work/matrix-js-sdk/matrix-js-sdk/complement-crypto/tests/main_test.go:18 +0xbd
main.main()
_testmain.go:101 +0xa8
goroutine 1565 [chan receive, 11 minutes]:
testing.(*T).Run(0xc000b51040, {0xc0010cc618?, 0xc000d37f00?}, 0xc0011cd270)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/testing/testing.go:1751 +0x3ab
github.com/matrix-org/complement-crypto/internal/cc.(*Instance).ClientTypeMatrix(0xc000d37f60?, 0xc000b51040, 0x1362478)
/home/runner/work/matrix-js-sdk/matrix-js-sdk/complement-crypto/internal/cc/instance.go:76 +0x78
github.com/matrix-org/complement-crypto/tests.TestRoomKeyIsCycledOnDeviceLogout(0xc000b51040?)
/home/runner/work/matrix-js-sdk/matrix-js-sdk/complement-crypto/tests/room_keys_test.go:45 +0x27
testing.tRunner(0xc000b51040, 0x13621e8)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/testing/testing.go:1690 +0xf4
created by testing.(*T).Run in goroutine 1
/home/runner/go/pkg/mod/golang.org/[email protected]/src/testing/testing.go:1743 +0x390
goroutine 1583 [select, 11 minutes]:
github.com/chromedp/chromedp.(*Target).run.func1()
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/target.go:106 +0xc5
created by github.com/chromedp/chromedp.(*Target).run in goroutine 1582
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/target.go:104 +0xc5
goroutine 1566 [select, 11 minutes]:
github.com/chromedp/chromedp.(*Target).Execute(0xc00110e460, {0x1415240, 0xc001237c50}, {0xb63445, 0xd}, {0xacae40, 0xc0011cd9f0}, {0xa27660, 0xc001237c80})
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/target.go:203 +0x526
github.com/chromedp/cdproto/cdp.Execute({0x1415240, 0xc001237c50}, {0xb63445, 0xd}, {0xacae40, 0xc0011cd9f0}, {0xa27660, 0xc001237c80})
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/cdp/types.go:46 +0x10e
github.com/chromedp/cdproto/page.(*NavigateParams).Do(0xc0011cd9f0, {0x1415240, 0xc001237c50})
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/page/page.go:803 +0x65
github.com/matrix-org/complement-crypto/internal/api/js/chrome.RunHeadless.Navigate.func11({0x1415240, 0xc001237c50})
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/nav.go:21 +0x3e
github.com/chromedp/chromedp.ActionFunc.Do(0x1415240?, {0x1415240?, 0xc001237c50?})
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/chromedp.go:728 +0x26
github.com/chromedp/chromedp.Tasks.Do(...)
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/chromedp.go:738
github.com/chromedp/chromedp.Run({0x1415240, 0xc001237c20}, {0xc0013a36b0, 0x1, 0x0?})
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/chromedp.go:335 +0xf0
github.com/matrix-org/complement-crypto/internal/api/js/chrome.RunHeadless.Navigate.responseAction.func13({0x1415240, 0xc001237c20})
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/chromedp.go:677 +0x38d
github.com/chromedp/chromedp.ActionFunc.Do(0x1415240?, {0x1415240?, 0xc001237c20?})
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/chromedp.go:728 +0x26
github.com/chromedp/chromedp.Tasks.Do(...)
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/chromedp.go:738
github.com/chromedp/chromedp.Run({0x1415240, 0xc001235110}, {0xc0011a7700, 0x1, 0x1?})
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/chromedp.go:335 +0xf0
github.com/matrix-org/complement-crypto/internal/api/js/chrome.RunHeadless(0xc00114dd40, 0xb1?, 0x0)
/home/runner/work/matrix-js-sdk/matrix-js-sdk/complement-crypto/internal/api/js/chrome/chrome.go:149 +0x8ed
github.com/matrix-org/complement-crypto/internal/api/js.NewJSClient({0x1417d18, 0xc000b51520}, {{0xc000b68438, 0x16}, {0xc0010bb9b1, 0x12}, {0xb7074e, 0x1a}, {0x0, 0x0}, ...})
/home/runner/work/matrix-js-sdk/matrix-js-sdk/complement-crypto/internal/api/js/js.go:72 +0x2af
github.com/matrix-org/complement-crypto/internal/api/langs.(*JSLanguageBindings).MustCreateClient(0xc001269a50?, {0x1417d18, 0xc000b51520}, {{0xc000b68438, 0x16}, {0xc0010bb9b1, 0x12}, {0xb7074e, 0x1a}, {0x0, ...}, ...})
/home/runner/work/matrix-js-sdk/matrix-js-sdk/complement-crypto/internal/api/langs/lang_jssdk.go:41 +0x5e
github.com/matrix-org/complement-crypto/internal/cc.mustCreateClient(0xc000b51520, {{0xb5d4d6?, 0xc000aec170?}, {0xb5d60d?, 0x781a80?}}, {{0xc000b68438, 0x16}, {0xc0010bb9b1, 0x12}, {0xb7074e, ...}, ...})
/home/runner/work/matrix-js-sdk/matrix-js-sdk/complement-crypto/internal/cc/test_context.go:324 +0x124
github.com/matrix-org/complement-crypto/internal/cc.(*TestContext).MustCreateClient(0xc0010b8400, 0xc000b51520, 0xc001269da8)
/home/runner/work/matrix-js-sdk/matrix-js-sdk/complement-crypto/internal/cc/test_context.go:312 +0x226
github.com/matrix-org/complement-crypto/internal/cc.(*TestContext).MustLoginClient(0xc0010b8400, 0xc000b51520, 0xc001269da8)
/home/runner/work/matrix-js-sdk/matrix-js-sdk/complement-crypto/internal/cc/test_context.go:293 +0x5c
github.com/matrix-org/complement-crypto/tests.TestRoomKeyIsCycledOnDeviceLogout.func1(0xc000b51520, {{0xb5d4d6?, 0xc000d37f60?}, {0xb5d60d?, 0x528f2e?}}, {{0xb5d4d6?, 0xc000d37f60?}, {0xb5d60d?, 0x4a46cf?}})
/home/runner/work/matrix-js-sdk/matrix-js-sdk/complement-crypto/tests/room_keys_test.go:57 +0x295
github.com/matrix-org/complement-crypto/internal/cc.(*Instance).ClientTypeMatrix.func1(0xc000b51520?)
/home/runner/work/matrix-js-sdk/matrix-js-sdk/complement-crypto/internal/cc/instance.go:77 +0x3c
testing.tRunner(0xc000b51520, 0xc0011cd270)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/testing/testing.go:1690 +0xf4
created by testing.(*T).Run in goroutine 1565
/home/runner/go/pkg/mod/golang.org/[email protected]/src/testing/testing.go:1743 +0x390
goroutine 45 [select, 14 minutes]:
net/http.(*persistConn).readLoop(0xc000e22480)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/http/transport.go:2325 +0xca5
created by net/http.(*Transport).dialConn in goroutine 43
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/http/transport.go:1874 +0x154f
goroutine 46 [select, 14 minutes]:
net/http.(*persistConn).writeLoop(0xc000e22480)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/http/transport.go:2519 +0xe7
created by net/http.(*Transport).dialConn in goroutine 43
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/http/transport.go:1875 +0x15a5
goroutine 10 [select, 14 minutes]:
net/http.(*persistConn).readLoop(0xc000d90480)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/http/transport.go:2325 +0xca5
created by net/http.(*Transport).dialConn in goroutine 8
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/http/transport.go:1874 +0x154f
goroutine 11 [select, 14 minutes]:
net/http.(*persistConn).writeLoop(0xc000d90480)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/http/transport.go:2519 +0xe7
created by net/http.(*Transport).dialConn in goroutine 8
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/http/transport.go:1875 +0x15a5
goroutine 1579 [select, 11 minutes]:
github.com/chromedp/chromedp.(*Browser).run(0xc000ddaf00, {0x1415240, 0xc001235110})
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/browser.go:304 +0x2b4
created by github.com/chromedp/chromedp.NewBrowser in goroutine 1566
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/browser.go:130 +0x412
goroutine 1576 [select, 11 minutes]:
os/exec.(*Cmd).watchCtx(0xc0008ce300, 0xc000e2c7e0)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/os/exec/exec.go:773 +0xb5
created by os/exec.(*Cmd).Start in goroutine 1566
/home/runner/go/pkg/mod/golang.org/[email protected]/src/os/exec/exec.go:759 +0x953
goroutine 1584 [IO wait, 11 minutes]:
internal/poll.runtime_pollWait(0x7fae06095568, 0x72)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/runtime/netpoll.go:351 +0x85
internal/poll.(*pollDesc).wait(0xc0013d9000?, 0xc00097c000?, 0x0)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/internal/poll/fd_poll_runtime.go:84 +0x27
internal/poll.(*pollDesc).waitRead(...)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/internal/poll/fd_poll_runtime.go:89
internal/poll.(*FD).Read(0xc0013d9000, {0xc00097c000, 0x1000, 0x1000})
/home/runner/go/pkg/mod/golang.org/[email protected]/src/internal/poll/fd_unix.go:165 +0x27a
net.(*netFD).Read(0xc0013d9000, {0xc00097c000?, 0xc00113aa40?, 0xc000e95690?})
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/fd_posix.go:55 +0x25
net.(*conn).Read(0xc000c87388, {0xc00097c000?, 0xc000d5f150?, 0xc0012358c8?})
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/net.go:189 +0x45
net/http.(*connReader).Read(0xc0012358c0, {0xc00097c000, 0x1000, 0x1000})
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/http/server.go:798 +0x14b
bufio.(*Reader).fill(0xc0011e7bc0)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/bufio/bufio.go:110 +0x103
bufio.(*Reader).ReadSlice(0xc0011e7bc0, 0xa)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/bufio/bufio.go:376 +0x29
bufio.(*Reader).ReadLine(0xc0011e7bc0)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/bufio/bufio.go:405 +0x25
net/textproto.(*Reader).readLineSlice(0xc000f00660, 0xffffffffffffffff)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/textproto/reader.go:64 +0xa5
net/textproto.(*Reader).ReadLine(...)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/textproto/reader.go:44
net/http.readRequest(0xc0011e7bc0)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/http/request.go:1096 +0xb0
net/http.(*conn).readRequest(0xc000c80120, {0x1415278, 0xc000b07270})
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/http/server.go:1048 +0x22c
net/http.(*conn).serve(0xc000c80120, {0x1415240, 0xc001235290})
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/http/server.go:2017 +0x32c
created by net/http.(*Server).Serve in goroutine 1575
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/http/server.go:3360 +0x485
goroutine 1575 [IO wait, 11 minutes]:
internal/poll.runtime_pollWait(0x7fae06094848, 0x72)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/runtime/netpoll.go:351 +0x85
internal/poll.(*pollDesc).wait(0xc0013d8e80?, 0x2c?, 0x0)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/internal/poll/fd_poll_runtime.go:84 +0x27
internal/poll.(*pollDesc).waitRead(...)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/internal/poll/fd_poll_runtime.go:89
internal/poll.(*FD).Accept(0xc0013d8e80)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/internal/poll/fd_unix.go:620 +0x295
net.(*netFD).accept(0xc0013d8e80)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/fd_unix.go:172 +0x29
net.(*TCPListener).accept(0xc00113a800)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/tcpsock_posix.go:159 +0x1e
net.(*TCPListener).Accept(0xc00113a800)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/tcpsock.go:372 +0x30
net/http.(*Server).Serve(0xc00114a000, {0x14144b0, 0xc00113a800})
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/http/server.go:3330 +0x30c
github.com/matrix-org/complement-crypto/internal/api/js/chrome.RunHeadless.func3()
/home/runner/work/matrix-js-sdk/matrix-js-sdk/complement-crypto/internal/api/js/chrome/chrome.go:142 +0x133
created by github.com/matrix-org/complement-crypto/internal/api/js/chrome.RunHeadless in goroutine 1566
/home/runner/work/matrix-js-sdk/matrix-js-sdk/complement-crypto/internal/api/js/chrome/chrome.go:145 +0x6dc
goroutine 1582 [select, 11 minutes]:
github.com/chromedp/chromedp.(*Target).run(0xc00110e460, {0x1415240, 0xc001235110})
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/target.go:145 +0x145
created by github.com/chromedp/chromedp.(*Context).attachTarget in goroutine 1566
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/chromedp.go:440 +0x2e5
goroutine 1577 [syscall, 11 minutes]:
syscall.Syscall6(0xf7, 0x3, 0x11, 0xc0010ab628, 0x4, 0xc00098f050, 0x0)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/syscall/syscall_linux.go:95 +0x39
os.(*Process).pidfdWait(0xc000923300?)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/os/pidfd_linux.go:110 +0x236
os.(*Process).wait(0x0?)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/os/exec_unix.go:27 +0x25
os.(*Process).Wait(...)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/os/exec.go:358
os/exec.(*Cmd).Wait(0xc0008ce300)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/os/exec/exec.go:906 +0x45
github.com/chromedp/chromedp.(*ExecAllocator).Allocate.func2()
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/allocate.go:222 +0x47
created by github.com/chromedp/chromedp.(*ExecAllocator).Allocate in goroutine 1566
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/allocate.go:217 +0xd07
goroutine 1574 [chan receive, 11 minutes]:
github.com/chromedp/chromedp.NewContext.func1()
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/chromedp.go:174 +0x49
created by github.com/chromedp/chromedp.NewContext in goroutine 1566
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/chromedp.go:173 +0x33c
goroutine 1581 [IO wait, 11 minutes]:
internal/poll.runtime_pollWait(0x7fae06094dc0, 0x72)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/runtime/netpoll.go:351 +0x85
internal/poll.(*pollDesc).wait(0xc0000d5900?, 0xc0008280b0?, 0x0)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/internal/poll/fd_poll_runtime.go:84 +0x27
internal/poll.(*pollDesc).waitRead(...)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/internal/poll/fd_poll_runtime.go:89
internal/poll.(*FD).Read(0xc0000d5900, {0xc0008280b0, 0x2, 0xc})
/home/runner/go/pkg/mod/golang.org/[email protected]/src/internal/poll/fd_unix.go:165 +0x27a
net.(*netFD).Read(0xc0000d5900, {0xc0008280b0?, 0x0?, 0xd10000c00126dbb8?})
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/fd_posix.go:55 +0x25
net.(*conn).Read(0xc000ebacf0, {0xc0008280b0?, 0x10000000200?, 0x200?})
/home/runner/go/pkg/mod/golang.org/[email protected]/src/net/net.go:189 +0x45
io.ReadAtLeast({0x140ff40, 0xc000ebacf0}, {0xc0008280b0, 0x2, 0xc}, 0x2)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/io/io.go:335 +0x90
io.ReadFull(...)
/home/runner/go/pkg/mod/golang.org/[email protected]/src/io/io.go:354
github.com/gobwas/ws/wsutil.(*Reader).readHeader(0xc000828018, {0x140ff40, 0xc000ebacf0})
/home/runner/go/pkg/mod/github.com/gobwas/[email protected]/wsutil/reader.go:282 +0x51
github.com/gobwas/ws/wsutil.(*Reader).NextFrame(0xc000828018)
/home/runner/go/pkg/mod/github.com/gobwas/[email protected]/wsutil/reader.go:171 +0x37
github.com/chromedp/chromedp.(*Conn).Read(0xc000828008, {0xc00126df08?, 0x1?}, 0xc0004e2360)
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/conn.go:76 +0xe9
github.com/chromedp/chromedp.(*Browser).run.func1()
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/browser.go:261 +0xcc
created by github.com/chromedp/chromedp.(*Browser).run in goroutine 1579
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/browser.go:255 +0x14f
goroutine 1580 [chan receive, 11 minutes]:
github.com/chromedp/chromedp.(*ExecAllocator).Allocate.func4()
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/allocate.go:270 +0x2b
created by github.com/chromedp/chromedp.(*ExecAllocator).Allocate in goroutine 1566
/home/runner/go/pkg/mod/github.com/chromedp/[email protected]/allocate.go:263 +0x1005