apm_tracing.go 14 KB

123456789101112131415161718192021222324252627282930313233343536373839404142434445464748495051525354555657585960616263646566676869707172737475767778798081828384858687888990919293949596979899100101102103104105106107108109110111112113114115116117118119120121122123124125126127128129130131132133134135136137138139140141142143144145146147148149150151152153154155156157158159160161162163164165166167168169170171172173174175176177178179180181182183184185186187188189190191192193194195196197198199200201202203204205206207208209210211212213214215216217218219220221222223224225226227228229230231232233234235236237238239240241242243244245246247248249250251252253254255256257258259260261262263264265266267268269270271272273274275276277278279280281282283284285286287288289290291292293294295296297298299300301302303304305306307308309310311312313314315316317318319320321322323324325326327328329330331332333334335336337338339340341342343344345346347348349350351352353354355356357358359360361362363364365366367368369370371372373374375376377378379380381382383384385386387388389390391392393394395396397398399400401402403404405406407408409410411412413414415416417418419420421422423424425426427428429430431432433434435436437438439440441442443444445446447448
  1. package tracing
  2. import (
  3. "context"
  4. "fmt"
  5. "github.com/coroot/coroot-node-agent/common"
  6. "github.com/coroot/coroot-node-agent/ebpftracer"
  7. "github.com/coroot/coroot-node-agent/ebpftracer/l7"
  8. "github.com/coroot/coroot-node-agent/utils"
  9. "go.opentelemetry.io/otel/attribute"
  10. "go.opentelemetry.io/otel/codes"
  11. semconv "go.opentelemetry.io/otel/semconv/v1.18.0"
  12. "go.opentelemetry.io/otel/trace"
  13. "inet.af/netaddr"
  14. "strconv"
  15. "sync/atomic"
  16. "time"
  17. )
  18. /**
  19. * Trace
  20. */
  21. func (t *Trace) setContext(ctx context.Context) {
  22. t.lock.Lock()
  23. defer t.lock.Unlock()
  24. t.ctx = ctx
  25. }
  26. func (t *Trace) setSpan(span trace.Span) {
  27. t.lock.Lock()
  28. defer t.lock.Unlock()
  29. t.span = span
  30. }
  31. type TimeMap struct {
  32. Time uint64
  33. Type int
  34. Map *ebpftracer.StackFunEvent
  35. }
  36. //func (t *Trace) buildFun() {
  37. // mapSlice := []TimeMap{}
  38. // for i, v := range t.stack {
  39. // timeStartMap := TimeMap{}
  40. // if v.StackEvent.Location == 0 {
  41. // timeStartMap = TimeMap{
  42. // Time: v.StackEvent.TimeNsStart,
  43. // Type: 0,
  44. // Map: &t.stack[i],
  45. // }
  46. // } else {
  47. // timeStartMap = TimeMap{
  48. // Time: v.StackEvent.TimeNsEnd,
  49. // Type: 1,
  50. // Map: &t.stack[i],
  51. // }
  52. // }
  53. // mapSlice = append(mapSlice, timeStartMap)
  54. // }
  55. // sort.Slice(mapSlice, func(i, j int) bool {
  56. // return mapSlice[i].Time < mapSlice[j].Time
  57. // })
  58. //
  59. // funStack := []TimeMap{}
  60. //
  61. // currentfunNum := 1
  62. //
  63. // // for k, v := range mapSlice {
  64. // // fmt.Println("---SliceSliceindex", k, "value", v.Time, v.Type, v.Map.Uprobe.Funcname, v.Map.StackEvent.Nid)
  65. // // }
  66. //
  67. // mapSliceLen := len(mapSlice)
  68. // for k, v := range mapSlice {
  69. // // fmt.Println("SliceSliceindex", k, "value", v.Time, v.Type, v.Map.Uprobe.Funcname, v.Map.StackEvent.Nid)
  70. // if v.Type == 0 {
  71. // // 函数入口
  72. // funStack = append(funStack, v)
  73. // } else if v.Type == 1 {
  74. // // 函数出口
  75. // len := len(funStack)
  76. // if len < 1 {
  77. // fmt.Printf("buildFun ErrorError return before enter: %v\n", v)
  78. // continue
  79. // }
  80. // currnt := funStack[len-1]
  81. // if currnt.Map.StackEvent.Location != 0 {
  82. // fmt.Printf("currnt StackEvent ErrorError is not enter: %v\n", v)
  83. // continue
  84. // }
  85. // if k < mapSliceLen-1 && len >= 2 {
  86. // nextfun := mapSlice[k+1]
  87. // preCurrnt := funStack[len-2]
  88. // // // 处理 .netcore 多次 returun
  89. // // 下一个事件是 return 并且函数名跟当前事件是一样的,且上一个函数不是当前函数
  90. // if nextfun.Map.StackEvent.Location == 1 && nextfun.Map.Uprobe.Funcname == currnt.Map.Uprobe.Funcname && preCurrnt.Map.Uprobe.Funcname != currnt.Map.Uprobe.Funcname {
  91. // currentfunNum++
  92. // continue
  93. // }
  94. // }
  95. // funStack = funStack[:len-1]
  96. // duration := v.Map.StackEvent.TimeNsEnd - currnt.Map.StackEvent.TimeNsStart
  97. // t.FuncTraceQuery(currnt.Map.Uprobe.Funcname, time.Duration(duration), currnt.Map.StackEvent.TimeNsStart, v.Map.StackEvent.TimeNsEnd, currentfunNum)
  98. // currentfunNum = 1
  99. // }
  100. // }
  101. //}
  102. func (t *Trace) startReady() {
  103. t.lock.Lock()
  104. defer t.lock.Unlock()
  105. t.startEventReady = true
  106. }
  107. func (t *Trace) endReadyEvent(needCount uint32) {
  108. t.lock.Lock()
  109. defer t.lock.Unlock()
  110. t.endEventReady = true
  111. t.needEventCount = needCount
  112. }
  113. func (t *Trace) AllEventReady(traceID uint64) bool {
  114. fmt.Printf("[AllEventReady (current/need)|start|end|traceid](%d/%d)%v|%v|%d\n", *t.currenEventCount, t.needEventCount, t.startEventReady, t.endEventReady, traceID)
  115. return t.startEventReady && t.endEventReady && *t.currenEventCount >= t.needEventCount
  116. }
  117. func (t *Trace) TraceStartEvent(method, path string, status l7.Status, addr netaddr.IPPort, pid uint32, appInfo common.AppInfo) {
  118. t.span.SetAttributes(semconv.HTTPURL(fmt.Sprintf("http://%s%s", addr.String(), path)),
  119. semconv.HTTPMethod(method),
  120. attribute.String("http.uri", path))
  121. if status > 399 {
  122. t.span.SetStatus(codes.Error, "")
  123. }
  124. t.destination = addr
  125. t.commonAttrs = []attribute.KeyValue{
  126. semconv.NetPeerName(addr.IP().String()),
  127. semconv.NetPeerPort(int(addr.Port())),
  128. // buildAppMapFromEvent
  129. attribute.Int("server.code_type", appInfo.CodeType.Int()),
  130. attribute.String("server.app_name", appInfo.AppName),
  131. attribute.String("server.service_name", appInfo.ServiceName),
  132. attribute.Int64("server.app_id", appInfo.AppId),
  133. attribute.Int64("server.agent_id", appInfo.AgentId),
  134. attribute.Int64("server.instance_id", appInfo.InstanceId),
  135. }
  136. t.span.SetAttributes(t.commonAttrs...)
  137. t.startReady()
  138. }
  139. // set context span
  140. func (t *Trace) CreateRootSpan(traceId uint64) {
  141. traceIdStr := strconv.Itoa(int(traceId))
  142. ctx, span := tracer(t.containerId).Start(context.Background(), traceIdStr, trace.WithSpanKind(trace.SpanKindClient))
  143. t.setContext(ctx)
  144. t.setSpan(span)
  145. }
  146. func (t *Trace) TraceStart(method, path string, status l7.Status, duration time.Duration) {
  147. if t == nil || method == "" {
  148. return
  149. }
  150. t.createParentSpan("APPLICATION", duration, status >= 400,
  151. semconv.HTTPURL(fmt.Sprintf("http://%s%s", t.destination.String(), path)),
  152. semconv.HTTPMethod(method),
  153. //semconv.HTTPStatusCode(int(status)),
  154. attribute.String("http.uri", path),
  155. )
  156. }
  157. func (t *Trace) TraceEnd(r *l7.RequestData) {
  158. if t == nil {
  159. return
  160. }
  161. t.span.SetAttributes(
  162. semconv.HTTPStatusCode(int(r.Status)),
  163. attribute.String("server.trace_id_from", r.ParentSpanContext.TraceIdFrom),
  164. )
  165. CalledId, err := strconv.ParseInt(r.ParentSpanContext.CalledId, 10, 64)
  166. if err == nil && CalledId != 0 {
  167. t.span.SetAttributes(attribute.Int64("server.called_id", CalledId))
  168. }
  169. InstanceIdFrom, err := strconv.ParseInt(r.ParentSpanContext.InstanceIdFrom, 10, 64)
  170. if err == nil && InstanceIdFrom != 0 {
  171. t.span.SetAttributes(attribute.Int64("server.instance_id_from", InstanceIdFrom))
  172. }
  173. AppIdFrom, err := strconv.ParseInt(r.ParentSpanContext.AppIdFrom, 10, 64)
  174. if err == nil && AppIdFrom != 0 {
  175. t.span.SetAttributes(attribute.Int64("server.app_id_from", AppIdFrom))
  176. }
  177. if r.ParentSpanContext.SpanIdFrom != "0000000000000000" {
  178. t.span.SetAttributes(attribute.String("server.span_id_from", r.ParentSpanContext.SpanIdFrom))
  179. }
  180. // for _, v := range t.stack {
  181. // fmt.Printf("TraceEndTraceEndTraceEnd%s\n", v)
  182. // }
  183. //t.buildFun()
  184. t.span.End(trace.WithTimestamp(time.Now()))
  185. }
  186. // 新增结束事件
  187. func (t *Trace) TraceEndEvent(r *l7.RequestData) {
  188. if t == nil {
  189. return
  190. }
  191. var attr []attribute.KeyValue
  192. attr = append(attr,
  193. semconv.HTTPStatusCode(int(r.Status)),
  194. attribute.String("server.trace_id_from", r.ParentSpanContext.TraceIdFrom),
  195. )
  196. //t.span.SetAttributes(
  197. // semconv.HTTPStatusCode(int(r.Status)),
  198. // attribute.String("server.trace_id_from", r.ParentSpanContext.TraceIdFrom),
  199. //)
  200. calledId, err := strconv.ParseInt(r.ParentSpanContext.CalledId, 10, 64)
  201. if err == nil && calledId != 0 {
  202. attr = append(attr, attribute.Int64("server.called_id", calledId))
  203. //t.span.SetAttributes(attribute.Int64("server.called_id", CalledId))
  204. }
  205. instanceIdFrom, err := strconv.ParseInt(r.ParentSpanContext.InstanceIdFrom, 10, 64)
  206. if err == nil && instanceIdFrom != 0 {
  207. attr = append(attr, attribute.Int64("server.instance_id_from", instanceIdFrom))
  208. //t.span.SetAttributes(attribute.Int64("server.instance_id_from", InstanceIdFrom))
  209. }
  210. appIdFrom, err := strconv.ParseInt(r.ParentSpanContext.AppIdFrom, 10, 64)
  211. if err == nil && appIdFrom != 0 {
  212. attr = append(attr, attribute.Int64("server.app_id_from", appIdFrom))
  213. //t.span.SetAttributes(attribute.Int64("server.app_id_from", AppIdFrom))
  214. }
  215. if r.ParentSpanContext.SpanIdFrom != "0000000000000000" {
  216. attr = append(attr, attribute.String("server.span_id_from", r.ParentSpanContext.SpanIdFrom))
  217. //t.span.SetAttributes(attribute.String("server.span_id_from", r.ParentSpanContext.SpanIdFrom))
  218. }
  219. t.appendTimestamp(&attr, r.StartAt, r.EndAt, r.Duration.Nanoseconds())
  220. t.span.SetAttributes(attr...)
  221. t.endReadyEvent(r.EventCount)
  222. }
  223. func (t *Trace) appendTimestamp(attr *[]attribute.KeyValue, s, e uint64, d int64) {
  224. *attr = append(*attr,
  225. attribute.Int64("time.start_at", utils.KtimeToTimestamp(s)),
  226. attribute.Int64("time.end_at", utils.KtimeToTimestamp(e)),
  227. attribute.Int64("time.duration", d),
  228. )
  229. }
  230. func (t *Trace) createParentSpan(name string, duration time.Duration, error bool, attrs ...attribute.KeyValue) {
  231. end := time.Now()
  232. start := end.Add(-duration)
  233. ctx, span := tracer(t.containerId).Start(context.Background(), name, trace.WithTimestamp(start), trace.WithSpanKind(trace.SpanKindClient))
  234. span.SetAttributes(attrs...)
  235. span.SetAttributes(t.commonAttrs...)
  236. if error {
  237. span.SetStatus(codes.Error, "")
  238. }
  239. t.setContext(ctx)
  240. t.setSpan(span)
  241. }
  242. func (t *Trace) SendEvent() {
  243. t.span.End()
  244. }
  245. func (t *Trace) GetSpan() trace.Span {
  246. return t.span
  247. }
  248. func (t *Trace) createTraceEvent(name string, eventType int, l7Type int, attrs ...attribute.KeyValue) {
  249. t.span.AddEventApm(name, eventType, l7Type, trace.WithAttributes(attrs...))
  250. atomic.AddUint32(t.currenEventCount, 1)
  251. }
  252. func (t *Trace) createTraceSpan(name string, duration time.Duration, error bool, attrs ...attribute.KeyValue) {
  253. end := time.Now()
  254. start := end.Add(-duration)
  255. //fmt.Println("createTraceSpan:", t.ctx)
  256. _, span := tracer(t.containerId).Start(t.ctx, name, trace.WithTimestamp(start), trace.WithSpanKind(trace.SpanKindClient))
  257. span.SetAttributes(t.commonAttrs...)
  258. span.SetAttributes(attrs...)
  259. if error {
  260. span.SetStatus(codes.Error, "")
  261. }
  262. span.End(trace.WithTimestamp(end))
  263. }
  264. func (t *Trace) MysqlTraceQuery(query string, error bool, duration time.Duration, destination netaddr.IPPort) {
  265. if t == nil || query == "" {
  266. return
  267. }
  268. t.createTraceSpan(l7.ProtocolMysql.String(), duration, error,
  269. semconv.DBSystemMySQL,
  270. semconv.DBStatement(query),
  271. semconv.NetPeerName(destination.IP().String()),
  272. semconv.NetPeerPort(int(destination.Port())),
  273. )
  274. }
  275. func (t *Trace) MysqlTraceQueryEvent(query string, r *l7.RequestData, destination netaddr.IPPort) {
  276. if t == nil || query == "" {
  277. return
  278. }
  279. var attr []attribute.KeyValue
  280. attr = append(attr,
  281. semconv.DBSystemMySQL,
  282. semconv.DBStatement(query),
  283. semconv.NetPeerName(destination.IP().String()),
  284. semconv.NetPeerPort(int(destination.Port())),
  285. )
  286. t.appendTimestamp(&attr, r.StartAt, r.EndAt, r.Duration.Nanoseconds())
  287. t.createTraceEvent(l7.ProtocolHTTP.String(), int(ebpftracer.EventTypeL7Request), int(l7.ProtocolMysql), attr...)
  288. }
  289. func (t *Trace) RedisTraceQuery(cmd, args string, error bool, duration time.Duration) {
  290. if t == nil || cmd == "" {
  291. return
  292. }
  293. statement := cmd
  294. if args != "" {
  295. statement += " " + args
  296. }
  297. t.createTraceSpan(l7.ProtocolRedis.String(), duration, error,
  298. semconv.DBSystemRedis,
  299. semconv.DBOperation(cmd),
  300. semconv.DBStatement(statement),
  301. )
  302. }
  303. func (t *Trace) RedisTraceQueryEvent(cmd, args string, r *l7.RequestData, destination netaddr.IPPort) {
  304. if t == nil || cmd == "" {
  305. return
  306. }
  307. statement := cmd
  308. if args != "" {
  309. statement += " " + args
  310. }
  311. var attr []attribute.KeyValue
  312. attr = append(attr,
  313. semconv.DBSystemRedis,
  314. semconv.DBOperation(cmd),
  315. semconv.DBStatement(statement),
  316. semconv.NetPeerName(destination.IP().String()),
  317. semconv.NetPeerPort(int(destination.Port())),
  318. )
  319. t.appendTimestamp(&attr, r.StartAt, r.EndAt, r.Duration.Nanoseconds())
  320. t.createTraceEvent(l7.ProtocolHTTP.String(), int(ebpftracer.EventTypeL7Request), int(l7.ProtocolRedis), attr...)
  321. }
  322. func (t *Trace) HttpTraceRequest(method, path, ip string, port uint16, r *l7.RequestData) {
  323. if t == nil || method == "" {
  324. return
  325. }
  326. assumedAppID, err := strconv.ParseInt(r.AssumedAppId, 10, 64)
  327. if err != nil {
  328. assumedAppID = 0
  329. }
  330. status := r.Status
  331. duration := r.Duration
  332. t.createTraceSpan(l7.ProtocolHTTP.String(), duration, status >= 400,
  333. semconv.HTTPURL(fmt.Sprintf("http://%s%s", t.destination.String(), path)),
  334. semconv.HTTPMethod(method),
  335. semconv.HTTPStatusCode(int(status)),
  336. attribute.String("http.uri", path),
  337. attribute.String("http.ip", ip),
  338. attribute.Int64("http.assumed_app_id", assumedAppID),
  339. attribute.String("http.span_id", r.SpanId),
  340. attribute.Int("http.port", int(port)),
  341. )
  342. }
  343. // 新增事件处理
  344. func (t *Trace) HttpTraceRequestEvent(method, path, ip string, port uint16, r *l7.RequestData) {
  345. if t == nil || method == "" {
  346. return
  347. }
  348. assumedAppID, err := strconv.ParseInt(r.AssumedAppId, 10, 64)
  349. if err != nil {
  350. assumedAppID = 0
  351. }
  352. status := r.Status
  353. var attr []attribute.KeyValue
  354. attr = append(attr,
  355. semconv.HTTPURL(fmt.Sprintf("http://%s%s", t.destination.String(), path)),
  356. semconv.HTTPMethod(method),
  357. semconv.HTTPStatusCode(int(status)),
  358. attribute.Bool("http.status_error", status > 399),
  359. attribute.String("http.uri", path),
  360. attribute.String("http.ip", ip),
  361. attribute.Int64("http.assumed_app_id", assumedAppID),
  362. attribute.String("http.span_id", r.SpanId),
  363. attribute.Int("http.port", int(port)),
  364. )
  365. t.appendTimestamp(&attr, r.StartAt, r.EndAt, r.Duration.Nanoseconds())
  366. t.createTraceEvent(l7.ProtocolHTTP.String(), int(ebpftracer.EventTypeL7Request), int(l7.ProtocolHTTP), attr...)
  367. }
  368. func (t *Trace) FuncTraceQuery(funcname string, duration time.Duration, start uint64, end uint64) {
  369. if t == nil || funcname == "" {
  370. return
  371. }
  372. t.createTraceSpanNoTime2(funcname, duration, false, start, end)
  373. //t.createTraceSpanNoTime2(funcname, duration, false, start, end, attribute.Int("num", num))
  374. }
  375. func (t *Trace) createTraceSpanNoTime(name string, duration time.Duration, error bool, start uint64, end uint64, attrs ...attribute.KeyValue) {
  376. // end := time.Now()
  377. // start := end.Add(-duration)
  378. startTime := time.Unix(0, int64(start))
  379. endTime := time.Unix(0, int64(end))
  380. //fmt.Println("createTraceSpan:", t.ctx)
  381. _, span := tracer(t.containerId).Start(t.ctx, name, trace.WithTimestamp(startTime), trace.WithSpanKind(trace.SpanKindClient))
  382. span.SetAttributes(t.commonAttrs...)
  383. span.SetAttributes(attrs...)
  384. if error {
  385. span.SetStatus(codes.Error, "")
  386. }
  387. span.End(trace.WithTimestamp(endTime))
  388. }
  389. func (t *Trace) createTraceSpanNoTime2(name string, duration time.Duration, error bool, start uint64, end uint64) {
  390. // end := time.Now()
  391. // start := end.Add(-duration)
  392. //startTime := time.Unix(0, int64(start))
  393. //endTime := time.Unix(0, int64(end))
  394. ////fmt.Println("createTraceSpan:", t.ctx)
  395. //_, span := tracer(t.containerId).Start(t.ctx, name, trace.WithTimestamp(startTime), trace.WithSpanKind(trace.SpanKindClient))
  396. //span.SetAttributes(t.commonAttrs...)
  397. //span.SetAttributes(attrs...)
  398. //if error {
  399. // span.SetStatus(codes.Error, "")
  400. //}
  401. //span.End(trace.WithTimestamp(endTime))
  402. //attrs = append([]attribute.KeyValue{
  403. // attribute.Int64("startAt", int64(start)),
  404. // attribute.Int64("endAt", int64(end)),
  405. //})
  406. var attr []attribute.KeyValue
  407. t.appendTimestamp(&attr, start, end, int64(end-start))
  408. t.createTraceEvent(name, int(ebpftracer.EventTypeFunEnt), 0, attr...)
  409. }