chore:rule execution time

actiontech · Apr 1, 2024 · 1820e2d · 1820e2d
1 parent 4fd76e6
commit 1820e2d
Showing 1 changed file with 7 additions and 1 deletion.
diff --git a/sqle/driver/mysql/mysql.go b/sqle/driver/mysql/mysql.go
@@ -6,6 +6,7 @@ import (
 	_driver "database/sql/driver"
 	"fmt"
 	"strings"
+	"time"
 
 	"github.com/actiontech/sqle/sqle/driver"
 	"github.com/actiontech/sqle/sqle/driver/mysql/executor"
@@ -355,7 +356,8 @@ func (i *MysqlDriverImpl) audit(ctx context.Context, sql string) (*driverV2.Audi
 			Res:  i.result,
 			Node: nodes[0],
 		}
-
+		startTime := time.Now()
+		i.Logger().Infof("[audit_rule]rule: %v,start time: %v", rule.Desc, startTime)
 		if err := handler.Func(input); err != nil {
 			// todo #1630 临时跳过解析建表语句失败导致的规则
 			if session.IsParseShowCreateTableContentErr(err) {
@@ -364,9 +366,12 @@ func (i *MysqlDriverImpl) audit(ctx context.Context, sql string) (*driverV2.Audi
 			}
 			return nil, err
 		}
+		i.Logger().Infof("[audit_rule]rule: %v,total time: %v", rule.Desc, time.Since(startTime))
 	}
 
 	if i.cnf.optimizeIndexEnabled && index.CanOptimize(i.log, i.Ctx, nodes[0]) {
+		startTime := time.Now()
+		i.Logger().Infof("[audit_rule]rule: %v,start time: %v", rulepkg.ConfigOptimizeIndexEnabled, startTime)
 		optimizer := index.NewOptimizer(
 			i.log, i.Ctx,
 			index.WithCalculateCardinalityMaxRow(i.cnf.calculateCardinalityMaxRow),
@@ -387,6 +392,7 @@ func (i *MysqlDriverImpl) audit(ctx context.Context, sql string) (*driverV2.Audi
 			}
 		}
 		i.result.Add(driverV2.RuleLevelNotice, rulepkg.ConfigOptimizeIndexEnabled, buf.String())
+		i.Logger().Infof("[audit_rule]rule: %v,total time: %v", rulepkg.ConfigOptimizeIndexEnabled, time.Since(startTime))
 	}
 
 	// dry run gh-ost