apm_tracing.go 15 KB

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