06/05/2024 19:06:38.663 [DEBUG] reactor.util.Loggers:main [Loggers.java:254:debug] {} > Using Slf4j logging framework
06/05/2024 19:06:38.709 [DEBUG] io.netty.util.internal.logging.InternalLoggerFactory:main [InternalLoggerFactory.java:63:useSlf4JLoggerFactory] {} > Using SLF4J as the default logging framework
06/05/2024 19:06:38.725 [DEBUG] io.netty.util.internal.PlatformDependent0:main [PlatformDependent0.java:496:explicitNoUnsafeCause0] {} > -Dio.netty.noUnsafe: false
06/05/2024 19:06:38.725 [DEBUG] io.netty.util.internal.PlatformDependent0:main [PlatformDependent0.java:1000:javaVersion0] {} > Java version: 8
06/05/2024 19:06:38.725 [DEBUG] io.netty.util.internal.PlatformDependent0:main [PlatformDependent0.java:137:<clinit>] {} > sun.misc.Unsafe.theUnsafe: available
06/05/2024 19:06:38.725 [DEBUG] io.netty.util.internal.PlatformDependent0:main [PlatformDependent0.java:161:<clinit>] {} > sun.misc.Unsafe.copyMemory: available
06/05/2024 19:06:38.725 [DEBUG] io.netty.util.internal.PlatformDependent0:main [PlatformDependent0.java:193:<clinit>] {} > sun.misc.Unsafe.storeFence: available
06/05/2024 19:06:38.725 [DEBUG] io.netty.util.internal.PlatformDependent0:main [PlatformDependent0.java:236:<clinit>] {} > java.nio.Buffer.address: available
06/05/2024 19:06:38.725 [DEBUG] io.netty.util.internal.PlatformDependent0:main [PlatformDependent0.java:307:<clinit>] {} > direct buffer constructor: available
06/05/2024 19:06:38.725 [DEBUG] io.netty.util.internal.PlatformDependent0:main [PlatformDependent0.java:385:<clinit>] {} > java.nio.Bits.unaligned: available, true
06/05/2024 19:06:38.725 [DEBUG] io.netty.util.internal.PlatformDependent0:main [PlatformDependent0.java:459:<clinit>] {} > jdk.internal.misc.Unsafe.allocateUninitializedArray(int): unavailable prior to Java9
06/05/2024 19:06:38.725 [DEBUG] io.netty.util.internal.PlatformDependent0:main [PlatformDependent0.java:482:<clinit>] {} > java.nio.DirectByteBuffer.<init>(long, int): available
06/05/2024 19:06:38.725 [DEBUG] io.netty.util.internal.PlatformDependent:main [PlatformDependent.java:1159:unsafeUnavailabilityCause0] {} > sun.misc.Unsafe: available
06/05/2024 19:06:38.725 [DEBUG] io.netty.util.internal.PlatformDependent:main [PlatformDependent.java:1289:tmpdir0] {} > -Dio.netty.tmpdir: C:\Users\mame0318\AppData\Local\Temp\2 (java.io.tmpdir)
06/05/2024 19:06:38.725 [DEBUG] io.netty.util.internal.PlatformDependent:main [PlatformDependent.java:1368:bitMode0] {} > -Dio.netty.bitMode: 64 (sun.arch.data.model)
06/05/2024 19:06:38.725 [DEBUG] io.netty.util.internal.PlatformDependent:main [PlatformDependent.java:1119:isWindows0] {} > Platform: Windows
06/05/2024 19:06:38.741 [DEBUG] io.netty.util.internal.PlatformDependent:main [PlatformDependent.java:178:<clinit>] {} > -Dio.netty.maxDirectMemory: 805306368 bytes
06/05/2024 19:06:38.741 [DEBUG] io.netty.util.internal.PlatformDependent:main [PlatformDependent.java:185:<clinit>] {} > -Dio.netty.uninitializedArrayAllocationThreshold: -1
06/05/2024 19:06:38.741 [DEBUG] io.netty.util.internal.CleanerJava6:main [CleanerJava6.java:92:<clinit>] {} > java.nio.ByteBuffer.cleaner(): available
06/05/2024 19:06:38.741 [DEBUG] io.netty.util.internal.PlatformDependent:main [PlatformDependent.java:205:<clinit>] {} > -Dio.netty.noPreferDirect: false
06/05/2024 19:06:38.975 [DEBUG] io.netty.util.internal.InternalThreadLocalMap:main [InternalThreadLocalMap.java:100:<clinit>] {} > -Dio.netty.threadLocalMap.stringBuilder.initialSize: 1024
06/05/2024 19:06:38.990 [DEBUG] io.netty.util.internal.InternalThreadLocalMap:main [InternalThreadLocalMap.java:101:<clinit>] {} > -Dio.netty.threadLocalMap.stringBuilder.maxSize: 4096
06/05/2024 19:06:39.069 [DEBUG] io.netty.util.ResourceLeakDetector:main [ResourceLeakDetector.java:129:<clinit>] {} > -Dio.netty.leakDetection.level: simple
06/05/2024 19:06:39.069 [DEBUG] io.netty.util.ResourceLeakDetector:main [ResourceLeakDetector.java:130:<clinit>] {} > -Dio.netty.leakDetection.targetRecords: 4
06/05/2024 19:06:40.100 [DEBUG] io.netty.util.NetUtil:pool-4-thread-1 [NetUtil.java:142:<clinit>] {} > -Djava.net.preferIPv4Stack: false
06/05/2024 19:06:40.116 [DEBUG] io.netty.util.NetUtil:pool-4-thread-1 [NetUtil.java:143:<clinit>] {} > -Djava.net.preferIPv6Addresses: false
06/05/2024 19:06:40.272 [DEBUG] io.netty.util.NetUtilInitializations:pool-4-thread-1 [NetUtilInitializations.java:139:determineLoopback] {} > Loopback interface: lo (Software Loopback Interface 1, 127.0.0.1)
06/05/2024 19:06:40.272 [DEBUG] io.netty.util.NetUtil:pool-4-thread-1 [NetUtil.java:200:run] {} > Failed to get SOMAXCONN from sysctl and file \proc\sys\net\core\somaxconn. Default: 200
06/05/2024 19:06:40.334 [DEBUG] org.springframework.web.reactive.function.client.ExchangeFunctions:pool-4-thread-1 [LogFormatUtils.java:119:traceDebug] {} > [1e149799] HTTP GET localhost:8000/api/endpoint
06/05/2024 19:06:40.334 [DEBUG] org.springframework.web.reactive.function.client.ExchangeFunctions:pool-4-thread-3 [LogFormatUtils.java:119:traceDebug] {} > [613d0fb6] HTTP GET localhost:8000/api/endpoint
06/05/2024 19:06:40.334 [DEBUG] org.springframework.web.reactive.function.client.ExchangeFunctions:pool-4-thread-2 [LogFormatUtils.java:119:traceDebug] {} > [3d5a02e0] HTTP GET localhost:8000/api/endpoint
06/05/2024 19:06:40.334 [DEBUG] org.springframework.web.reactive.function.client.ExchangeFunctions:pool-4-thread-6 [LogFormatUtils.java:119:traceDebug] {} > [64804b52] HTTP GET localhost:8000/api/endpoint
06/05/2024 19:06:40.334 [DEBUG] org.springframework.web.reactive.function.client.ExchangeFunctions:pool-4-thread-5 [LogFormatUtils.java:119:traceDebug] {} > [6520b303] HTTP GET localhost:8000/api/endpoint
06/05/2024 19:06:40.334 [DEBUG] org.springframework.web.reactive.function.client.ExchangeFunctions:pool-4-thread-4 [LogFormatUtils.java:119:traceDebug] {} > [86afd37] HTTP GET localhost:8000/api/endpoint
06/05/2024 19:06:40.365 [DEBUG] reactor.netty.tcp.TcpResources:pool-4-thread-4 [Loggers.java:259:debug] {} > [http] resources will use the default LoopResources: DefaultLoopResources {prefix=reactor-http, daemon=true, selectCount=8, workerCount=8}
06/05/2024 19:06:40.365 [DEBUG] reactor.netty.tcp.TcpResources:pool-4-thread-4 [Loggers.java:259:debug] {} > [http] resources will use the default ConnectionProvider: reactor.netty.resources.DefaultPooledConnectionProvider@42115b5a
06/05/2024 19:06:40.381 [DEBUG] reactor.netty.resources.DefaultLoopIOUring:pool-4-thread-4 [Loggers.java:254:debug] {} > Default io_uring support : false
06/05/2024 19:06:40.397 [DEBUG] reactor.netty.resources.DefaultLoopEpoll:pool-4-thread-4 [Loggers.java:254:debug] {} > Default Epoll support : false
06/05/2024 19:06:40.397 [DEBUG] reactor.netty.resources.DefaultLoopKQueue:pool-4-thread-4 [Loggers.java:254:debug] {} > Default KQueue support : false
06/05/2024 19:06:40.412 [DEBUG] io.netty.channel.MultithreadEventLoopGroup:pool-4-thread-2 [MultithreadEventLoopGroup.java:44:<clinit>] {} > -Dio.netty.eventLoopThreads: 16
06/05/2024 19:06:40.428 [DEBUG] io.netty.util.concurrent.GlobalEventExecutor:pool-4-thread-1 [GlobalEventExecutor.java:53:<clinit>] {} > -Dio.netty.globalEventExecutor.quietPeriodSeconds: 1
06/05/2024 19:06:40.444 [DEBUG] io.netty.channel.nio.NioEventLoop:pool-4-thread-1 [NioEventLoop.java:110:<clinit>] {} > -Dio.netty.noKeySetOptimization: false
06/05/2024 19:06:40.444 [DEBUG] io.netty.channel.nio.NioEventLoop:pool-4-thread-1 [NioEventLoop.java:111:<clinit>] {} > -Dio.netty.selectorAutoRebuildThreshold: 512
06/05/2024 19:06:40.459 [DEBUG] io.netty.util.internal.PlatformDependent:pool-4-thread-1 [PlatformDependent.java:1010:<clinit>] {} > org.jctools-core.MpscChunkedArrayQueue: available
06/05/2024 19:06:40.553 [DEBUG] io.netty.resolver.DefaultHostsFileEntriesResolver:pool-4-thread-5 [DefaultHostsFileEntriesResolver.java:53:<clinit>] {} > -Dio.netty.hostsFileRefreshInterval: 0
06/05/2024 19:06:40.569 [DEBUG] io.netty.resolver.dns.DefaultDnsServerAddressStreamProvider:pool-4-thread-5 [DefaultDnsServerAddressStreamProvider.java:82:<clinit>] {} > Default DNS servers: [/10.119.1.1:53, /10.112.1.1:53, /10.112.1.2:53] (sun.net.dns.ResolverConfiguration)
06/05/2024 19:06:40.584 [DEBUG] reactor.netty.resources.PooledConnectionProvider:pool-4-thread-4 [Loggers.java:259:debug] {} > Creating a new [webclient-pool] client pool [PoolFactory{evictionInterval=PT2S, leasingStrategy=fifo, maxConnections=1, maxIdleTime=300000, maxLifeTime=-1, metricsEnabled=false, pendingAcquireMaxCount=10, pendingAcquireTimeout=200}] for [localhost:8000]
06/05/2024 19:06:40.678 [DEBUG] io.netty.channel.DefaultChannelId:pool-4-thread-3 [DefaultChannelId.java:79:<clinit>] {} > -Dio.netty.processId: 105864 (auto-detected)
06/05/2024 19:06:40.694 [DEBUG] io.netty.channel.DefaultChannelId:pool-4-thread-3 [DefaultChannelId.java:101:<clinit>] {} > -Dio.netty.machineId: 00:15:5d:ff:fe:05:3b:bc (auto-detected)
06/05/2024 19:06:40.725 [DEBUG] io.netty.buffer.PooledByteBufAllocator:pool-4-thread-3 [PooledByteBufAllocator.java:157:<clinit>] {} > -Dio.netty.allocator.numHeapArenas: 16
06/05/2024 19:06:40.725 [DEBUG] io.netty.buffer.PooledByteBufAllocator:pool-4-thread-3 [PooledByteBufAllocator.java:158:<clinit>] {} > -Dio.netty.allocator.numDirectArenas: 16
06/05/2024 19:06:40.725 [DEBUG] io.netty.buffer.PooledByteBufAllocator:pool-4-thread-3 [PooledByteBufAllocator.java:160:<clinit>] {} > -Dio.netty.allocator.pageSize: 8192
06/05/2024 19:06:40.725 [DEBUG] io.netty.buffer.PooledByteBufAllocator:pool-4-thread-3 [PooledByteBufAllocator.java:165:<clinit>] {} > -Dio.netty.allocator.maxOrder: 9
06/05/2024 19:06:40.725 [DEBUG] io.netty.buffer.PooledByteBufAllocator:pool-4-thread-3 [PooledByteBufAllocator.java:169:<clinit>] {} > -Dio.netty.allocator.chunkSize: 4194304
06/05/2024 19:06:40.725 [DEBUG] io.netty.buffer.PooledByteBufAllocator:pool-4-thread-3 [PooledByteBufAllocator.java:170:<clinit>] {} > -Dio.netty.allocator.smallCacheSize: 256
06/05/2024 19:06:40.725 [DEBUG] io.netty.buffer.PooledByteBufAllocator:pool-4-thread-3 [PooledByteBufAllocator.java:171:<clinit>] {} > -Dio.netty.allocator.normalCacheSize: 64
06/05/2024 19:06:40.741 [DEBUG] io.netty.buffer.PooledByteBufAllocator:pool-4-thread-3 [PooledByteBufAllocator.java:172:<clinit>] {} > -Dio.netty.allocator.maxCachedBufferCapacity: 32768
06/05/2024 19:06:40.741 [DEBUG] io.netty.buffer.PooledByteBufAllocator:pool-4-thread-3 [PooledByteBufAllocator.java:173:<clinit>] {} > -Dio.netty.allocator.cacheTrimInterval: 8192
06/05/2024 19:06:40.741 [DEBUG] io.netty.buffer.PooledByteBufAllocator:pool-4-thread-3 [PooledByteBufAllocator.java:174:<clinit>] {} > -Dio.netty.allocator.cacheTrimIntervalMillis: 0
06/05/2024 19:06:40.741 [DEBUG] io.netty.buffer.PooledByteBufAllocator:pool-4-thread-3 [PooledByteBufAllocator.java:175:<clinit>] {} > -Dio.netty.allocator.useCacheForAllThreads: false
06/05/2024 19:06:40.741 [DEBUG] io.netty.buffer.PooledByteBufAllocator:pool-4-thread-3 [PooledByteBufAllocator.java:176:<clinit>] {} > -Dio.netty.allocator.maxCachedByteBuffersPerChunk: 1023
06/05/2024 19:06:40.756 [DEBUG] io.netty.buffer.ByteBufUtil:pool-4-thread-3 [ByteBufUtil.java:87:<clinit>] {} > -Dio.netty.allocator.type: pooled
06/05/2024 19:06:40.756 [DEBUG] io.netty.buffer.ByteBufUtil:pool-4-thread-3 [ByteBufUtil.java:96:<clinit>] {} > -Dio.netty.threadLocalDirectBufferSize: 0
06/05/2024 19:06:40.756 [DEBUG] io.netty.buffer.ByteBufUtil:pool-4-thread-3 [ByteBufUtil.java:99:<clinit>] {} > -Dio.netty.maxThreadLocalCharBufferSize: 16384
06/05/2024 19:06:40.772 [DEBUG] reactor.netty.resources.PooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f] Created a new pooled channel, now: 0 active connections, 0 inactive connections and 5 pending acquire requests.
06/05/2024 19:06:40.818 [DEBUG] io.netty.buffer.AbstractByteBuf:reactor-http-nio-39 [AbstractByteBuf.java:63:<clinit>] {} > -Dio.netty.buffer.checkAccessible: true
06/05/2024 19:06:40.818 [DEBUG] io.netty.buffer.AbstractByteBuf:reactor-http-nio-39 [AbstractByteBuf.java:64:<clinit>] {} > -Dio.netty.buffer.checkBounds: true
06/05/2024 19:06:40.818 [DEBUG] io.netty.util.ResourceLeakDetectorFactory:reactor-http-nio-39 [ResourceLeakDetectorFactory.java:196:newResourceLeakDetector] {} > Loaded default ResourceLeakDetector: io.netty.util.ResourceLeakDetector@fdf2ac3
06/05/2024 19:06:40.818 [DEBUG] reactor.netty.transport.TransportConfig:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f] Initialized pipeline DefaultChannelPipeline{(reactor.left.httpCodec = io.netty.handler.codec.http.HttpClientCodec), (reactor.right.reactiveBridge = reactor.netty.channel.ChannelOperationsHandler)}
06/05/2024 19:06:40.866 [DEBUG] reactor.netty.transport.TransportConnector:reactor-http-nio-39 [Loggers.java:254:debug] {} > [b7fc1d8f] Connecting to [localhost/127.0.0.1:8000].
06/05/2024 19:06:40.866 [ERROR] webflux.ReactorNettyPoolWebfluxTests2:pool-4-thread-4 [ReactorNettyPoolWebfluxTests2.java:141:executeRequestNoTimeout] {} > org.springframework.web.reactive.function.client.WebClientRequestException: Pool#acquire(Duration) has been pending for more than the configured timeout of 200ms; nested exception is reactor.netty.internal.shaded.reactor.pool.PoolAcquireTimeoutException: Pool#acquire(Duration) has been pending for more than the configured timeout of 200ms
06/05/2024 19:06:40.881 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:254:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Registering pool release on close event for channel
06/05/2024 19:06:40.881 [DEBUG] reactor.netty.resources.PooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Channel connected, now: 1 active connections, 0 inactive connections and 4 pending acquire requests.
06/05/2024 19:06:40.881 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}, [connected])
06/05/2024 19:06:40.912 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-1, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=null, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [configured])
06/05/2024 19:06:40.928 [DEBUG] reactor.netty.ReactorNetty:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-1, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Added decoder [ReadTimeoutHandler] at the end of the user pipeline, full pipeline: [reactor.left.httpCodec, ReadTimeoutHandler, reactor.right.reactiveBridge, DefaultChannelPipeline$TailContext#0]
06/05/2024 19:06:40.928 [DEBUG] reactor.netty.http.client.HttpClientConnect:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-1, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Handler is being applied: {uri=http://localhost:8000/api/endpoint, method=GET}
06/05/2024 19:06:40.943 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-1, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [request_prepared])
06/05/2024 19:06:40.959 [DEBUG] io.netty.util.Recycler:reactor-http-nio-39 [Recycler.java:90:<clinit>] {} > -Dio.netty.recycler.maxCapacityPerThread: 4096
06/05/2024 19:06:40.959 [DEBUG] io.netty.util.Recycler:reactor-http-nio-39 [Recycler.java:91:<clinit>] {} > -Dio.netty.recycler.ratio: 8
06/05/2024 19:06:40.959 [DEBUG] io.netty.util.Recycler:reactor-http-nio-39 [Recycler.java:92:<clinit>] {} > -Dio.netty.recycler.chunkSize: 32
06/05/2024 19:06:40.959 [DEBUG] io.netty.util.Recycler:reactor-http-nio-39 [Recycler.java:93:<clinit>] {} > -Dio.netty.recycler.blocking: false
06/05/2024 19:06:40.959 [DEBUG] io.netty.util.Recycler:reactor-http-nio-39 [Recycler.java:94:<clinit>] {} > -Dio.netty.recycler.batchFastThreadLocalOnly: true
06/05/2024 19:06:40.990 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-1, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [request_sent])
06/05/2024 19:06:40.990 [INFO ] webflux.ReactorNettyPoolWebfluxTests2:pool-2-thread-1 [ReactorNettyPoolWebfluxTests2.java:39:lambda$before$0] {} > IN: RECEIVED REQUEST
06/05/2024 19:06:44.022 [DEBUG] reactor.netty.http.client.HttpClientOperations:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-1, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Received response (auto-read:false) : RESPONSE(decodeResult: success, version: HTTP/1.1)
HTTP/1.1 200 OK
Connection: <filtered>
Keep-alive: <filtered>
Date: <filtered>
Content-length: <filtered>
06/05/2024 19:06:44.022 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-1, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [response_received])
06/05/2024 19:06:44.037 [DEBUG] org.springframework.web.reactive.function.client.ExchangeFunctions:reactor-http-nio-39 [LogFormatUtils.java:119:traceDebug] {} > [613d0fb6] [b7fc1d8f-1, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Response 200 OK
06/05/2024 19:06:44.069 [DEBUG] reactor.netty.channel.FluxReceive:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-1, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] [terminated=false, cancelled=false, pending=0, error=null]: subscribing inbound receiver
06/05/2024 19:06:44.084 [DEBUG] reactor.netty.http.client.HttpClientOperations:reactor-http-nio-39 [Loggers.java:254:debug] {} > [b7fc1d8f-1, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Received last HTTP packet
06/05/2024 19:06:44.084 [DEBUG] org.springframework.core.codec.StringDecoder:reactor-http-nio-39 [CompositeLog.java:127:debug] {} > [613d0fb6] [b7fc1d8f-1, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Decoded "{"success": true}"
{"success": true}
06/05/2024 19:06:44.084 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [response_completed])
06/05/2024 19:06:44.084 [DEBUG] reactor.netty.ReactorNetty:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Removed handler: ReadTimeoutHandler, pipeline: DefaultChannelPipeline{(reactor.left.httpCodec = io.netty.handler.codec.http.HttpClientCodec), (reactor.right.reactiveBridge = reactor.netty.channel.ChannelOperationsHandler)}
06/05/2024 19:06:44.084 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [disconnecting])
06/05/2024 19:06:44.084 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:254:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Releasing channel
06/05/2024 19:06:44.100 [DEBUG] reactor.netty.resources.PooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Channel cleaned, now: 0 active connections, 1 inactive connections and 4 pending acquire requests.
06/05/2024 19:06:44.100 [DEBUG] reactor.netty.resources.PooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Channel acquired, now: 1 active connections, 0 inactive connections and 3 pending acquire requests.
06/05/2024 19:06:44.100 [DEBUG] reactor.netty.ReactorNetty:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-2, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Added decoder [ReadTimeoutHandler] at the end of the user pipeline, full pipeline: [reactor.left.httpCodec, ReadTimeoutHandler, reactor.right.reactiveBridge, DefaultChannelPipeline$TailContext#0]
06/05/2024 19:06:44.100 [DEBUG] reactor.netty.http.client.HttpClientConnect:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-2, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Handler is being applied: {uri=http://localhost:8000/api/endpoint, method=GET}
06/05/2024 19:06:44.100 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-2, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [request_prepared])
06/05/2024 19:06:44.100 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-2, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [request_sent])
06/05/2024 19:06:44.100 [INFO ] webflux.ReactorNettyPoolWebfluxTests2:pool-2-thread-2 [ReactorNettyPoolWebfluxTests2.java:39:lambda$before$0] {} > IN: RECEIVED REQUEST
06/05/2024 19:06:47.115 [DEBUG] reactor.netty.http.client.HttpClientOperations:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-2, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Received response (auto-read:false) : RESPONSE(decodeResult: success, version: HTTP/1.1)
HTTP/1.1 200 OK
Connection: <filtered>
Keep-alive: <filtered>
Date: <filtered>
Content-length: <filtered>
06/05/2024 19:06:47.115 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-2, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [response_received])
06/05/2024 19:06:47.115 [DEBUG] org.springframework.web.reactive.function.client.ExchangeFunctions:reactor-http-nio-39 [LogFormatUtils.java:119:traceDebug] {} > [6520b303] [b7fc1d8f-2, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Response 200 OK
06/05/2024 19:06:47.115 [DEBUG] reactor.netty.channel.FluxReceive:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-2, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] [terminated=false, cancelled=false, pending=0, error=null]: subscribing inbound receiver
06/05/2024 19:06:47.115 [DEBUG] reactor.netty.http.client.HttpClientOperations:reactor-http-nio-39 [Loggers.java:254:debug] {} > [b7fc1d8f-2, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Received last HTTP packet
06/05/2024 19:06:47.115 [DEBUG] org.springframework.core.codec.StringDecoder:reactor-http-nio-39 [CompositeLog.java:127:debug] {} > [6520b303] [b7fc1d8f-2, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Decoded "{"success": true}"
{"success": true}
06/05/2024 19:06:47.115 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [response_completed])
06/05/2024 19:06:47.115 [DEBUG] reactor.netty.ReactorNetty:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Removed handler: ReadTimeoutHandler, pipeline: DefaultChannelPipeline{(reactor.left.httpCodec = io.netty.handler.codec.http.HttpClientCodec), (reactor.right.reactiveBridge = reactor.netty.channel.ChannelOperationsHandler)}
06/05/2024 19:06:47.115 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [disconnecting])
06/05/2024 19:06:47.115 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:254:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Releasing channel
06/05/2024 19:06:47.115 [DEBUG] reactor.netty.resources.PooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Channel cleaned, now: 0 active connections, 1 inactive connections and 3 pending acquire requests.
06/05/2024 19:06:47.115 [DEBUG] reactor.netty.resources.PooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Channel acquired, now: 1 active connections, 0 inactive connections and 2 pending acquire requests.
06/05/2024 19:06:47.115 [DEBUG] reactor.netty.ReactorNetty:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-3, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Added decoder [ReadTimeoutHandler] at the end of the user pipeline, full pipeline: [reactor.left.httpCodec, ReadTimeoutHandler, reactor.right.reactiveBridge, DefaultChannelPipeline$TailContext#0]
06/05/2024 19:06:47.115 [DEBUG] reactor.netty.http.client.HttpClientConnect:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-3, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Handler is being applied: {uri=http://localhost:8000/api/endpoint, method=GET}
06/05/2024 19:06:47.115 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-3, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [request_prepared])
06/05/2024 19:06:47.115 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-3, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [request_sent])
06/05/2024 19:06:47.115 [INFO ] webflux.ReactorNettyPoolWebfluxTests2:pool-2-thread-3 [ReactorNettyPoolWebfluxTests2.java:39:lambda$before$0] {} > IN: RECEIVED REQUEST
06/05/2024 19:06:50.115 [DEBUG] reactor.netty.http.client.HttpClientOperations:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-3, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Received response (auto-read:false) : RESPONSE(decodeResult: success, version: HTTP/1.1)
HTTP/1.1 200 OK
Connection: <filtered>
Keep-alive: <filtered>
Date: <filtered>
Content-length: <filtered>
06/05/2024 19:06:50.115 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-3, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [response_received])
06/05/2024 19:06:50.115 [DEBUG] org.springframework.web.reactive.function.client.ExchangeFunctions:reactor-http-nio-39 [LogFormatUtils.java:119:traceDebug] {} > [64804b52] [b7fc1d8f-3, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Response 200 OK
06/05/2024 19:06:50.115 [DEBUG] reactor.netty.channel.FluxReceive:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-3, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] [terminated=false, cancelled=false, pending=0, error=null]: subscribing inbound receiver
06/05/2024 19:06:50.115 [DEBUG] reactor.netty.http.client.HttpClientOperations:reactor-http-nio-39 [Loggers.java:254:debug] {} > [b7fc1d8f-3, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Received last HTTP packet
06/05/2024 19:06:50.115 [DEBUG] org.springframework.core.codec.StringDecoder:reactor-http-nio-39 [CompositeLog.java:127:debug] {} > [64804b52] [b7fc1d8f-3, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Decoded "{"success": true}"
{"success": true}
06/05/2024 19:06:50.115 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [response_completed])
06/05/2024 19:06:50.115 [DEBUG] reactor.netty.ReactorNetty:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Removed handler: ReadTimeoutHandler, pipeline: DefaultChannelPipeline{(reactor.left.httpCodec = io.netty.handler.codec.http.HttpClientCodec), (reactor.right.reactiveBridge = reactor.netty.channel.ChannelOperationsHandler)}
06/05/2024 19:06:50.115 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [disconnecting])
06/05/2024 19:06:50.115 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:254:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Releasing channel
06/05/2024 19:06:50.115 [DEBUG] reactor.netty.resources.PooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Channel cleaned, now: 0 active connections, 1 inactive connections and 2 pending acquire requests.
06/05/2024 19:06:50.115 [DEBUG] reactor.netty.resources.PooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Channel acquired, now: 1 active connections, 0 inactive connections and 1 pending acquire requests.
06/05/2024 19:06:50.115 [DEBUG] reactor.netty.ReactorNetty:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-4, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Added decoder [ReadTimeoutHandler] at the end of the user pipeline, full pipeline: [reactor.left.httpCodec, ReadTimeoutHandler, reactor.right.reactiveBridge, DefaultChannelPipeline$TailContext#0]
06/05/2024 19:06:50.115 [DEBUG] reactor.netty.http.client.HttpClientConnect:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-4, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Handler is being applied: {uri=http://localhost:8000/api/endpoint, method=GET}
06/05/2024 19:06:50.115 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-4, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [request_prepared])
06/05/2024 19:06:50.115 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-4, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [request_sent])
06/05/2024 19:06:50.115 [INFO ] webflux.ReactorNettyPoolWebfluxTests2:pool-2-thread-4 [ReactorNettyPoolWebfluxTests2.java:39:lambda$before$0] {} > IN: RECEIVED REQUEST
06/05/2024 19:06:53.131 [DEBUG] reactor.netty.http.client.HttpClientOperations:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-4, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Received response (auto-read:false) : RESPONSE(decodeResult: success, version: HTTP/1.1)
HTTP/1.1 200 OK
Connection: <filtered>
Keep-alive: <filtered>
Date: <filtered>
Content-length: <filtered>
06/05/2024 19:06:53.131 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-4, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [response_received])
06/05/2024 19:06:53.131 [DEBUG] org.springframework.web.reactive.function.client.ExchangeFunctions:reactor-http-nio-39 [LogFormatUtils.java:119:traceDebug] {} > [1e149799] [b7fc1d8f-4, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Response 200 OK
06/05/2024 19:06:53.131 [DEBUG] reactor.netty.channel.FluxReceive:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-4, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] [terminated=false, cancelled=false, pending=0, error=null]: subscribing inbound receiver
06/05/2024 19:06:53.131 [DEBUG] reactor.netty.http.client.HttpClientOperations:reactor-http-nio-39 [Loggers.java:254:debug] {} > [b7fc1d8f-4, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Received last HTTP packet
06/05/2024 19:06:53.131 [DEBUG] org.springframework.core.codec.StringDecoder:reactor-http-nio-39 [CompositeLog.java:127:debug] {} > [1e149799] [b7fc1d8f-4, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Decoded "{"success": true}"
{"success": true}
06/05/2024 19:06:53.131 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [response_completed])
06/05/2024 19:06:53.131 [DEBUG] reactor.netty.ReactorNetty:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Removed handler: ReadTimeoutHandler, pipeline: DefaultChannelPipeline{(reactor.left.httpCodec = io.netty.handler.codec.http.HttpClientCodec), (reactor.right.reactiveBridge = reactor.netty.channel.ChannelOperationsHandler)}
06/05/2024 19:06:53.131 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [disconnecting])
06/05/2024 19:06:53.131 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:254:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Releasing channel
06/05/2024 19:06:53.131 [DEBUG] reactor.netty.resources.PooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Channel cleaned, now: 0 active connections, 1 inactive connections and 1 pending acquire requests.
06/05/2024 19:06:53.131 [DEBUG] reactor.netty.resources.PooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Channel acquired, now: 1 active connections, 0 inactive connections and 0 pending acquire requests.
06/05/2024 19:06:53.131 [DEBUG] reactor.netty.ReactorNetty:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-5, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Added decoder [ReadTimeoutHandler] at the end of the user pipeline, full pipeline: [reactor.left.httpCodec, ReadTimeoutHandler, reactor.right.reactiveBridge, DefaultChannelPipeline$TailContext#0]
06/05/2024 19:06:53.131 [DEBUG] reactor.netty.http.client.HttpClientConnect:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-5, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Handler is being applied: {uri=http://localhost:8000/api/endpoint, method=GET}
06/05/2024 19:06:53.131 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-5, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [request_prepared])
06/05/2024 19:06:53.131 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-5, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [request_sent])
06/05/2024 19:06:53.131 [INFO ] webflux.ReactorNettyPoolWebfluxTests2:pool-2-thread-5 [ReactorNettyPoolWebfluxTests2.java:39:lambda$before$0] {} > IN: RECEIVED REQUEST
06/05/2024 19:06:56.147 [DEBUG] reactor.netty.http.client.HttpClientOperations:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-5, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Received response (auto-read:false) : RESPONSE(decodeResult: success, version: HTTP/1.1)
HTTP/1.1 200 OK
Connection: <filtered>
Keep-alive: <filtered>
Date: <filtered>
Content-length: <filtered>
06/05/2024 19:06:56.147 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-5, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [response_received])
06/05/2024 19:06:56.147 [DEBUG] org.springframework.web.reactive.function.client.ExchangeFunctions:reactor-http-nio-39 [LogFormatUtils.java:119:traceDebug] {} > [3d5a02e0] [b7fc1d8f-5, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Response 200 OK
06/05/2024 19:06:56.147 [DEBUG] reactor.netty.channel.FluxReceive:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f-5, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] [terminated=false, cancelled=false, pending=0, error=null]: subscribing inbound receiver
06/05/2024 19:06:56.147 [DEBUG] reactor.netty.http.client.HttpClientOperations:reactor-http-nio-39 [Loggers.java:254:debug] {} > [b7fc1d8f-5, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Received last HTTP packet
06/05/2024 19:06:56.147 [DEBUG] org.springframework.core.codec.StringDecoder:reactor-http-nio-39 [CompositeLog.java:127:debug] {} > [3d5a02e0] [b7fc1d8f-5, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Decoded "{"success": true}"
{"success": true}
06/05/2024 19:06:56.147 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [response_completed])
06/05/2024 19:06:56.147 [DEBUG] webflux.ReactorNettyPoolWebfluxTests2:main [ReactorNettyPoolWebfluxTests2.java:125:test] {} > Correct attempt 0
06/05/2024 19:06:56.147 [DEBUG] reactor.netty.ReactorNetty:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Removed handler: ReadTimeoutHandler, pipeline: DefaultChannelPipeline{(reactor.left.httpCodec = io.netty.handler.codec.http.HttpClientCodec), (reactor.right.reactiveBridge = reactor.netty.channel.ChannelOperationsHandler)}
06/05/2024 19:06:56.147 [DEBUG] webflux.ReactorNettyPoolWebfluxTests2:main [ReactorNettyPoolWebfluxTests2.java:125:test] {} > Correct attempt 1
06/05/2024 19:06:56.147 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] onStateChange(GET{uri=/api/endpoint, connection=PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000]}}, [disconnecting])
06/05/2024 19:06:56.147 [DEBUG] webflux.ReactorNettyPoolWebfluxTests2:main [ReactorNettyPoolWebfluxTests2.java:125:test] {} > Correct attempt 2
06/05/2024 19:06:56.147 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:254:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Releasing channel
06/05/2024 19:06:56.147 [DEBUG] webflux.ReactorNettyPoolWebfluxTests2:main [ReactorNettyPoolWebfluxTests2.java:125:test] {} > Correct attempt 3
06/05/2024 19:06:56.147 [DEBUG] reactor.netty.resources.PooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 - R:localhost/127.0.0.1:8000] Channel cleaned, now: 0 active connections, 1 inactive connections and 0 pending acquire requests.
06/05/2024 19:06:56.147 [DEBUG] webflux.ReactorNettyPoolWebfluxTests2:main [ReactorNettyPoolWebfluxTests2.java:125:test] {} > Correct attempt 4
06/05/2024 19:06:56.147 [DEBUG] webflux.ReactorNettyPoolWebfluxTests2:main [ReactorNettyPoolWebfluxTests2.java:125:test] {} > Correct attempt 5
06/05/2024 19:06:56.147 [DEBUG] reactor.netty.resources.DefaultPooledConnectionProvider:reactor-http-nio-39 [Loggers.java:259:debug] {} > [b7fc1d8f, L:/127.0.0.1:59932 ! R:localhost/127.0.0.1:8000] onStateChange(PooledConnection{channel=[id: 0xb7fc1d8f, L:/127.0.0.1:59932 ! R:localhost/127.0.0.1:8000]}, [disconnecting])