observability.adoc 14 KB

123456789101112131415161718192021222324252627282930313233343536373839404142434445464748495051525354555657585960616263646566676869707172737475767778798081828384858687888990919293949596979899100101102103104105106107108109110111112113114115116117118119120121122123124125126127128129130131132133134135136137138139140141142143144145146147148149150151152153154155156157158159160161162163164165166167168169170171172173174175176177178179180181182183184185186187188189190191192193194195196197198199200201202203204205206207208209210211212213214215216217218219220221222223224225226227228229230
  1. [[webflux-observability]]
  2. = Observability
  3. Spring Security integrates with Spring Observability out-of-the-box for tracing; though it's also quite simple to configure for gathering metrics.
  4. [[webflux-observability-tracing]]
  5. == Tracing
  6. When an `ObservationRegistry` bean is present, Spring Security creates traces for:
  7. * the filter chain
  8. * the `ReactiveAuthenticationManager`, and
  9. * the `ReactiveAuthorizationManager`
  10. [[webflux-observability-tracing-boot]]
  11. === Boot Integration
  12. For example, consider a simple Boot application:
  13. ====
  14. .Java
  15. [source,java,role="primary"]
  16. ----
  17. @SpringBootApplication
  18. public class MyApplication {
  19. @Bean
  20. public ReactiveUserDetailsService userDetailsService() {
  21. return new MapReactiveUserDetailsManager(
  22. User.withDefaultPasswordEncoder()
  23. .username("user")
  24. .password("password")
  25. .authorities("app")
  26. .build()
  27. );
  28. }
  29. @Bean
  30. ObservationRegistryCustomizer<ObservationRegistry> addTextHandler() {
  31. return (registry) -> registry.observationConfig().observationHandler(new ObservationTextHandler());
  32. }
  33. public static void main(String[] args) {
  34. SpringApplication.run(ListenerSamplesApplication.class, args);
  35. }
  36. }
  37. ----
  38. .Kotlin
  39. [source,kotlin,role="secondary"]
  40. ----
  41. @SpringBootApplication
  42. class MyApplication {
  43. @Bean
  44. fun userDetailsService(): ReactiveUserDetailsService {
  45. MapReactiveUserDetailsManager(
  46. User.withDefaultPasswordEncoder()
  47. .username("user")
  48. .password("password")
  49. .authorities("app")
  50. .build()
  51. );
  52. }
  53. @Bean
  54. fun addTextHandler(): ObservationRegistryCustomizer<ObservationRegistry> {
  55. return registry: ObservationRegistry -> registry.observationConfig()
  56. .observationHandler(ObservationTextHandler());
  57. }
  58. fun main(args: Array<String>) {
  59. runApplication<MyApplication>(*args)
  60. }
  61. }
  62. ----
  63. ====
  64. And a corresponding request:
  65. ====
  66. [source,bash]
  67. ----
  68. ?> http -a user:password :8080
  69. ----
  70. ====
  71. Will produce the following output (indentation added for clarity):
  72. ====
  73. [source,bash]
  74. ----
  75. START - name='http.server.requests', contextualName='null', error='null', lowCardinalityKeyValues=[], highCardinalityKeyValues=[], map=[class io.micrometer.tracing.handler.TracingObservationHandler$TracingContext='io.micrometer.tracing.handler.TracingObservationHandler$TracingContext@5dfdb78', class io.micrometer.core.instrument.LongTaskTimer$Sample='SampleImpl{duration(seconds)=0.00191856, duration(nanos)=1918560.0, startTimeNanos=101177265022745}', class io.micrometer.core.instrument.Timer$Sample='io.micrometer.core.instrument.Timer$Sample@121549e0']
  76. START - name='spring.security.http.chains', contextualName='spring.security.http.chains.before', error='null', lowCardinalityKeyValues=[chain.size='14', filter.section='before'], highCardinalityKeyValues=[request.line='/'], map=[class io.micrometer.tracing.handler.TracingObservationHandler$TracingContext='io.micrometer.tracing.handler.TracingObservationHandler$TracingContext@3932a48c', class io.micrometer.core.instrument.LongTaskTimer$Sample='SampleImpl{duration(seconds)=4.65777E-4, duration(nanos)=465777.0, startTimeNanos=101177276300777}', class io.micrometer.core.instrument.Timer$Sample='io.micrometer.core.instrument.Timer$Sample@562db70f']
  77. STOP - name='spring.security.http.chains', contextualName='spring.security.http.chains.before', error='null', lowCardinalityKeyValues=[chain.size='14', filter.section='before'], highCardinalityKeyValues=[request.line='/'], map=[class io.micrometer.tracing.handler.TracingObservationHandler$TracingContext='io.micrometer.tracing.handler.TracingObservationHandler$TracingContext@3932a48c', class io.micrometer.core.instrument.LongTaskTimer$Sample='SampleImpl{duration(seconds)=0.003733105, duration(nanos)=3733105.0, startTimeNanos=101177276300777}', class io.micrometer.core.instrument.Timer$Sample='io.micrometer.core.instrument.Timer$Sample@562db70f']
  78. START - name='spring.security.authentications', contextualName='null', error='null', lowCardinalityKeyValues=[authentication.failure.type='Optional', authentication.method='UserDetailsRepositoryReactiveAuthenticationManager', authentication.request.type='UsernamePasswordAuthenticationToken'], highCardinalityKeyValues=[], map=[class io.micrometer.tracing.handler.TracingObservationHandler$TracingContext='io.micrometer.tracing.handler.TracingObservationHandler$TracingContext@574ba6cd', class io.micrometer.core.instrument.LongTaskTimer$Sample='SampleImpl{duration(seconds)=3.21015E-4, duration(nanos)=321015.0, startTimeNanos=101177336038417}', class io.micrometer.core.instrument.Timer$Sample='io.micrometer.core.instrument.Timer$Sample@49202cc7']
  79. STOP - name='spring.security.authentications', contextualName='null', error='null', lowCardinalityKeyValues=[authentication.failure.type='Optional', authentication.method='UserDetailsRepositoryReactiveAuthenticationManager', authentication.request.type='UsernamePasswordAuthenticationToken', authentication.result.type='UsernamePasswordAuthenticationToken'], highCardinalityKeyValues=[], map=[class io.micrometer.tracing.handler.TracingObservationHandler$TracingContext='io.micrometer.tracing.handler.TracingObservationHandler$TracingContext@574ba6cd', class io.micrometer.core.instrument.LongTaskTimer$Sample='SampleImpl{duration(seconds)=0.37574992, duration(nanos)=3.7574992E8, startTimeNanos=101177336038417}', class io.micrometer.core.instrument.Timer$Sample='io.micrometer.core.instrument.Timer$Sample@49202cc7']
  80. START - name='spring.security.authorizations', contextualName='null', error='null', lowCardinalityKeyValues=[object.type='SecurityContextServerWebExchange'], highCardinalityKeyValues=[], map=[class io.micrometer.tracing.handler.TracingObservationHandler$TracingContext='io.micrometer.tracing.handler.TracingObservationHandler$TracingContext@6f837332', class io.micrometer.core.instrument.LongTaskTimer$Sample='SampleImpl{duration(seconds)=2.65687E-4, duration(nanos)=265687.0, startTimeNanos=101177777941381}', class io.micrometer.core.instrument.Timer$Sample='io.micrometer.core.instrument.Timer$Sample@7f5bc7cb']
  81. STOP - name='spring.security.authorizations', contextualName='null', error='null', lowCardinalityKeyValues=[authorization.decision='true', object.type='SecurityContextServerWebExchange'], highCardinalityKeyValues=[authentication.authorities='[app]', authorization.decision.details='AuthorizationDecision [granted=true]'], map=[class io.micrometer.tracing.handler.TracingObservationHandler$TracingContext='io.micrometer.tracing.handler.TracingObservationHandler$TracingContext@6f837332', class io.micrometer.core.instrument.LongTaskTimer$Sample='SampleImpl{duration(seconds)=0.039239047, duration(nanos)=3.9239047E7, startTimeNanos=101177777941381}', class io.micrometer.core.instrument.Timer$Sample='io.micrometer.core.instrument.Timer$Sample@7f5bc7cb']
  82. START - name='spring.security.http.secured.requests', contextualName='null', error='null', lowCardinalityKeyValues=[], highCardinalityKeyValues=[], map=[class io.micrometer.tracing.handler.TracingObservationHandler$TracingContext='io.micrometer.tracing.handler.TracingObservationHandler$TracingContext@2f33dfae', class io.micrometer.core.instrument.LongTaskTimer$Sample='SampleImpl{duration(seconds)=3.1775E-4, duration(nanos)=317750.0, startTimeNanos=101177821377592}', class io.micrometer.core.instrument.Timer$Sample='io.micrometer.core.instrument.Timer$Sample@63b0d28f']
  83. STOP - name='spring.security.http.secured.requests', contextualName='null', error='null', lowCardinalityKeyValues=[], highCardinalityKeyValues=[], map=[class io.micrometer.tracing.handler.TracingObservationHandler$TracingContext='io.micrometer.tracing.handler.TracingObservationHandler$TracingContext@2f33dfae', class io.micrometer.core.instrument.LongTaskTimer$Sample='SampleImpl{duration(seconds)=0.219901971, duration(nanos)=2.19901971E8, startTimeNanos=101177821377592}', class io.micrometer.core.instrument.Timer$Sample='io.micrometer.core.instrument.Timer$Sample@63b0d28f']
  84. START - name='spring.security.http.chains', contextualName='spring.security.http.chains.after', error='null', lowCardinalityKeyValues=[chain.size='14', filter.section='after'], highCardinalityKeyValues=[request.line='/'], map=[class io.micrometer.tracing.handler.TracingObservationHandler$TracingContext='io.micrometer.tracing.handler.TracingObservationHandler$TracingContext@40b25623', class io.micrometer.core.instrument.LongTaskTimer$Sample='SampleImpl{duration(seconds)=3.25118E-4, duration(nanos)=325118.0, startTimeNanos=101178044824275}', class io.micrometer.core.instrument.Timer$Sample='io.micrometer.core.instrument.Timer$Sample@3b6cec2']
  85. STOP - name='spring.security.http.chains', contextualName='spring.security.http.chains.after', error='null', lowCardinalityKeyValues=[chain.size='14', filter.section='after'], highCardinalityKeyValues=[request.line='/'], map=[class io.micrometer.tracing.handler.TracingObservationHandler$TracingContext='io.micrometer.tracing.handler.TracingObservationHandler$TracingContext@40b25623', class io.micrometer.core.instrument.LongTaskTimer$Sample='SampleImpl{duration(seconds)=0.001693146, duration(nanos)=1693146.0, startTimeNanos=101178044824275}', class io.micrometer.core.instrument.Timer$Sample='io.micrometer.core.instrument.Timer$Sample@3b6cec2']
  86. STOP - name='http.server.requests', contextualName='null', error='null', lowCardinalityKeyValues=[], highCardinalityKeyValues=[], map=[class io.micrometer.tracing.handler.TracingObservationHandler$TracingContext='io.micrometer.tracing.handler.TracingObservationHandler$TracingContext@5dfdb78', class io.micrometer.core.instrument.LongTaskTimer$Sample='SampleImpl{duration(seconds)=0.784320641, duration(nanos)=7.84320641E8, startTimeNanos=101177265022745}', class io.micrometer.core.instrument.Timer$Sample='io.micrometer.core.instrument.Timer$Sample@121549e0']
  87. ----
  88. ====
  89. [[webflux-observability-tracing-manual-configuration]]
  90. === Manual Configuration
  91. For a non-Spring Boot application, or to override the existing Boot configuration, you can publish your own `ObservationRegistry` and Spring Security will still pick it up.
  92. ====
  93. .Java
  94. [source,java,role="primary"]
  95. ----
  96. @SpringBootApplication
  97. public class MyApplication {
  98. @Bean
  99. public ReactiveUserDetailsService userDetailsService() {
  100. return new MapReactiveUserDetailsManager(
  101. User.withDefaultPasswordEncoder()
  102. .username("user")
  103. .password("password")
  104. .authorities("app")
  105. .build()
  106. );
  107. }
  108. @Bean
  109. ObservationRegistry<ObservationRegistry> observationRegistry() {
  110. ObservationRegistry registry = ObservationRegistry.create();
  111. registry.observationConfig().observationHandler(new ObservationTextHandler());
  112. return registry;
  113. }
  114. public static void main(String[] args) {
  115. SpringApplication.run(ListenerSamplesApplication.class, args);
  116. }
  117. }
  118. ----
  119. .Kotlin
  120. [source,kotlin,role="secondary"]
  121. ----
  122. @SpringBootApplication
  123. class MyApplication {
  124. @Bean
  125. fun userDetailsService(): ReactiveUserDetailsService {
  126. MapReactiveUserDetailsManager(
  127. User.withDefaultPasswordEncoder()
  128. .username("user")
  129. .password("password")
  130. .authorities("app")
  131. .build()
  132. );
  133. }
  134. @Bean
  135. fun observationRegistry(): ObservationRegistry<ObservationRegistry> {
  136. ObservationRegistry registry = ObservationRegistry.create()
  137. registry.observationConfig().observationHandler(ObservationTextHandler())
  138. return registry
  139. }
  140. fun main(args: Array<String>) {
  141. runApplication<MyApplication>(*args)
  142. }
  143. }
  144. ----
  145. .Xml
  146. [source,kotlin,role="secondary"]
  147. ----
  148. <sec:http auto-config="true" observation-registry-ref="ref">
  149. <sec:intercept-url pattern="/**" access="authenticated"/>
  150. </sec:http>
  151. <!-- define and configure ObservationRegistry bean -->
  152. ----
  153. ====
  154. [[webflux-observability-tracing-disable]]
  155. === Disabling Observability
  156. If you don't want any Spring Security observations, in a Spring Boot application you can publish a `ObservationRegistry.NOOP` `@Bean`.
  157. However, this may turn off observations for more than just Spring Security.
  158. Instead, you can alter the provided `ObservationRegistry` with an `ObservationPredicate` like the following:
  159. ====
  160. .Java
  161. [source,java,role="primary"]
  162. ----
  163. @Bean
  164. ObservationRegistryCustomizer<ObservationRegistry> noSpringSecurityObservations() {
  165. ObservationPredicate predicate = (name, context) -> name.startsWith("spring.security.")
  166. return (registry) -> registry.observationConfig().observationPredicate(predicate)
  167. }
  168. ----
  169. .Kotlin
  170. [source,kotlin,role="secondary"]
  171. ----
  172. @Bean
  173. fun noSpringSecurityObservations(): ObservationRegistryCustomizer<ObservationRegistry> {
  174. ObservationPredicate predicate = (name: String, context: Observation.Context) -> name.startsWith("spring.security.")
  175. (registry: ObservationRegistry) -> registry.observationConfig().observationPredicate(predicate)
  176. }
  177. ----
  178. ====
  179. [TIP]
  180. There is no facility for disabling observations with XML support.
  181. Instead, simply do not set the `observation-registry-ref` attribute.
  182. [[webflux-observability-tracing-listing]]
  183. === Trace Listing
  184. Spring Security tracks the following spans on each request:
  185. 1. `spring.security.http.requests` - a span that wraps the entire filter chain, including the request
  186. 2. `spring.security.http.chains.before` - a span that wraps the receiving part of the security filters
  187. 3. `spring.security.http.chains.after` - a span that wraps the returning part of the security filters
  188. 4. `spring.security.http.secured.requests` - a span that wraps the now-secured application request
  189. 5. `spring.security.http.unsecured.requests` - a span that wraps requests that Spring Security does not secure
  190. 6. `spring.security.authentications` - a span that wraps authentication attempts
  191. 7. `spring.security.authorizations` - a span that wraps authorization attempts
  192. [TIP]
  193. `spring.security.http.chains.before` + `spring.security.http.secured.requests` + `spring.security.http.chains.after` = `spring.security.http.requests`
  194. `spring.security.http.chains.before` + `spring.security.http.chains.after` = Spring Security's part of the request