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 #118471

Closed
cockroach-teamcity opened this issue Jan 30, 2024 · 23 comments
Closed

kv/kvnemesis: TestKVNemesisMultiNode failed #118471

cockroach-teamcity opened this issue Jan 30, 2024 · 23 comments
Assignees
Labels
A-testing Testing tools and infrastructure branch-release-23.2 Used to mark GA and release blockers, technical advisories, and bugs for 23.2 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. P-3 Issues/test failures with no fix SLA T-kv KV Team
Milestone

Comments

@cockroach-teamcity
Copy link
Member

cockroach-teamcity commented Jan 30, 2024

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on release-23.2 @ 1a497ceea8c72d2a853404a9c5a0020f7509fadf:

Fatal error:

panic: r88/3: closed timestamp regression 1706618852.999744812,0 -> 1706618852.423906975,0 (cmd "1b062242f346d0cf" index 15 term 6 (LAI=2 CTS=1706618852.423906975,0))
data: 001b062242f346d0cf30056a13420092010e093b275e94ce20af1760e302680172420a40000000000000000001000000172f016b12ec123665396239373563396462313336306600ff01000174786e2da0cd69beea894263b9401fc1bcfc77e30002b2017a0020028a010a089fedd88dde99c8d717 [recovered]
	panic: r88/3: closed timestamp regression 1706618852.999744812,0 -> 1706618852.423906975,0 (cmd "1b062242f346d0cf" index 15 term 6 (LAI=2 CTS=1706618852.423906975,0))
data: 001b062242f346d0cf30056a13420092010e093b275e94ce20af1760e302680172420a40000000000000000001000000172f016b12ec123665396239373563396462313336306600ff01000174786e2da0cd69beea894263b9401fc1bcfc77e30002b2017a0020028a010a089fedd88dde99c8d717

Stack:

goroutine 6731760 [running]:
github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).recover(0x1eebf45?, {0x6b7dd80, 0xc01af827b0})
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:230 +0x65
panic({0x46b3ec0?, 0xc00a2fd980?})
	GOROOT/src/runtime/panic.go:914 +0x21f
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*rangeAsserter).apply.func1({0x503d361?, 0xc02274f830?}, {0xc0039dd9c0?, 0x8?, 0xc0039dd610?})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:250 +0x165
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*rangeAsserter).apply(0xc01527eeb0, 0x3, {0xc00e2590d0, 0x8}, {0x6, 0xf, 0x0, {0xc00a444000, 0x75, 0x80}}, ...)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:301 +0x5f5
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Asserter).Apply(0x4f119e0?, 0x41aa3d?, 0x48b5600?, {0xc00e2590d0, 0x8}, {0x6, 0xf, 0x0, {0xc00a444000, 0x75, ...}}, ...)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:231 +0xa5
github.com/cockroachdb/cockroach/pkg/kv/kvnemesis.kvnemesisTestCfg.testClusterArgs.func7({{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, {0x0, 0x0, 0x0}, {0x0, ...}, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:145 +0x86
github.com/cockroachdb/cockroach/pkg/kv/kvserver.replicaApplyTestingFilters({0xc00bf67310?, 0x6b7dd80?}, 0xc000aa5020?, 0xc007d350b8?, {0xc00bf67360?, 0xc01981e0a0?, 0x0?}, 0x80?)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_application_state_machine.go:117 +0x1f7
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*replicaAppBatch).Stage(0xc00bf67310, {0x6b7dd80, 0xc000aa5020}, {0x6b9dd20?, 0xc007d350b8})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_app_batch.go:113 +0xe9
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.mapCmdIter({0x6bacf50?, 0xc00bf676b8}, 0xc0039de568)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/cmd.go:192 +0x148
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Task).applyOneBatch(0xc0039dead8, {0x6b7dd80, 0xc000aa5020}, {0x6bacf50, 0xc00bf67688})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/task.go:284 +0x150
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Task).ApplyCommittedEntries(0xc0039dead8, {0x6b7dd80, 0xc000aa5020})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/task.go:251 +0x95
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReadyRaftMuLocked(_, {_, _}, {{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_raft.go:1059 +0x1445
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReady(_, {_, _}, {{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_raft.go:744 +0x1b0
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Store).processReady(0xc0028e2000, 0x6b7dd80?)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/store_raft.go:689 +0x12b
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftSchedulerShard).worker(0xc004d2f7a0, {0x6b7dd80, 0xc01af827b0}, {0x6b61fa0, 0xc0028e2000}, 0xc002b16000)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/scheduler.go:418 +0x18d
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftScheduler).Start.func2({0x6b7dd80?, 0xc01af827b0?})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/scheduler.go:321 +0x46
github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunAsyncTaskEx.func2()
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:484 +0x13a
created by github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunAsyncTaskEx in goroutine 6115467
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:475 +0x415
Log preceding fatal error

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode1802307199
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:319: seed: 5385177087411898684
    kvnemesis_test.go:226: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis694914194

Parameters:

  • TAGS=bazel,gss,deadlock
  • stress=true
Help

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

Same failure on other branches

/cc @cockroachdb/kv

This test on roachdash | Improve this report!

Jira issue: CRDB-35752

@cockroach-teamcity cockroach-teamcity added branch-release-23.2 Used to mark GA and release blockers, technical advisories, and bugs for 23.2 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 Jan 30, 2024
@cockroach-teamcity cockroach-teamcity added this to the 23.2 milestone Jan 30, 2024
@kvoli
Copy link
Collaborator

kvoli commented Jan 30, 2024

Duplicate of #118017.

@kvoli kvoli added X-duplicate Closed as a duplicate of another issue. P-2 Issues/test failures with a fix SLA of 3 months labels Jan 30, 2024
@erikgrinaker
Copy link
Contributor

Pretty sure this is a test issue, as described here: #118017 (comment)

@pav-kv Can you confirm, and remove the blocker label in that case?

@pav-kv
Copy link
Collaborator

pav-kv commented Feb 1, 2024

There is no raft log tail for this one to confirm 100%. But the signature looks the same. A LAI 3 proposal got reassigned LAI 1 (by this test), and accidentally overtaken a legit LAI 2 proposal.

@pav-kv pav-kv removed the release-blocker Indicates a release-blocker. Use with branch-release-2x.x label to denote which branch is blocked. label Feb 1, 2024
@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on release-23.2 @ 11ad4340c4949d16546de5f4064357d7c39882fe:

Fatal error:

panic: r89/2: closed timestamp regression 1707574504.121837297,0 -> 1707574503.127286278,0 (cmd "476612f1a0f28f28" index 14 term 6 (LAI=2 CTS=1707574503.127286278,0))
data: 00476612f1a0f28f2830056a1542009201100915007758f785b217800104a801940472b1020aae02000000000000000002000000012e017a6b12ec123736313930393531383137653338643000ff010001000257285805e27a43dab44fe5057ab5ea1e12600a480a1057285805e27a43dab44fe5057ab5ea1e10011a14ec123730386334663534306338346466326600012a0a089580dcc3f5bea1d91730aa90074a0a089580dcc3f5bea1d9175003120c089580dcc3f5bea1d91710001800200028004801012e017a6b12ec123737343766343664303033373039643500ff010001000257285805e27a43dab44fe5057ab5ea1e12600a480a1057285805e27a43dab44fe5057ab5ea1e10011a14ec123730386334663534306338346466326600012a0a089580dcc3f5bea1d91730aa90074a0a089580dcc3f5bea1d9175003120c089580dcc3f5bea1d917100018002000280048017a008201260a12637264622d7472616365722d7370616e69641210323531626135343435653338643663658201270a13637264622d7472616365722d747261636569641210363333643934306630363937323734328201080a0372656312017620028a010a088684aab3eabea1d917 [recovered]
	panic: r89/2: closed timestamp regression 1707574504.121837297,0 -> 1707574503.127286278,0 (cmd "476612f1a0f28f28" index 14 term 6 (LAI=2 CTS=1707574503.127286278,0))
data: 00476612f1a0f28f2830056a1542009201100915007758f785b217800104a801940472b1020aae02000000000000000002000000012e017a6b12ec123736313930393531383137653338643000ff010001000257285805e27a43dab44fe5057ab5ea1e12600a480a1057285805e27a43dab44fe5057ab5ea1e10011a14ec123730386334663534306338346466326600012a0a089580dcc3f5bea1d91730aa90074a0a089580dcc3f5bea1d9175003120c089580dcc3f5bea1d91710001800200028004801012e017a6b12ec123737343766343664303033373039643500ff010001000257285805e27a43dab44fe5057ab5ea1e12600a480a1057285805e27a43dab44fe5057ab5ea1e10011a14ec123730386334663534306338346466326600012a0a089580dcc3f5bea1d91730aa90074a0a089580dcc3f5bea1d9175003120c089580dcc3f5bea1d917100018002000280048017a008201260a12637264622d7472616365722d7370616e69641210323531626135343435653338643663658201270a13637264622d7472616365722d747261636569641210363333643934306630363937323734328201080a0372656312017620028a010a088684aab3eabea1d917

Stack:

goroutine 8441550 [running]:
github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).recover(0x1eeea05?, {0x6c0d500, 0xc0186c4210})
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:230 +0x65
panic({0x46ca060?, 0xc010f4c260?})
	GOROOT/src/runtime/panic.go:914 +0x21f
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*rangeAsserter).apply.func1({0x505419b?, 0xc0159f4c00?}, {0xc0114759c0?, 0x8?, 0xc011475800?})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:250 +0x165
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*rangeAsserter).apply(0xc00a41c5f0, 0x2, {0xc013e754b0, 0x8}, {0x6, 0xe, 0x0, {0xc0163d5680, 0x1c5, 0x1e0}}, ...)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:301 +0x5f5
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Asserter).Apply(0x6c0d3e8?, 0x9f48240?, 0x6bcf3a0?, {0xc013e754b0, 0x8}, {0x6, 0xe, 0x0, {0xc0163d5680, 0x1c5, ...}}, ...)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:231 +0xa5
github.com/cockroachdb/cockroach/pkg/kv/kvnemesis.kvnemesisTestCfg.testClusterArgs.func7({{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, {0x0, 0x0, 0x0}, {0x0, ...}, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:145 +0x86
github.com/cockroachdb/cockroach/pkg/kv/kvserver.replicaApplyTestingFilters({0xc00495da10?, 0x6c0d500?}, 0xc007ecf980?, 0xc007108128?, {0xc00495da60?, 0xc00eb110e0?, 0x0?}, 0x0?)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_application_state_machine.go:117 +0x1f7
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*replicaAppBatch).Stage(0xc00495da10, {0x6c0d500, 0xc007ecf980}, {0x6c2d4a0?, 0xc007108128})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_app_batch.go:113 +0xe9
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.mapCmdIter({0x6c3c6d0?, 0xc00495ddb8}, 0xc00ba1c568)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/cmd.go:192 +0x148
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Task).applyOneBatch(0xc00ba1cad8, {0x6c0d500, 0xc0065fce40}, {0x6c3c6d0, 0xc00495dd88})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/task.go:284 +0x150
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Task).ApplyCommittedEntries(0xc00ba1cad8, {0x6c0d500, 0xc0065fce40})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/task.go:251 +0x95
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReadyRaftMuLocked(_, {_, _}, {{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_raft.go:1059 +0x1445
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReady(_, {_, _}, {{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_raft.go:744 +0x1b0
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Store).processReady(0xc00a892a80, 0x6c0d500?)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/store_raft.go:689 +0x12b
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftSchedulerShard).worker(0xc0101bb030, {0x6c0d500, 0xc0186c4210}, {0x6bf1700, 0xc00a892a80}, 0xc00ae7a000)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/scheduler.go:418 +0x18d
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftScheduler).Start.func2({0x6c0d500?, 0xc0186c4210?})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/scheduler.go:321 +0x46
github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunAsyncTaskEx.func2()
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:484 +0x13a
created by github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunAsyncTaskEx in goroutine 7775924
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:475 +0x415
Log preceding fatal error

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode340074369
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:319: seed: 5414376692568374743
    kvnemesis_test.go:226: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis4027102145

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 release-23.2 @ 0df66c4306badb5116db98f43baa4ea39aade92f:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2778024207
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:319: seed: 5297208183558467334
    kvnemesis_test.go:226: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3164087104
    kvnemesis.go:177: range 67 (/Table/65) RANGE_CONSISTENT_STATS_INCORRECT:
        stats: {ContainsEstimates:0 LastUpdateNanos:1708519951602532721 LockAge:0 GCBytesAge:25968 LiveBytes:318 LiveCount:6 KeyBytes:1491 KeyCount:35 ValBytes:1032 ValCount:63 IntentBytes:0 IntentCount:0 LockBytes:0 LockCount:0 RangeKeyCount:8 RangeKeyBytes:606 RangeValCount:30 RangeValBytes:301 SysBytes:8098 SysCount:10 AbortSpanBytes:257}
        delta (stats-computed): {ContainsEstimates:0 LastUpdateNanos:1708519951602532721 LockAge:0 GCBytesAge:0 LiveBytes:0 LiveCount:0 KeyBytes:0 KeyCount:0 ValBytes:0 ValCount:0 IntentBytes:0 IntentCount:0 LockBytes:0 LockCount:0 RangeKeyCount:0 RangeKeyBytes:0 RangeValCount:0 RangeValBytes:0 SysBytes:-10 SysCount:0 AbortSpanBytes:0}
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3164087104/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3164087104/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3164087104/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3164087104/kvs-scan.txt
    kvnemesis_test.go:347: [0] proposed: 3104
    kvnemesis_test.go:349: [0] reproposed unchanged: 68
    kvnemesis_test.go:351: [0] reproposed with new LAI: 6
    kvnemesis_test.go:347: [1] proposed: 298
    kvnemesis_test.go:349: [1] reproposed unchanged: 4
    kvnemesis_test.go:351: [1] reproposed with new LAI: 2
    kvnemesis_test.go:347: [2] proposed: 255
    kvnemesis_test.go:349: [2] reproposed unchanged: 2
    kvnemesis_test.go:351: [2] reproposed with new LAI: 0
    kvnemesis_test.go:347: [3] proposed: 416
    kvnemesis_test.go:349: [3] reproposed unchanged: 4
    kvnemesis_test.go:351: [3] reproposed with new LAI: 9
    kvnemesis_test.go:356: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:356
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:296
        	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/logTestKVNemesisMultiNode2778024207
--- FAIL: TestKVNemesisMultiNode (22.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 release-23.2 @ 3790ccda4a0d705525ace94aaff1793f2a5e9c2f:

Fatal error:

panic: r100/2: closed timestamp regression 1709207752.949702375,0 -> 1709207752.938002744,0 (cmd "38286b218c464b4a" index 14 term 6 (LAI=2 CTS=1709207752.938002744,0))
data: 0038286b218c464b4a30046a0a420092010560e802680272c7010ac401000000000000000001000000012f016b12ec126162386663613166326164613239626200ff01000174786e2d6a3fea56fad947d0b4982fe54850c55b008501120408001000180020002800327722633073030a4a0a106a3fea56fad947d0b4982fe54850c55b10011a14ec126162386663613166326164613239626200012a0a089fded386dfec94dc1730cdd11238024a0a089fded386dfec94dc17500120022a0a089fded386dfec94dc175a161a14ec123064383132363565373632393463636600017a008201260a12637264622d7472616365722d7370616e69641210313762633463366136646534313164378201270a13637264622d7472616365722d747261636569641210343330343535313261376662316466368201080a0372656312017620028a010a08b8b2da89d4ec94dc17 [recovered]
	panic: r100/2: closed timestamp regression 1709207752.949702375,0 -> 1709207752.938002744,0 (cmd "38286b218c464b4a" index 14 term 6 (LAI=2 CTS=1709207752.938002744,0))
data: 0038286b218c464b4a30046a0a420092010560e802680272c7010ac401000000000000000001000000012f016b12ec126162386663613166326164613239626200ff01000174786e2d6a3fea56fad947d0b4982fe54850c55b008501120408001000180020002800327722633073030a4a0a106a3fea56fad947d0b4982fe54850c55b10011a14ec126162386663613166326164613239626200012a0a089fded386dfec94dc1730cdd11238024a0a089fded386dfec94dc17500120022a0a089fded386dfec94dc175a161a14ec123064383132363565373632393463636600017a008201260a12637264622d7472616365722d7370616e69641210313762633463366136646534313164378201270a13637264622d7472616365722d747261636569641210343330343535313261376662316466368201080a0372656312017620028a010a08b8b2da89d4ec94dc17

Stack:

goroutine 6520400 [running]:
github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).recover(0x1f18ac5?, {0x6c21840, 0xc0152e71a0})
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:230 +0x65
panic({0x46d9e20?, 0xc012c25760?})
	GOROOT/src/runtime/panic.go:914 +0x21f
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*rangeAsserter).apply.func1({0x5066595?, 0xc0272e4060?}, {0xc0034f79c0?, 0x8?, 0xc0034f7610?})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:250 +0x165
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*rangeAsserter).apply(0xc0272bfb80, 0x2, {0xc027634360, 0x8}, {0x6, 0xe, 0x0, {0xc02871e480, 0x150, 0x161}}, ...)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:301 +0x5f5
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Asserter).Apply(0x5?, 0x41aa3d?, 0x48dbda0?, {0xc027634360, 0x8}, {0x6, 0xe, 0x0, {0xc02871e480, 0x150, ...}}, ...)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:231 +0xa5
github.com/cockroachdb/cockroach/pkg/kv/kvnemesis.kvnemesisTestCfg.testClusterArgs.func7({{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, {0x0, 0x0, 0x0}, {0x0, ...}, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:145 +0x86
github.com/cockroachdb/cockroach/pkg/kv/kvserver.replicaApplyTestingFilters({0xc004463a10?, 0x6c21840?}, 0xc02221f8f0?, 0xc001de9028?, {0xc004463a60?, 0xc00e5ad360?, 0x0?}, 0x40?)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_application_state_machine.go:117 +0x1f7
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*replicaAppBatch).Stage(0xc004463a10, {0x6c21840, 0xc02221f8f0}, {0x6c417e0?, 0xc001de9028})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_app_batch.go:113 +0xe9
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.mapCmdIter({0x6c50a10?, 0xc004463db8}, 0xc0034f8568)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/cmd.go:192 +0x148
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Task).applyOneBatch(0xc0034f8ad8, {0x6c21840, 0xc0225c7770}, {0x6c50a10, 0xc004463d88})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/task.go:284 +0x150
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Task).ApplyCommittedEntries(0xc0034f8ad8, {0x6c21840, 0xc0225c7770})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/task.go:251 +0x95
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReadyRaftMuLocked(_, {_, _}, {{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_raft.go:1059 +0x1445
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReady(_, {_, _}, {{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_raft.go:744 +0x1b0
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Store).processReady(0xc016aeb500, 0x6c21840?)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/store_raft.go:689 +0x12b
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftSchedulerShard).worker(0xc01801ab60, {0x6c21840, 0xc0152e71a0}, {0x6c059c0, 0xc016aeb500}, 0xc00ec4d500)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/scheduler.go:418 +0x18d
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftScheduler).Start.func2({0x6c21840?, 0xc0152e71a0?})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/scheduler.go:321 +0x46
github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunAsyncTaskEx.func2()
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:484 +0x13a
created by github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunAsyncTaskEx in goroutine 5932463
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:475 +0x415
Log preceding fatal error

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode3971718726
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:319: seed: 571330369405210777
    kvnemesis_test.go:226: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3482381387

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 release-23.2 @ e61e140a75b2f7dde9f5410c1d90e557d009c437:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode3532383412
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:320: seed: 8087338366799840157
    kvnemesis_test.go:227: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis552059587
    kvnemesis.go:177: range 81 (/Table/100/"11261c0eec0d16e1") RANGE_CONSISTENT_STATS_INCORRECT:
        stats: {ContainsEstimates:0 LastUpdateNanos:1712926802505130421 LockAge:0 GCBytesAge:16473 LiveBytes:0 LiveCount:0 KeyBytes:525 KeyCount:13 ValBytes:327 ValCount:21 IntentBytes:0 IntentCount:0 LockBytes:0 LockCount:0 RangeKeyCount:1 RangeKeyBytes:55 RangeValCount:1 RangeValBytes:10 SysBytes:4475 SysCount:22 AbortSpanBytes:1289}
        delta (stats-computed): {ContainsEstimates:0 LastUpdateNanos:1712926802505130421 LockAge:0 GCBytesAge:0 LiveBytes:0 LiveCount:0 KeyBytes:0 KeyCount:0 ValBytes:0 ValCount:0 IntentBytes:0 IntentCount:0 LockBytes:0 LockCount:0 RangeKeyCount:0 RangeKeyBytes:0 RangeValCount:0 RangeValBytes:0 SysBytes:-10 SysCount:0 AbortSpanBytes:0}
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis552059587/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis552059587/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis552059587/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis552059587/kvs-scan.txt
    kvnemesis_test.go:348: [0] proposed: 3763
    kvnemesis_test.go:350: [0] reproposed unchanged: 53
    kvnemesis_test.go:352: [0] reproposed with new LAI: 9
    kvnemesis_test.go:348: [1] proposed: 494
    kvnemesis_test.go:350: [1] reproposed unchanged: 14
    kvnemesis_test.go:352: [1] reproposed with new LAI: 7
    kvnemesis_test.go:348: [2] proposed: 332
    kvnemesis_test.go:350: [2] reproposed unchanged: 16
    kvnemesis_test.go:352: [2] reproposed with new LAI: 6
    kvnemesis_test.go:348: [3] proposed: 577
    kvnemesis_test.go:350: [3] reproposed unchanged: 12
    kvnemesis_test.go:352: [3] reproposed with new LAI: 6
    kvnemesis_test.go:357: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:357
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:297
        	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/logTestKVNemesisMultiNode3532383412
--- FAIL: TestKVNemesisMultiNode (63.23s)

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!

@pav-kv
Copy link
Collaborator

pav-kv commented Apr 12, 2024

The last RANGE_CONSISTENT_STATS_INCORRECT error looks like #93896. We filter out SysBytes:10 discrepancies (#93897), maybe we should do the same for SysBytes:-10 until this is fixed?

@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on release-23.2 @ d91ca866b265a837f832bc93b59481847d721f18:

Fatal error:

panic: r96/2: closed timestamp regression 1713793215.942906144,0 -> 1713793215.157224464,0 (cmd "3f85315965c5d722" index 14 term 6 (LAI=2 CTS=1713793215.157224464,0))
data: 003f85315965c5d72230156a13420092010e0976c49712da9dc817609103680172420a40000000000000000001000000172f016b12ec123538363963373363653366303538346100ff01000174786e2d2db8fc118a2c439582bf1815746833a10002c9017a0020028a010a089088afff95bba7e417 [recovered]
	panic: r96/2: closed timestamp regression 1713793215.942906144,0 -> 1713793215.157224464,0 (cmd "3f85315965c5d722" index 14 term 6 (LAI=2 CTS=1713793215.157224464,0))
data: 003f85315965c5d72230156a13420092010e0976c49712da9dc817609103680172420a40000000000000000001000000172f016b12ec123538363963373363653366303538346100ff01000174786e2d2db8fc118a2c439582bf1815746833a10002c9017a0020028a010a089088afff95bba7e417

Stack:

goroutine 5805755 [running]:
github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).recover(0x1f1c545?, {0x6c3a760, 0xc016522f60})
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:230 +0x65
panic({0x46e7320?, 0xc02266ea10?})
	GOROOT/src/runtime/panic.go:914 +0x21f
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*rangeAsserter).apply.func1({0x5074870?, 0xc01481ab10?}, {0xc009e6b9c0?, 0x8?, 0xc009e6b610?})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:250 +0x165
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*rangeAsserter).apply(0xc000140410, 0x2, {0xc017105ab8, 0x8}, {0x6, 0xe, 0x0, {0xc00c1e7b00, 0x75, 0x80}}, ...)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:301 +0x5f5
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Asserter).Apply(0x4f48740?, 0x41aa3d?, 0x48e9560?, {0xc017105ab8, 0x8}, {0x6, 0xe, 0x0, {0xc00c1e7b00, 0x75, ...}}, ...)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:231 +0xa5
github.com/cockroachdb/cockroach/pkg/kv/kvnemesis.kvnemesisTestCfg.testClusterArgs.func7({{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, {0x0, 0x0, 0x0}, {0x0, ...}, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:146 +0x86
github.com/cockroachdb/cockroach/pkg/kv/kvserver.replicaApplyTestingFilters({0xc011a73310?, 0x6c3a760?}, 0xc001323c20?, 0xc0065e1028?, {0xc011a73360?, 0xc00c52f4f0?, 0x0?}, 0x60?)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_application_state_machine.go:117 +0x1f7
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*replicaAppBatch).Stage(0xc011a73310, {0x6c3a760, 0xc001323c20}, {0x6c5a700?, 0xc0065e1028})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_app_batch.go:113 +0xe9
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.mapCmdIter({0x6c69970?, 0xc011a736b8}, 0xc009e6c568)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/cmd.go:192 +0x148
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Task).applyOneBatch(0xc009e6cad8, {0x6c3a760, 0xc001323c20}, {0x6c69970, 0xc011a73688})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/task.go:284 +0x150
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Task).ApplyCommittedEntries(0xc009e6cad8, {0x6c3a760, 0xc001323c20})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/task.go:251 +0x95
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReadyRaftMuLocked(_, {_, _}, {{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_raft.go:1065 +0x1445
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReady(_, {_, _}, {{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_raft.go:750 +0x1b0
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Store).processReady(0xc00fbb8000, 0x6c3a760?)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/store_raft.go:689 +0x12b
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftSchedulerShard).worker(0xc00b0507e0, {0x6c3a760, 0xc016522f60}, {0x6c1e8e0, 0xc00fbb8000}, 0xc00660ea80)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/scheduler.go:418 +0x18d
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftScheduler).Start.func2({0x6c3a760?, 0xc016522f60?})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/scheduler.go:321 +0x46
github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunAsyncTaskEx.func2()
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:484 +0x13a
created by github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunAsyncTaskEx in goroutine 5231455
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:475 +0x415
Log preceding fatal error

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode3427376016
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:320: seed: 1191021409785454069
    kvnemesis_test.go:227: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis184041319

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!

@arulajmani
Copy link
Collaborator

I'm surprised this failed again given we backported #122067 to 23.2. The failure mode looks similar at first glance, but I'll dig in more.

@arulajmani arulajmani added P-3 Issues/test failures with no fix SLA and removed P-2 Issues/test failures with a fix SLA of 3 months labels Apr 29, 2024
@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on release-23.2 @ a36883a03ce0b30af0c025ff2b0de2f3845ad8d7:

Fatal error:

panic: r89/1: closed timestamp regression 1715001675.801674892,0 -> 1715001674.450978063,0 (cmd "59c19eed4c2fca6e" index 14 term 6 (LAI=2 CTS=1715001674.450978063,0))
data: 0059c19eed4c2fca6e30116a154200920110094dfeca6cf0e8cc17800104a801880472ab020aa802000000000000000002000000012e017a6b12ec123635356635356162653462313137663600ff010001000173058c654e43492d9d307d3ffda8fa4c125d0a450a1073058c654e43492d9d307d3ffda8fa4c1a14ec123331366235326238636165616662363100012a0a08cdfcabe6869ebae61730b0764a0a08cdfcabe6869ebae6175001120c08cdfcabe6869ebae61710001800200028004801012e017a6b12ec123930393738336333646465613034613600ff010001000173058c654e43492d9d307d3ffda8fa4c125d0a450a1073058c654e43492d9d307d3ffda8fa4c1a14ec123331366235326238636165616662363100012a0a08cdfcabe6869ebae61730b0764a0a08cdfcabe6869ebae6175001120c08cdfcabe6869ebae617100018002000280048017a008201260a12637264622d7472616365722d7370616e69641210343230653636373461313631383165668201260a13637264622d7472616365722d74726163656964120f6330386564623034643839393630368201080a0372656312017620028a010a088f8ae7d6fb9dbae617 [recovered]
	panic: r89/1: closed timestamp regression 1715001675.801674892,0 -> 1715001674.450978063,0 (cmd "59c19eed4c2fca6e" index 14 term 6 (LAI=2 CTS=1715001674.450978063,0))
data: 0059c19eed4c2fca6e30116a154200920110094dfeca6cf0e8cc17800104a801880472ab020aa802000000000000000002000000012e017a6b12ec123635356635356162653462313137663600ff010001000173058c654e43492d9d307d3ffda8fa4c125d0a450a1073058c654e43492d9d307d3ffda8fa4c1a14ec123331366235326238636165616662363100012a0a08cdfcabe6869ebae61730b0764a0a08cdfcabe6869ebae6175001120c08cdfcabe6869ebae61710001800200028004801012e017a6b12ec123930393738336333646465613034613600ff010001000173058c654e43492d9d307d3ffda8fa4c125d0a450a1073058c654e43492d9d307d3ffda8fa4c1a14ec123331366235326238636165616662363100012a0a08cdfcabe6869ebae61730b0764a0a08cdfcabe6869ebae6175001120c08cdfcabe6869ebae617100018002000280048017a008201260a12637264622d7472616365722d7370616e69641210343230653636373461313631383165668201260a13637264622d7472616365722d74726163656964120f6330386564623034643839393630368201080a0372656312017620028a010a088f8ae7d6fb9dbae617

Stack:

goroutine 5359966 [running]:
github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).recover(0x1f1d105?, {0x6c4cba0, 0xc011840b10})
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:230 +0x65
panic({0x46f0320?, 0xc00b787570?})
	GOROOT/src/runtime/panic.go:914 +0x21f
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*rangeAsserter).apply.func1({0x507fc47?, 0xc0094049c0?}, {0xc01593d9c0?, 0x8?, 0xc01593d8d0?})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:250 +0x165
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*rangeAsserter).apply(0xc0149174f0, 0x1, {0xc00e9d4508, 0x8}, {0x6, 0xe, 0x0, {0xc00c0688c0, 0x1be, 0x1c0}}, ...)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:301 +0x5f5
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Asserter).Apply(0x4f53500?, 0x41aa3d?, 0x48f28a0?, {0xc00e9d4508, 0x8}, {0x6, 0xe, 0x0, {0xc00c0688c0, 0x1be, ...}}, ...)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:231 +0xa5
github.com/cockroachdb/cockroach/pkg/kv/kvnemesis.kvnemesisTestCfg.testClusterArgs.func7({{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, {0x0, 0x0, 0x0}, {0x0, ...}, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:146 +0x86
github.com/cockroachdb/cockroach/pkg/kv/kvserver.replicaApplyTestingFilters({0xc0117e6110?, 0x6c4cba0?}, 0xc0099cbe90?, 0xc00deaeb28?, {0xc0117e6160?, 0xc00927ccd0?, 0x0?}, 0xa0?)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_application_state_machine.go:117 +0x1f7
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*replicaAppBatch).Stage(0xc0117e6110, {0x6c4cba0, 0xc0099cbe90}, {0x6c6cb40?, 0xc00deaeb28})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_app_batch.go:113 +0xe9
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.mapCmdIter({0x6c7be30?, 0xc0117e64b8}, 0xc01593e568)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/cmd.go:192 +0x148
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Task).applyOneBatch(0xc01593ead8, {0x6c4cba0, 0xc0159efbf0}, {0x6c7be30, 0xc0117e6488})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/task.go:284 +0x150
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Task).ApplyCommittedEntries(0xc01593ead8, {0x6c4cba0, 0xc0159efbf0})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/task.go:251 +0x95
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReadyRaftMuLocked(_, {_, _}, {{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_raft.go:1065 +0x1445
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReady(_, {_, _}, {{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_raft.go:750 +0x1b0
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Store).processReady(0xc0027da000, 0x6c4cba0?)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/store_raft.go:689 +0x12b
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftSchedulerShard).worker(0xc01d8920e0, {0x6c4cba0, 0xc011840b10}, {0x6c30d20, 0xc0027da000}, 0xc01778c000)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/scheduler.go:418 +0x18d
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftScheduler).Start.func2({0x6c4cba0?, 0xc011840b10?})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/scheduler.go:321 +0x46
github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunAsyncTaskEx.func2()
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:484 +0x13a
created by github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunAsyncTaskEx in goroutine 4760174
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:475 +0x415
Log preceding fatal error

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode3377100284
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:320: seed: 2509758491275437011
    kvnemesis_test.go:227: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis1862549721

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!

@nvanbenschoten nvanbenschoten added C-bug Code not up to spec/doc, specs & docs deemed correct. Solution expected to change code/behavior. A-testing Testing tools and infrastructure labels Jun 18, 2024
Copy link

We have marked this test failure issue as stale because it has been
inactive for 1 month. If this failure is still relevant, removing the
stale label or adding a comment will keep it active. Otherwise,
we'll close it in 5 days to keep the test failure queue tidy.

@cockroach-teamcity
Copy link
Member Author

kv/kvnemesis.TestKVNemesisMultiNode failed with artifacts on release-23.2 @ f53be59b1ef3f769fda678812d2c5a17fd817a63:

Fatal error:

panic: r74/1: closed timestamp regression 1723294114.111604369,0 -> 1723294114.107322655,0 (cmd "2e67ba60b7b6ed1a" index 14 term 6 (LAI=2 CTS=1723294114.107322655,0))
data: 002e67ba60b7b6ed1a300b6a12420a08bee2efebe7db97f51792010360ba0372c7020ac4020000000000000000020000000139017a6b12016b12ec123962343563643533336538376338623000ffff0100ff017264736300010003b871739424f64b18be1dba1d02a80e1e126a0a520a10b871739424f64b18be1dba1d02a80e1e1a1e016b12ec123962343563643533336538376338623000ff010001726473632a0a08bee2efebe7db97f51730f4f30638014a0a08bee2efebe7db97f5175003120c08bee2efebe7db97f51710001800200c286748010128016b12ec123962343563643533336538376338623000ff010001726473630017ea5ede7d7bf13e09675cf568c503084a1214ec123962343563643533336538376338623000011a14ec12633931643463313834396362646430390001220808031003180120002208080410041802200022080801100118032000280530383a1008ffffffffffffffff7f10ffffffff077a008201260a12637264622d7472616365722d7370616e69641210366337663837303131306261643930638201270a13637264622d7472616365722d747261636569641210333534663036306166313835363866308201080a0372656312017620028a010a089fe28dd7dcdb97f517 [recovered]
	panic: r74/1: closed timestamp regression 1723294114.111604369,0 -> 1723294114.107322655,0 (cmd "2e67ba60b7b6ed1a" index 14 term 6 (LAI=2 CTS=1723294114.107322655,0))
data: 002e67ba60b7b6ed1a300b6a12420a08bee2efebe7db97f51792010360ba0372c7020ac4020000000000000000020000000139017a6b12016b12ec123962343563643533336538376338623000ffff0100ff017264736300010003b871739424f64b18be1dba1d02a80e1e126a0a520a10b871739424f64b18be1dba1d02a80e1e1a1e016b12ec123962343563643533336538376338623000ff010001726473632a0a08bee2efebe7db97f51730f4f30638014a0a08bee2efebe7db97f5175003120c08bee2efebe7db97f51710001800200c286748010128016b12ec123962343563643533336538376338623000ff010001726473630017ea5ede7d7bf13e09675cf568c503084a1214ec123962343563643533336538376338623000011a14ec12633931643463313834396362646430390001220808031003180120002208080410041802200022080801100118032000280530383a1008ffffffffffffffff7f10ffffffff077a008201260a12637264622d7472616365722d7370616e69641210366337663837303131306261643930638201270a13637264622d7472616365722d747261636569641210333534663036306166313835363866308201080a0372656312017620028a010a089fe28dd7dcdb97f517

Stack:

goroutine 27183 [running]:
github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).recover(0x44e665?, {0x6c3b800, 0xc0013094a0})
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:230 +0x65
panic({0x46f11e0?, 0xc01673dd80?})
	GOROOT/src/runtime/panic.go:914 +0x21f
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*rangeAsserter).apply.func1({0x50853af?, 0xc014d581e0?}, {0xc00b06f9f0?, 0x8?, 0xc00b06f650?})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:250 +0x165
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*rangeAsserter).apply(0xc008d0aaf0, 0x1, {0xc007be2210, 0x8}, {0x6, 0xe, 0x0, {0xc014fc6600, 0x1d8, 0x1e9}}, ...)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:301 +0x5ff
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Asserter).Apply(0x4fdebc?, 0xc00d11c0f0?, 0xb06fdd0?, {0xc007be2210, 0x8}, {0x6, 0xe, 0x0, {0xc014fc6600, 0x1d8, ...}}, ...)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:231 +0xa5
github.com/cockroachdb/cockroach/pkg/kv/kvnemesis.kvnemesisTestCfg.testClusterArgs.func7({{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, {0x17ea5ede7d7bf13e, 0x0, 0x0}, {0x0, ...}, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:146 +0x86
github.com/cockroachdb/cockroach/pkg/kv/kvserver.replicaApplyTestingFilters({0xc002afc690?, 0x6c3b800?}, 0xc014b78090?, 0xc002662128?, {0xc002afc6e0?, 0xc0039a3900?, 0x0?}, 0x0?)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_application_state_machine.go:117 +0x1f7
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*replicaAppBatch).Stage(0xc002afc690, {0x6c3b800, 0xc014b78090}, {0x6c5b7e0?, 0xc002662128})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_app_batch.go:113 +0xe9
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.mapCmdIter({0x6c6aa10?, 0xc002afca38}, 0xc00b070598)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/cmd.go:192 +0x148
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Task).applyOneBatch(0xc00b070af8, {0x6c3b800, 0xc014b78090}, {0x6c6aa10, 0xc002afca08})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/task.go:284 +0x150
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Task).ApplyCommittedEntries(0xc00b070af8, {0x6c3b800, 0xc014b78090})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/task.go:251 +0x95
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReadyRaftMuLocked(_, {_, _}, {{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_raft.go:1065 +0x13c5
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReady(_, {_, _}, {{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_raft.go:750 +0x1b8
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Store).processReady(0xc003554000, 0x6c3b800?)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/store_raft.go:689 +0x12b
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftSchedulerShard).worker(0xc00a430230, {0x6c3b800, 0xc0013094a0}, {0x6c1f980, 0xc003554000}, 0xc0055a9c00)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/scheduler.go:418 +0x1e6
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftScheduler).Start.func2({0x6c3b800?, 0xc0013094a0?})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/scheduler.go:321 +0x46
github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunAsyncTaskEx.func2()
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:484 +0x13a
created by github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunAsyncTaskEx in goroutine 23573
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:475 +0x415
Log preceding fatal error

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode343731875
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:320: seed: 1952197258109574561
    kvnemesis_test.go:227: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis4055105524

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 release-23.2 @ 9e7ed1f7ee5346ea2357389f06963e411fd49306:

Fatal error:

panic: r111/2: closed timestamp regression 1723816024.335218234,0 -> 1723816024.323225314,0 (cmd "49a8c53cb1d1aecb" index 14 term 6 (LAI=2 CTS=1723816024.323225314,0))
data: 0049a8c53cb1d1aecb300b6a24420a08a5b59ef4b2b18ef61792011509a59a872e8b39ec1720682802304238024026480272420a40000000000000000001000000011eec126662386565333065613134643463616200010017ec398b2e879aa5091300000005650a0012016dcec46d0903763130397a260a240a220a14ec12666238656533306561313464346361620001120a08a5b59ef4b2b18ef6178201260a12637264622d7472616365722d7370616e69641210346161303636656431313365346539308201270a13637264622d7472616365722d747261636569641210346262323261633630333964366535668201080a0372656312017620028a010a08e2eda5dfa7b18ef617 [recovered]
	panic: r111/2: closed timestamp regression 1723816024.335218234,0 -> 1723816024.323225314,0 (cmd "49a8c53cb1d1aecb" index 14 term 6 (LAI=2 CTS=1723816024.323225314,0))
data: 0049a8c53cb1d1aecb300b6a24420a08a5b59ef4b2b18ef61792011509a59a872e8b39ec1720682802304238024026480272420a40000000000000000001000000011eec126662386565333065613134643463616200010017ec398b2e879aa5091300000005650a0012016dcec46d0903763130397a260a240a220a14ec12666238656533306561313464346361620001120a08a5b59ef4b2b18ef6178201260a12637264622d7472616365722d7370616e69641210346161303636656431313365346539308201270a13637264622d7472616365722d747261636569641210346262323261633630333964366535668201080a0372656312017620028a010a08e2eda5dfa7b18ef617

Stack:

goroutine 6206411 [running]:
github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).recover(0x1ef86e5?, {0x6c46e80, 0xc011cd6240})
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:230 +0x65
panic({0x46fd2c0?, 0xc023d5aed0?})
	GOROOT/src/runtime/panic.go:914 +0x21f
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*rangeAsserter).apply.func1({0x509048d?, 0xc024700420?}, {0xc01f7779c0?, 0x8?, 0x7f8dc082f200?})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:250 +0x165
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*rangeAsserter).apply(0xc023a7d630, 0x2, {0xc00cc1ed40, 0x8}, {0x6, 0xe, 0x0, {0xc008e04120, 0x10a, 0x120}}, ...)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:301 +0x5f5
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Asserter).Apply(0x6c46d68?, 0x9fa5400?, 0x6c08b00?, {0xc00cc1ed40, 0x8}, {0x6, 0xe, 0x0, {0xc008e04120, 0x10a, ...}}, ...)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/asserter.go:231 +0xa5
github.com/cockroachdb/cockroach/pkg/kv/kvnemesis.kvnemesisTestCfg.testClusterArgs.func7({{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, {0x17ec398b2e879aa5, 0x0, 0x0}, {0x0, ...}, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:146 +0x86
github.com/cockroachdb/cockroach/pkg/kv/kvserver.replicaApplyTestingFilters({0xc01b6cc690?, 0x6c46e80?}, 0xc00e87b860?, 0xc007042128?, {0xc01b6cc6e0?, 0xc00fa95310?, 0x0?}, 0x80?)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_application_state_machine.go:117 +0x1f7
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*replicaAppBatch).Stage(0xc01b6cc690, {0x6c46e80, 0xc00e87b860}, {0x6c66e60?, 0xc007042128})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_app_batch.go:113 +0xe9
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.mapCmdIter({0x6c76090?, 0xc01b6cca38}, 0xc01f778568)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/cmd.go:192 +0x148
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Task).applyOneBatch(0xc01f778ad8, {0x6c46e80, 0xc01c8fc7b0}, {0x6c76090, 0xc01b6cca08})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/task.go:284 +0x150
github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply.(*Task).ApplyCommittedEntries(0xc01f778ad8, {0x6c46e80, 0xc01c8fc7b0})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/apply/task.go:251 +0x95
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReadyRaftMuLocked(_, {_, _}, {{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_raft.go:1065 +0x1445
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReady(_, {_, _}, {{0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...}, ...})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/replica_raft.go:750 +0x1b0
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Store).processReady(0xc0050cd500, 0x6c46e80?)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/store_raft.go:689 +0x12b
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftSchedulerShard).worker(0xc019a6bf10, {0x6c46e80, 0xc011cd6240}, {0x6c2af40, 0xc0050cd500}, 0xc001d70000)
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/scheduler.go:418 +0x18d
github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftScheduler).Start.func2({0x6c46e80?, 0xc011cd6240?})
	github.com/cockroachdb/cockroach/pkg/kv/kvserver/pkg/kv/kvserver/scheduler.go:321 +0x46
github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunAsyncTaskEx.func2()
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:484 +0x13a
created by github.com/cockroachdb/cockroach/pkg/util/stop.(*Stopper).RunAsyncTaskEx in goroutine 5484429
	github.com/cockroachdb/cockroach/pkg/util/stop/stopper.go:475 +0x415
Log preceding fatal error

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode2635336155
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:320: seed: 8302354284549062549
    kvnemesis_test.go:227: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis3969918184

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 release-23.2 @ 52d7f3db720359153214894c6dd3b4f96bd8be6b:

=== RUN   TestKVNemesisMultiNode
    test_log_scope.go:170: test logs captured to: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/logTestKVNemesisMultiNode3739392545
    test_log_scope.go:81: use -show-logs to present logs inline
    kvnemesis_test.go:320: seed: 9182830139301971631
    kvnemesis_test.go:227: kvnemesis logging to /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis854121778
    kvnemesis.go:177: range 79 (/Table/100/"53f5273cd363cced") RANGE_CONSISTENT_STATS_INCORRECT:
        stats: {ContainsEstimates:0 LastUpdateNanos:1726405707631069785 LockAge:0 GCBytesAge:11485 LiveBytes:0 LiveCount:0 KeyBytes:192 KeyCount:4 ValBytes:115 ValCount:9 IntentBytes:0 IntentCount:0 LockBytes:0 LockCount:0 RangeKeyCount:2 RangeKeyBytes:129 RangeValCount:5 RangeValBytes:50 SysBytes:2193 SysCount:11 AbortSpanBytes:342}
        delta (stats-computed): {ContainsEstimates:0 LastUpdateNanos:1726405707631069785 LockAge:0 GCBytesAge:0 LiveBytes:0 LiveCount:0 KeyBytes:0 KeyCount:0 ValBytes:0 ValCount:0 IntentBytes:0 IntentCount:0 LockBytes:0 LockCount:0 RangeKeyCount:0 RangeKeyBytes:0 RangeValCount:0 RangeValBytes:0 SysBytes:-10 SysCount:0 AbortSpanBytes:0}
    kvnemesis.go:212: failures(verbose): /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis854121778/failures
        repro steps: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis854121778/repro.go
        rangefeed KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis854121778/kvs-rangefeed.txt
        scan KVs: /artifacts/tmp/_tmp/1f42cf5be2fc021646bf9b2daf5eaef3/kvnemesis854121778/kvs-scan.txt
    kvnemesis_test.go:348: [0] proposed: 3768
    kvnemesis_test.go:350: [0] reproposed unchanged: 52
    kvnemesis_test.go:352: [0] reproposed with new LAI: 17
    kvnemesis_test.go:348: [1] proposed: 983
    kvnemesis_test.go:350: [1] reproposed unchanged: 4
    kvnemesis_test.go:352: [1] reproposed with new LAI: 9
    kvnemesis_test.go:348: [2] proposed: 697
    kvnemesis_test.go:350: [2] reproposed unchanged: 4
    kvnemesis_test.go:352: [2] reproposed with new LAI: 4
    kvnemesis_test.go:348: [3] proposed: 199
    kvnemesis_test.go:350: [3] reproposed unchanged: 0
    kvnemesis_test.go:352: [3] reproposed with new LAI: 1
    kvnemesis_test.go:357: 
        	Error Trace:	github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:357
        	            				github.com/cockroachdb/cockroach/pkg/kv/kvnemesis/pkg/kv/kvnemesis/kvnemesis_test.go:297
        	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/logTestKVNemesisMultiNode3739392545
--- FAIL: TestKVNemesisMultiNode (68.68s)

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!

@arulajmani
Copy link
Collaborator

I looked through the test failures here -- most seem to be because of CTS regressions, for which I've created a new tracking issue (#131450).

Others are because of inconsistent range stats, which should be addressed by #131446.

The only failure that doesn't fall into this bucket is #118471 (comment). However, the artifacts for it are long gone.

I'll close this issue out now that we have #131450 to track most of the failures. That way, new KVNemesis failures will pop up in our triage queues.

@pav-kv
Copy link
Collaborator

pav-kv commented Sep 27, 2024

@arulajmani

The only failure that doesn't fall into this bucket is #118471 (comment). However, the artifacts for it are long gone.

Should be fixed by #131503.

craig bot pushed a commit that referenced this issue Sep 27, 2024
131503: apply: fix initial snapshot assertion r=arulajmani a=pav-kv

The broad assertion here is that all the entries composing a snapshot must have been applied by someone before (at least the sender of the snapshot has done it, otherwise it couldn’t have sent this snapshot).

And then there is an additional assertion (which failed occasionally before this PR) that there can’t be a snapshot without at least one entry applied. This is generally true, except that there are entries not registered by the "apply" stack: particularly, empty entries that raft leader appends at the start of its term. There is a comment to this extent in the `asserter.go` code:
 https://github.com/cockroachdb/cockroach/blob/5b0371b44009ac2ae3f58ea7ed95b290dd4e8227/pkg/kv/kvserver/apply/asserter.go#L254-L259

This commit modifies the snapshot assertion to correctly handle this case. It is possible that an initial snapshot sent from leader to followers contains only this dummy entry, and there were no "real" proposals applied.

Fixes the false assertion failure in #118471 (comment)
Related to #116319

Co-authored-by: Pavel Kalinnikov <pavel@cockroachlabs.com>
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
A-testing Testing tools and infrastructure branch-release-23.2 Used to mark GA and release blockers, technical advisories, and bugs for 23.2 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. P-3 Issues/test failures with no fix SLA T-kv KV Team
Projects
No open projects
Status: Incoming
Development

No branches or pull requests

6 participants