[perf] RunRecognition 的耗时与实际的 Recognition.Run 的内部耗时不匹配

[isHarryh]

版本信息

• Go binding: v4.0.0-beta.12

  module github.com/MaaXYZ/MaaEnd/agent/go-service
  
  go 1.25.6
  
  require (
  	github.com/MaaXYZ/maa-framework-go/v4 v4.0.0-beta.12
  	github.com/rs/zerolog v1.33.0
  	golang.org/x/image v0.36.0
  	golang.org/x/sys v0.12.0
  )
  
  require (
  	github.com/ebitengine/purego v0.9.1 // indirect
  	github.com/mattn/go-colorable v0.1.13 // indirect
  	github.com/mattn/go-isatty v0.0.19 // indirect
  )
  

• MaaFW: v5.8.0

测试代码

// In my_recognition.go
func (i *MyRecognition) Run(ctx *maa.Context, arg *maa.CustomRecognitionArg) (*maa.CustomRecognitionResult, bool) {
	t0 := time.Now()

	// Some shit here ...

	t1 := time.Now()
	log.Info().Dur("Run", t1.Sub(t0)).Msg("CustomRecognition internal timing")

	return &maa.CustomRecognitionResult{
		Box:    arg.Roi,
		Detail: "foobar",
	}, true
}

// In my_action.go

t0 := time.Now()
ctrl.PostScreencap().Wait()
t1 := time.Now()
img, err := ctrl.CacheImage()
t2 := time.Now()

if err != nil {
	log.Error().Err(err).Msg("Failed to get cached image")
	return nil, err
}
if img == nil {
	log.Error().Msg("Cached image is nil")
	return nil, fmt.Errorf("cached image is nil")
}

nodeName := "MyNodeName"
config := map[string]any{
	nodeName: map[string]any{
		"recognition":        "Custom",
		"custom_recognition": "MyRecognition",
		"custom_recognition_param": map[string]any{
			"foo": "bar,
		},
	},
}

res, err := ctx.RunRecognition(nodeName, img, config)

t4 := time.Now()

log.Info().Dur("Screencap", t1.Sub(t0)).
	Dur("CacheImage", t2.Sub(t1)).
	Dur("RunRecognition", t4.Sub(t2)).
	Msg("CustomAction invocation timing")

测试结果

自定义识别节点的内部墙钟中值时间为 11.7ms，调用方 RunRecognition 的墙钟中值时间为 141.8ms，二者差距 130.1ms。

详细数据如下：

Details

{"level":"info","Run":904.973,"time":"2026-03-04T21:35:51+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.934,"CacheImage":13.0915,"RunRecognition":1028.4652,"time":"2026-03-04T21:35:51+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","closest_index":14,"closest_dist":5.8309518948453,"time":"2026-03-04T21:35:51+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:93","message":"Path trim enabled, skipping earlier targets"}
{"level":"info","map":"map01_lv006","targetsCount":3,"time":"2026-03-04T21:35:51+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:101","message":"Starting navigation to targets"}
{"level":"info","index":0,"targetX":352,"targetY":412,"time":"2026-03-04T21:35:51+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:113","message":"Navigating to next target point"}
{"level":"info","Run":15.9191,"time":"2026-03-04T21:35:51+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":8.5438,"CacheImage":10.5782,"RunRecognition":136.2855,"time":"2026-03-04T21:35:51+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":16.3306,"time":"2026-03-04T21:35:51+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":6.2309,"CacheImage":13.6924,"RunRecognition":144.3778,"time":"2026-03-04T21:35:51+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":16.3939,"time":"2026-03-04T21:35:52+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":11.0495,"CacheImage":13.4452,"RunRecognition":156.0894,"time":"2026-03-04T21:35:52+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.2331,"time":"2026-03-04T21:35:53+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":8.6648,"CacheImage":12.4954,"RunRecognition":142.1907,"time":"2026-03-04T21:35:53+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":15.7823,"time":"2026-03-04T21:35:54+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.4703,"CacheImage":11.8311,"RunRecognition":141.1731,"time":"2026-03-04T21:35:54+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","x":351,"y":409,"index":0,"time":"2026-03-04T21:35:54+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:166","message":"Target point reached"}
{"level":"info","index":1,"targetX":370,"targetY":438,"time":"2026-03-04T21:35:54+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:113","message":"Navigating to next target point"}
{"level":"info","Run":13.1487,"time":"2026-03-04T21:35:54+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.0739,"CacheImage":11.6977,"RunRecognition":149.5607,"time":"2026-03-04T21:35:54+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":13.5532,"time":"2026-03-04T21:35:54+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.3036,"CacheImage":13.2071,"RunRecognition":145.5968,"time":"2026-03-04T21:35:54+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":13.4376,"time":"2026-03-04T21:35:55+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":15.5019,"CacheImage":18.4543,"RunRecognition":140.6723,"time":"2026-03-04T21:35:55+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":9.2587,"time":"2026-03-04T21:35:56+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.8731,"CacheImage":13.4899,"RunRecognition":140.6366,"time":"2026-03-04T21:35:56+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":13.8592,"time":"2026-03-04T21:35:57+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.1828,"CacheImage":11.1487,"RunRecognition":144.6639,"time":"2026-03-04T21:35:57+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":13.409,"time":"2026-03-04T21:35:57+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":4.7143,"CacheImage":13.341,"RunRecognition":150.5426,"time":"2026-03-04T21:35:57+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":8.4992,"time":"2026-03-04T21:35:57+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.6919,"CacheImage":11.8348,"RunRecognition":174.0877,"time":"2026-03-04T21:35:57+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":12.4327,"time":"2026-03-04T21:35:57+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.4189,"CacheImage":10.5908,"RunRecognition":138.875,"time":"2026-03-04T21:35:57+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":10.007,"time":"2026-03-04T21:35:58+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":4.6766,"CacheImage":11.1103,"RunRecognition":134.9214,"time":"2026-03-04T21:35:58+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.4164,"time":"2026-03-04T21:35:58+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":9.1011,"CacheImage":12.3673,"RunRecognition":150.3451,"time":"2026-03-04T21:35:58+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.7351,"time":"2026-03-04T21:35:58+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":4.9713,"CacheImage":12.8693,"RunRecognition":138.0891,"time":"2026-03-04T21:35:58+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":10.8247,"time":"2026-03-04T21:35:58+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":11.415,"CacheImage":12.0119,"RunRecognition":165.8709,"time":"2026-03-04T21:35:58+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":12.624,"time":"2026-03-04T21:35:58+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":6.0517,"CacheImage":14.9325,"RunRecognition":132.1139,"time":"2026-03-04T21:35:58+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":8.9149,"time":"2026-03-04T21:35:58+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.1481,"CacheImage":14.2302,"RunRecognition":164.8538,"time":"2026-03-04T21:35:59+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":15.3379,"time":"2026-03-04T21:35:59+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.9303,"CacheImage":12.9006,"RunRecognition":155.4132,"time":"2026-03-04T21:35:59+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.4284,"time":"2026-03-04T21:35:59+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.2606,"CacheImage":15.0213,"RunRecognition":139.2561,"time":"2026-03-04T21:35:59+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":12.1596,"time":"2026-03-04T21:35:59+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.2987,"CacheImage":13.4592,"RunRecognition":151.8979,"time":"2026-03-04T21:35:59+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.1045,"time":"2026-03-04T21:35:59+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.0506,"CacheImage":13.569,"RunRecognition":138.2604,"time":"2026-03-04T21:35:59+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":17.6819,"time":"2026-03-04T21:36:00+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.0143,"CacheImage":14.2969,"RunRecognition":140.3678,"time":"2026-03-04T21:36:00+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":9.035,"time":"2026-03-04T21:36:00+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":8.0154,"CacheImage":13.8194,"RunRecognition":135.3249,"time":"2026-03-04T21:36:00+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":10.4838,"time":"2026-03-04T21:36:00+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":4.7348,"CacheImage":11.1735,"RunRecognition":149.4795,"time":"2026-03-04T21:36:00+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":10.9264,"time":"2026-03-04T21:36:00+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.4108,"CacheImage":11.1633,"RunRecognition":128.9965,"time":"2026-03-04T21:36:00+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.7153,"time":"2026-03-04T21:36:00+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":11.626,"CacheImage":13.6522,"RunRecognition":130.1428,"time":"2026-03-04T21:36:00+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":14.0013,"time":"2026-03-04T21:36:00+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":8.8002,"CacheImage":12.1876,"RunRecognition":146.7408,"time":"2026-03-04T21:36:00+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.6589,"time":"2026-03-04T21:36:01+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.0743,"CacheImage":11.2146,"RunRecognition":140.3822,"time":"2026-03-04T21:36:01+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.309,"time":"2026-03-04T21:36:01+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.9032,"CacheImage":14.4239,"RunRecognition":155.2764,"time":"2026-03-04T21:36:01+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.3392,"time":"2026-03-04T21:36:01+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.7447,"CacheImage":12.9573,"RunRecognition":139.909,"time":"2026-03-04T21:36:01+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":9.6437,"time":"2026-03-04T21:36:01+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.2743,"CacheImage":11.7258,"RunRecognition":156.3209,"time":"2026-03-04T21:36:01+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","time":"2026-03-04T21:36:01+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:189","message":"Stuck detected, jumping..."}
{"level":"info","Run":13.1439,"time":"2026-03-04T21:36:01+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":4.8857,"CacheImage":11.2056,"RunRecognition":149.2552,"time":"2026-03-04T21:36:02+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","time":"2026-03-04T21:36:02+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:189","message":"Stuck detected, jumping..."}
{"level":"info","Run":10.4552,"time":"2026-03-04T21:36:02+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":9.705,"CacheImage":12.3313,"RunRecognition":135.1183,"time":"2026-03-04T21:36:02+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.8338,"time":"2026-03-04T21:36:02+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":6.5684,"CacheImage":13.3767,"RunRecognition":132.6236,"time":"2026-03-04T21:36:02+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":12.433,"time":"2026-03-04T21:36:02+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":8.2579,"CacheImage":15.7883,"RunRecognition":140.7027,"time":"2026-03-04T21:36:02+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":12.5881,"time":"2026-03-04T21:36:02+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.049,"CacheImage":12.2127,"RunRecognition":151.6719,"time":"2026-03-04T21:36:02+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":8.4201,"time":"2026-03-04T21:36:02+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.753,"CacheImage":10.029,"RunRecognition":157.4397,"time":"2026-03-04T21:36:03+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":10.2042,"time":"2026-03-04T21:36:03+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":6.0505,"CacheImage":11.2851,"RunRecognition":124.1421,"time":"2026-03-04T21:36:03+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":13.0492,"time":"2026-03-04T21:36:03+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":6.2651,"CacheImage":15.4203,"RunRecognition":141.1794,"time":"2026-03-04T21:36:03+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.6575,"time":"2026-03-04T21:36:03+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":10.6105,"CacheImage":12.728,"RunRecognition":136.7931,"time":"2026-03-04T21:36:03+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":10.0011,"time":"2026-03-04T21:36:03+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":7.5209,"CacheImage":12.6955,"RunRecognition":140.094,"time":"2026-03-04T21:36:03+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":16.9929,"time":"2026-03-04T21:36:03+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.1234,"CacheImage":12.793,"RunRecognition":143.1396,"time":"2026-03-04T21:36:03+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":10.4,"time":"2026-03-04T21:36:04+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":8.9576,"CacheImage":14.2415,"RunRecognition":129.5409,"time":"2026-03-04T21:36:04+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":12.3419,"time":"2026-03-04T21:36:04+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.3592,"CacheImage":14.5017,"RunRecognition":127.8139,"time":"2026-03-04T21:36:04+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":10.9018,"time":"2026-03-04T21:36:04+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.5675,"CacheImage":11.3767,"RunRecognition":140.4128,"time":"2026-03-04T21:36:05+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","x":369,"y":438,"index":1,"time":"2026-03-04T21:36:05+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:166","message":"Target point reached"}
{"level":"info","index":2,"targetX":355,"targetY":464,"time":"2026-03-04T21:36:05+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:113","message":"Navigating to next target point"}
{"level":"info","Run":12.6055,"time":"2026-03-04T21:36:05+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":10.2432,"CacheImage":13.2115,"RunRecognition":145.0374,"time":"2026-03-04T21:36:05+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.2007,"time":"2026-03-04T21:36:05+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":10.8788,"CacheImage":12.1296,"RunRecognition":133.3402,"time":"2026-03-04T21:36:05+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":10.5362,"time":"2026-03-04T21:36:06+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":7.0782,"CacheImage":12.3896,"RunRecognition":135.8614,"time":"2026-03-04T21:36:06+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.7059,"time":"2026-03-04T21:36:07+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.4939,"CacheImage":16.9238,"RunRecognition":157.9255,"time":"2026-03-04T21:36:07+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":15.8905,"time":"2026-03-04T21:36:07+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":4.9236,"CacheImage":11.9699,"RunRecognition":130.1693,"time":"2026-03-04T21:36:08+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":10.0262,"time":"2026-03-04T21:36:08+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":4.7851,"CacheImage":11.4256,"RunRecognition":131.9814,"time":"2026-03-04T21:36:08+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":10.4726,"time":"2026-03-04T21:36:08+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.3566,"CacheImage":11.7299,"RunRecognition":138.3462,"time":"2026-03-04T21:36:08+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":12.3177,"time":"2026-03-04T21:36:08+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":8.4753,"CacheImage":10.2918,"RunRecognition":150.8138,"time":"2026-03-04T21:36:08+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":10.2673,"time":"2026-03-04T21:36:08+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":6.4165,"CacheImage":13.4132,"RunRecognition":137.0665,"time":"2026-03-04T21:36:08+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":10.5413,"time":"2026-03-04T21:36:09+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":7.4168,"CacheImage":13.4959,"RunRecognition":142.0165,"time":"2026-03-04T21:36:09+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":9.744,"time":"2026-03-04T21:36:09+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.9268,"CacheImage":11.2754,"RunRecognition":131.3526,"time":"2026-03-04T21:36:09+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":14.1499,"time":"2026-03-04T21:36:09+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.6695,"CacheImage":15.3254,"RunRecognition":144.5002,"time":"2026-03-04T21:36:09+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.1307,"time":"2026-03-04T21:36:09+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":4.555,"CacheImage":14.2182,"RunRecognition":141.8317,"time":"2026-03-04T21:36:09+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.1897,"time":"2026-03-04T21:36:09+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.2721,"CacheImage":13.2557,"RunRecognition":141.1296,"time":"2026-03-04T21:36:09+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.5692,"time":"2026-03-04T21:36:09+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.5279,"CacheImage":13.6132,"RunRecognition":146.2971,"time":"2026-03-04T21:36:09+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":12.9314,"time":"2026-03-04T21:36:10+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":10.2569,"CacheImage":11.5676,"RunRecognition":139.0445,"time":"2026-03-04T21:36:10+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":12.2851,"time":"2026-03-04T21:36:10+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.6881,"CacheImage":9.6302,"RunRecognition":144.3507,"time":"2026-03-04T21:36:10+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":10.6173,"time":"2026-03-04T21:36:10+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":4.3785,"CacheImage":10.1036,"RunRecognition":168.2479,"time":"2026-03-04T21:36:10+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":13.3647,"time":"2026-03-04T21:36:10+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":8.6656,"CacheImage":13.8188,"RunRecognition":145.4711,"time":"2026-03-04T21:36:10+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":12.7867,"time":"2026-03-04T21:36:10+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.7544,"CacheImage":10.0385,"RunRecognition":126.5144,"time":"2026-03-04T21:36:10+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":13.6311,"time":"2026-03-04T21:36:11+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":6.5648,"CacheImage":13.1388,"RunRecognition":152.2637,"time":"2026-03-04T21:36:11+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":8.464,"time":"2026-03-04T21:36:11+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.4069,"CacheImage":10.9217,"RunRecognition":138.8307,"time":"2026-03-04T21:36:11+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":12.355,"time":"2026-03-04T21:36:11+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":4.3192,"CacheImage":10.2154,"RunRecognition":159.9004,"time":"2026-03-04T21:36:11+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":14.6438,"time":"2026-03-04T21:36:11+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":6.9194,"CacheImage":12.9451,"RunRecognition":138.7755,"time":"2026-03-04T21:36:11+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":12.7458,"time":"2026-03-04T21:36:11+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.4214,"CacheImage":13.2712,"RunRecognition":165.2174,"time":"2026-03-04T21:36:11+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":10.9127,"time":"2026-03-04T21:36:11+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":9.9615,"CacheImage":12.7474,"RunRecognition":148.6884,"time":"2026-03-04T21:36:12+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":12.7332,"time":"2026-03-04T21:36:12+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.7247,"CacheImage":10.6779,"RunRecognition":155.0469,"time":"2026-03-04T21:36:12+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":11.3619,"time":"2026-03-04T21:36:12+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":5.3,"CacheImage":13.1633,"RunRecognition":152.5113,"time":"2026-03-04T21:36:12+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}
{"level":"info","Run":18.9631,"time":"2026-03-04T21:36:13+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/infer.go:346","message":"CustomRecognition internal timing"}
{"level":"info","Screencap":7.1202,"CacheImage":13.8656,"RunRecognition":175.1611,"time":"2026-03-04T21:36:13+08:00","caller":"D:/Repos/MaaEnd/agent/go-service/map-tracker/move.go:404","message":"CustomAction invocation timing"}

[isHarryh]

对 MaaXYZ\maa-framework-go\v4\context.go 中的调用方函数 func (ctx *Context) RunRecognition 进行详细计时，结果如下图：

耗时主要集中在 MaaRunRecognition 中。

计时划分如下：

// In context.go

func (ctx *Context) runRecognition(
	entry, override string,
	img image.Image,
) (*RecognitionDetail, error) {

	// "SetImg" group 👇
	imgBuf := buffer.NewImageBuffer()
	imgBuf.Set(img)
	defer imgBuf.Destroy()

	// "MaaRunRecognition" group 👇
	recId := native.MaaContextRunRecognition(ctx.handle, entry, override, imgBuf.Handle())
	if recId == 0 {
		return nil, errors.New("failed to run recognition")
	}

	// "GetRecognitionDetail" group 👇
	tasker := ctx.GetTasker()
	recognitionDetail, err := tasker.GetRecognitionDetail(recId)

	return recognitionDetail, err
}

func (ctx *Context) RunRecognition(
	entry string,
	img image.Image,
	override ...any,
) (*RecognitionDetail, error) {
	return ctx.runRecognition(entry, ctx.handleOverride(override...), img)
}

[dongwlin]

方便贴一下运行机器的 CPU 型号吗？

[isHarryh]

方便贴一下运行机器的 CPU 型号吗？

13th Gen Intel(R) Core(TM) i7-13650HX

[isHarryh]

已观察到不同 CPU 型号的设备中该延迟的显著性不同，在 30~100 ms不等。

临时解决方案

若被调用的识别节点也是 Go Agent 内注册的，则建议采用内部快路径方法来完全消除此延迟。

将 ctx.RunRecognition 调用改为 myRecognition.Run 调用即可。