TimingCollector.cs 6.1 KB

123456789101112131415161718192021222324252627282930313233343536373839404142434445464748495051525354555657585960616263646566676869707172737475767778798081828384858687888990919293949596979899100101102103104105106107108109110111112113114115116117118119120121122123124125126127128129130131132133134135136137138139140141142143144145146147148149150151152153154155156157158159160161162163164165166167168169170171172173174175176177178179180181182183
  1. using System;
  2. using System.Collections.Generic;
  3. using Best.HTTP.Shared;
  4. using Best.HTTP.Shared.PlatformSupport.Text;
  5. namespace Best.HTTP.Request.Timings
  6. {
  7. struct PartialEvent
  8. {
  9. public string EventName;
  10. public DateTime StartedAt;
  11. public PartialEvent(string eventName, DateTime startedAt)
  12. {
  13. this.EventName = eventName;
  14. this.StartedAt = startedAt;
  15. }
  16. public bool IsSet() => !string.IsNullOrEmpty(EventName) && StartedAt != DateTime.MinValue;
  17. public override string ToString() => $"[PartialEvent '{EventName}', {StartedAt.ToString("hh:mm:ss.fffffff")}]";
  18. }
  19. /// <summary>
  20. /// Helper class to store, calculate and manage request related events and theirs duration, referenced by <see cref="HTTPRequest.Timing"/> field.
  21. /// </summary>
  22. public sealed class TimingCollector
  23. {
  24. public HTTPRequest ParentRequest { get; }
  25. /// <summary>
  26. /// When the TimingCollector instance created.
  27. /// </summary>
  28. public DateTime Created { get; private set; }
  29. /// <summary>
  30. /// When the closing Finish event is sent.
  31. /// </summary>
  32. public DateTime Finished { get; private set; }
  33. /// <summary>
  34. /// List of added events.
  35. /// </summary>
  36. public List<TimingEvent> Events { get; private set; }
  37. private PartialEvent _partialEvent;
  38. internal TimingCollector(HTTPRequest parentRequest)
  39. {
  40. this.ParentRequest = parentRequest;
  41. this.Created = DateTime.Now;
  42. this.Finished = DateTime.MinValue;
  43. this._partialEvent = new PartialEvent(TimingEventNames.Initial, this.Created);
  44. }
  45. public void StartNext(string eventName) => TimingEventHelper.Enqueue(new TimingEventInfo(this.ParentRequest, TimingEvents.StartNext, eventName));
  46. /// <summary>
  47. /// Finish the last event.
  48. /// </summary>
  49. public void Finish() => TimingEventHelper.Enqueue(new TimingEventInfo(this.ParentRequest, TimingEvents.Finish, null));
  50. /// <summary>
  51. /// Abort the currently running event.
  52. /// </summary>
  53. internal void Abort() => TimingEventHelper.Enqueue(new TimingEventInfo(this.ParentRequest, TimingEvents.Abort));
  54. internal void AddEvent(TimingEventInfo timingEvent)
  55. {
  56. switch(timingEvent.Event)
  57. {
  58. case TimingEvents.StartNext:
  59. if (this._partialEvent.IsSet())
  60. {
  61. // If it's the same event name as the last one, merge the two event by not doing anything now.
  62. if (timingEvent.Name.Equals(this._partialEvent.EventName, StringComparison.OrdinalIgnoreCase))
  63. break;
  64. AddEvent(this._partialEvent.EventName, this._partialEvent.StartedAt, timingEvent.Time - this._partialEvent.StartedAt);
  65. }
  66. if (timingEvent.Name != null)
  67. this._partialEvent = new PartialEvent(timingEvent.Name, timingEvent.Time);
  68. break;
  69. case TimingEvents.Finish:
  70. AddEvent(this._partialEvent.EventName, this._partialEvent.StartedAt, timingEvent.Time - this._partialEvent.StartedAt);
  71. var now = DateTime.Now;
  72. AddEvent(TimingEventNames.Finished, now, now - this.Created);
  73. this.Finished = now;
  74. if (HTTPManager.Logger.IsDiagnostic)
  75. HTTPManager.Logger.Information(nameof(TimingCollector), this.ToString(),ParentRequest.Context);
  76. break;
  77. }
  78. }
  79. /// <summary>
  80. /// When the event happened and for how long.
  81. /// </summary>
  82. internal void AddEvent(string name, DateTime when, TimeSpan duration)
  83. {
  84. if (this.Events == null)
  85. this.Events = new List<TimingEvent>();
  86. if (duration == TimeSpan.Zero)
  87. {
  88. DateTime prevEventAt = this.Created;
  89. if (this.Events.Count > 0)
  90. prevEventAt = this.Events[this.Events.Count - 1].When;
  91. duration = when - prevEventAt;
  92. }
  93. this.Events.Add(new TimingEvent(name, when, duration));
  94. }
  95. public TimingEvent FindFirst(string name)
  96. {
  97. if (this.Events == null)
  98. return TimingEvent.Empty;
  99. for (int i = 0; i < this.Events.Count; ++i)
  100. {
  101. if (this.Events[i].Name == name)
  102. return this.Events[i];
  103. }
  104. return TimingEvent.Empty;
  105. }
  106. public TimingEvent FindLast(string name)
  107. {
  108. if (this.Events == null)
  109. return TimingEvent.Empty;
  110. for (int i = this.Events.Count - 1; i >= 0; --i)
  111. {
  112. if (this.Events[i].Name == name)
  113. return this.Events[i];
  114. }
  115. return TimingEvent.Empty;
  116. }
  117. public override string ToString()
  118. {
  119. var sb = StringBuilderPool.Get(0);
  120. sb.Append("{\"Created\": \"");
  121. sb.Append(this.Created.ToString("yyyy-MM-dd hh:mm:ss.fffffff"));
  122. sb.Append("\",\"Finished\": \"");
  123. sb.Append(this.Finished.ToString("yyyy-MM-dd hh:mm:ss.fffffff"));
  124. if (this.Events != null)
  125. {
  126. sb.Append("\", \"Events\": ");
  127. sb.Append('[');
  128. for (int i = 0; i < this.Events.Count; ++i)
  129. {
  130. var @event = this.Events[i];
  131. sb.Append("{\"Name\": \"");
  132. sb.Append(@event.Name);
  133. sb.Append("\", \"Duration\": \"");
  134. sb.Append(@event.Duration);
  135. sb.Append("\"}");
  136. if (i < this.Events.Count - 1)
  137. sb.Append(',');
  138. }
  139. sb.Append(']');
  140. }
  141. sb.Append('}');
  142. return StringBuilderPool.ReleaseAndGrab(sb);
  143. }
  144. }
  145. }