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

[BugFix] Avoid optimization time exceeding the limit in ApplyRuleTask (backport #50795) #50838

Merged
merged 2 commits into from
Sep 9, 2024

Conversation

mergify[bot]
Copy link
Contributor

@mergify mergify bot commented Sep 9, 2024

Why I'm doing:

  • MultiJoinBinder will cause slow locks sometimes:
024 - 09 - 03 06 : 59 : 49.641Z WARN(starrocks - mysql - nio - pool - 3278 | 15273792)[LockManager.logSlowLockTrace() : 398] LockManager detects slow lock: {
	"owners": [{
		"id": 15246789,
		"name": "thrift-server-pool-14973570",
		"heldFor": 2133813,
		"waitTime": 0,
		"stack": ["app//com.starrocks.sql.optimizer.rule.Binder$MultiJoinBinder.enumerate(Binder.java:202)", "app//com.starrocks.sql.optimizer.rule.Binder$MultiJoinBinder.match(Binder.java:190)", "app//com.starrocks.sql.optimizer.rule.Binder.match(Binder.java:109)", "app//com.starrocks.sql.optimizer.rule.Binder.next(Binder.java:96)", "app//com.starrocks.sql.optimizer.task.ApplyRuleTask.execute(ApplyRuleTask.java:104)", "app//com.starrocks.sql.optimizer.task.SeriallyTaskScheduler.executeTasks(SeriallyTaskScheduler.java:65)", "app//com.starrocks.sql.optimizer.Optimizer.memoOptimize(Optimizer.java:803)", "app//com.starrocks.sql.optimizer.Optimizer.optimizeByCost(Optimizer.java:260)", "app//com.starrocks.sql.optimizer.Optimizer.optimize(Optimizer.java:184)", "app//com.starrocks.sql.StatementPlanner.createQueryPlan(StatementPlanner.java:219)", "app//com.starrocks.sql.StatementPlanner.plan(StatementPlanner.java:130)", "app//com.starrocks.sql.StatementPlanner.plan(StatementPlanner.java:92)", "app//com.starrocks.qe.StmtExecutor.execute(StmtExecutor.java:533)", "app//com.starrocks.qe.ConnectProcessor.proxyExecute(ConnectProcessor.java:811)", "app//com.starrocks.service.FrontendServiceImpl.forward(FrontendServiceImpl.java:1207)", "app//com.starrocks.thrift.FrontendService$Processor$forward.getResult(FrontendService.java:4481)", "app//com.starrocks.thrift.FrontendService$Processor$forward.getResult(FrontendService.java:4461)", "app//org.apache.thrift.ProcessFunction.process(ProcessFunction.java:40)", "app//org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:40)", "app//com.starrocks.common.SRTThreadPoolServer$WorkerProcess.run(SRTThreadPoolServer.java:311)"]
	},

2024 - 09 - 03 07 : 02 : 39.354Z WARN(thrift - server - pool - 15005392 | 15278893)[LockManager.logSlowLockTrace() : 398] LockManager detects slow lock: {
	"owners": [{
		"id": 15246789,
		"name": "thrift-server-pool-14973570",
		"heldFor": 2303527,
		"waitTime": 0,
		"stack": ["app//com.starrocks.sql.optimizer.rule.Binder$MultiJoinBinder.enumerate(Binder.java:202)", "app//com.starrocks.sql.optimizer.rule.Binder$MultiJoinBinder.match(Binder.java:190)", "app//com.starrocks.sql.optimizer.rule.Binder.match(Binder.java:109)", "app//com.starrocks.sql.optimizer.rule.Binder.next(Binder.java:96)", "app//com.starrocks.sql.optimizer.task.ApplyRuleTask.execute(ApplyRuleTask.java:104)", "app//com.starrocks.sql.optimizer.task.SeriallyTaskScheduler.executeTasks(SeriallyTaskScheduler.java:65)", "app//com.starrocks.sql.optimizer.Optimizer.memoOptimize(Optimizer.java:803)", "app//com.starrocks.sql.optimizer.Optimizer.optimizeByCost(Optimizer.java:260)", "app//com.starrocks.sql.optimizer.Optimizer.optimize(Optimizer.java:184)", "app//com.starrocks.sql.StatementPlanner.createQueryPlan(StatementPlanner.java:219)", "app//com.starrocks.sql.StatementPlanner.plan(StatementPlanner.java:130)", "app//com.starrocks.sql.StatementPlanner.plan(StatementPlanner.java:92)", "app//com.starrocks.qe.StmtExecutor.execute(StmtExecutor.java:533)", "app//com.starrocks.qe.ConnectProcessor.proxyExecute(ConnectProcessor.java:811)", "app//com.starrocks.service.FrontendServiceImpl.forward(FrontendServiceImpl.java:1207)", "app//com.starrocks.thrift.FrontendService$Processor$forward.getResult(FrontendService.java:4481)", "app//com.starrocks.thrift.FrontendService$Processor$forward.getResult(FrontendService.java:4461)", "app//org.apache.thrift.ProcessFunction.process(ProcessFunction.java:40)", "app//org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:40)", "app//com.starrocks.common.SRTThreadPoolServer$WorkerProcess.run(SRTThreadPoolServer.java:311)"]
	},

What I'm doing:

  • Avoid optimization time exceeding the limit in ApplyRuleTask by using rule exhausting check.
    Fixes #issue

What type of PR is this:

  • BugFix
  • Feature
  • Enhancement
  • Refactor
  • UT
  • Doc
  • Tool

Does this PR entail a change in behavior?

  • Yes, this PR will result in a change in behavior.
  • No, this PR will not result in a change in behavior.

If yes, please specify the type of change:

  • Interface/UI changes: syntax, type conversion, expression evaluation, display information
  • Parameter changes: default values, similar parameters but with different default values
  • Policy changes: use new policy to replace old one, functionality automatically enabled
  • Feature removed
  • Miscellaneous: upgrade & downgrade compatibility, etc.

Checklist:

  • I have added test cases for my bug fix or my new feature
  • This pr needs user documentation (for new or modified features or behaviors)
    • I have added documentation for my new feature or new function
  • This is a backport pr

Bugfix cherry-pick branch check:

  • I have checked the version labels which the pr will be auto-backported to the target branch
    • 3.3
    • 3.2
    • 3.1
    • 3.0
    • 2.5

This is an automatic backport of pull request #50795 done by [Mergify](https://mergify.com). ## Why I'm doing: - `MultiJoinBinder` will cause slow locks sometimes: ``` 024 - 09 - 03 06 : 59 : 49.641Z WARN(starrocks - mysql - nio - pool - 3278 | 15273792)[LockManager.logSlowLockTrace() : 398] LockManager detects slow lock: { "owners": [{ "id": 15246789, "name": "thrift-server-pool-14973570", "heldFor": 2133813, "waitTime": 0, "stack": ["app//com.starrocks.sql.optimizer.rule.Binder$MultiJoinBinder.enumerate(Binder.java:202)", "app//com.starrocks.sql.optimizer.rule.Binder$MultiJoinBinder.match(Binder.java:190)", "app//com.starrocks.sql.optimizer.rule.Binder.match(Binder.java:109)", "app//com.starrocks.sql.optimizer.rule.Binder.next(Binder.java:96)", "app//com.starrocks.sql.optimizer.task.ApplyRuleTask.execute(ApplyRuleTask.java:104)", "app//com.starrocks.sql.optimizer.task.SeriallyTaskScheduler.executeTasks(SeriallyTaskScheduler.java:65)", "app//com.starrocks.sql.optimizer.Optimizer.memoOptimize(Optimizer.java:803)", "app//com.starrocks.sql.optimizer.Optimizer.optimizeByCost(Optimizer.java:260)", "app//com.starrocks.sql.optimizer.Optimizer.optimize(Optimizer.java:184)", "app//com.starrocks.sql.StatementPlanner.createQueryPlan(StatementPlanner.java:219)", "app//com.starrocks.sql.StatementPlanner.plan(StatementPlanner.java:130)", "app//com.starrocks.sql.StatementPlanner.plan(StatementPlanner.java:92)", "app//com.starrocks.qe.StmtExecutor.execute(StmtExecutor.java:533)", "app//com.starrocks.qe.ConnectProcessor.proxyExecute(ConnectProcessor.java:811)", "app//com.starrocks.service.FrontendServiceImpl.forward(FrontendServiceImpl.java:1207)", "app//com.starrocks.thrift.FrontendService$Processor$forward.getResult(FrontendService.java:4481)", "app//com.starrocks.thrift.FrontendService$Processor$forward.getResult(FrontendService.java:4461)", "app//org.apache.thrift.ProcessFunction.process(ProcessFunction.java:40)", "app//org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:40)", "app//com.starrocks.common.SRTThreadPoolServer$WorkerProcess.run(SRTThreadPoolServer.java:311)"] },

2024 - 09 - 03 07 : 02 : 39.354Z WARN(thrift - server - pool - 15005392 | 15278893)[LockManager.logSlowLockTrace() : 398] LockManager detects slow lock: {
"owners": [{
"id": 15246789,
"name": "thrift-server-pool-14973570",
"heldFor": 2303527,
"waitTime": 0,
"stack": ["app//com.starrocks.sql.optimizer.rule.Binder$MultiJoinBinder.enumerate(Binder.java:202)", "app//com.starrocks.sql.optimizer.rule.Binder$MultiJoinBinder.match(Binder.java:190)", "app//com.starrocks.sql.optimizer.rule.Binder.match(Binder.java:109)", "app//com.starrocks.sql.optimizer.rule.Binder.next(Binder.java:96)", "app//com.starrocks.sql.optimizer.task.ApplyRuleTask.execute(ApplyRuleTask.java:104)", "app//com.starrocks.sql.optimizer.task.SeriallyTaskScheduler.executeTasks(SeriallyTaskScheduler.java:65)", "app//com.starrocks.sql.optimizer.Optimizer.memoOptimize(Optimizer.java:803)", "app//com.starrocks.sql.optimizer.Optimizer.optimizeByCost(Optimizer.java:260)", "app//com.starrocks.sql.optimizer.Optimizer.optimize(Optimizer.java:184)", "app//com.starrocks.sql.StatementPlanner.createQueryPlan(StatementPlanner.java:219)", "app//com.starrocks.sql.StatementPlanner.plan(StatementPlanner.java:130)", "app//com.starrocks.sql.StatementPlanner.plan(StatementPlanner.java:92)", "app//com.starrocks.qe.StmtExecutor.execute(StmtExecutor.java:533)", "app//com.starrocks.qe.ConnectProcessor.proxyExecute(ConnectProcessor.java:811)", "app//com.starrocks.service.FrontendServiceImpl.forward(FrontendServiceImpl.java:1207)", "app//com.starrocks.thrift.FrontendService$Processor$forward.getResult(FrontendService.java:4481)", "app//com.starrocks.thrift.FrontendService$Processor$forward.getResult(FrontendService.java:4461)", "app//org.apache.thrift.ProcessFunction.process(ProcessFunction.java:40)", "app//org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:40)", "app//com.starrocks.common.SRTThreadPoolServer$WorkerProcess.run(SRTThreadPoolServer.java:311)"]
},

## What I'm doing:
- Avoid optimization time exceeding the limit in ApplyRuleTask by using rule exhausting check.
Fixes #issue

## What type of PR is this:

- [x] BugFix
- [ ] Feature
- [ ] Enhancement
- [ ] Refactor
- [ ] UT
- [ ] Doc
- [ ] Tool

Does this PR entail a change in behavior?

- [ ] Yes, this PR will result in a change in behavior.
- [x] No, this PR will not result in a change in behavior.

If yes, please specify the type of change:

- [ ] Interface/UI changes: syntax, type conversion, expression evaluation, display information
- [ ] Parameter changes: default values, similar parameters but with different default values
- [ ] Policy changes: use new policy to replace old one, functionality automatically enabled
- [ ] Feature removed
- [ ] Miscellaneous: upgrade & downgrade compatibility, etc.

## Checklist:

- [x] I have added test cases for my bug fix or my new feature
- [ ] This pr needs user documentation (for new or modified features or behaviors)
  - [ ] I have added documentation for my new feature or new function
- [x] This is a backport pr


…#50795)

Signed-off-by: shuming.li <[email protected]>
(cherry picked from commit b797336)

# Conflicts:
#	fe/fe-core/src/test/java/com/starrocks/planner/MaterializedViewRewriteWithSSBTest.java
@mergify mergify bot added the conflicts label Sep 9, 2024
Copy link
Contributor Author

mergify bot commented Sep 9, 2024

Cherry-pick of b797336 has failed:

On branch mergify/bp/branch-3.2/pr-50795
Your branch is up to date with 'origin/branch-3.2'.

You are currently cherry-picking commit b79733626d.
  (fix conflicts and run "git cherry-pick --continue")
  (use "git cherry-pick --skip" to skip this patch)
  (use "git cherry-pick --abort" to cancel the cherry-pick operation)

Changes to be committed:
	modified:   fe/fe-core/src/main/java/com/starrocks/sql/optimizer/GroupExpression.java
	modified:   fe/fe-core/src/main/java/com/starrocks/sql/optimizer/rule/Binder.java
	modified:   fe/fe-core/src/main/java/com/starrocks/sql/optimizer/task/ApplyRuleTask.java
	modified:   fe/fe-core/src/main/java/com/starrocks/sql/optimizer/task/OptimizerTask.java

Unmerged paths:
  (use "git add/rm <file>..." as appropriate to mark resolution)
	deleted by us:   fe/fe-core/src/test/java/com/starrocks/planner/MaterializedViewRewriteWithSSBTest.java

To fix up this pull request, you can check it out locally. See documentation: https://docs.github.com/en/pull-requests/collaborating-with-pull-requests/reviewing-changes-in-pull-requests/checking-out-pull-requests-locally

Copy link
Contributor Author

mergify bot commented Sep 9, 2024

@mergify[bot]: Backport conflict, please reslove the conflict and resubmit the pr

@mergify mergify bot deleted the mergify/bp/branch-3.2/pr-50795 branch September 9, 2024 06:44
@LiShuMing LiShuMing restored the mergify/bp/branch-3.2/pr-50795 branch September 9, 2024 06:47
@LiShuMing LiShuMing reopened this Sep 9, 2024
@wanpengfei-git wanpengfei-git enabled auto-merge (squash) September 9, 2024 06:48
Signed-off-by: shuming.li <[email protected]>
Copy link

sonarcloud bot commented Sep 9, 2024

@wanpengfei-git wanpengfei-git merged commit 2cd9ed9 into branch-3.2 Sep 9, 2024
28 checks passed
@wanpengfei-git wanpengfei-git deleted the mergify/bp/branch-3.2/pr-50795 branch September 9, 2024 07:21
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

Successfully merging this pull request may close these issues.

2 participants