cockroachdb / cockroachdb/cockroach
basalt-26.3: roachtest: clearrange/checks=false failed
- 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
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