sql/catalog/lease: TestDescriptorByteSizeOrder failed

[cockroach-teamcity]

sql/catalog/lease.TestDescriptorByteSizeOrder failed with artifacts on master @ 9caf75810ff7dbfa42d16b746c21c86bfa0c6324:

  github.com/cockroachdb/cockroach/pkg/kv/kvclient/rangecache.tryLookupImpl()
      github.com/cockroachdb/cockroach/pkg/kv/kvclient/rangecache/range_cache.go:917 +0x1a8
  github.com/cockroachdb/cockroach/pkg/kv/kvclient/rangecache.(*RangeCache).tryLookup.func3()
      github.com/cockroachdb/cockroach/pkg/kv/kvclient/rangecache/range_cache.go:815 +0xd9
  github.com/cockroachdb/cockroach/pkg/util/syncutil/singleflight.(*Group).doCall.func1()
      github.com/cockroachdb/cockroach/pkg/util/syncutil/singleflight/singleflight.go:387 +0x51
  github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunTask()
      github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:305 +0x147
  github.com/cockroachdb/cockroach/pkg/util/syncutil/singleflight.(*Group).doCall()
      github.com/cockroachdb/cockroach/pkg/util/syncutil/singleflight/singleflight.go:386 +0x2a4
  github.com/cockroachdb/cockroach/pkg/util/syncutil/singleflight.(*Group).DoChan.func1()
      github.com/cockroachdb/cockroach/pkg/util/syncutil/singleflight/singleflight.go:356 +0xd0

Goroutine 28883 (running) created at:
  github.com/cockroachdb/cockroach/pkg/sql/pgwire.(*conn).processCommandsAsync()
      github.com/cockroachdb/cockroach/pkg/sql/pgwire/conn.go:644 +0x3c4
  github.com/cockroachdb/cockroach/pkg/sql/pgwire.(*conn).serveImpl()
      github.com/cockroachdb/cockroach/pkg/sql/pgwire/conn.go:368 +0xd15
  github.com/cockroachdb/cockroach/pkg/sql/pgwire.(*Server).serveConn()
      github.com/cockroachdb/cockroach/pkg/sql/pgwire/conn.go:177 +0x48c
  github.com/cockroachdb/cockroach/pkg/sql/pgwire.(*Server).ServeConn()
      github.com/cockroachdb/cockroach/pkg/sql/pgwire/server.go:774 +0xc0a
  github.com/cockroachdb/cockroach/pkg/server.(*systemServerWrapper).serveConn()
      github.com/cockroachdb/cockroach/pkg/server/server_controller.go:755 +0x1fa
  github.com/cockroachdb/cockroach/pkg/server.(*serverController).sqlMux()
      github.com/cockroachdb/cockroach/pkg/server/server_controller.go:592 +0x4dc
  github.com/cockroachdb/cockroach/pkg/server.(*serverController).sqlMux-fm()
      <autogenerated>:1 +0xc7
  github.com/cockroachdb/cockroach/pkg/server.startServeSQL.func1.1()
      github.com/cockroachdb/cockroach/pkg/server/server_sql.go:1610 +0x2eb
  github.com/cockroachdb/cockroach/pkg/util/netutil.(*TCPServer).ServeWith.func1()
      github.com/cockroachdb/cockroach/pkg/util/netutil/net.go:188 +0x111
  github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunAsyncTaskEx.func2()
      github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:470 +0x1f6
==================
=== RUN   TestDescriptorByteSizeOrder/tables_with_varying_col
I230126 09:14:58.281141 28947 5@util/log/event_log.go:32  [T1,n1,client=127.0.0.1:38990,user=root] 2042 ={"Timestamp":1674724498269332642,"EventType":"create_table","Statement":"CREATE TABLE defaultdb.public.smallxxcol (col1 INT8)","Tag":"CREATE TABLE","User":"root","DescriptorID":108,"TableName":"defaultdb.public.smallxxcol"}
I230126 09:14:58.312536 28947 5@util/log/event_log.go:32  [T1,n1,client=127.0.0.1:38990,user=root] 2043 ={"Timestamp":1674724498301071507,"EventType":"create_table","Statement":"CREATE TABLE defaultdb.public.mediumxcol (col1 INT8, col2 INT8)","Tag":"CREATE TABLE","User":"root","DescriptorID":109,"TableName":"defaultdb.public.mediumxcol"}
I230126 09:14:58.348637 28947 5@util/log/event_log.go:32  [T1,n1,client=127.0.0.1:38990,user=root] 2044 ={"Timestamp":1674724498336123856,"EventType":"create_table","Statement":"CREATE TABLE defaultdb.public.largexxcol (col1 INT8, col2 INT8, col3 UUID)","Tag":"CREATE TABLE","User":"root","DescriptorID":110,"TableName":"defaultdb.public.largexxcol"}
I230126 09:14:58.381272 28947 5@util/log/event_log.go:32  [T1,n1,client=127.0.0.1:38990,user=root] 2045 ={"Timestamp":1674724498369594894,"EventType":"create_table","Statement":"CREATE TABLE defaultdb.public.largestcol (col1 INT8, col2 INT8, col3 UUID, col4 UUID)","Tag":"CREATE TABLE","User":"root","DescriptorID":111,"TableName":"defaultdb.public.largestcol"}
=== RUN   TestDescriptorByteSizeOrder/tables_with_varying_idx
I230126 09:14:58.415326 28947 5@util/log/event_log.go:32  [T1,n1,client=127.0.0.1:38990,user=root] 2046 ={"Timestamp":1674724498403062321,"EventType":"create_table","Statement":"CREATE TABLE defaultdb.public.largexx (col1 INT8, col2 INT8, col3 STRING, col4 BOOL, INDEX (col1, col2, col3))","Tag":"CREATE TABLE","User":"root","DescriptorID":112,"TableName":"defaultdb.public.largexx"}
I230126 09:14:58.447687 28947 5@util/log/event_log.go:32  [T1,n1,client=127.0.0.1:38990,user=root] 2047 ={"Timestamp":1674724498435730511,"EventType":"create_table","Statement":"CREATE TABLE defaultdb.public.largest (col1 INT8, col2 INT8, col3 STRING, col4 BOOL, INDEX (col1, col2, col3, col4))","Tag":"CREATE TABLE","User":"root","DescriptorID":113,"TableName":"defaultdb.public.largest"}
I230126 09:14:58.478863 28947 5@util/log/event_log.go:32  [T1,n1,client=127.0.0.1:38990,user=root] 2048 ={"Timestamp":1674724498467309538,"EventType":"create_table","Statement":"CREATE TABLE defaultdb.public.smallxx (col1 INT8, col2 INT8, col3 STRING, col4 BOOL, INDEX (col1))","Tag":"CREATE TABLE","User":"root","DescriptorID":114,"TableName":"defaultdb.public.smallxx"}
I230126 09:14:58.511410 28947 5@util/log/event_log.go:32  [T1,n1,client=127.0.0.1:38990,user=root] 2049 ={"Timestamp":1674724498499856692,"EventType":"create_table","Statement":"CREATE TABLE defaultdb.public.mediumx (col1 INT8, col2 INT8, col3 STRING, col4 BOOL, INDEX (col1, col2))","Tag":"CREATE TABLE","User":"root","DescriptorID":115,"TableName":"defaultdb.public.mediumx"}
=== RUN   TestDescriptorByteSizeOrder/tables_with_varying_name
I230126 09:14:58.144962 28947 5@util/log/event_log.go:32  [T1,n1,client=127.0.0.1:38990,user=root] 2038 ={"Timestamp":1674724498134068309,"EventType":"create_table","Statement":"CREATE TABLE defaultdb.public.largestxxxxxx (col1 INT8)","Tag":"CREATE TABLE","User":"root","DescriptorID":104,"TableName":"defaultdb.public.largestxxxxxx"}
I230126 09:14:58.186221 28947 5@util/log/event_log.go:32  [T1,n1,client=127.0.0.1:38990,user=root] 2039 ={"Timestamp":1674724498175290979,"EventType":"create_table","Statement":"CREATE TABLE defaultdb.public.s (col1 INT8)","Tag":"CREATE TABLE","User":"root","DescriptorID":105,"TableName":"defaultdb.public.s"}
I230126 09:14:58.216623 28947 5@util/log/event_log.go:32  [T1,n1,client=127.0.0.1:38990,user=root] 2040 ={"Timestamp":1674724498205128954,"EventType":"create_table","Statement":"CREATE TABLE defaultdb.public.medxx (col1 INT8)","Tag":"CREATE TABLE","User":"root","DescriptorID":106,"TableName":"defaultdb.public.medxx"}
I230126 09:14:58.248360 28947 5@util/log/event_log.go:32  [T1,n1,client=127.0.0.1:38990,user=root] 2041 ={"Timestamp":1674724498235100092,"EventType":"create_table","Statement":"CREATE TABLE defaultdb.public.largexxxx (col1 INT8)","Tag":"CREATE TABLE","User":"root","DescriptorID":107,"TableName":"defaultdb.public.largexxxx"}

Parameters: TAGS=bazel,gss,race

Help

See also: How To Investigate a Go Test Failure (internal)

/cc @cockroachdb/sql-schema

This test on roachdash | Improve this report!

Jira issue: CRDB-23848

[yuzefovich]

dup of #95937