Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

kv/kvnemesis: TestKVNemesisMultiNode failed #111893

Closed
cockroach-teamcity opened this issue Oct 6, 2023 · 21 comments
Closed

kv/kvnemesis: TestKVNemesisMultiNode failed #111893

cockroach-teamcity opened this issue Oct 6, 2023 · 21 comments
Assignees
Labels
branch-master Failures and bugs on the master branch. C-bug Code not up to spec/doc, specs & docs deemed correct. Solution expected to change code/behavior. C-test-failure Broken test (automatically or manually discovered). O-robot Originated from a bot. release-blocker Indicates a release-blocker. Use with branch-release-2x.x label to denote which branch is blocked. T-kv KV Team
Milestone

Comments

@cockroach-teamcity
Copy link
Member

cockroach-teamcity commented Oct 6, 2023

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ b28fa0dbc55332c4bf01e8dd8399b827e9944672:

Fatal error:

panic: test timed out after 4m55s
running tests:
	TestKVNemesisMultiNode (4m31s)

Stack:

goroutine 78760 [running]:
testing.(*M).startAlarm.func1()
	GOROOT/src/testing/testing.go:2241 +0x31c
created by time.goFunc
	GOROOT/src/time/sleep.go:176 +0x38
Log preceding fatal error

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/4aeb9c412c16a003ed8ddc43788d2bba/logTestKVNemesisMultiNode1545812899
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 1205960823697348385
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/4aeb9c412c16a003ed8ddc43788d2bba/kvnemesis3784167393

Help

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

/cc @cockroachdb/kv

This test on roachdash | Improve this report!

Jira issue: CRDB-32119

@cockroach-teamcity cockroach-teamcity added branch-master Failures and bugs on the master branch. C-test-failure Broken test (automatically or manually discovered). O-robot Originated from a bot. release-blocker Indicates a release-blocker. Use with branch-release-2x.x label to denote which branch is blocked. T-kv KV Team labels Oct 6, 2023
@cockroach-teamcity cockroach-teamcity added this to the 23.2 milestone Oct 6, 2023
nvanbenschoten added a commit to nvanbenschoten/cockroach that referenced this issue Oct 6, 2023
Informs cockroachdb#111352.
Informs cockroachdb#111530.
Informs cockroachdb#111564.
Informs cockroachdb#111893.

This commit demonstrates that when an initial call to the lockTable's
ScanAndEnqueue method returns an error, the request was not previously being
dequeued from any other wait-queues that it may have entered.

Release note: None
nvanbenschoten added a commit to nvanbenschoten/cockroach that referenced this issue Oct 6, 2023
Informs cockroachdb#111352.
Informs cockroachdb#111530.
Informs cockroachdb#111564.
Informs cockroachdb#111893.

In 8205b43, we added a case to the lock table where a request's initial scan
could throw an error. This was not being handled properly if the request had
already entered any other lock wait-queues. In these cases, the request's entries
in those wait-queues would be abandoned and the locks would get stuck. This commit
fixes that issue by dequeuing the request from the lock table when throwing an
error from ScanAndEnqueue.

This was one of the causes of the recent kvnemesis instability, but we believe
that there is at least one other issue that is still causing timeouts.

Release note: None
craig bot pushed a commit that referenced this issue Oct 6, 2023
111884: cast: remove invalid casts for REFCURSOR r=DrewKimball a=DrewKimball

#### cast: remove invalid casts for REFCURSOR

The initial implementation for the `REFCURSOR` data type included
assignment casts from and explicit casts to every other type. In postgres,
`REFCURSOR` has only the following valid casts:
1. Explicit casts from each string type.
2. Assignment casts to each string type.

This patch aligns the casts for `REFCURSOR` with those of postgres, and
adds according tests.

Informs #111560

Release note: None

111923: kv: dequeue request from lock table wait-queues on scan error r=nvanbenschoten a=nvanbenschoten

Informs #111352.
Informs #111530.
Informs #111564.
Informs #111893.

In 8205b43, we added a case to the lock table where a request's initial scan could throw an error. This was not being handled properly if the request had already entered any other lock wait-queues. In these cases, the request's entries in those wait-queues would be abandoned and the locks would get stuck. This commit fixes that issue by dequeuing the request from the lock table when throwing an error from ScanAndEnqueue.

This was one of the causes of the recent kvnemesis instability, but we believe that there is at least one other issue that is still causing timeouts.

Release note: None

Co-authored-by: Drew Kimball <[email protected]>
Co-authored-by: Nathan VanBenschoten <[email protected]>
@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ 981b050f28f406b495b530ec5725fa2c91cd20c5:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode3176146430
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 1223574463877958642
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2643060483
    kvnemesis.go:177: error applying x.Txn(ctx, func(ctx context.Context, txn *kv.Txn) error {
          txn.SetIsoLevel(isolation.Serializable)
          txn.GetForUpdateSkipLocked(ctx, tk(7541402886544057907)) // @1696675185.233521891,0 (<nil>, <nil>)
          b := &kv.Batch{}
          b.ReverseScanForUpdateGuaranteedDurability(tk(3429441133484003031), tk(17106945857748821670)) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
          b.Put(tk(11917748767252285268), sv(154)) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
          txn.CommitInBatch(ctx, b) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
          return nil
        }) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
    kvnemesis.go:177: error applying x.ReverseScanForUpdateGuaranteedDurability(ctx, tk(3429441133484003031), tk(17106945857748821670), 0) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
    kvnemesis.go:177: error applying x.Put(ctx, tk(11917748767252285268), sv(154)) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2643060483/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2643060483/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2643060483/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2643060483/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 3
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:522: -- test log scope end --
test logs left over in: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode3176146430
--- FAIL: TestKVNemesisMultiNode (34.73s)

Parameters: TAGS=bazel,gss , stress=true

Help

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

This test on roachdash | Improve this report!

@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ 330500d0433bae42bc7c0d6842a427aab3b27f03:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2312240958
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 4039571310978632165
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3073051906
    kvnemesis.go:177: committed scan non-atomic timestamps: [s]/Table/100/"{8db103aca9faa78c"-dc467b5d22af366d"}:{0:[1696845382.793452296,0, 1696845383.407966011,1), 1:[1696845383.516461787,0, 1696845386.691997544,1), gap:[<min>, 1696845381.844857832,0),[1696845389.220586051,2, <max>),[1696845386.697722264,0, 1696845386.864470328,0),[1696845386.691997544,1, 1696845386.697722264,0),[1696845383.770443568,0, 1696845383.891262483,0),[1696845383.735433479,0, 1696845383.770443568,0),[1696845383.550217786,0, 1696845383.735433479,0),[1696845383.540195596,0, 1696845383.550217786,0),[1696845383.480814378,0, 1696845383.540195596,0),[1696845383.407966011,1, 1696845383.480814378,0),[1696845382.746147507,0, 1696845383.250564708,0),[1696845382.731854348,0, 1696845382.746147507,0),[1696845382.474407497,1, 1696845382.598409503,0),[1696845381.939622468,0, 1696845382.074701643,0),[1696845381.844857832,0, 1696845381.939622468,0)}->[/Table/100/"ac6dd8d34dd5b415":v36, /Table/100/"c90e8d072c4d9e58":v56]
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3073051906/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3073051906/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3073051906/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3073051906/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 1
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:522: -- test log scope end --
test logs left over in: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2312240958
--- FAIL: TestKVNemesisMultiNode (58.19s)

Parameters: TAGS=bazel,gss , stress=true

Help

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

This test on roachdash | Improve this report!

@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ 330500d0433bae42bc7c0d6842a427aab3b27f03:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2325601235
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 3707148923953701394
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1638029438
    kvnemesis.go:177: error applying x.Txn(ctx, func(ctx context.Context, txn *kv.Txn) error {
          txn.SetIsoLevel(isolation.Serializable)
          txn.ReverseScanForShare(ctx, tk(13022925816391870326), tk(14845968196079099879), 0) // @1696848353.598511296,0 (/Table/100/"c8dfb879f98968a0":v21, /Table/100/"c36f30b42d88bf3c":v21, <nil>)
          txn.ScanForShare(ctx, tk(3445095879652870196), tk(9363152008907828269), 0) // @1696848353.598511296,0 <nil>
          txn.GetForShareSkipLockedGuaranteedDurability(ctx, tk(12424434986267241171)) // @1696848353.598511296,0 (v21, <nil>)
          b := &kv.Batch{}
          b.ReverseScanForUpdateGuaranteedDurability(tk(11314999153882127624), tk(17324988695122563857)) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
          b.Put(tk(17018781593471932974), sv(22)) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
          b.Put(tk(7340350460042643609), sv(23)) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
          txn.CommitInBatch(ctx, b) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
          return nil
        }) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
    kvnemesis.go:177: error applying x.ReverseScanForUpdateGuaranteedDurability(ctx, tk(11314999153882127624), tk(17324988695122563857), 0) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
    kvnemesis.go:177: error applying x.Put(ctx, tk(17018781593471932974), sv(22)) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
    kvnemesis.go:177: error applying x.Put(ctx, tk(7340350460042643609), sv(23)) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1638029438/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1638029438/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1638029438/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1638029438/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 4
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:522: -- test log scope end --
test logs left over in: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2325601235
--- FAIL: TestKVNemesisMultiNode (93.98s)

Parameters: TAGS=bazel,gss,deadlock , stress=true

Help

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

This test on roachdash | Improve this report!

@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ bee7cfe930cabbbee3c813a273872e785e3b8ef4:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode44622659
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 2672618944380653393
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3370086706
    kvnemesis.go:177: error applying x.Txn(ctx, func(ctx context.Context, txn *kv.Txn) error {
          txn.SetIsoLevel(isolation.Serializable)
          {
            b := &kv.Batch{}
            b.ReverseScanForUpdateSkipLockedGuaranteedDurability(tk(3813779165314815393), tk(14608894420329784821)) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
            b.Put(tk(9352194361228735148), sv(40)) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
            txn.Run(ctx, b) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
          }
          txn.GetForUpdate(ctx, tk(12123624352229744890)) // omitted
          b := &kv.Batch{}
          txn.CommitInBatch(ctx, b) // omitted
          return nil
        }) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
    kvnemesis.go:177: error applying x.ReverseScanForUpdateSkipLockedGuaranteedDurability(ctx, tk(3813779165314815393), tk(14608894420329784821), 0) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
    kvnemesis.go:177: error applying x.Put(ctx, tk(9352194361228735148), sv(40)) // cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Exclusive at seq number 0, already held at higher seq number 1
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3370086706/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3370086706/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3370086706/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3370086706/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 3
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:522: -- test log scope end --
test logs left over in: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode44622659
--- FAIL: TestKVNemesisMultiNode (105.42s)

Parameters: TAGS=bazel,gss,deadlock , stress=true

Help

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

This test on roachdash | Improve this report!

@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ bee7cfe930cabbbee3c813a273872e785e3b8ef4:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2588933945
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 3669099335163391531
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3448160334
    kvnemesis.go:177: committed reverse scan non-atomic timestamps: [rs(skip-locked)]/Table/100/"{123fc7c6708d5173"-c2d94dae0715839a"}:{0:[1696933366.428017620,0, 1696933366.472695015,1), 1:[1696933366.118155210,0, 1696933366.472695015,1), 2:[1696933366.036039917,0, 1696933366.472695015,1), 3:[1696933366.420894820,0, 1696933366.472695015,1), 4:[1696933366.171106044,0, 1696933366.472695015,1), 5:[1696933366.118155210,0, 1696933366.472695015,1), 6:[1696933366.118155210,0, 1696933366.472695015,1), 7:[1696933366.036039917,0, 1696933366.118155210,0), 8:[1696933366.036039917,0, 1696933366.118155210,0), 9:[1696933366.036039917,0, 1696933366.472695015,1), 10:[1696933366.036039917,0, 1696933366.472695015,1), 11:[1696933366.497193583,0, 1696933366.967998228,0), 12:[1696933366.036039917,0, 1696933366.472695015,1), 13:[1696933366.036039917,0, 1696933366.472695015,1), gap:[<min>, <max>)}->[/Table/100/"c20051e559a39d76":v36, /Table/100/"c1d22a205350546c":v20, /Table/100/"af16a93b3a2576a5":v18, /Table/100/"acee7a6738274f69":v34, /Table/100/"a795607588270b12":v22, /Table/100/"9e8ef7a0a8c123e8":v20, /Table/100/"83291a7655e217a5":v20, /Table/100/"722190f0453e68ef":v18, /Table/100/"60b3740a200cb96f":v18, /Table/100/"57298b948ddb5c70":v18, /Table/100/"44516956608398f3":v18, /Table/100/"3a10b57d1fa4c19f":v38, /Table/100/"27747f8f01b5c510":v18, /Table/100/"203bed7ca6032018":v18]
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3448160334/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3448160334/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3448160334/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3448160334/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 1
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:522: -- test log scope end --
test logs left over in: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2588933945
--- FAIL: TestKVNemesisMultiNode (43.59s)

Parameters: TAGS=bazel,gss , stress=true

Help

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

This test on roachdash | Improve this report!

@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ 619aa6edad0269f75d12cb7d4b88b52f09825c7b:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode900808363
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 5195474811363532681
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2373293666
    kvnemesis.go:177: error applying x.Txn(ctx, func(ctx context.Context, txn *kv.Txn) error {
          txn.SetIsoLevel(isolation.Serializable)
          txn.GetForUpdateGuaranteedDurability(ctx, tk(13458908678218184615)) // @1697105140.464607971,0 (<nil>, <nil>)
          b := &kv.Batch{}
          b.ScanForShareGuaranteedDurability(tk(2024955328519925752), tk(7802481950680812783)) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
          b.Put(tk(4522765838274163937), sv(144)) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
          b.ReverseScanForShareSkipLockedGuaranteedDurability(tk(7270767705982639611), tk(16297672559698680572)) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
          txn.CommitInBatch(ctx, b) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
          return nil
        }) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
    kvnemesis.go:177: error applying x.ScanForShareGuaranteedDurability(ctx, tk(2024955328519925752), tk(7802481950680812783), 0) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
    kvnemesis.go:177: error applying x.Put(ctx, tk(4522765838274163937), sv(144)) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
    kvnemesis.go:177: error applying x.ReverseScanForShareSkipLockedGuaranteedDurability(ctx, tk(7270767705982639611), tk(16297672559698680572), 0) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2373293666/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2373293666/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2373293666/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2373293666/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 4
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:522: -- test log scope end --
--- FAIL: TestKVNemesisMultiNode (77.11s)

Parameters: TAGS=bazel,gss,deadlock , stress=true

Help

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

This test on roachdash | Improve this report!

@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ 8637692f77d69cda6052fef8e063cdac53870d3c:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode563695551
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 1545137183884969716
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2732802683
    kvnemesis.go:177: error applying x.Txn(ctx, func(ctx context.Context, txn *kv.Txn) error {
          txn.SetIsoLevel(isolation.Serializable)
          txn.Put(ctx, tk(8984331277108978738), sv(96)) // @1697193103.157539859,2 <nil>
          {
            b := &kv.Batch{}
            b.ScanForUpdateGuaranteedDurability(tk(1299266755581500206), tk(16530993450994937438)) // (/Table/100/"6a1405aa8dfb680d":v88, /Table/100/"7caec1b20c832432":v96, /Table/100/"940e9d785e8ca6b9":v100, /Table/100/"d491146603ca415d":v92, <nil>)
            txn.Run(ctx, b) // @1697193103.157539859,2 <nil>
          }
          txn.ScanForUpdateGuaranteedDurability(ctx, tk(12892340855967993839), tk(14287818056933251908), 0) // @1697193103.157539859,2 <nil>
          b := &kv.Batch{}
          b.Del(tk(15982153275517182942) /* @s97 */) // cannot acquire lock with strength Shared at seq number 2, already held at higher seq number 3
          b.ReverseScanForShareSkipLockedGuaranteedDurability(tk(5762171583672402455), tk(18312963354680504962)) // cannot acquire lock with strength Shared at seq number 2, already held at higher seq number 3
          b.Del(tk(10668637707595196089) /* @s98 */) // cannot acquire lock with strength Shared at seq number 2, already held at higher seq number 3
          txn.CommitInBatch(ctx, b) // cannot acquire lock with strength Shared at seq number 2, already held at higher seq number 3
          return nil
        }) // cannot acquire lock with strength Shared at seq number 2, already held at higher seq number 3: cannot acquire lock with strength Shared at seq number 2, already held at higher seq number 3
    kvnemesis.go:177: error applying x.Del(ctx, tk(15982153275517182942) /* @s97 */) // cannot acquire lock with strength Shared at seq number 2, already held at higher seq number 3: cannot acquire lock with strength Shared at seq number 2, already held at higher seq number 3
    kvnemesis.go:177: error applying x.ReverseScanForShareSkipLockedGuaranteedDurability(ctx, tk(5762171583672402455), tk(18312963354680504962), 0) // cannot acquire lock with strength Shared at seq number 2, already held at higher seq number 3: cannot acquire lock with strength Shared at seq number 2, already held at higher seq number 3
    kvnemesis.go:177: error applying x.Del(ctx, tk(10668637707595196089) /* @s98 */) // cannot acquire lock with strength Shared at seq number 2, already held at higher seq number 3: cannot acquire lock with strength Shared at seq number 2, already held at higher seq number 3
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2732802683/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2732802683/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2732802683/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2732802683/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 4
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:522: -- test log scope end --
test logs left over in: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode563695551
--- FAIL: TestKVNemesisMultiNode (58.50s)

Parameters: TAGS=bazel,gss , stress=true

Help

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

Same failure on other branches

This test on roachdash | Improve this report!

@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ fbbf7ef7869c725a73663f13347d38a413b33abd:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode208545769
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 5066837092697386799
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2132729977
    kvnemesis.go:177: error applying x.Txn(ctx, func(ctx context.Context, txn *kv.Txn) error {
          txn.SetIsoLevel(isolation.ReadCommitted)
          txn.GetForShareSkipLocked(ctx, tk(17144251159100582173)) // @1697450187.631302850,0 (<nil>, <nil>)
          b := &kv.Batch{}
          b.ScanForShareGuaranteedDurability(tk(10383546445024458260), tk(14475831523500873631)) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
          b.Put(tk(10403125587359502591), sv(103)) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
          b.ReverseScanForUpdateSkipLocked(tk(3232995388042647321), tk(16083570676207740680)) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
          txn.CommitInBatch(ctx, b) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
          return nil
        }) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
    kvnemesis.go:177: error applying x.ScanForShareGuaranteedDurability(ctx, tk(10383546445024458260), tk(14475831523500873631), 0) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
    kvnemesis.go:177: error applying x.Put(ctx, tk(10403125587359502591), sv(103)) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
    kvnemesis.go:177: error applying x.ReverseScanForUpdateSkipLocked(ctx, tk(3232995388042647321), tk(16083570676207740680), 0) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2132729977/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2132729977/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2132729977/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2132729977/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 4
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:522: -- test log scope end --
test logs left over in: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode208545769
--- FAIL: TestKVNemesisMultiNode (54.23s)

Parameters: TAGS=bazel,gss , stress=true

Help

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

Same failure on other branches

This test on roachdash | Improve this report!

@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ a45a76f76ee5d7bf87f5e642410d9be497caa051:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2016119153
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 887553841918239982
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2106356292
    kvnemesis.go:177: committed scan non-atomic timestamps: [s(skip-locked)]/Table/100/"{396d7606424d5a7e"-b32b2f4c75389510"}:{0:[1697629495.901882892,0, 1697629495.983703450,0), 1:[1697629495.901882892,0, 1697629495.983703450,0), 2:[1697629497.578304246,0, 1697629497.735355214,1), 3:[1697629496.927779958,0, 1697629497.735355214,1), 4:[1697629497.627945269,0, 1697629497.735355214,1), gap:[<min>, <max>)}->[/Table/100/"3c2bf78cf50e0994":v3, /Table/100/"421f00cb8f725986":v3, /Table/100/"452323cbb7b340c5":v92, /Table/100/"4d19d657f4c675f3":v66, /Table/100/"690150a92705bcdf":v95]
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2106356292/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2106356292/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2106356292/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2106356292/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 1
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:522: -- test log scope end --
test logs left over in: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2016119153
--- FAIL: TestKVNemesisMultiNode (26.55s)

Parameters: TAGS=bazel,gss , stress=true

Help

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

Same failure on other branches

This test on roachdash | Improve this report!

@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ a45a76f76ee5d7bf87f5e642410d9be497caa051:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode3730823413
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 3105118163580882536
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis4031446118
    kvnemesis.go:177: committed read committed txn non-atomic timestamps: [r]/Table/100/"318d7c9f5986b4de":[1697630238.690195060,0, 1697630243.361436469,1)->v20 [r]/Table/100/"3288b42aab4392db":[1697630238.690195060,0, 1697630243.361436469,1)->v20 [r(skip-locked)]/Table/100/"0c55e27e88674ac8":[1697630238.690195060,0, 1697630238.787468622,0)->v20 [r(skip-locked)]/Table/100/"3838fda66cb3a477":[1697630238.787468622,0, 1697630243.361436469,1)->v21
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis4031446118/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis4031446118/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis4031446118/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis4031446118/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 1
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:522: -- test log scope end --
test logs left over in: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode3730823413
--- FAIL: TestKVNemesisMultiNode (74.52s)

Parameters: TAGS=bazel,gss,deadlock , stress=true

Help

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

Same failure on other branches

This test on roachdash | Improve this report!

@andrewbaptist andrewbaptist added the C-bug Code not up to spec/doc, specs & docs deemed correct. Solution expected to change code/behavior. label Oct 19, 2023
@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ 9ae8d5cd9eae8df71dd7128f2cd02fea1f9d5fec:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2813877584
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 1438086008005333832
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1361443897
    kvnemesis.go:177: range 75 (/Table/100/"88b90f42f27d911d") RANGE_CONSISTENT_STATS_INCORRECT:
        stats: {ContainsEstimates:0 LastUpdateNanos:1697802897486226847 LockAge:0 GCBytesAge:2807 LiveBytes:53 LiveCount:1 KeyBytes:294 KeyCount:6 ValBytes:182 ValCount:14 IntentBytes:0 IntentCount:0 LockBytes:0 LockCount:0 RangeKeyCount:2 RangeKeyBytes:111 RangeValCount:3 RangeValBytes:31 SysBytes:4194 SysCount:34 AbortSpanBytes:2132}
        delta (stats-computed): {ContainsEstimates:0 LastUpdateNanos:1697802897486226847 LockAge:0 GCBytesAge:-1037 LiveBytes:0 LiveCount:0 KeyBytes:0 KeyCount:0 ValBytes:0 ValCount:0 IntentBytes:0 IntentCount:0 LockBytes:0 LockCount:0 RangeKeyCount:-1 RangeKeyBytes:-51 RangeValCount:-1 RangeValBytes:-10 SysBytes:0 SysCount:0 AbortSpanBytes:0}
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1361443897/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1361443897/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1361443897/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1361443897/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 1
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:522: -- test log scope end --
test logs left over in: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2813877584
--- FAIL: TestKVNemesisMultiNode (55.23s)

Parameters: TAGS=bazel,gss , stress=true

Help

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

Same failure on other branches

This test on roachdash | Improve this report!

@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ 9ae8d5cd9eae8df71dd7128f2cd02fea1f9d5fec:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode4140833291
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 4160834049327256606
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1885349348
    kvnemesis.go:177: committed read committed txn non-atomic timestamps: [r]/Table/100/"f0db8021318b5c38":[1697803037.277074592,0, <max>)->v71 [r]/Table/100/"ef7830da64b4c9f6":[1697803037.446353120,0, 1697803039.882643734,0)->v73 [w]/Table/100/"7f65c6f7be998836":1697803039.908205166,0->v82@s82
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1885349348/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1885349348/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1885349348/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1885349348/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 1
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:522: -- test log scope end --
test logs left over in: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode4140833291
--- FAIL: TestKVNemesisMultiNode (96.60s)

Parameters: TAGS=bazel,gss,deadlock , stress=true

Help

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

Same failure on other branches

This test on roachdash | Improve this report!

@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ e945e0dfa7aa644fec63d2550acbf137d1f3f77b:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode327251254
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 5653321609121677401
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2257394170
    kvnemesis.go:177: error applying x.Txn(ctx, func(ctx context.Context, txn *kv.Txn) error {
          txn.SetIsoLevel(isolation.Serializable)
          {
            b := &kv.Batch{}
            txn.Run(ctx, b) // <nil>
          }
          txn.GetForShareSkipLockedGuaranteedDurability(ctx, tk(13450443927532137491)) // @1697888839.652870881,0 (<nil>, <nil>)
          txn.ReverseScan(ctx, tk(6696776062276754077), tk(8565654149844647509), 0) // @1697888839.652870881,0 <nil>
          txn.ReverseScanForShareSkipLocked(ctx, tk(8904774748480204710), tk(12314689010047748385), 0) // @1697888839.652870881,0 <nil>
          {
            b := &kv.Batch{}
            txn.Run(ctx, b) // <nil>
          }
          b := &kv.Batch{}
          b.ReverseScanForShareGuaranteedDurability(tk(72771147179343552), tk(11647656063047543360)) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
          b.Del(tk(10216807126696623649) /* @s125 */) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
          b.ReverseScanForUpdateGuaranteedDurability(tk(2176068356282318994), tk(15118378894653368633)) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
          txn.CommitInBatch(ctx, b) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
          return nil
        }) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
    kvnemesis.go:177: error applying x.ReverseScanForShareGuaranteedDurability(ctx, tk(72771147179343552), tk(11647656063047543360), 0) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
    kvnemesis.go:177: error applying x.Del(ctx, tk(10216807126696623649) /* @s125 */) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
    kvnemesis.go:177: error applying x.ReverseScanForUpdateGuaranteedDurability(ctx, tk(2176068356282318994), tk(15118378894653368633), 0) // cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1: cannot acquire lock with strength Shared at seq number 0, already held at higher seq number 1
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2257394170/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2257394170/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2257394170/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis2257394170/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 4
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:522: -- test log scope end --
test logs left over in: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode327251254
--- FAIL: TestKVNemesisMultiNode (89.05s)

Parameters: TAGS=bazel,gss,deadlock , stress=true

Help

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

Same failure on other branches

This test on roachdash | Improve this report!

nvanbenschoten added a commit to nvanbenschoten/cockroach that referenced this issue Oct 23, 2023
Informs cockroachdb#112221.
Informs cockroachdb#112174.
Informs cockroachdb#112173.
Informs cockroachdb#111984.
Informs cockroachdb#111893.
Informs cockroachdb#111564.
Informs cockroachdb#111530.

This commit fixes the handling of replayed batches that contain a
replicated lock acquisition and a later write to the same key. In such
cases, the write at the higher sequence number should not be detected as
an error during the replay. Instead, it should simply be ignored.

Release note: None
craig bot pushed a commit that referenced this issue Oct 23, 2023
112725: sql: disallow refcursor comparisons during type-checking r=DrewKimball a=DrewKimball

This patch adds back the comparison functions for the `REFCURSOR` data type, since there are various points within the codebase where we rely on their existence. Users are still not allowed to use the comparison functions, but now this is checked during type-checking of the AST, rather than at execution-time. This should avoid internal errors from places in planning and execution that expect comparison overloads to exist, but also maintains parity with postgres.

Fixes #112365
Fixes #112642
Fixes #112362
Fixes #112368

Release note: None

112891: sql: fix for crash with null elements on jsonb_array_to_string_array r=maryliag a=maryliag

Fixes #112829

The builtin `jsonb_array_to_string_array` was crashing, when it had `null` elements. This commit handles this case, removing them from the final array.

Release note: None

112893: kv: tolerate lock acquisition replay with writes in same batch r=arulajmani a=nvanbenschoten

Informs #112221.
Informs #112174.
Informs #112173.
Informs #111984.
Informs #111893.
Informs #111564.
Informs #111530.

This commit fixes the handling of replayed batches that contain a replicated lock acquisition and a later write to the same key. In such cases, the write at the higher sequence number should not be detected as an error during the replay. Instead, it should simply be ignored.

Release note: None

Co-authored-by: Drew Kimball <[email protected]>
Co-authored-by: maryliag <[email protected]>
Co-authored-by: Nathan VanBenschoten <[email protected]>
blathers-crl bot pushed a commit that referenced this issue Oct 23, 2023
Informs #112221.
Informs #112174.
Informs #112173.
Informs #111984.
Informs #111893.
Informs #111564.
Informs #111530.

This commit fixes the handling of replayed batches that contain a
replicated lock acquisition and a later write to the same key. In such
cases, the write at the higher sequence number should not be detected as
an error during the replay. Instead, it should simply be ignored.

Release note: None
@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ aabfe2e105d1633d110050c236c8e0d8c107e168:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2229843898
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 6823248645473416827
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3406862794
    kvnemesis.go:177: committed read committed txn non-atomic timestamps: [r(skip-locked)]/Table/100/"c3e91f9283e5d2db":[1698148309.162352143,0, 1698148309.198469996,0)->v9 [r(skip-locked)]/Table/100/"eb80a1b76116eef3":[1698148308.969433379,0, 1698148311.431397830,2)->v4 [r(skip-locked)]/Table/100/"dc361c18083d56da":[1698148308.969433379,0, 1698148311.431397830,2)->v4 [r(skip-locked)]/Table/100/"c3e91f9283e5d2db":[1698148309.198469996,0, 1698148311.431397830,2)->v10 [r(skip-locked)]/Table/100/"c22bd1bbea1de348":[1698148309.085611718,0, 1698148311.431397830,2)->v6 [r(skip-locked)]/Table/100/"c13a357186f56580":[1698148308.969433379,0, 1698148311.431397830,2)->v4 [r(skip-locked)]/Table/100/"bca4d80f05e6e26e":[1698148309.198469996,0, 1698148311.431397830,2)->v10 [r(skip-locked)]/Table/100/"858bbfe5d7f1a199":[1698148308.969433379,0, 1698148315.069530295,0)->v4
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3406862794/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3406862794/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3406862794/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3406862794/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 1
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:522: -- test log scope end --
test logs left over in: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2229843898
--- FAIL: TestKVNemesisMultiNode (31.84s)

Parameters: TAGS=bazel,gss , stress=true

Help

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

Same failure on other branches

This test on roachdash | Improve this report!

@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ c933595a44fe594ccb1aa1c553315a2dae902767:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode3177644722
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 152118262270952960
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1794534346
    kvnemesis.go:177: committed read committed txn non-atomic timestamps: [d]/Table/100/"8a058e9d26788396":1698237808.202746561,0-><nil>@s13 [r(skip-locked)]/Table/100/"d068abf8be2e4d58":[1698237807.245840434,0, 1698237809.239836112,1)->v5 [r(skip-locked)]/Table/100/"decf87d9f4fd280d":[1698237807.914907131,2, 1698237808.192665413,4)->v12 [r(skip-locked)]/Table/100/"e7a1e82e92fccdb3":[1698237807.645404297,0, 1698237808.192665413,4)->v10 [r(skip-locked)]/Table/100/"decf87d9f4fd280d":[1698237808.192665413,4, 1698237808.458914501,0)->v15
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1794534346/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1794534346/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1794534346/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1794534346/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 1
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:523: -- test log scope end --
test logs left over in: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode3177644722
--- FAIL: TestKVNemesisMultiNode (86.02s)

Parameters: TAGS=bazel,gss,deadlock , stress=true

Help

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

Same failure on other branches

This test on roachdash | Improve this report!

nvanbenschoten added a commit to nvanbenschoten/cockroach that referenced this issue Oct 26, 2023
Informs cockroachdb#111984.
Informs cockroachdb#111893.
Informs cockroachdb#111530.

This commit changes storage.CheckSSTConflicts to also check for
replicated locks, in addition to checking for intents. This is necessary
to prevent AddSSTable from ingesting key-values pairs below replicated
locks, which could violate the isolation expected from these locks if an
ingested key-value is below the commit timestamp of the lock holder.
This was caught in kvnemesis by the validation logic recently introduced
in 7ff1c79.

The change alters the semantics of CheckSSTConflicts slightly for
intents. It now detects conflicts with any intents in the AddSSTable's
key span, instead of just those on overlapping keys. I think this is ok,
as the `checkConflicts = false` path in `EvalAddSSTable` already had
this behavior. This is reflected in the changes to cmd_add_sstable_test.go,
where three cases are no longer inconsistent with blind writes. I also
don't think this is a performance concern (like the comment that's being
deleted was suggesting) because we now have the separated lock table. In
fact, this change allows us to avoid using an `intentInterleavingIter`,
so if anything, it may provide a small performance win.

Release note: None
nvanbenschoten added a commit to nvanbenschoten/cockroach that referenced this issue Oct 26, 2023
Informs cockroachdb#111984.
Informs cockroachdb#111893.
Informs cockroachdb#111530.

This commit changes storage.CheckSSTConflicts to also check for
replicated locks, in addition to checking for intents. This is necessary
to prevent AddSSTable from ingesting key-values pairs below replicated
locks, which could violate the isolation expected from these locks if an
ingested key-value is below the commit timestamp of the lock holder.
This was caught in kvnemesis by the validation logic recently introduced
in 7ff1c79.

The change alters the semantics of CheckSSTConflicts slightly for
intents. It now detects conflicts with any intents in the AddSSTable's
key span, instead of just those on overlapping keys. I think this is ok,
as the `checkConflicts = false` path in `EvalAddSSTable` already had
this behavior. This is reflected in the changes to cmd_add_sstable_test.go,
where three cases are no longer inconsistent with blind writes. I also
don't think this is a performance concern (like the comment that's being
deleted was suggesting) because we now have the separated lock table. In
fact, this change allows us to avoid using an `intentInterleavingIter`,
so if anything, it may provide a small performance win.

Release note: None
craig bot pushed a commit that referenced this issue Oct 26, 2023
113058: roachtest: add min support version for 24.1 in multitenant-upgrade r=yuzefovich a=yuzefovich

Fixes: #112776.

Release note: None

113120: storage: check for replicated locks in CheckSSTConflicts r=nvanbenschoten a=nvanbenschoten

Informs #111984.
Informs #111893.
Informs #111530.

This commit changes storage.CheckSSTConflicts to also check for replicated locks, in addition to checking for intents. This is necessary to prevent AddSSTable from ingesting key-values pairs below replicated locks, which could violate the isolation expected from these locks if an ingested key-value is below the commit timestamp of the lock holder. This was caught in kvnemesis by the validation logic recently introduced in 7ff1c79.

The change alters the semantics of CheckSSTConflicts slightly for intents. It now detects conflicts with any intents in the AddSSTable's key span, instead of just those on overlapping keys. I think this is ok, as the `checkConflicts = false` path in `EvalAddSSTable` already had this behavior. This is reflected in the changes to cmd_add_sstable_test.go, where three cases are no longer inconsistent with blind writes. I also don't think this is a performance concern (like the comment that's being deleted was suggesting) because we now have the separated lock table. In fact, this change allows us to avoid using an `intentInterleavingIter`, so if anything, it may provide a small performance win.

Release note: None

Co-authored-by: Yahor Yuzefovich <[email protected]>
Co-authored-by: Nathan VanBenschoten <[email protected]>
nvanbenschoten added a commit to nvanbenschoten/cockroach that referenced this issue Oct 26, 2023
Informs cockroachdb#111984.
Informs cockroachdb#111893.
Informs cockroachdb#111530.

This commit changes storage.CheckSSTConflicts to also check for
replicated locks, in addition to checking for intents. This is necessary
to prevent AddSSTable from ingesting key-values pairs below replicated
locks, which could violate the isolation expected from these locks if an
ingested key-value is below the commit timestamp of the lock holder.
This was caught in kvnemesis by the validation logic recently introduced
in 7ff1c79.

The change alters the semantics of CheckSSTConflicts slightly for
intents. It now detects conflicts with any intents in the AddSSTable's
key span, instead of just those on overlapping keys. I think this is ok,
as the `checkConflicts = false` path in `EvalAddSSTable` already had
this behavior. This is reflected in the changes to cmd_add_sstable_test.go,
where three cases are no longer inconsistent with blind writes. I also
don't think this is a performance concern (like the comment that's being
deleted was suggesting) because we now have the separated lock table. In
fact, this change allows us to avoid using an `intentInterleavingIter`,
so if anything, it may provide a small performance win.

Release note: None
@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ aa85c69776da942b4d539891725b780ba995401e:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2152225735
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 3884413526920329392
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3727672669
    kvnemesis.go:177: committed serializable txn non-atomic timestamps: [rs]/Table/100/"{144929e8c9dc6a42"-f55d9b6e3d837fba"}:{0:[1698493407.148180765,0, 1698493407.597177480,1), 1:[1698493408.107391237,0, <max>), 2:[1698493408.535758873,1, 1698493409.297449867,1), 3:[1698493408.535758873,1, 1698493409.297449867,1), 4:[1698493408.179153133,0, 1698493409.297449867,1), 5:[1698493408.155963536,0, 1698493409.297449867,1), 6:[1698493408.529331179,0, 1698493409.297449867,1), 7:[1698493408.485751525,0, 1698493409.297449867,1), 8:[1698493408.485751525,0, 1698493409.297449867,1), 9:[1698493408.485751525,0, 1698493409.297449867,1), 10:[1698493408.485751525,0, 1698493409.297449867,1), 11:[1698493408.485751525,0, 1698493409.297449867,1), 12:[1698493408.485751525,0, 1698493409.297449867,1), 13:[1698493408.294449962,0, 1698493408.967880938,0), 14:[1698493408.485751525,0, 1698493409.297449867,1), 15:[1698493408.485751525,0, 1698493409.297449867,1), 16:[1698493408.485751525,0, 1698493409.297449867,1), 17:[1698493408.485751525,0, 1698493409.297449867,1), 18:[1698493408.485751525,0, 1698493409.297449867,1), gap:[<min>, 1698493406.503853997,0),[1698493406.608670961,1, 1698493406.618914237,0),[1698493410.252160503,1, 1698493410.254544967,0),[1698493408.708836980,0, 1698493408.723296795,0),[1698493408.597433659,0, 1698493408.708836980,0),[1698493408.548142399,0, 1698493408.597433659,0),[1698493406.676795537,0, 1698493406.754034027,0)}->[/Table/100/"f4fdbd628dfdd976":v40, /Table/100/"f2559b955bbf28ad":v71, /Table/100/"e668538f1dcb2cd1":v81, /Table/100/"e668538f1dcb2cd1":v81, /Table/100/"e1a1b9e79afc69a0":v77, /Table/100/"dfa656c123249555":v76, /Table/100/"cc8016df55f1a532":v84, /Table/100/"c6feea83bc13f8be":v82, /Table/100/"c6a1c9e91ad27487":v82, /Table/100/"c4f0c5db0915af6d":v82, /Table/100/"c25a0a5058a54eb5":v82, /Table/100/"c24620fdc9afc3eb":v82, /Table/100/"c08ad29b6ecd23e3":v82, /Table/100/"c02b49e6294b9d56":v79, /Table/100/"beb0eba8baff8f24":v82, /Table/100/"bab5088ed28b99ea":v82, /Table/100/"b9bc5249c83078e4":v82, /Table/100/"b8c78bb79a8bd250":v82, /Table/100/"b80850fc9bca072a":v82] [s]/Table/100/"{659d580ffec9cec7"-e0be2e76c9293886"}:{0:[1698493408.485751525,0, 1698493409.297449867,1), 1:[1698493408.485751525,0, 1698493409.297449867,1), 2:[1698493408.485751525,0, 1698493409.297449867,1), 3:[1698493408.485751525,0, 1698493409.297449867,1), 4:[1698493408.485751525,0, 1698493409.297449867,1), 5:[1698493408.294449962,0, 1698493408.967880938,0), 6:[1698493408.485751525,0, 1698493409.297449867,1), 7:[1698493408.485751525,0, 1698493409.297449867,1), 8:[1698493408.485751525,0, 1698493409.297449867,1), 9:[1698493408.485751525,0, 1698493409.297449867,1), 10:[1698493408.485751525,0, 1698493409.297449867,1), 11:[1698493408.485751525,0, 1698493409.297449867,1), 12:[1698493408.529331179,0, 1698493409.297449867,1), 13:[1698493408.155963536,0, 1698493409.297449867,1), gap:[<min>, 1698493406.618914237,0),[1698493406.622483380,0, 1698493406.666006420,0),[1698493406.666006420,0, 1698493406.674559908,0),[1698493417.276856897,3, 1698493417.327834122,0),[1698493410.252160503,1, 1698493410.254544967,0),[1698493409.976127145,1, 1698493409.997444928,0),[1698493409.297449867,1, 1698493409.501432895,0),[1698493408.597433659,0, 1698493408.723296795,0),[1698493408.529331179,1, 1698493408.597433659,0),[1698493408.510144285,1, 1698493408.529331179,1),[1698493407.937427418,1, 1698493407.967184691,0),[1698493407.864927453,1, 1698493407.937427418,1),[1698493407.041058034,0, 1698493407.062811755,0),[1698493406.676795537,0, 1698493407.041058034,0)}->[/Table/100/"b80850fc9bca072a":v82, /Table/100/"b8c78bb79a8bd250":v82, /Table/100/"b9bc5249c83078e4":v82, /Table/100/"bab5088ed28b99ea":v82, /Table/100/"beb0eba8baff8f24":v82, /Table/100/"c02b49e6294b9d56":v79, /Table/100/"c08ad29b6ecd23e3":v82, /Table/100/"c24620fdc9afc3eb":v82, /Table/100/"c25a0a5058a54eb5":v82, /Table/100/"c4f0c5db0915af6d":v82, /Table/100/"c6a1c9e91ad27487":v82, /Table/100/"c6feea83bc13f8be":v82, /Table/100/"cc8016df55f1a532":v84, /Table/100/"dfa656c123249555":v76] [r]/Table/100/"09737a2fa32d685d":[<min>, 1698493406.676795537,0),[1698493406.676795537,0, <max>)-><nil> [rs]/Table/100/"{65c6f3c788f3c305"-8b367ddfb7ba4f1f"}:{gap:[<min>, 1698493406.618914237,0),[1698493406.622483380,0, 1698493406.676795537,0),[1698493417.276856897,1, <max>),[1698493411.546109479,0, 1698493411.855456235,0),[1698493410.252160503,1, 1698493410.254544967,0),[1698493409.768376711,1, 1698493409.997444928,0),[1698493409.292354043,0, 1698493409.512816366,1),[1698493408.529331179,1, 1698493409.292354043,0),[1698493408.510144285,1, 1698493408.529331179,1),[1698493407.937427418,1, 1698493408.213099628,0),[1698493407.666751755,1, 1698493407.937427418,1),[1698493407.041058034,0, 1698493407.062811755,0),[1698493406.676795537,0, 1698493407.041058034,0)}->[] [s]/Table/100/"{4c3506a77328481c"-ec0ea97f7e4f8e0d"}:{0:[1698493408.485751525,0, 1698493409.297449867,1), 1:[1698493408.485751525,0, 1698493409.297449867,1), 2:[1698493408.485751525,0, 1698493409.297449867,1), 3:[1698493408.485751525,0, 1698493409.297449867,1), 4:[1698493408.485751525,0, 1698493409.297449867,1), 5:[1698493408.294449962,0, 1698493408.967880938,0), 6:[1698493408.485751525,0, 1698493409.297449867,1), 7:[1698493408.485751525,0, 1698493409.297449867,1), 8:[1698493408.485751525,0, 1698493409.297449867,1), 9:[1698493408.485751525,0, 1698493409.297449867,1), 10:[1698493408.485751525,0, 1698493409.297449867,1), 11:[1698493408.485751525,0, 1698493409.297449867,1), 12:[1698493408.529331179,0, 1698493409.297449867,1), 13:[1698493408.155963536,0, 1698493409.297449867,1), 14:[1698493408.179153133,0, 1698493409.297449867,1), 15:[1698493408.535758873,1, 1698493409.297449867,1), gap:[<min>, 1698493406.503853997,0),[1698493406.608670961,1, 1698493406.618914237,0),[1698493417.276856897,3, 1698493417.327834122,0),[1698493410.252160503,1, 1698493410.254544967,0),[1698493409.976127145,1, 1698493409.997444928,0),[1698493409.478692984,0, 1698493409.501432895,0),[1698493409.297449867,1, 1698493409.478692984,0),[1698493408.597433659,0, 1698493408.723296795,0),[1698493408.535758873,0, 1698493408.597433659,0),[1698493408.529331179,1, 1698493408.535758873,0),[1698493408.510144285,1, 1698493408.529331179,1),[1698493407.937427418,1, 1698493407.967184691,0),[1698493407.864927453,1, 1698493407.937427418,1),[1698493407.041058034,0, 1698493407.062811755,0),[1698493407.011963372,0, 1698493407.041058034,0),[1698493406.971423477,0, 1698493407.011963372,0),[1698493406.676795537,0, 1698493406.971423477,0)}->[/Table/100/"b80850fc9bca072a":v82, /Table/100/"b8c78bb79a8bd250":v82, /Table/100/"b9bc5249c83078e4":v82, /Table/100/"bab5088ed28b99ea":v82, /Table/100/"beb0eba8baff8f24":v82, /Table/100/"c02b49e6294b9d56":v79, /Table/100/"c08ad29b6ecd23e3":v82, /Table/100/"c24620fdc9afc3eb":v82, /Table/100/"c25a0a5058a54eb5":v82, /Table/100/"c4f0c5db0915af6d":v82, /Table/100/"c6a1c9e91ad27487":v82, /Table/100/"c6feea83bc13f8be":v82, /Table/100/"cc8016df55f1a532":v84, /Table/100/"dfa656c123249555":v76, /Table/100/"e1a1b9e79afc69a0":v77, /Table/100/"e668538f1dcb2cd1":v81] [s]/Table/100/"{1c50360838d93e86"-b3672b7c0c2a9556"}:{gap:[<min>, 1698493406.503853997,0),[1698493406.608670961,1, 1698493406.618914237,0),[1698493417.276856897,1, 1698493417.276856897,2),[1698493411.702776371,0, 1698493411.855456235,0),[1698493411.698277664,0, 1698493411.702776371,0),[1698493411.569715263,0, 1698493411.698277664,0),[1698493411.546109479,0, 1698493411.569715263,0),[1698493410.252160503,1, 1698493410.254544967,0),[1698493408.708836980,0, 1698493408.752178404,1),[1698493408.597433659,0, 1698493408.708836980,0),[1698493408.548142399,0, 1698493408.597433659,0),[1698493406.676795537,0, 1698493406.754034027,0)}->[]
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3727672669/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3727672669/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3727672669/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3727672669/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 1
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:523: -- test log scope end --
test logs left over in: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2152225735
--- FAIL: TestKVNemesisMultiNode (51.77s)

Parameters: TAGS=bazel,gss , stress=true

Help

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

Same failure on other branches

This test on roachdash | Improve this report!

@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on master @ cedd409f2115655efe2c529f6052f3056673b277:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2197849698
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:283: seed: 4458599304591706160
    kvnemesis_test.go:197: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3334970184
    kvnemesis.go:177: committed read committed txn non-atomic timestamps: [r]/Table/100/"59a3d54f65d4aefc":[1698925345.096956336,1, 1698925345.131341092,1)->v12 [r]/Table/100/"59a3d54f65d4aefc":[1698925345.096956336,1, 1698925345.131341092,1)->v12 [w]/Table/100/"65fd24fc7306482b":1698925345.144801171,0->v15@s15 [d]/Table/100/"8bbf3fd4d0259ab2":1698925345.144801171,0-><nil>@s16
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3334970184/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3334970184/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3334970184/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3334970184/kvs-scan.txt
    kvnemesis_test.go:310: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:310
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:261
        	Error:      	Should be zero, but was 1
        	Test:       	TestKVNemesisMultiNode
        	Messages:   	kvnemesis detected failures
    panic.go:523: -- test log scope end --
test logs left over in: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2197849698
--- FAIL: TestKVNemesisMultiNode (39.39s)

Parameters: TAGS=bazel,gss,deadlock , stress=true

Help

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

Same failure on other branches

This test on roachdash | Improve this report!

@cockroach-teamcity

This comment was marked as duplicate.

nvanbenschoten added a commit to nvanbenschoten/cockroach that referenced this issue Nov 6, 2023
Informs cockroachdb#111893, and others.

Deflakes kvnemesis, until cockroachdb#113768 is addressed.

Release note: None
craig bot pushed a commit that referenced this issue Nov 6, 2023
113887: kv/kvnemesis: disable replicated lock validation r=nvanbenschoten a=nvanbenschoten

Informs #111893, and others.

Deflakes kvnemesis, until #113768 is addressed.

Release note: None

Co-authored-by: Nathan VanBenschoten <[email protected]>
@nvanbenschoten
Copy link
Member

Closing, as the only remaining failure mode is due to #113768, which is disabled by #113887. If this fails again, I'd like it to open a new issue.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
branch-master Failures and bugs on the master branch. C-bug Code not up to spec/doc, specs & docs deemed correct. Solution expected to change code/behavior. C-test-failure Broken test (automatically or manually discovered). O-robot Originated from a bot. release-blocker Indicates a release-blocker. Use with branch-release-2x.x label to denote which branch is blocked. T-kv KV Team
Projects
No open projects
Archived in project
Development

No branches or pull requests

4 participants