cockroachdb / cockroachdb/cockroach

basalt-26.3: roachtest: clearrange/checks=false failed

Open
#174,342 20 comments 0 reactions 0 assignees View on GitHub
A-storage basalt-red branch-basalt-26.3 C-test-failure O-roachtest O-robot release-blocker T-storage
Dominant language
Go
Stars
32.5k
Forks
4.1k
PR merge metrics
PR metrics pending

Description

roachtest.clearrange/checks=false [failed](https://teamcity.cockroachdb.com/buildConfiguration/Cockroach_Nightlies_Roachtests_RedRoachtestNightlyGcePrivateBasaltBazel/21491312?buildTab=log) with [artifacts](https://teamcity.cockroachdb.com/buildConfiguration/Cockroach_Nightlies_Roachtests_RedRoachtestNightlyGcePrivateBasaltBazel/21491312?buildTab=artifacts#/clearrange/checks=false) on basalt-26.3 @ [23c3d5a11cfe60e3d3650467f2be08b000eba5ef](https://github.com/cockroachdb/cockroach/commits/23c3d5a11cfe60e3d3650467f2be08b000eba5ef):

Failed with:

```
(cluster.go:3380).Run: context canceled
(monitor.go:267).Wait: monitor failure: monitor user task failed: t.Fatal() was called
unexpected node event: n4: cockroach process for system interface died (exit code 7)
test artifacts and logs in: /artifacts/clearrange/checks=false/run_1
```
Fatal entries found in Cockroach logs:

```
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 while storing log entries: during sideloading: reading response header: read tcp ‹10.91.111.253:54326› -> ‹10.91.96.30:26255›: ‹use of closed network connection›
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 +(1) attached stack trace
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 + -- stack trace:
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReadyRaftMuLocked
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 + | pkg/kv/kvserver/replica_raft.go:1217
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReady
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 + | pkg/kv/kvserver/replica_raft.go:881
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Store).processReady
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 + | pkg/kv/kvserver/store_raft.go:766
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftSchedulerShard).worker
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 while storing log entries: during sideloading: reading response header: read tcp ‹10.91.111.253:54326› -> ‹10.91.96.30:26255›: ‹use of closed network connection›
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 +(1) attached stack trace
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 + -- stack trace:
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReadyRaftMuLocked
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 + | pkg/kv/kvserver/replica_raft.go:1217
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReady
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 + | pkg/kv/kvserver/replica_raft.go:881
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Store).processReady
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 + | pkg/kv/kvserver/store_raft.go:766
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 3238 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftSchedulerShard).worker
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 while storing log entries: during sideloading: reading response header: read tcp ‹10.91.111.253:54326› -> ‹10.91.96.30:26255›: ‹use of closed network connection›
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 +(1) attached stack trace
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 + -- stack trace:
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReadyRaftMuLocked
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 + | pkg/kv/kvserver/replica_raft.go:1217
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReady
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 + | pkg/kv/kvserver/replica_raft.go:881
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Store).processReady
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 + | pkg/kv/kvserver/store_raft.go:766
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftSchedulerShard).worker
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 [T1,Vsystem,n4,s4,r619/3:/Table/106/1/2{07525…-60400…},raft] 140575 while storing log entries: during sideloading: reading response header: read tcp 10.91.111.253:54326 -> 10.91.96.30:26255: use of closed network connection
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 [T1,Vsystem,n4,s4,r619/3:/Table/106/1/2{07525…-60400…},raft] 140575 +(1) attached stack trace
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 [T1,Vsystem,n4,s4,r619/3:/Table/106/1/2{07525…-60400…},raft] 140575 + -- stack trace:
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 [T1,Vsystem,n4,s4,r619/3:/Table/106/1/2{07525…-60400…},raft] 140575 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReadyRaftMuLocked
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 [T1,Vsystem,n4,s4,r619/3:/Table/106/1/2{07525…-60400…},raft] 140575 + | pkg/kv/kvserver/replica_raft.go:1217
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 [T1,Vsystem,n4,s4,r619/3:/Table/106/1/2{07525…-60400…},raft] 140575 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReady
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 [T1,Vsystem,n4,s4,r619/3:/Table/106/1/2{07525…-60400…},raft] 140575 + | pkg/kv/kvserver/replica_raft.go:881
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 [T1,Vsystem,n4,s4,r619/3:/Table/106/1/2{07525…-60400…},raft] 140575 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Store).processReady
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 [T1,Vsystem,n4,s4,r619/3:/Table/106/1/2{07525…-60400…},raft] 140575 + | pkg/kv/kvserver/store_raft.go:766
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 [T1,Vsystem,n4,s4,r619/3:/Table/106/1/2{07525…-60400…},raft] 140575 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftSchedulerShard).worker
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 while storing log entries: during sideloading: reading response header: read tcp ‹10.91.111.253:54326› -> ‹10.91.96.30:26255›: ‹use of closed network connection›
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 +(1) attached stack trace
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 + -- stack trace:
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReadyRaftMuLocked
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 + | pkg/kv/kvserver/replica_raft.go:1217
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Replica).handleRaftReady
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 + | pkg/kv/kvserver/replica_raft.go:881
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*Store).processReady
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 + | pkg/kv/kvserver/store_raft.go:766
F260829 07:30:01.664460 931 15@kv/kvserver/store_raft.go:767 ⋮ [T1,Vsystem,n4,s4,r619/3:/Table/106/1/‹2›{‹07525›…-‹60400›…},raft] 34431 + | github.com/cockroachdb/cockroach/pkg/kv/kvserver.(*raftSchedulerShard).worker
```
Cluster Node to Ip Mapping:

| Node | Public IP | Private IP |
| --- | --- | --- |
| teamcity-21491312-1787983444-09-n10cpu16-0001 | | 10.91.97.196 |
| teamcity-21491312-1787983444-09-n10cpu16-0002 | | 10.91.97.199 |
| teamcity-21491312-1787983444-09-n10cpu16-0003 | | 10.91.97.193 |
| teamcity-21491312-1787983444-09-n10cpu16-0004 | | 10.91.111.253 |
| teamcity-21491312-1787983444-09-n10cpu16-0005 | | 10.91.97.200 |
| teamcity-21491312-1787983444-09-n10cpu16-0006 | | 10.91.97.198 |
| teamcity-21491312-1787983444-09-n10cpu16-0007 | | 10.91.97.195 |
| teamcity-21491312-1787983444-09-n10cpu16-0008 | | 10.91.97.194 |
| teamcity-21491312-1787983444-09-n10cpu16-0009 | | 10.91.97.197 |
| teamcity-21491312-1787983444-09-n10cpu16-0010 | | 10.91.97.192 |

Parameters:
- arch=amd64
- cloud=gce
- coverageBuild=false
- cpu=16
- diskCount=0
- encrypted=false
- fs=ext4
- localSSD=true
- metamorphicLeases=default
- runtimeAssertionsBuild=false
Help

See: [roachtest README](https://github.com/cockroachdb/cockroach/blob/master/pkg/cmd/roachtest/README.md)

See: [How To Investigate \(internal\)](https://cockroachlabs.atlassian.net/l/c/SSSBr8c7)

See: [Grafana](https://go.crdb.dev/roachtest-grafana/teamcity-21491312/clearrange-checks-false/1787983930118/1787988861019)

_Logs were not uploaded to Datadog_

/cc @cockroachlabs/storage

[This test on roachdash](https://roachdash.crdb.dev/?filter=status:open%20t:.*clearrange/checks=false.*&sort=title+created&display=lastcommented+project) | [Improve this report!](https://github.com/cockroachdb/cockroach/tree/master/pkg/cmd/bazci/githubpost/issues)

Contributor guide

Open the contributing guide

Research direction

Start with the clearrange/checks=false artifacts and logs, then inspect pkg/kv/kvserver/store_raft.go around line 767 and pkg/kv/kvserver/replica_raft.go around lines 881 and 1217. Determine why node n4 exits while sideloading encounters a closed network connection, reproduce the roachtest failure if possible, and verify that the test completes without the fatal process event.

Written by the indexing model from the issue text.

Assessment

Tech stack
go, google-cloud
Domain
cloud, databases, distributed-systems, testing-qa
Issue type
Bug
Difficulty
4/5
Estimated time
3-5 days
Activity status
Active
Clarity
Needs clarification
Newbie friendliness
35/100

Get new issues in your inbox

A short digest of beginner-friendly GitHub issues.