Files
Zhen YeandGitHub b07c62d53c enhance: replace log package with context-aware mlog (#50094)
issue: #35917

- mlog package: move logger initialization, zap core, async buffered
writes, field helpers, and scoped logger binding into pkg/mlog while
removing pkg/log.
- logging callsites: migrate Milvus logging usage to context-aware mlog
APIs and simplify redundant With chains across components, utilities,
tests, and tools.
- trace propagation: replace logutil trace interceptors with mlog/tracer
integration and add client_request_id fallback propagation for server
stats handlers.

---------

Signed-off-by: chyezh <chyezh@outlook.com>
2026-06-24 00:58:28 +08:00

384 lines
12 KiB
Go

// Licensed to the LF AI & Data foundation under one
// or more contributor license agreements. See the NOTICE file
// distributed with this work for additional information
// regarding copyright ownership. The ASF licenses this file
// to you under the Apache License, Version 2.0 (the
// "License"); you may not use this file except in compliance
// with the License. You may obtain a copy of the License at
//
// http://www.apache.org/licenses/LICENSE-2.0
//
// Unless required by applicable law or agreed to in writing, software
// distributed under the License is distributed on an "AS IS" BASIS,
// WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
// See the License for the specific language governing permissions and
// limitations under the License.
package datacoord
import (
"context"
"fmt"
"strconv"
"golang.org/x/time/rate"
"google.golang.org/protobuf/proto"
"github.com/milvus-io/milvus/internal/metastore"
"github.com/milvus-io/milvus/pkg/v3/metrics"
"github.com/milvus-io/milvus/pkg/v3/mlog"
"github.com/milvus-io/milvus/pkg/v3/proto/indexpb"
"github.com/milvus-io/milvus/pkg/v3/proto/workerpb"
"github.com/milvus-io/milvus/pkg/v3/util/lock"
"github.com/milvus-io/milvus/pkg/v3/util/merr"
"github.com/milvus-io/milvus/pkg/v3/util/timerecord"
"github.com/milvus-io/milvus/pkg/v3/util/typeutil"
)
type statsTaskMeta struct {
ctx context.Context
catalog metastore.DataCoordCatalog
keyLock *lock.KeyLock[UniqueID]
// taskID -> statsTask
tasks *typeutil.ConcurrentMap[UniqueID, *indexpb.StatsTask]
// segmentID + SubJobType -> statsTask
segmentID2Tasks *typeutil.ConcurrentMap[string, *indexpb.StatsTask]
}
func newStatsTaskMeta(ctx context.Context, catalog metastore.DataCoordCatalog) (*statsTaskMeta, error) {
stm := &statsTaskMeta{
ctx: ctx,
catalog: catalog,
keyLock: lock.NewKeyLock[UniqueID](),
tasks: typeutil.NewConcurrentMap[UniqueID, *indexpb.StatsTask](),
segmentID2Tasks: typeutil.NewConcurrentMap[string, *indexpb.StatsTask](),
}
if err := stm.reloadFromKV(); err != nil {
return nil, err
}
return stm, nil
}
func createSecondaryIndexKey(segmentID UniqueID, subJobType string) string {
return strconv.FormatUint(uint64(segmentID), 10) + "-" + subJobType
}
func (stm *statsTaskMeta) reloadFromKV() error {
record := timerecord.NewTimeRecorder("statsTaskMeta-reloadFromKV")
// load stats task
statsTasks, err := stm.catalog.ListStatsTasks(stm.ctx)
if err != nil {
mlog.Error(stm.ctx, "statsTaskMeta reloadFromKV load stats tasks failed", mlog.Err(err))
return err
}
for _, t := range statsTasks {
// sort stats task no need to reload
if t.GetSubJobType() == indexpb.StatsSubJob_Sort {
if err := stm.catalog.DropStatsTask(stm.ctx, t.GetTaskID()); err != nil {
mlog.Warn(stm.ctx, "drop stats task failed",
mlog.FieldTaskID(t.GetTaskID()),
mlog.FieldSegmentID(t.GetSegmentID()),
mlog.Err(err))
}
continue
}
stm.tasks.Insert(t.GetTaskID(), t)
secondaryKey := createSecondaryIndexKey(t.GetSegmentID(), t.GetSubJobType().String())
stm.segmentID2Tasks.Insert(secondaryKey, t)
}
mlog.Info(stm.ctx, "statsTaskMeta reloadFromKV done", mlog.Duration("duration", record.ElapseSpan()))
return nil
}
func (stm *statsTaskMeta) updateMetrics() {
taskMetrics := make(map[indexpb.JobState]int)
taskMetrics[indexpb.JobState_JobStateNone] = 0
taskMetrics[indexpb.JobState_JobStateInit] = 0
taskMetrics[indexpb.JobState_JobStateInProgress] = 0
taskMetrics[indexpb.JobState_JobStateFinished] = 0
taskMetrics[indexpb.JobState_JobStateFailed] = 0
taskMetrics[indexpb.JobState_JobStateRetry] = 0
allTasks := stm.tasks.Values()
for _, t := range allTasks {
taskMetrics[t.GetState()]++
}
jobType := indexpb.JobType_JobTypeStatsJob.String()
for k, v := range taskMetrics {
metrics.IndexStatsTaskNum.WithLabelValues(jobType, k.String()).Set(float64(v))
}
}
func (stm *statsTaskMeta) AddStatsTask(t *indexpb.StatsTask) error {
taskID := t.GetTaskID()
secondaryKey := createSecondaryIndexKey(t.GetSegmentID(), t.GetSubJobType().String())
task, alreadyExist := stm.segmentID2Tasks.Get(secondaryKey)
if alreadyExist {
msg := fmt.Sprintf("stats task already exist in meta of segment %d with subJobType: %s",
t.GetSegmentID(), t.GetSubJobType().String())
mlog.RatedWarn(stm.ctx, rate.Limit(10), msg, mlog.FieldTaskID(t.GetTaskID()), mlog.Int64("exist taskID", task.GetTaskID()))
return merr.WrapErrTaskDuplicate(indexpb.JobType_JobTypeStatsJob.String(), msg)
}
stm.keyLock.Lock(taskID)
defer stm.keyLock.Unlock(taskID)
mlog.Info(stm.ctx, "add stats task", mlog.FieldTaskID(t.GetTaskID()), mlog.Int64("originSegmentID", t.GetSegmentID()),
mlog.Int64("targetSegmentID", t.GetTargetSegmentID()), mlog.String("subJobType", t.GetSubJobType().String()))
t.State = indexpb.JobState_JobStateInit
if err := stm.catalog.SaveStatsTask(stm.ctx, t); err != nil {
mlog.Warn(stm.ctx, "adding stats task failed",
mlog.FieldTaskID(taskID),
mlog.FieldSegmentID(t.GetSegmentID()),
mlog.String("subJobType", t.GetSubJobType().String()),
mlog.Err(err))
return err
}
stm.tasks.Insert(taskID, t)
stm.segmentID2Tasks.Insert(secondaryKey, t)
mlog.Info(stm.ctx, "add stats task success", mlog.FieldTaskID(t.GetTaskID()), mlog.Int64("originSegmentID", t.GetSegmentID()),
mlog.Int64("targetSegmentID", t.GetTargetSegmentID()), mlog.String("subJobType", t.GetSubJobType().String()))
return nil
}
func (stm *statsTaskMeta) DropStatsTask(ctx context.Context, taskID int64) error {
stm.keyLock.Lock(taskID)
defer stm.keyLock.Unlock(taskID)
mlog.Info(ctx, "drop stats task by taskID", mlog.FieldTaskID(taskID))
t, ok := stm.tasks.Get(taskID)
if !ok {
mlog.Info(ctx, "remove stats task success, task already not exist", mlog.FieldTaskID(taskID))
return nil
}
if err := stm.catalog.DropStatsTask(ctx, taskID); err != nil {
mlog.Warn(ctx, "drop stats task failed",
mlog.FieldTaskID(taskID),
mlog.FieldSegmentID(t.GetSegmentID()),
mlog.Err(err))
return err
}
stm.tasks.Remove(taskID)
secondaryKey := createSecondaryIndexKey(t.GetSegmentID(), t.GetSubJobType().String())
stm.segmentID2Tasks.Remove(secondaryKey)
mlog.Info(ctx, "remove stats task success", mlog.FieldTaskID(taskID))
return nil
}
func (stm *statsTaskMeta) UpdateVersion(taskID, nodeID int64) error {
stm.keyLock.Lock(taskID)
defer stm.keyLock.Unlock(taskID)
t, ok := stm.tasks.Get(taskID)
if !ok {
return merr.WrapErrServiceInternalMsg("task %d not found", taskID)
}
cloneT := proto.Clone(t).(*indexpb.StatsTask)
cloneT.Version++
cloneT.NodeID = nodeID
if err := stm.catalog.SaveStatsTask(stm.ctx, cloneT); err != nil {
mlog.Warn(stm.ctx, "update stats task version failed",
mlog.FieldTaskID(t.GetTaskID()),
mlog.FieldSegmentID(t.GetSegmentID()),
mlog.FieldNodeID(nodeID),
mlog.Err(err))
return err
}
stm.tasks.Insert(taskID, cloneT)
secondaryKey := createSecondaryIndexKey(t.GetSegmentID(), t.GetSubJobType().String())
stm.segmentID2Tasks.Insert(secondaryKey, cloneT)
mlog.Info(stm.ctx, "update stats task version success", mlog.FieldTaskID(taskID), mlog.FieldNodeID(nodeID),
mlog.Int64("newVersion", cloneT.GetVersion()))
return nil
}
func (stm *statsTaskMeta) UpdateTaskState(taskID int64, state indexpb.JobState, failReason string) error {
stm.keyLock.Lock(taskID)
defer stm.keyLock.Unlock(taskID)
t, ok := stm.tasks.Get(taskID)
if !ok {
return merr.WrapErrServiceInternalMsg("task %d not found", taskID)
}
cloneT := proto.Clone(t).(*indexpb.StatsTask)
cloneT.State = state
cloneT.FailReason = failReason
if err := stm.catalog.SaveStatsTask(stm.ctx, cloneT); err != nil {
mlog.Warn(stm.ctx, "update stats task state failed",
mlog.FieldTaskID(t.GetTaskID()),
mlog.Err(err))
return err
}
stm.tasks.Insert(taskID, cloneT)
secondaryKey := createSecondaryIndexKey(t.GetSegmentID(), t.GetSubJobType().String())
stm.segmentID2Tasks.Insert(secondaryKey, cloneT)
return nil
}
func (stm *statsTaskMeta) UpdateBuildingTask(taskID int64) error {
stm.keyLock.Lock(taskID)
defer stm.keyLock.Unlock(taskID)
t, ok := stm.tasks.Get(taskID)
if !ok {
return merr.WrapErrServiceInternalMsg("task %d not found", taskID)
}
cloneT := proto.Clone(t).(*indexpb.StatsTask)
cloneT.State = indexpb.JobState_JobStateInProgress
if err := stm.catalog.SaveStatsTask(stm.ctx, cloneT); err != nil {
mlog.Warn(stm.ctx, "update stats task state building failed",
mlog.FieldTaskID(t.GetTaskID()),
mlog.FieldSegmentID(t.GetSegmentID()),
mlog.Err(err))
return err
}
stm.tasks.Insert(taskID, cloneT)
secondaryKey := createSecondaryIndexKey(t.GetSegmentID(), t.GetSubJobType().String())
stm.segmentID2Tasks.Insert(secondaryKey, cloneT)
mlog.Info(stm.ctx, "update building stats task success", mlog.FieldTaskID(taskID))
return nil
}
func (stm *statsTaskMeta) FinishTask(taskID int64, result *workerpb.StatsResult) error {
stm.keyLock.Lock(taskID)
defer stm.keyLock.Unlock(taskID)
t, ok := stm.tasks.Get(taskID)
if !ok {
return merr.WrapErrServiceInternalMsg("task %d not found", taskID)
}
cloneT := proto.Clone(t).(*indexpb.StatsTask)
cloneT.State = result.GetState()
cloneT.FailReason = result.GetFailReason()
if err := stm.catalog.SaveStatsTask(stm.ctx, cloneT); err != nil {
mlog.Warn(stm.ctx, "finish stats task state failed",
mlog.FieldTaskID(t.GetTaskID()),
mlog.FieldSegmentID(t.GetSegmentID()),
mlog.Err(err))
return err
}
stm.tasks.Insert(taskID, cloneT)
secondaryKey := createSecondaryIndexKey(t.GetSegmentID(), t.GetSubJobType().String())
stm.segmentID2Tasks.Insert(secondaryKey, cloneT)
mlog.Info(stm.ctx, "finish stats task meta success", mlog.FieldTaskID(taskID), mlog.FieldSegmentID(t.SegmentID),
mlog.String("state", result.GetState().String()), mlog.String("failReason", t.GetFailReason()))
return nil
}
func (stm *statsTaskMeta) GetStatsTask(taskID int64) *indexpb.StatsTask {
t, _ := stm.tasks.Get(taskID)
return t
}
func (stm *statsTaskMeta) GetStatsTaskState(taskID int64) indexpb.JobState {
t, ok := stm.tasks.Get(taskID)
if !ok {
return indexpb.JobState_JobStateNone
}
return t.GetState()
}
func (stm *statsTaskMeta) GetStatsTaskStateBySegmentID(segmentID int64, subJobType indexpb.StatsSubJob) indexpb.JobState {
state := indexpb.JobState_JobStateNone
secondaryKey := createSecondaryIndexKey(segmentID, subJobType.String())
t, exists := stm.segmentID2Tasks.Get(secondaryKey)
if exists {
state = t.GetState()
}
return state
}
func (stm *statsTaskMeta) CanCleanedTasks() []int64 {
needCleanedTaskIDs := make([]int64, 0)
stm.tasks.Range(func(key UniqueID, value *indexpb.StatsTask) bool {
if value.GetCanRecycle() && (value.GetState() == indexpb.JobState_JobStateFinished ||
value.GetState() == indexpb.JobState_JobStateFailed) {
needCleanedTaskIDs = append(needCleanedTaskIDs, key)
}
return true
})
return needCleanedTaskIDs
}
func (stm *statsTaskMeta) GetAllTasks() map[int64]*indexpb.StatsTask {
tasks := make(map[int64]*indexpb.StatsTask)
allTasks := stm.tasks.Values()
for _, v := range allTasks {
tasks[v.GetTaskID()] = proto.Clone(v).(*indexpb.StatsTask)
}
return tasks
}
func (stm *statsTaskMeta) GetStatsTaskBySegmentID(segmentID int64, subJobType indexpb.StatsSubJob) *indexpb.StatsTask {
secondaryKey := createSecondaryIndexKey(segmentID, subJobType.String())
t, exists := stm.segmentID2Tasks.Get(secondaryKey)
if exists {
mlog.Info(stm.ctx, "get stats task by segmentID success",
mlog.FieldTaskID(t.GetTaskID()),
mlog.FieldSegmentID(segmentID),
mlog.String("subJobType", subJobType.String()))
return t
}
mlog.Info(stm.ctx, "get stats task by segmentID failed, task not exist", mlog.FieldSegmentID(segmentID),
mlog.String("subJobType", subJobType.String()))
return nil
}
func (stm *statsTaskMeta) MarkTaskCanRecycle(taskID int64) error {
mlog.Info(stm.ctx, "mark stats task can recycle", mlog.FieldTaskID(taskID))
t, ok := stm.tasks.Get(taskID)
if !ok {
return merr.WrapErrServiceInternalMsg("task %d not found", taskID)
}
cloneT := proto.Clone(t).(*indexpb.StatsTask)
cloneT.CanRecycle = true
if err := stm.catalog.SaveStatsTask(stm.ctx, cloneT); err != nil {
mlog.Warn(stm.ctx, "mark stats task can recycle failed",
mlog.FieldTaskID(taskID),
mlog.FieldSegmentID(t.GetSegmentID()),
mlog.Err(err))
return err
}
stm.tasks.Insert(taskID, cloneT)
secondaryKey := createSecondaryIndexKey(t.GetSegmentID(), t.GetSubJobType().String())
stm.segmentID2Tasks.Insert(secondaryKey, cloneT)
mlog.Info(stm.ctx, "mark stats task can recycle success", mlog.FieldTaskID(taskID),
mlog.FieldSegmentID(t.SegmentID),
mlog.String("subJobType", t.GetSubJobType().String()))
return nil
}