search

reactor / reactor-netty

TCP/HTTP/UDP/QUIC client/server with Reactor over Netty
https://projectreactor.io
Apache License 2.0
2.59k stars 646 forks source link

DNS name resolution taking a lot of time in windows. The same works fine with MacOS #2088

Closed Kryptonian-C closed 2 years ago

Kryptonian-C commented 2 years ago

I was building a Simple webflux application using netty but the DNS resolution is taking a lot of time almost 1min for the 1st request in windows. The same happens in 1second on MacOs and Linux.

Below are the logs.

13:11:27.823 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsQueryContext - [id: 0xc4d4fa95] WRITE: UDP, [53282: /10.150.1.252:53], DefaultDnsQuestion(dev-542348.okta.com. IN A)
13:11:27.830 [nioEventLoopGroup-2-1] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.maxCapacityPerThread: 4096
13:11:27.831 [nioEventLoopGroup-2-1] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.ratio: 8
13:11:27.831 [nioEventLoopGroup-2-1] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.chunkSize: 32
13:11:27.831 [nioEventLoopGroup-2-1] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.blocking: false
13:11:27.834 [nioEventLoopGroup-2-1] DEBUG io.netty.buffer.AbstractByteBuf - -Dio.netty.buffer.checkAccessible: true
13:11:27.834 [nioEventLoopGroup-2-1] DEBUG io.netty.buffer.AbstractByteBuf - -Dio.netty.buffer.checkBounds: true
13:11:27.834 [nioEventLoopGroup-2-1] DEBUG io.netty.util.ResourceLeakDetectorFactory - Loaded default ResourceLeakDetector: io.netty.util.ResourceLeakDetector@14896267
13:11:32.859 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsQueryContext - [id: 0xc4d4fa95] WRITE: UDP, [10561: /10.202.1.252:53], DefaultDnsQuestion(dev-542348.okta.com. IN A)
13:11:37.868 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsQueryContext - [id: 0xc4d4fa95] WRITE: UDP, [40341: /10.180.1.20:53], DefaultDnsQuestion(dev-542348.okta.com. IN A)
13:11:42.880 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsQueryContext - [id: 0xc4d4fa95] WRITE: UDP, [10043: /10.180.1.201:53], DefaultDnsQuestion(dev-542348.okta.com. IN A)
13:11:47.892 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsQueryContext - [id: 0xc4d4fa95] WRITE: UDP, [28012: /10.180.1.202:53], DefaultDnsQuestion(dev-542348.okta.com. IN A)
13:11:52.898 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsQueryContext - [id: 0xc4d4fa95] WRITE: UDP, [8689: /180.235.155.185:53], DefaultDnsQuestion(dev-542348.okta.com. IN A)
13:11:53.045 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsNameResolver - [id: 0xc4d4fa95] RECEIVED: UDP [8689: /180.235.155.185:53], DatagramDnsResponse(from: /180.235.155.185:53, to: /0.0.0.0:56937, 8689, QUERY(0), Refused(5), RD)
    DefaultDnsQuestion(dev-542348.okta.com. IN A)
13:11:53.045 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsQueryContext - [id: 0xc4d4fa95] WRITE: UDP, [43413: /192.168.1.1:53], DefaultDnsQuestion(dev-542348.okta.com. IN A)
13:11:53.171 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsNameResolver - [id: 0xc4d4fa95] RECEIVED: UDP [43413: /192.168.1.1:53], DatagramDnsResponse(from: /192.168.1.1:53, to: /0.0.0.0:56937, 43413, QUERY(0), NoError(0), RD RA)
    DefaultDnsQuestion(dev-542348.okta.com. IN A)
    DefaultDnsRawRecord(dev-542348.okta.com. 300 IN CNAME 25B)
    DefaultDnsRawRecord(ok11-crtrs.tng.okta.com. 202 IN CNAME 66B)
    DefaultDnsRawRecord(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. 60 IN A 4B)
    DefaultDnsRawRecord(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. 60 IN A 4B)
    DefaultDnsRawRecord(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. 60 IN A 4B)
    DefaultDnsRawRecord(elb.us-east-2.amazonaws.com. 41957 IN NS 23B)
    DefaultDnsRawRecord(elb.us-east-2.amazonaws.com. 41957 IN NS 25B)
    DefaultDnsRawRecord(elb.us-east-2.amazonaws.com. 41957 IN NS 21B)
    DefaultDnsRawRecord(elb.us-east-2.amazonaws.com. 41957 IN NS 22B)
13:11:53.175 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsQueryContext - [id: 0xc4d4fa95] WRITE: UDP, [61505: /10.150.1.252:53], DefaultDnsQuestion(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. IN A)
13:11:58.185 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsQueryContext - [id: 0xc4d4fa95] WRITE: UDP, [46866: /10.202.1.252:53], DefaultDnsQuestion(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. IN A)
13:12:03.187 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsQueryContext - [id: 0xc4d4fa95] WRITE: UDP, [39078: /10.180.1.20:53], DefaultDnsQuestion(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. IN A)
13:12:08.196 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsQueryContext - [id: 0xc4d4fa95] WRITE: UDP, [50281: /10.180.1.201:53], DefaultDnsQuestion(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. IN A)
13:12:13.208 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsQueryContext - [id: 0xc4d4fa95] WRITE: UDP, [53416: /10.180.1.202:53], DefaultDnsQuestion(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. IN A)
13:12:18.214 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsQueryContext - [id: 0xc4d4fa95] WRITE: UDP, [19383: /180.235.155.185:53], DefaultDnsQuestion(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. IN A)
13:12:18.320 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsNameResolver - [id: 0xc4d4fa95] RECEIVED: UDP [19383: /180.235.155.185:53], DatagramDnsResponse(from: /180.235.155.185:53, to: /0.0.0.0:56937, 19383, QUERY(0), Refused(5), RD)
    DefaultDnsQuestion(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. IN A)
13:12:18.320 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsQueryContext - [id: 0xc4d4fa95] WRITE: UDP, [39446: /192.168.1.1:53], DefaultDnsQuestion(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. IN A)
13:12:18.367 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsNameResolver - [id: 0xc4d4fa95] RECEIVED: UDP [39446: /192.168.1.1:53], DatagramDnsResponse(from: /192.168.1.1:53, to: /0.0.0.0:56937, 39446, QUERY(0), NoError(0), RD RA)
    DefaultDnsQuestion(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. IN A)
    DefaultDnsRawRecord(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. 34 IN A 4B)
    DefaultDnsRawRecord(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. 34 IN A 4B)
    DefaultDnsRawRecord(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. 34 IN A 4B)
2[dev-542348.okta.com/3.15.36.194, dev-542348.okta.com/3.15.36.192, dev-542348.okta.com/3.15.36.193]

When doing the same with MacOS only one Write request get's triggered instead the above 10.

Below are the logs from MacOS

> 13:23:20.937 [nioEventLoopGroup-2-1] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.maxCapacityPerThread: 4096
> 13:23:20.937 [nioEventLoopGroup-2-1] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.ratio: 8
> 13:23:20.937 [nioEventLoopGroup-2-1] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.chunkSize: 32
> 13:23:20.937 [nioEventLoopGroup-2-1] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.blocking: false
> 13:23:20.941 [nioEventLoopGroup-2-1] DEBUG io.netty.buffer.AbstractByteBuf - -Dio.netty.buffer.checkAccessible: true
> 13:23:20.941 [nioEventLoopGroup-2-1] DEBUG io.netty.buffer.AbstractByteBuf - -Dio.netty.buffer.checkBounds: true
> 13:23:20.941 [nioEventLoopGroup-2-1] DEBUG io.netty.util.ResourceLeakDetectorFactory - Loaded default ResourceLeakDetector: io.netty.util.ResourceLeakDetector@41e61236
> 13:23:21.046 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsNameResolver - [id: 0x0ea5f4b3] RECEIVED: UDP [52950: /192.168.1.1:53], DatagramDnsResponse(from: /192.168.1.1:53, to: /0.0.0.0:49776, 52950, QUERY(0), NoError(0), RD RA)
>   DefaultDnsQuestion(dev-542348.okta.com. IN A)
>   DefaultDnsRawRecord(dev-542348.okta.com. 300 IN CNAME 25B)
>   DefaultDnsRawRecord(ok11-crtrs.tng.okta.com. 217 IN CNAME 66B)
>   DefaultDnsRawRecord(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. 60 IN A 4B)
>   DefaultDnsRawRecord(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. 60 IN A 4B)
>   DefaultDnsRawRecord(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. 60 IN A 4B)
>   DefaultDnsRawRecord(elb.us-east-2.amazonaws.com. 41273 IN NS 23B)
>   DefaultDnsRawRecord(elb.us-east-2.amazonaws.com. 41273 IN NS 25B)
>   DefaultDnsRawRecord(elb.us-east-2.amazonaws.com. 41273 IN NS 21B)
>   DefaultDnsRawRecord(elb.us-east-2.amazonaws.com. 41273 IN NS 22B)
> 13:23:21.048 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsQueryContext - [id: 0x0ea5f4b3] WRITE: UDP, [2623: /192.168.1.1:53], DefaultDnsQuestion(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. IN A)
> 13:23:21.084 [nioEventLoopGroup-2-1] DEBUG io.netty.resolver.dns.DnsNameResolver - [id: 0x0ea5f4b3] RECEIVED: UDP [2623: /192.168.1.1:53], DatagramDnsResponse(from: /192.168.1.1:53, to: /0.0.0.0:49776, 2623, QUERY(0), NoError(0), RD RA)
>   DefaultDnsQuestion(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. IN A)
>   DefaultDnsRawRecord(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. 60 IN A 4B)
>   DefaultDnsRawRecord(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. 60 IN A 4B)
>   DefaultDnsRawRecord(ok11-crtr-tls12-nlb-7429507134c4aa04.elb.us-east-2.amazonaws.com. 60 IN A 4B)
>   DefaultDnsRawRecord(elb.us-east-2.amazonaws.com. 41273 IN NS 23B)
>   DefaultDnsRawRecord(elb.us-east-2.amazonaws.com. 41273 IN NS 25B)
>   DefaultDnsRawRecord(elb.us-east-2.amazonaws.com. 41273 IN NS 21B)
>   DefaultDnsRawRecord(elb.us-east-2.amazonaws.com. 41273 IN NS 22B)
> 2[dev-542348.okta.com/3.15.36.192, dev-542348.okta.com/3.15.36.194, dev-542348.okta.com/3.15.36.193]
> 13:23:23.184 [nioEventLoopGroup-2-1] DEBUG io.netty.buffer.PoolThreadCache - Freed 8 thread-local buffer(s) from thread: nioEventLoopGroup-2-1

I reproduced the same using below piece of code. Getting the same logs from WebFlux debug and the below code.

NioEventLoopGroup group = new NioEventLoopGroup(1);
        DnsNameResolver resolver = new DnsNameResolverBuilder(group.next())
                .channelFactory(NioDatagramChannel::new)
                .optResourceEnabled(false)
                .build();
        try {
            System.out.println("2" + resolver.resolveAll("dev-542348.okta.com").get());
        }
        finally {
            resolver.close();
            group.shutdownGracefully();
        }

Java Version : openjdk 14.0.2 2020-07-14 OpenJDK Runtime Environment AdoptOpenJDK (build 14.0.2+12)
and openjdk 11.0.14.1 2022-02-08 OpenJDK Runtime Environment Temurin-11.0.14.1+1 (build 11.0.14.1+1)

Facing same issue on both the version.

Netty Version 4.1.73.Final

violetagg commented 2 years ago

@Kryptonian-C Thanks for getting in touch, but the reproducible code above involves only Netty code. Could you please report this issue to Netty project (https://github.com/netty/netty)?

I've edited your comment to improve the formatting. You might want to check out this Mastering Markdown guide for future reference.

Kryptonian-C commented 2 years ago

Thanks @violetagg.

But I am getting the same behaviour with reactor-netty using webflux.

Below is the dependency of webflux and Spring parent

<parent>
    <groupId>org.springframework.boot</groupId>
    <artifactId>spring-boot-starter-parent</artifactId>
    <version>2.6.3</version>
    <relativePath/> <!-- lookup parent from repository -->
</parent>

<dependency>
    <groupId>org.springframework.boot</groupId>
    <artifactId>spring-boot-starter-webflux</artifactId>
</dependency>

The okta config I am having.

okta:
  oauth2:
    issuer: https://dev-52925747.okta.com/oauth2/default

Now when I send a request from postman I see the same issue on windows.

> 2022-03-09 13:36:36.521 DEBUG 8372 --- [ctor-http-nio-2] io.netty.handler.ssl.JdkSslContext       : Default protocols (JDK): [TLSv1.3, TLSv1.2] 
> 2022-03-09 13:36:36.521 DEBUG 8372 --- [ctor-http-nio-2] io.netty.handler.ssl.JdkSslContext       : Default cipher suites (JDK): [TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_AES_128_GCM_SHA256, TLS_AES_256_GCM_SHA384]
> 2022-03-09 13:36:36.578 DEBUG 8372 --- [ctor-http-nio-2] i.n.r.DefaultHostsFileEntriesResolver    : -Dio.netty.hostsFileRefreshInterval: 0
> 2022-03-09 13:36:36.766 DEBUG 8372 --- [ctor-http-nio-2] .d.DefaultDnsServerAddressStreamProvider : Default DNS servers: [/10.150.1.252:53, /10.202.1.252:53, /10.180.1.20:53, /10.180.1.201:53, /10.180.1.202:53, /180.235.155.185:53, /192.168.1.1:53] (sun.net.dns.ResolverConfiguration)
> 2022-03-09 13:36:36.779 DEBUG 8372 --- [ctor-http-nio-2] r.n.resources.PooledConnectionProvider   : Creating a new [http] client pool [PoolFactory{evictionInterval=PT0S, leasingStrategy=fifo, maxConnections=500, maxIdleTime=-1, maxLifeTime=-1, metricsEnabled=false, pendingAcquireMaxCount=1000, pendingAcquireTimeout=45000}] for [dev-52925747.okta.com:443]
> 2022-03-09 13:36:36.811 DEBUG 8372 --- [ctor-http-nio-2] r.n.resources.PooledConnectionProvider   : [c9c9f9dc] Created a new pooled channel, now: 0 active connections, 0 inactive connections and 0 pending acquire requests.
> 2022-03-09 13:36:36.827 DEBUG 8372 --- [ctor-http-nio-2] reactor.netty.tcp.SslProvider            : [c9c9f9dc] SSL enabled using engine sun.security.ssl.SSLEngineImpl@12f678fd and SNI dev-52925747.okta.com:443
> 2022-03-09 13:36:36.836 DEBUG 8372 --- [ctor-http-nio-2] reactor.netty.transport.TransportConfig  : [c9c9f9dc] Initialized pipeline DefaultChannelPipeline{(reactor.left.sslHandler = io.netty.handler.ssl.SslHandler), (reactor.left.sslReader = reactor.netty.tcp.SslProvider$SslReadHandler), (reactor.left.httpCodec = io.netty.handler.codec.http.HttpClientCodec), (reactor.left.httpDecompressor = io.netty.handler.codec.http.HttpContentDecompressor), (reactor.right.reactiveBridge = reactor.netty.channel.ChannelOperationsHandler)}
> 2022-03-09 13:36:36.885 DEBUG 8372 --- [ctor-http-nio-2] i.n.util.ResourceLeakDetectorFactory     : Loaded default ResourceLeakDetector: io.netty.util.ResourceLeakDetector@78ab4240
> 2022-03-09 13:36:36.888 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsQueryContext    : [id: 0x7834599a] WRITE: UDP, [39133: /10.150.1.252:53], DefaultDnsQuestion(dev-52925747.okta.com. IN A)
> 2022-03-09 13:36:41.915 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsQueryContext    : [id: 0x7834599a] WRITE: UDP, [13600: /10.202.1.252:53], DefaultDnsQuestion(dev-52925747.okta.com. IN A)
> 2022-03-09 13:36:46.927 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsQueryContext    : [id: 0x7834599a] WRITE: UDP, [34408: /10.180.1.20:53], DefaultDnsQuestion(dev-52925747.okta.com. IN A)
> 2022-03-09 13:36:51.937 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsQueryContext    : [id: 0x7834599a] WRITE: UDP, [18518: /10.180.1.201:53], DefaultDnsQuestion(dev-52925747.okta.com. IN A)
> 2022-03-09 13:36:56.939 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsQueryContext    : [id: 0x7834599a] WRITE: UDP, [3634: /10.180.1.202:53], DefaultDnsQuestion(dev-52925747.okta.com. IN A)
> 2022-03-09 13:37:01.945 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsQueryContext    : [id: 0x7834599a] WRITE: UDP, [13153: /180.235.155.185:53], DefaultDnsQuestion(dev-52925747.okta.com. IN A)
> 2022-03-09 13:37:02.070 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsNameResolver    : [id: 0x7834599a] RECEIVED: UDP [13153: /180.235.155.185:53], DatagramDnsResponse(from: /180.235.155.185:53, to: /0.0.0.0:57201, 13153, QUERY(0), Refused(5), RD)
>   DefaultDnsQuestion(dev-52925747.okta.com. IN A)
>   DefaultDnsRawRecord(OPT flags:0 udp:4096 0B)
> 2022-03-09 13:37:02.073 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsQueryContext    : [id: 0x7834599a] WRITE: UDP, [2272: /192.168.1.1:53], DefaultDnsQuestion(dev-52925747.okta.com. IN A)
> 2022-03-09 13:37:02.143 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsNameResolver    : [id: 0x7834599a] RECEIVED: UDP [2272: /192.168.1.1:53], DatagramDnsResponse(from: /192.168.1.1:53, to: /0.0.0.0:57201, 2272, QUERY(0), NoError(0), RD RA)
>   DefaultDnsQuestion(dev-52925747.okta.com. IN A)
>   DefaultDnsRawRecord(dev-52925747.okta.com. 264 IN CNAME 25B)
>   DefaultDnsRawRecord(ok12-crtrs.tng.okta.com. 405 IN CNAME 66B)
>   DefaultDnsRawRecord(ok12-crtr-tls12-nlb-accb74947e05b94a.elb.us-west-2.amazonaws.com. 60 IN A 4B)
>   DefaultDnsRawRecord(ok12-crtr-tls12-nlb-accb74947e05b94a.elb.us-west-2.amazonaws.com. 60 IN A 4B)
>   DefaultDnsRawRecord(ok12-crtr-tls12-nlb-accb74947e05b94a.elb.us-west-2.amazonaws.com. 60 IN A 4B)
>   DefaultDnsRawRecord(elb.us-west-2.amazonaws.com. 40333 IN NS 23B)
>   DefaultDnsRawRecord(elb.us-west-2.amazonaws.com. 40333 IN NS 25B)
>   DefaultDnsRawRecord(elb.us-west-2.amazonaws.com. 40333 IN NS 22B)
>   DefaultDnsRawRecord(elb.us-west-2.amazonaws.com. 40333 IN NS 22B)
>   DefaultDnsRawRecord(OPT flags:0 udp:4096 0B)
> 2022-03-09 13:37:02.145 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsQueryContext    : [id: 0x7834599a] WRITE: UDP, [20480: /10.150.1.252:53], DefaultDnsQuestion(ok12-crtr-tls12-nlb-accb74947e05b94a.elb.us-west-2.amazonaws.com. IN A)
> 2022-03-09 13:37:07.159 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsQueryContext    : [id: 0x7834599a] WRITE: UDP, [57925: /10.202.1.252:53], DefaultDnsQuestion(ok12-crtr-tls12-nlb-accb74947e05b94a.elb.us-west-2.amazonaws.com. IN A)
> 2022-03-09 13:37:12.166 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsQueryContext    : [id: 0x7834599a] WRITE: UDP, [50809: /10.180.1.20:53], DefaultDnsQuestion(ok12-crtr-tls12-nlb-accb74947e05b94a.elb.us-west-2.amazonaws.com. IN A)
> 2022-03-09 13:37:17.175 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsQueryContext    : [id: 0x7834599a] WRITE: UDP, [2073: /10.180.1.201:53], DefaultDnsQuestion(ok12-crtr-tls12-nlb-accb74947e05b94a.elb.us-west-2.amazonaws.com. IN A)
> 2022-03-09 13:37:22.192 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsQueryContext    : [id: 0x7834599a] WRITE: UDP, [57346: /10.180.1.202:53], DefaultDnsQuestion(ok12-crtr-tls12-nlb-accb74947e05b94a.elb.us-west-2.amazonaws.com. IN A)
> 2022-03-09 13:37:27.206 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsQueryContext    : [id: 0x7834599a] WRITE: UDP, [62372: /180.235.155.185:53], DefaultDnsQuestion(ok12-crtr-tls12-nlb-accb74947e05b94a.elb.us-west-2.amazonaws.com. IN A)
> 2022-03-09 13:37:27.321 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsNameResolver    : [id: 0x7834599a] RECEIVED: UDP [62372: /180.235.155.185:53], DatagramDnsResponse(from: /180.235.155.185:53, to: /0.0.0.0:57201, 62372, QUERY(0), Refused(5), RD)
>   DefaultDnsQuestion(ok12-crtr-tls12-nlb-accb74947e05b94a.elb.us-west-2.amazonaws.com. IN A)
>   DefaultDnsRawRecord(OPT flags:0 udp:4096 0B)
> 2022-03-09 13:37:27.321 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsQueryContext    : [id: 0x7834599a] WRITE: UDP, [52514: /192.168.1.1:53], DefaultDnsQuestion(ok12-crtr-tls12-nlb-accb74947e05b94a.elb.us-west-2.amazonaws.com. IN A)
> 2022-03-09 13:37:27.359 DEBUG 8372 --- [ctor-http-nio-2] io.netty.resolver.dns.DnsNameResolver    : [id: 0x7834599a] RECEIVED: UDP [52514: /192.168.1.1:53], DatagramDnsResponse(from: /192.168.1.1:53, to: /0.0.0.0:57201, 52514, QUERY(0), NoError(0), RD RA)
>   DefaultDnsQuestion(ok12-crtr-tls12-nlb-accb74947e05b94a.elb.us-west-2.amazonaws.com. IN A)
>   DefaultDnsRawRecord(ok12-crtr-tls12-nlb-accb74947e05b94a.elb.us-west-2.amazonaws.com. 34 IN A 4B)
>   DefaultDnsRawRecord(ok12-crtr-tls12-nlb-accb74947e05b94a.elb.us-west-2.amazonaws.com. 34 IN A 4B)
>   DefaultDnsRawRecord(ok12-crtr-tls12-nlb-accb74947e05b94a.elb.us-west-2.amazonaws.com. 34 IN A 4B)
>   DefaultDnsRawRecord(OPT flags:0 udp:4096 0B)
> 2022-03-09 13:37:27.363 DEBUG 8372 --- [ctor-http-nio-2] r.netty.transport.TransportConnector     : [c9c9f9dc] Connecting to [dev-52925747.okta.com/44.234.52.11:443].
> 2022-03-09 13:37:27.638 DEBUG 8372 --- [ctor-http-nio-2] r.n.r.DefaultPooledConnectionProvider    : [c9c9f9dc, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] Registering pool release on close event for channel
> 2022-03-09 13:37:27.643 DEBUG 8372 --- [ctor-http-nio-2] r.n.resources.PooledConnectionProvider   : [c9c9f9dc, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] Channel connected, now: 1 active connections, 0 inactive connections and 0 pending acquire requests.
> 2022-03-09 13:37:27.994 DEBUG 8372 --- [ctor-http-nio-2] jdk.event.security                       : ValidationChain: 1341898239, 128597027, -1751274746
> 2022-03-09 13:37:28.280 DEBUG 8372 --- [ctor-http-nio-2] jdk.event.security                       :  TLSHandshake: dev-52925747.okta.com:443, TLSv1.2, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, -1751274746
> 2022-03-09 13:37:28.280 DEBUG 8372 --- [ctor-http-nio-2] io.netty.handler.ssl.SslHandler          : [id: 0xc9c9f9dc, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] HANDSHAKEN: protocol:TLSv1.2 cipher suite:TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256
> 2022-03-09 13:37:28.283 DEBUG 8372 --- [ctor-http-nio-2] r.n.r.DefaultPooledConnectionProvider    : [c9c9f9dc, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] onStateChange(PooledConnection{channel=[id: 0xc9c9f9dc, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443]}, [connected])
> 2022-03-09 13:37:28.311 DEBUG 8372 --- [ctor-http-nio-2] r.n.r.DefaultPooledConnectionProvider    : [c9c9f9dc-1, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] onStateChange(GET{uri=/, connection=PooledConnection{channel=[id: 0xc9c9f9dc, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443]}}, [configured])
> 2022-03-09 13:37:28.315 DEBUG 8372 --- [ctor-http-nio-2] r.netty.http.client.HttpClientConnect    : [c9c9f9dc-1, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] Handler is being applied: {uri=https://dev-52925747.okta.com/oauth2/default/v1/keys, method=GET}
> 2022-03-09 13:37:28.316 DEBUG 8372 --- [ctor-http-nio-2] r.n.r.DefaultPooledConnectionProvider    : [c9c9f9dc-1, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] onStateChange(GET{uri=/oauth2/default/v1/keys, connection=PooledConnection{channel=[id: 0xc9c9f9dc, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443]}}, [request_prepared])
> 2022-03-09 13:37:28.338 DEBUG 8372 --- [ctor-http-nio-2] r.n.r.DefaultPooledConnectionProvider    : [c9c9f9dc-1, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] onStateChange(GET{uri=/oauth2/default/v1/keys, connection=PooledConnection{channel=[id: 0xc9c9f9dc, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443]}}, [request_sent])
> 2022-03-09 13:37:28.908 DEBUG 8372 --- [ctor-http-nio-2] i.n.handler.codec.compression.Brotli     : brotli4j not in the classpath; Brotli support will be unavailable.
> 2022-03-09 13:37:28.914 DEBUG 8372 --- [ctor-http-nio-2] r.n.http.client.HttpClientOperations     : [c9c9f9dc-1, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] Received response (auto-read:false) : [Date=Wed, 09 Mar 2022 08:07:29 GMT, Content-Type=application/json, Transfer-Encoding=chunked, Connection=keep-alive, Server=nginx, Public-Key-Pins-Report-Only=pin-sha256="r5EfzZxQVvQpKo3AgYRaT7X2bDO/kj3ACwmxfdT2zt8="; pin-sha256="MaqlcUgk2mvY/RFSGeSwBRkI+rZ6/dxe/DuQfBT/vnQ="; pin-sha256="72G5IEvDEWn+EThf3qjR7/bQSWaS2ZSLqolhnO6iyJI="; pin-sha256="rrV6CLCCvqnk89gWibYT0JO6fNQ8cCit7GGoiVTjCOg="; max-age=60; report-uri="https://okta.report-uri.com/r/default/hpkp/reportOnly", x-xss-protection=0, p3p=CP="HONK", content-security-policy-report-only=default-src 'self' dev-52925747.okta.com *.oktacdn.com; connect-src 'self' dev-52925747.okta.com dev-52925747-admin.okta.com *.oktacdn.com *.mixpanel.com *.mapbox.com app.pendo.io data.pendo.io pendo-static-5634101834153984.storage.googleapis.com https://oinmanager.okta.com data:; script-src 'unsafe-inline' 'unsafe-eval' 'self' dev-52925747.okta.com *.oktacdn.com; style-src 'unsafe-inline' 'self' dev-52925747.okta.com *.oktacdn.com app.pendo.io cdn.pendo.io pendo-static-5634101834153984.storage.googleapis.com; frame-src 'self' dev-52925747.okta.com dev-52925747-admin.okta.com login.okta.com; img-src 'self' dev-52925747.okta.com *.oktacdn.com *.tiles.mapbox.com *.mapbox.com app.pendo.io data.pendo.io cdn.pendo.io pendo-static-5634101834153984.storage.googleapis.com data: blob:; font-src 'self' dev-52925747.okta.com data: *.oktacdn.com fonts.gstatic.com; frame-ancestors 'self', content-security-policy=default-src 'self' dev-52925747.okta.com *.oktacdn.com; connect-src 'self' dev-52925747.okta.com dev-52925747-admin.okta.com *.oktacdn.com *.mixpanel.com *.mapbox.com app.pendo.io data.pendo.io pendo-static-5634101834153984.storage.googleapis.com https://oinmanager.okta.com data:; script-src 'unsafe-inline' 'unsafe-eval' 'self' dev-52925747.okta.com *.oktacdn.com; style-src 'unsafe-inline' 'self' dev-52925747.okta.com *.oktacdn.com app.pendo.io cdn.pendo.io pendo-static-5634101834153984.storage.googleapis.com; frame-src 'self' dev-52925747.okta.com dev-52925747-admin.okta.com login.okta.com; img-src 'self' dev-52925747.okta.com *.oktacdn.com *.tiles.mapbox.com *.mapbox.com app.pendo.io data.pendo.io cdn.pendo.io pendo-static-5634101834153984.storage.googleapis.com data: blob:; font-src 'self' dev-52925747.okta.com data: *.oktacdn.com fonts.gstatic.com, expect-ct=report-uri="https://oktaexpectct.report-uri.com/r/t/ct/reportOnly", max-age=0, cache-control=max-age=2368353, must-revalidate, expires=Tue, 05 Apr 2022 18:00:02 GMT, vary=Origin, x-content-type-options=nosniff, Strict-Transport-Security=max-age=315360000; includeSubDomains, X-Okta-Request-Id=YihgQX7OY25NHeZ8CPeg6gAACr0]
> 2022-03-09 13:37:28.914 DEBUG 8372 --- [ctor-http-nio-2] r.n.r.DefaultPooledConnectionProvider    : [c9c9f9dc-1, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] onStateChange(GET{uri=/oauth2/default/v1/keys, connection=PooledConnection{channel=[id: 0xc9c9f9dc, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443]}}, [response_received])
> 2022-03-09 13:37:28.930 DEBUG 8372 --- [ctor-http-nio-2] o.s.w.r.f.client.ExchangeFunctions       : [15ad9754] [c9c9f9dc-1, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] Response 200 OK
> 2022-03-09 13:37:28.970 DEBUG 8372 --- [ctor-http-nio-2] reactor.netty.channel.FluxReceive        : [c9c9f9dc-1, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] FluxReceive{pending=0, cancelled=false, inboundDone=false, inboundError=null}: subscribing inbound receiver
> 2022-03-09 13:37:28.975 DEBUG 8372 --- [ctor-http-nio-2] r.n.http.client.HttpClientOperations     : [c9c9f9dc-1, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] Received last HTTP packet
> 2022-03-09 13:37:28.976 DEBUG 8372 --- [ctor-http-nio-2] o.s.core.codec.StringDecoder             : [15ad9754] [c9c9f9dc-1, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] Decoded "{"keys":[{"kty":"RSA","alg":"RS256","kid":"i6na8yJv3z647rdQ-KajAW7t6MCpJto5UlsZQAT9RMo","use":"sig", (truncated)..."
> 2022-03-09 13:37:28.988 DEBUG 8372 --- [ctor-http-nio-2] o.s.w.r.f.client.ExchangeFunctions       : [362cb965] HTTP GET https://dev-52925747.okta.com/oauth2/default/v1/keys
> 2022-03-09 13:37:28.989 DEBUG 8372 --- [ctor-http-nio-2] r.n.resources.PooledConnectionProvider   : [23147a8b] Created a new pooled channel, now: 1 active connections, 0 inactive connections and 0 pending acquire requests.
> 2022-03-09 13:37:28.990 DEBUG 8372 --- [ctor-http-nio-2] reactor.netty.tcp.SslProvider            : [23147a8b] SSL enabled using engine sun.security.ssl.SSLEngineImpl@7541e0f and SNI dev-52925747.okta.com:443
> 2022-03-09 13:37:28.990 DEBUG 8372 --- [ctor-http-nio-2] reactor.netty.transport.TransportConfig  : [23147a8b] Initialized pipeline DefaultChannelPipeline{(reactor.left.sslHandler = io.netty.handler.ssl.SslHandler), (reactor.left.sslReader = reactor.netty.tcp.SslProvider$SslReadHandler), (reactor.left.httpCodec = io.netty.handler.codec.http.HttpClientCodec), (reactor.left.httpDecompressor = io.netty.handler.codec.http.HttpContentDecompressor), (reactor.right.reactiveBridge = reactor.netty.channel.ChannelOperationsHandler)}
> 2022-03-09 13:37:28.990 DEBUG 8372 --- [ctor-http-nio-2] r.n.r.DefaultPooledConnectionProvider    : [c9c9f9dc, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] onStateChange(GET{uri=/oauth2/default/v1/keys, connection=PooledConnection{channel=[id: 0xc9c9f9dc, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443]}}, [response_completed])
> 2022-03-09 13:37:28.991 DEBUG 8372 --- [ctor-http-nio-2] r.n.r.DefaultPooledConnectionProvider    : [c9c9f9dc, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] onStateChange(GET{uri=/oauth2/default/v1/keys, connection=PooledConnection{channel=[id: 0xc9c9f9dc, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443]}}, [disconnecting])
> 2022-03-09 13:37:28.991 DEBUG 8372 --- [ctor-http-nio-2] r.n.r.DefaultPooledConnectionProvider    : [c9c9f9dc, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] Releasing channel
> 2022-03-09 13:37:28.993 DEBUG 8372 --- [ctor-http-nio-2] r.n.resources.PooledConnectionProvider   : [c9c9f9dc, L:/192.168.1.32:64243 - R:dev-52925747.okta.com/44.234.52.11:443] Channel cleaned, now: 0 active connections, 1 inactive connections and 0 pending acquire requests.
> 2022-03-09 13:37:28.993 DEBUG 8372 --- [ctor-http-nio-2] r.netty.transport.TransportConnector     : [23147a8b] Connecting to [dev-52925747.okta.com/44.234.52.11:443].
> 2022-03-09 13:37:29.261 DEBUG 8372 --- [ctor-http-nio-2] r.n.r.DefaultPooledConnectionProvider    : [23147a8b, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443] Registering pool release on close event for channel
> 2022-03-09 13:37:29.261 DEBUG 8372 --- [ctor-http-nio-2] r.n.resources.PooledConnectionProvider   : [23147a8b, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443] Channel connected, now: 1 active connections, 1 inactive connections and 0 pending acquire requests.
> 2022-03-09 13:37:29.533 DEBUG 8372 --- [ctor-http-nio-2] jdk.event.security                       : ValidationChain: 1341898239, 128597027, -1751274746
> 2022-03-09 13:37:29.823 DEBUG 8372 --- [ctor-http-nio-2] jdk.event.security                       :  TLSHandshake: dev-52925747.okta.com:443, TLSv1.2, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, -1751274746
> 2022-03-09 13:37:29.824 DEBUG 8372 --- [ctor-http-nio-2] io.netty.handler.ssl.SslHandler          : [id: 0x23147a8b, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443] HANDSHAKEN: protocol:TLSv1.2 cipher suite:TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256
> 2022-03-09 13:37:29.824 DEBUG 8372 --- [ctor-http-nio-2] r.n.r.DefaultPooledConnectionProvider    : [23147a8b, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443] onStateChange(PooledConnection{channel=[id: 0x23147a8b, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443]}, [connected])
> 2022-03-09 13:37:29.824 DEBUG 8372 --- [ctor-http-nio-2] r.n.r.DefaultPooledConnectionProvider    : [23147a8b-1, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443] onStateChange(GET{uri=/, connection=PooledConnection{channel=[id: 0x23147a8b, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443]}}, [configured])
> 2022-03-09 13:37:29.825 DEBUG 8372 --- [ctor-http-nio-2] r.netty.http.client.HttpClientConnect    : [23147a8b-1, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443] Handler is being applied: {uri=https://dev-52925747.okta.com/oauth2/default/v1/keys, method=GET}
> 2022-03-09 13:37:29.825 DEBUG 8372 --- [ctor-http-nio-2] r.n.r.DefaultPooledConnectionProvider    : [23147a8b-1, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443] onStateChange(GET{uri=/oauth2/default/v1/keys, connection=PooledConnection{channel=[id: 0x23147a8b, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443]}}, [request_prepared])
> 2022-03-09 13:37:29.829 DEBUG 8372 --- [ctor-http-nio-2] r.n.r.DefaultPooledConnectionProvider    : [23147a8b-1, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443] onStateChange(GET{uri=/oauth2/default/v1/keys, connection=PooledConnection{channel=[id: 0x23147a8b, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443]}}, [request_sent])
> 2022-03-09 13:37:30.349 DEBUG 8372 --- [ctor-http-nio-2] r.n.http.client.HttpClientOperations     : [23147a8b-1, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443] Received response (auto-read:false) : [Date=Wed, 09 Mar 2022 08:07:31 GMT, Content-Type=application/json, Transfer-Encoding=chunked, Connection=keep-alive, Server=nginx, Public-Key-Pins-Report-Only=pin-sha256="r5EfzZxQVvQpKo3AgYRaT7X2bDO/kj3ACwmxfdT2zt8="; pin-sha256="MaqlcUgk2mvY/RFSGeSwBRkI+rZ6/dxe/DuQfBT/vnQ="; pin-sha256="72G5IEvDEWn+EThf3qjR7/bQSWaS2ZSLqolhnO6iyJI="; pin-sha256="rrV6CLCCvqnk89gWibYT0JO6fNQ8cCit7GGoiVTjCOg="; max-age=60; report-uri="https://okta.report-uri.com/r/default/hpkp/reportOnly", x-xss-protection=0, p3p=CP="HONK", content-security-policy-report-only=default-src 'self' dev-52925747.okta.com *.oktacdn.com; connect-src 'self' dev-52925747.okta.com dev-52925747-admin.okta.com *.oktacdn.com *.mixpanel.com *.mapbox.com app.pendo.io data.pendo.io pendo-static-5634101834153984.storage.googleapis.com https://oinmanager.okta.com data:; script-src 'unsafe-inline' 'unsafe-eval' 'self' dev-52925747.okta.com *.oktacdn.com; style-src 'unsafe-inline' 'self' dev-52925747.okta.com *.oktacdn.com app.pendo.io cdn.pendo.io pendo-static-5634101834153984.storage.googleapis.com; frame-src 'self' dev-52925747.okta.com dev-52925747-admin.okta.com login.okta.com; img-src 'self' dev-52925747.okta.com *.oktacdn.com *.tiles.mapbox.com *.mapbox.com app.pendo.io data.pendo.io cdn.pendo.io pendo-static-5634101834153984.storage.googleapis.com data: blob:; font-src 'self' dev-52925747.okta.com data: *.oktacdn.com fonts.gstatic.com; frame-ancestors 'self'; report-uri https://okta.report-uri.com/r/d/csp/reportOnly; report-to csp, content-security-policy=default-src 'self' dev-52925747.okta.com *.oktacdn.com; connect-src 'self' dev-52925747.okta.com dev-52925747-admin.okta.com *.oktacdn.com *.mixpanel.com *.mapbox.com app.pendo.io data.pendo.io pendo-static-5634101834153984.storage.googleapis.com https://oinmanager.okta.com data:; script-src 'unsafe-inline' 'unsafe-eval' 'self' dev-52925747.okta.com *.oktacdn.com; style-src 'unsafe-inline' 'self' dev-52925747.okta.com *.oktacdn.com app.pendo.io cdn.pendo.io pendo-static-5634101834153984.storage.googleapis.com; frame-src 'self' dev-52925747.okta.com dev-52925747-admin.okta.com login.okta.com; img-src 'self' dev-52925747.okta.com *.oktacdn.com *.tiles.mapbox.com *.mapbox.com app.pendo.io data.pendo.io cdn.pendo.io pendo-static-5634101834153984.storage.googleapis.com data: blob:; font-src 'self' dev-52925747.okta.com data: *.oktacdn.com fonts.gstatic.com, report-to={"group":"csp","max_age":31536000,"endpoints":[{"url":"https://okta.report-uri.com/a/d/g"}],"include_subdomains":true}, expect-ct=report-uri="https://oktaexpectct.report-uri.com/r/t/ct/reportOnly", max-age=0, cache-control=max-age=2368352, must-revalidate, expires=Tue, 05 Apr 2022 18:00:03 GMT, vary=Origin, x-content-type-options=nosniff, Strict-Transport-Security=max-age=315360000; includeSubDomains, X-Okta-Request-Id=YihgQmGDO0pylZHRCas79AAADwE]
> 2022-03-09 13:37:30.349 DEBUG 8372 --- [ctor-http-nio-2] r.n.r.DefaultPooledConnectionProvider    : [23147a8b-1, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443] onStateChange(GET{uri=/oauth2/default/v1/keys, connection=PooledConnection{channel=[id: 0x23147a8b, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443]}}, [response_received])
> 2022-03-09 13:37:30.351 DEBUG 8372 --- [ctor-http-nio-2] o.s.w.r.f.client.ExchangeFunctions       : [362cb965] [23147a8b-1, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443] Response 200 OK
> 2022-03-09 13:37:30.355 DEBUG 8372 --- [ctor-http-nio-2] reactor.netty.channel.FluxReceive        : [23147a8b-1, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443] FluxReceive{pending=0, cancelled=false, inboundDone=false, inboundError=null}: subscribing inbound receiver
> 2022-03-09 13:37:30.356 DEBUG 8372 --- [ctor-http-nio-2] r.n.http.client.HttpClientOperations     : [23147a8b-1, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443] Received last HTTP packet
> 2022-03-09 13:37:30.357 DEBUG 8372 --- [ctor-http-nio-2] o.s.core.codec.StringDecoder             : [362cb965] [23147a8b-1, L:/192.168.1.32:64244 - R:dev-52925747.okta.com/44.234.52.11:443] Decoded "{"keys":[{"kty":"RSA","alg":"RS256","kid":"i6na8yJv3z647rdQ-KajAW7t6MCpJto5UlsZQAT9RMo","use":"sig", (truncated)..."
>
violetagg commented 2 years ago

@Kryptonian-C I understand your confusion. Reactor Netty is built on top of Netty so sometimes the issues are in Netty and not Reactor Netty.

Kryptonian-C commented 2 years ago

Understood. Thanks a lot for explaining. Have raised a issue in netty.

https://github.com/netty/netty/issues/12153