18:09:01.582 [Test worker] DEBUG ersatz.jboss.logging - Logging Provider: ersatz.jboss.logging.Slf4jLoggerProvider
18:09:01.628 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@6f316df7
18:09:01.653 [Test worker] INFO ersatz.xnio - XNIO version 3.3.8.Final
18:09:01.713 [Test worker] INFO ersatz.xnio.nio - XNIO NIO Implementation Version 3.3.8.Final
18:09:01.860 [XNIO-1 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 I/O-1', selector sun.nio.ch.EPollSelectorImpl@584d255d
18:09:01.889 [XNIO-1 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 I/O-3', selector sun.nio.ch.EPollSelectorImpl@7cdadd2f
18:09:01.889 [XNIO-1 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 I/O-4', selector sun.nio.ch.EPollSelectorImpl@66eaaf26
18:09:01.861 [XNIO-1 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 I/O-2', selector sun.nio.ch.EPollSelectorImpl@5bc9775e
18:09:01.943 [XNIO-1 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 I/O-5', selector sun.nio.ch.EPollSelectorImpl@bb88579
18:09:01.947 [XNIO-1 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 I/O-6', selector sun.nio.ch.EPollSelectorImpl@66b1d496
18:09:01.963 [XNIO-1 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 I/O-7', selector sun.nio.ch.EPollSelectorImpl@42dbd50d
18:09:01.979 [XNIO-1 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 I/O-8', selector sun.nio.ch.EPollSelectorImpl@2bfd223c
18:09:01.994 [XNIO-1 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 Accept', selector sun.nio.ch.EPollSelectorImpl@3c5d69f
18:09:01.998 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:02.178 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:03.149 [XNIO-1 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:35065]}, cookies=[:]): <empty> }
18:09:03.259 [XNIO-1 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:03.427 [XNIO-1 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:35065]}, cookies=[:]): <empty> }
18:09:03.476 [XNIO-1 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:03.514 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@6f316df7
18:09:03.567 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@12afc175
18:09:03.573 [XNIO-2 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 I/O-1', selector sun.nio.ch.EPollSelectorImpl@7b45878c
18:09:03.575 [XNIO-2 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 I/O-2', selector sun.nio.ch.EPollSelectorImpl@41e47215
18:09:03.591 [XNIO-2 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 I/O-3', selector sun.nio.ch.EPollSelectorImpl@1079f0d5
18:09:03.606 [XNIO-2 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 I/O-4', selector sun.nio.ch.EPollSelectorImpl@6e74851a
18:09:03.625 [XNIO-2 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 I/O-5', selector sun.nio.ch.EPollSelectorImpl@ec1ba7b
18:09:03.631 [XNIO-2 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 I/O-6', selector sun.nio.ch.EPollSelectorImpl@36d38e31
18:09:03.654 [XNIO-2 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 I/O-7', selector sun.nio.ch.EPollSelectorImpl@5c80053c
18:09:03.659 [XNIO-2 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 I/O-8', selector sun.nio.ch.EPollSelectorImpl@101706e3
18:09:03.664 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:03.668 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:03.665 [XNIO-2 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 Accept', selector sun.nio.ch.EPollSelectorImpl@5584eaf0
18:09:04.128 [XNIO-2 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37913]}, cookies=[:]): <empty> }
18:09:04.129 [XNIO-2 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:04.141 [XNIO-2 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37913]}, cookies=[:]): <empty> }
18:09:04.142 [XNIO-2 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:04.146 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@12afc175
18:09:04.192 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@41e4da8c
18:09:04.203 [XNIO-3 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 I/O-1', selector sun.nio.ch.EPollSelectorImpl@39685787
18:09:04.204 [XNIO-3 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 I/O-3', selector sun.nio.ch.EPollSelectorImpl@d1ba0e8
18:09:04.206 [XNIO-3 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 I/O-4', selector sun.nio.ch.EPollSelectorImpl@3a886c8b
18:09:04.206 [XNIO-3 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 I/O-5', selector sun.nio.ch.EPollSelectorImpl@3587a3c2
18:09:04.207 [XNIO-3 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 I/O-6', selector sun.nio.ch.EPollSelectorImpl@5bf21c67
18:09:04.208 [XNIO-3 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 I/O-2', selector sun.nio.ch.EPollSelectorImpl@7d13b338
18:09:04.210 [XNIO-3 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 I/O-7', selector sun.nio.ch.EPollSelectorImpl@67e5020c
18:09:04.212 [XNIO-3 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 I/O-8', selector sun.nio.ch.EPollSelectorImpl@499af2f1
18:09:04.220 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:04.221 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:04.220 [XNIO-3 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 Accept', selector sun.nio.ch.EPollSelectorImpl@6e6b8772
18:09:04.322 [XNIO-3 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:43903]}, cookies=[:]): <empty> }
18:09:04.324 [XNIO-3 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:04.333 [XNIO-3 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:43903]}, cookies=[:]): <empty> }
18:09:04.335 [XNIO-3 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:04.337 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@41e4da8c
18:09:04.349 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@30a7fbe5
18:09:04.352 [XNIO-4 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 I/O-1', selector sun.nio.ch.EPollSelectorImpl@4813be36
18:09:04.352 [XNIO-4 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 I/O-2', selector sun.nio.ch.EPollSelectorImpl@47e95eff
18:09:04.355 [XNIO-4 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 I/O-3', selector sun.nio.ch.EPollSelectorImpl@7333b4dd
18:09:04.359 [XNIO-4 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 I/O-4', selector sun.nio.ch.EPollSelectorImpl@56b47fde
18:09:04.360 [XNIO-4 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 I/O-6', selector sun.nio.ch.EPollSelectorImpl@5c75dea1
18:09:04.362 [XNIO-4 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 I/O-5', selector sun.nio.ch.EPollSelectorImpl@2c5dd427
18:09:04.362 [XNIO-4 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 I/O-7', selector sun.nio.ch.EPollSelectorImpl@12cb5079
18:09:04.363 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:04.363 [XNIO-4 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 Accept', selector sun.nio.ch.EPollSelectorImpl@4671caea
18:09:04.369 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:04.371 [XNIO-4 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 I/O-8', selector sun.nio.ch.EPollSelectorImpl@77ae9f3f
18:09:04.395 [XNIO-4 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:35789]}, cookies=[:]): <empty> }
18:09:04.396 [XNIO-4 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:04.401 [XNIO-4 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:35789]}, cookies=[:]): <empty> }
18:09:04.402 [XNIO-4 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:04.404 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@30a7fbe5
18:09:04.417 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@435cd57e
18:09:04.423 [XNIO-5 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 I/O-2', selector sun.nio.ch.EPollSelectorImpl@10dbdd83
18:09:04.423 [XNIO-5 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 I/O-3', selector sun.nio.ch.EPollSelectorImpl@7ff8ca80
18:09:04.426 [XNIO-5 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 I/O-1', selector sun.nio.ch.EPollSelectorImpl@522474e4
18:09:04.426 [XNIO-5 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 I/O-4', selector sun.nio.ch.EPollSelectorImpl@ad7e31b
18:09:04.427 [XNIO-5 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 I/O-5', selector sun.nio.ch.EPollSelectorImpl@77931ebc
18:09:04.431 [XNIO-5 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 I/O-6', selector sun.nio.ch.EPollSelectorImpl@708a98e2
18:09:04.433 [XNIO-5 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 I/O-7', selector sun.nio.ch.EPollSelectorImpl@3986bd9f
18:09:04.440 [XNIO-5 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 I/O-8', selector sun.nio.ch.EPollSelectorImpl@2f4847cc
18:09:04.451 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:04.453 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:04.459 [XNIO-5 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 Accept', selector sun.nio.ch.EPollSelectorImpl@45a94194
18:09:04.471 [XNIO-5 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[alpha:[one]], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:42117]}, cookies=[:]): <empty> }
18:09:04.512 [XNIO-5 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:04.519 [XNIO-5 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[alpha:[one]], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:42117]}, cookies=[:]): <empty> }
18:09:04.520 [XNIO-5 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:04.522 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@435cd57e
18:09:04.563 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@fabf9cd
18:09:04.572 [XNIO-6 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 I/O-2', selector sun.nio.ch.EPollSelectorImpl@4ae91f7b
18:09:04.572 [XNIO-6 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 I/O-1', selector sun.nio.ch.EPollSelectorImpl@12881516
18:09:04.573 [XNIO-6 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 I/O-3', selector sun.nio.ch.EPollSelectorImpl@8d128dc
18:09:04.581 [XNIO-6 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 I/O-4', selector sun.nio.ch.EPollSelectorImpl@5f46676c
18:09:04.584 [XNIO-6 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 I/O-5', selector sun.nio.ch.EPollSelectorImpl@4105c4c2
18:09:04.599 [XNIO-6 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 I/O-6', selector sun.nio.ch.EPollSelectorImpl@c842ea1
18:09:04.600 [XNIO-6 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 I/O-7', selector sun.nio.ch.EPollSelectorImpl@6eb65278
18:09:04.602 [XNIO-6 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 I/O-8', selector sun.nio.ch.EPollSelectorImpl@4e784360
18:09:04.603 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:04.605 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:04.607 [XNIO-6 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 Accept', selector sun.nio.ch.EPollSelectorImpl@5f7c5da0
18:09:04.634 [XNIO-6 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[alpha:[one]], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:44263]}, cookies=[:]): <empty> }
18:09:04.642 [XNIO-6 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:04.665 [XNIO-6 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[alpha:[one]], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:44263]}, cookies=[:]): <empty> }
18:09:04.667 [XNIO-6 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:04.669 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@fabf9cd
18:09:04.680 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@37eae30d
18:09:04.690 [XNIO-7 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 I/O-1', selector sun.nio.ch.EPollSelectorImpl@543ec670
18:09:04.692 [XNIO-6 task-2] DEBUG ersatz.undertow.request.io - UT005013: An IOException occurred
java.nio.channels.ClosedChannelException: null
at sun.nio.ch.SocketChannelImpl.shutdownOutput(SocketChannelImpl.java:797)
at ersatz.xnio.nio.NioSocketConduit.write(NioSocketConduit.java:170)
at ersatz.undertow.server.protocol.http.HttpResponseConduit.processWrite(HttpResponseConduit.java:242)
at ersatz.undertow.server.protocol.http.HttpResponseConduit.flush(HttpResponseConduit.java:739)
at ersatz.undertow.conduits.HeadStreamSinkConduit.flush(HeadStreamSinkConduit.java:131)
at ersatz.xnio.conduits.ConduitStreamSinkChannel.flush(ConduitStreamSinkChannel.java:162)
at ersatz.undertow.channels.DetachableStreamSinkChannel.flush(DetachableStreamSinkChannel.java:119)
at ersatz.xnio.channels.Channels.flushBlocking(Channels.java:63)
at ersatz.undertow.io.UndertowOutputStream.close(UndertowOutputStream.java:344)
at ersatz.undertow.io.BlockingSenderImpl.close(BlockingSenderImpl.java:170)
at ersatz.undertow.io.DefaultIoCallback.onComplete(DefaultIoCallback.java:54)
at ersatz.undertow.io.BlockingSenderImpl.invokeOnComplete(BlockingSenderImpl.java:227)
at ersatz.undertow.io.BlockingSenderImpl.send(BlockingSenderImpl.java:95)
at ersatz.undertow.io.BlockingSenderImpl.send(BlockingSenderImpl.java:117)
at com.stehno.ersatz.ErsatzServer.send(ErsatzServer.groovy:494)
at com.stehno.ersatz.ErsatzServer.access$0(ErsatzServer.groovy)
at com.stehno.ersatz.ErsatzServer$1.handleRequest(ErsatzServer.groovy:370)
at ersatz.undertow.server.handlers.HttpTraceHandler.handleRequest(HttpTraceHandler.java:70)
at ersatz.undertow.server.handlers.encoding.EncodingHandler.handleRequest(EncodingHandler.java:72)
at ersatz.undertow.server.Connectors.executeRootHandler(Connectors.java:332)
at ersatz.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:812)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Suppressed: java.nio.channels.ClosedByInterruptException: null
at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:202)
at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:478)
at ersatz.xnio.nio.NioSocketConduit.write(NioSocketConduit.java:164)
... 22 common frames omitted
18:09:04.711 [XNIO-7 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 I/O-2', selector sun.nio.ch.EPollSelectorImpl@69f23c84
18:09:04.719 [XNIO-7 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 I/O-3', selector sun.nio.ch.EPollSelectorImpl@2f89cb8f
18:09:04.731 [XNIO-7 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 I/O-4', selector sun.nio.ch.EPollSelectorImpl@16a7d9f7
18:09:04.738 [XNIO-7 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 I/O-5', selector sun.nio.ch.EPollSelectorImpl@1e016ba7
18:09:04.749 [XNIO-7 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 I/O-6', selector sun.nio.ch.EPollSelectorImpl@43e6b304
18:09:04.753 [XNIO-7 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 I/O-7', selector sun.nio.ch.EPollSelectorImpl@2904d0f0
18:09:04.754 [XNIO-7 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 I/O-8', selector sun.nio.ch.EPollSelectorImpl@1939bc7c
18:09:04.763 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:04.764 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:04.767 [XNIO-7 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 Accept', selector sun.nio.ch.EPollSelectorImpl@c5af732
18:09:04.795 [XNIO-7 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[alpha:[one, two]], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:36931]}, cookies=[:]): <empty> }
18:09:04.796 [XNIO-7 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:04.812 [XNIO-7 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[alpha:[one, two]], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:36931]}, cookies=[:]): <empty> }
18:09:04.814 [XNIO-7 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:04.816 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@37eae30d
18:09:04.823 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@4c857389
18:09:04.843 [XNIO-8 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 I/O-1', selector sun.nio.ch.EPollSelectorImpl@621c1511
18:09:04.851 [XNIO-8 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 I/O-2', selector sun.nio.ch.EPollSelectorImpl@3d8c2946
18:09:04.863 [XNIO-8 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 I/O-3', selector sun.nio.ch.EPollSelectorImpl@3debb56f
18:09:04.884 [XNIO-8 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 I/O-4', selector sun.nio.ch.EPollSelectorImpl@6b01b6b7
18:09:04.899 [XNIO-8 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 I/O-5', selector sun.nio.ch.EPollSelectorImpl@29c3c272
18:09:04.911 [XNIO-8 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 I/O-6', selector sun.nio.ch.EPollSelectorImpl@6cff846e
18:09:04.927 [XNIO-8 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 I/O-7', selector sun.nio.ch.EPollSelectorImpl@61771f2a
18:09:04.950 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:04.951 [XNIO-8 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 Accept', selector sun.nio.ch.EPollSelectorImpl@2fc43d80
18:09:04.951 [XNIO-8 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 I/O-8', selector sun.nio.ch.EPollSelectorImpl@7b2f39c8
18:09:04.956 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:04.982 [XNIO-8 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[alpha:[one, two], bravo:[three]], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:34801]}, cookies=[:]): <empty> }
18:09:04.995 [XNIO-8 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.002 [XNIO-8 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[alpha:[one, two], bravo:[three]], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:34801]}, cookies=[:]): <empty> }
18:09:05.013 [XNIO-8 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.016 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@4c857389
18:09:05.017 [XNIO-8 I/O-8] DEBUG ersatz.undertow - Failed to schedule task ersatz.undertow.server.protocol.ParseTimeoutUpdater@36364ccd as worker is shutting down
java.util.concurrent.RejectedExecutionException: XNIO007007: Thread is terminating
at ersatz.xnio.nio.WorkerThread.executeAfter(WorkerThread.java:617)
at ersatz.undertow.util.WorkerUtils.executeAfter(WorkerUtils.java:46)
at ersatz.undertow.server.protocol.ParseTimeoutUpdater.handleSchedule(ParseTimeoutUpdater.java:107)
at ersatz.undertow.server.protocol.ParseTimeoutUpdater.connectionIdle(ParseTimeoutUpdater.java:85)
at ersatz.undertow.server.protocol.http.HttpReadListener.newRequest(HttpReadListener.java:118)
at ersatz.undertow.server.protocol.http.HttpReadListener.exchangeComplete(HttpReadListener.java:310)
at ersatz.undertow.server.protocol.http.HttpServerConnection.exchangeComplete(HttpServerConnection.java:232)
at ersatz.undertow.server.HttpServerExchange.invokeExchangeCompleteListeners(HttpServerExchange.java:1261)
at ersatz.undertow.server.HttpServerExchange.closeAndFlushResponse(HttpServerExchange.java:1675)
at ersatz.undertow.server.HttpServerExchange.endExchange(HttpServerExchange.java:1665)
at ersatz.undertow.server.AbstractServerConnection$CloseSetter.handleEvent(AbstractServerConnection.java:316)
at ersatz.undertow.server.AbstractServerConnection$CloseSetter.handleEvent(AbstractServerConnection.java:296)
at ersatz.xnio.ChannelListeners.invokeChannelListener(ChannelListeners.java:92)
at ersatz.xnio.StreamConnection.invokeCloseListener(StreamConnection.java:80)
at ersatz.xnio.Connection.writeClosed(Connection.java:117)
at ersatz.xnio.nio.AbstractNioStreamConnection.writeClosed(AbstractNioStreamConnection.java:47)
at ersatz.xnio.nio.NioSocketConduit.terminateWrites(NioSocketConduit.java:214)
at ersatz.xnio.nio.NioSocketConduit.truncateWrites(NioSocketConduit.java:228)
at ersatz.undertow.server.protocol.http.HttpResponseConduit.truncateWrites(HttpResponseConduit.java:774)
at ersatz.xnio.conduits.AbstractSinkConduit.truncateWrites(AbstractSinkConduit.java:82)
at ersatz.xnio.conduits.ConduitStreamSinkChannel.close(ConduitStreamSinkChannel.java:186)
at ersatz.xnio.IoUtils.safeClose(IoUtils.java:134)
at ersatz.xnio.conduits.WriteReadyHandler$ChannelListenerHandler.forceTermination(WriteReadyHandler.java:57)
at ersatz.xnio.nio.NioSocketConduit.forceTermination(NioSocketConduit.java:107)
at ersatz.xnio.nio.WorkerThread.run(WorkerThread.java:494)
18:09:05.041 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@7f7f63b5
18:09:05.047 [XNIO-9 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 I/O-1', selector sun.nio.ch.EPollSelectorImpl@14485078
18:09:05.061 [XNIO-9 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 I/O-2', selector sun.nio.ch.EPollSelectorImpl@1a46e0a8
18:09:05.061 [XNIO-9 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 I/O-3', selector sun.nio.ch.EPollSelectorImpl@287fe45a
18:09:05.063 [XNIO-9 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 I/O-4', selector sun.nio.ch.EPollSelectorImpl@76b4d84e
18:09:05.067 [XNIO-9 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 I/O-5', selector sun.nio.ch.EPollSelectorImpl@6b989e3
18:09:05.068 [XNIO-9 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 I/O-6', selector sun.nio.ch.EPollSelectorImpl@52efc6d
18:09:05.071 [XNIO-9 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 I/O-7', selector sun.nio.ch.EPollSelectorImpl@2d26d99f
18:09:05.095 [XNIO-9 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 I/O-8', selector sun.nio.ch.EPollSelectorImpl@7515c19b
18:09:05.105 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:05.106 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:05.115 [XNIO-9 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 Accept', selector sun.nio.ch.EPollSelectorImpl@645fb96c
18:09:05.145 [XNIO-9 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:45291]}, cookies=[:]): <empty> }
18:09:05.151 [XNIO-9 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.171 [XNIO-9 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:45291]}, cookies=[:]): <empty> }
18:09:05.174 [XNIO-9 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.176 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@7f7f63b5
18:09:05.188 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@887d54b
18:09:05.203 [XNIO-10 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 I/O-1', selector sun.nio.ch.EPollSelectorImpl@1872f422
18:09:05.215 [XNIO-10 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 I/O-2', selector sun.nio.ch.EPollSelectorImpl@26792940
18:09:05.223 [XNIO-10 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 I/O-3', selector sun.nio.ch.EPollSelectorImpl@33ce9481
18:09:05.224 [XNIO-10 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 I/O-4', selector sun.nio.ch.EPollSelectorImpl@16d2e112
18:09:05.226 [XNIO-10 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 I/O-5', selector sun.nio.ch.EPollSelectorImpl@a19bc97
18:09:05.235 [XNIO-10 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 I/O-7', selector sun.nio.ch.EPollSelectorImpl@4b331ea9
18:09:05.239 [XNIO-10 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 I/O-8', selector sun.nio.ch.EPollSelectorImpl@72b8256d
18:09:05.243 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:05.244 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:05.245 [XNIO-10 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 I/O-6', selector sun.nio.ch.EPollSelectorImpl@577eac33
18:09:05.245 [XNIO-10 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 Accept', selector sun.nio.ch.EPollSelectorImpl@58bac5f2
18:09:05.255 [XNIO-10 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:36833]}, cookies=[:]): <empty> }
18:09:05.256 [XNIO-10 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.262 [XNIO-10 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:36833]}, cookies=[:]): <empty> }
18:09:05.263 [XNIO-10 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.265 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@887d54b
18:09:05.270 [XNIO-10 task-2] DEBUG ersatz.undertow.request.io - UT005013: An IOException occurred
java.nio.channels.ClosedChannelException: null
at sun.nio.ch.SocketChannelImpl.shutdownOutput(SocketChannelImpl.java:797)
at ersatz.xnio.nio.NioSocketConduit.write(NioSocketConduit.java:170)
at ersatz.undertow.server.protocol.http.HttpResponseConduit.processWrite(HttpResponseConduit.java:242)
at ersatz.undertow.server.protocol.http.HttpResponseConduit.flush(HttpResponseConduit.java:739)
at ersatz.undertow.conduits.HeadStreamSinkConduit.flush(HeadStreamSinkConduit.java:131)
at ersatz.xnio.conduits.ConduitStreamSinkChannel.flush(ConduitStreamSinkChannel.java:162)
at ersatz.undertow.channels.DetachableStreamSinkChannel.flush(DetachableStreamSinkChannel.java:119)
at ersatz.xnio.channels.Channels.flushBlocking(Channels.java:63)
at ersatz.undertow.io.UndertowOutputStream.close(UndertowOutputStream.java:344)
at ersatz.undertow.io.BlockingSenderImpl.close(BlockingSenderImpl.java:170)
at ersatz.undertow.io.DefaultIoCallback.onComplete(DefaultIoCallback.java:54)
at ersatz.undertow.io.BlockingSenderImpl.invokeOnComplete(BlockingSenderImpl.java:227)
at ersatz.undertow.io.BlockingSenderImpl.send(BlockingSenderImpl.java:95)
at ersatz.undertow.io.BlockingSenderImpl.send(BlockingSenderImpl.java:117)
at com.stehno.ersatz.ErsatzServer.send(ErsatzServer.groovy:494)
at com.stehno.ersatz.ErsatzServer.access$0(ErsatzServer.groovy)
at com.stehno.ersatz.ErsatzServer$1.handleRequest(ErsatzServer.groovy:370)
at ersatz.undertow.server.handlers.HttpTraceHandler.handleRequest(HttpTraceHandler.java:70)
at ersatz.undertow.server.handlers.encoding.EncodingHandler.handleRequest(EncodingHandler.java:72)
at ersatz.undertow.server.Connectors.executeRootHandler(Connectors.java:332)
at ersatz.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:812)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Suppressed: java.nio.channels.ClosedByInterruptException: null
at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:202)
at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:478)
at ersatz.xnio.nio.NioSocketConduit.write(NioSocketConduit.java:164)
... 22 common frames omitted
18:09:05.284 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@2def8312
18:09:05.295 [XNIO-11 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-11 I/O-1', selector sun.nio.ch.EPollSelectorImpl@1bf098e5
18:09:05.297 [XNIO-11 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-11 I/O-2', selector sun.nio.ch.EPollSelectorImpl@17a63856
18:09:05.305 [XNIO-11 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-11 I/O-3', selector sun.nio.ch.EPollSelectorImpl@1e5a9b60
18:09:05.309 [XNIO-11 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-11 I/O-4', selector sun.nio.ch.EPollSelectorImpl@341d7ca7
18:09:05.311 [XNIO-11 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-11 I/O-5', selector sun.nio.ch.EPollSelectorImpl@6002c6fc
18:09:05.311 [XNIO-11 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-11 I/O-6', selector sun.nio.ch.EPollSelectorImpl@29ab08ba
18:09:05.311 [XNIO-11 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-11 I/O-7', selector sun.nio.ch.EPollSelectorImpl@2f18a447
18:09:05.315 [XNIO-11 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-11 I/O-8', selector sun.nio.ch.EPollSelectorImpl@5028a673
18:09:05.319 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:05.319 [XNIO-11 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-11 Accept', selector sun.nio.ch.EPollSelectorImpl@5495f696
18:09:05.320 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:05.338 [XNIO-11 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], Cookie=[flavor=chocolate-chip], User-Agent=[okhttp/3.5.0], Host=[localhost:37803]}, cookies=[flavor:ersatz.undertow.server.handlers.CookieImpl@437ea208]): <empty> }
18:09:05.355 [XNIO-11 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.363 [XNIO-11 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], Cookie=[flavor=chocolate-chip], User-Agent=[okhttp/3.5.0], Host=[localhost:37803]}, cookies=[flavor:ersatz.undertow.server.handlers.CookieImpl@5ce64c69]): <empty> }
18:09:05.365 [XNIO-11 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.366 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@2def8312
18:09:05.381 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@24ebb30b
18:09:05.384 [XNIO-12 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-12 I/O-2', selector sun.nio.ch.EPollSelectorImpl@5195cd42
18:09:05.388 [XNIO-12 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-12 I/O-3', selector sun.nio.ch.EPollSelectorImpl@39dbc4c
18:09:05.395 [XNIO-12 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-12 I/O-4', selector sun.nio.ch.EPollSelectorImpl@1271edda
18:09:05.384 [XNIO-12 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-12 I/O-1', selector sun.nio.ch.EPollSelectorImpl@310c1980
18:09:05.399 [XNIO-12 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-12 I/O-5', selector sun.nio.ch.EPollSelectorImpl@3d8fb680
18:09:05.400 [XNIO-12 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-12 I/O-6', selector sun.nio.ch.EPollSelectorImpl@1ffcaa9b
18:09:05.400 [XNIO-12 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-12 I/O-7', selector sun.nio.ch.EPollSelectorImpl@42d2c563
18:09:05.401 [XNIO-12 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-12 I/O-8', selector sun.nio.ch.EPollSelectorImpl@6a4262f4
18:09:05.403 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:05.403 [XNIO-12 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-12 Accept', selector sun.nio.ch.EPollSelectorImpl@5913bc53
18:09:05.404 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:05.419 [XNIO-12 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], Cookie=[flavor=chocolate-chip; count=dozen], User-Agent=[okhttp/3.5.0], Host=[localhost:34413]}, cookies=[count:ersatz.undertow.server.handlers.CookieImpl@5fcb5753, flavor:ersatz.undertow.server.handlers.CookieImpl@510cb2da]): <empty> }
18:09:05.421 [XNIO-12 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.427 [XNIO-12 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], Cookie=[flavor=chocolate-chip; count=dozen], User-Agent=[okhttp/3.5.0], Host=[localhost:34413]}, cookies=[count:ersatz.undertow.server.handlers.CookieImpl@665b0e5d, flavor:ersatz.undertow.server.handlers.CookieImpl@689cece6]): <empty> }
18:09:05.431 [XNIO-12 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.433 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@24ebb30b
18:09:05.446 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@4f488ca6
18:09:05.455 [XNIO-13 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-13 I/O-1', selector sun.nio.ch.EPollSelectorImpl@1ab66a5c
18:09:05.470 [XNIO-13 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-13 I/O-2', selector sun.nio.ch.EPollSelectorImpl@34997428
18:09:05.470 [XNIO-13 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-13 I/O-3', selector sun.nio.ch.EPollSelectorImpl@36bf827a
18:09:05.473 [XNIO-13 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-13 I/O-4', selector sun.nio.ch.EPollSelectorImpl@75a652a7
18:09:05.475 [XNIO-13 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-13 I/O-5', selector sun.nio.ch.EPollSelectorImpl@3d68b892
18:09:05.476 [XNIO-13 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-13 I/O-6', selector sun.nio.ch.EPollSelectorImpl@64b0ee1f
18:09:05.483 [XNIO-13 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-13 I/O-7', selector sun.nio.ch.EPollSelectorImpl@2ff88a5d
18:09:05.487 [XNIO-13 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-13 I/O-8', selector sun.nio.ch.EPollSelectorImpl@52d88f4
18:09:05.487 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:05.489 [XNIO-13 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-13 Accept', selector sun.nio.ch.EPollSelectorImpl@b8e972f
18:09:05.490 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:05.526 [XNIO-13 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:46181]}, cookies=[:]): <empty> }
18:09:05.527 [XNIO-13 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.539 [XNIO-13 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:46181]}, cookies=[:]): <empty> }
18:09:05.539 [XNIO-13 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.541 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@4f488ca6
18:09:05.549 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@53aa55ad
18:09:05.555 [XNIO-14 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-14 I/O-1', selector sun.nio.ch.EPollSelectorImpl@73dd1615
18:09:05.555 [XNIO-14 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-14 I/O-2', selector sun.nio.ch.EPollSelectorImpl@68fa5eb2
18:09:05.556 [XNIO-14 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-14 I/O-3', selector sun.nio.ch.EPollSelectorImpl@487026de
18:09:05.556 [XNIO-14 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-14 I/O-4', selector sun.nio.ch.EPollSelectorImpl@2aa2c672
18:09:05.556 [XNIO-14 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-14 I/O-5', selector sun.nio.ch.EPollSelectorImpl@58e25d5f
18:09:05.557 [XNIO-14 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-14 I/O-6', selector sun.nio.ch.EPollSelectorImpl@702623c0
18:09:05.563 [XNIO-14 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-14 I/O-7', selector sun.nio.ch.EPollSelectorImpl@4a5333ab
18:09:05.565 [XNIO-14 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-14 I/O-8', selector sun.nio.ch.EPollSelectorImpl@617272c2
18:09:05.565 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:05.565 [XNIO-14 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-14 Accept', selector sun.nio.ch.EPollSelectorImpl@5a79c37a
18:09:05.568 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:05.577 [XNIO-14 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:44711]}, cookies=[:]): <empty> }
18:09:05.578 [XNIO-14 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.585 [XNIO-14 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:44711]}, cookies=[:]): <empty> }
18:09:05.587 [XNIO-14 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.588 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@53aa55ad
18:09:05.591 [XNIO-14 task-2] DEBUG ersatz.undertow.request.io - UT005013: An IOException occurred
java.nio.channels.ClosedChannelException: null
at sun.nio.ch.SocketChannelImpl.shutdownOutput(SocketChannelImpl.java:797)
at ersatz.xnio.nio.NioSocketConduit.write(NioSocketConduit.java:170)
at ersatz.undertow.server.protocol.http.HttpResponseConduit.processWrite(HttpResponseConduit.java:242)
at ersatz.undertow.server.protocol.http.HttpResponseConduit.flush(HttpResponseConduit.java:739)
at ersatz.undertow.conduits.HeadStreamSinkConduit.flush(HeadStreamSinkConduit.java:131)
at ersatz.xnio.conduits.ConduitStreamSinkChannel.flush(ConduitStreamSinkChannel.java:162)
at ersatz.undertow.channels.DetachableStreamSinkChannel.flush(DetachableStreamSinkChannel.java:119)
at ersatz.xnio.channels.Channels.flushBlocking(Channels.java:63)
at ersatz.undertow.io.UndertowOutputStream.close(UndertowOutputStream.java:344)
at ersatz.undertow.io.BlockingSenderImpl.close(BlockingSenderImpl.java:170)
at ersatz.undertow.io.DefaultIoCallback.onComplete(DefaultIoCallback.java:54)
at ersatz.undertow.io.BlockingSenderImpl.invokeOnComplete(BlockingSenderImpl.java:227)
at ersatz.undertow.io.BlockingSenderImpl.send(BlockingSenderImpl.java:95)
at ersatz.undertow.io.BlockingSenderImpl.send(BlockingSenderImpl.java:117)
at com.stehno.ersatz.ErsatzServer.send(ErsatzServer.groovy:494)
at com.stehno.ersatz.ErsatzServer.access$0(ErsatzServer.groovy)
at com.stehno.ersatz.ErsatzServer$1.handleRequest(ErsatzServer.groovy:370)
at ersatz.undertow.server.handlers.HttpTraceHandler.handleRequest(HttpTraceHandler.java:70)
at ersatz.undertow.server.handlers.encoding.EncodingHandler.handleRequest(EncodingHandler.java:72)
at ersatz.undertow.server.Connectors.executeRootHandler(Connectors.java:332)
at ersatz.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:812)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Suppressed: java.nio.channels.ClosedByInterruptException: null
at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:202)
at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:478)
at ersatz.xnio.nio.NioSocketConduit.write(NioSocketConduit.java:164)
... 22 common frames omitted
18:09:05.608 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@416bc6b5
18:09:05.611 [XNIO-15 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-15 I/O-1', selector sun.nio.ch.EPollSelectorImpl@3d5024c8
18:09:05.615 [XNIO-15 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-15 I/O-2', selector sun.nio.ch.EPollSelectorImpl@4055ed0c
18:09:05.619 [XNIO-15 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-15 I/O-4', selector sun.nio.ch.EPollSelectorImpl@279d1677
18:09:05.619 [XNIO-15 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-15 I/O-3', selector sun.nio.ch.EPollSelectorImpl@240c9976
18:09:05.619 [XNIO-15 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-15 I/O-7', selector sun.nio.ch.EPollSelectorImpl@7074dc73
18:09:05.619 [XNIO-15 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-15 I/O-8', selector sun.nio.ch.EPollSelectorImpl@337c5150
18:09:05.619 [XNIO-15 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-15 I/O-5', selector sun.nio.ch.EPollSelectorImpl@4cd4ca67
18:09:05.620 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:05.621 [XNIO-15 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-15 Accept', selector sun.nio.ch.EPollSelectorImpl@7e7bc1d5
18:09:05.621 [XNIO-15 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-15 I/O-6', selector sun.nio.ch.EPollSelectorImpl@474ef8b0
18:09:05.622 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:05.644 [XNIO-15 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], hat=[fedora], Host=[localhost:35315]}, cookies=[:]): <empty> }
18:09:05.655 [XNIO-15 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.659 [XNIO-15 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], hat=[fedora], Host=[localhost:35315]}, cookies=[:]): <empty> }
18:09:05.661 [XNIO-15 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.662 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@416bc6b5
18:09:05.680 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@4a5c11ef
18:09:05.682 [XNIO-16 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-16 I/O-1', selector sun.nio.ch.EPollSelectorImpl@40cfc6c5
18:09:05.682 [XNIO-16 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-16 I/O-2', selector sun.nio.ch.EPollSelectorImpl@238f3bc7
18:09:05.683 [XNIO-16 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-16 I/O-5', selector sun.nio.ch.EPollSelectorImpl@63f73eb4
18:09:05.683 [XNIO-16 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-16 I/O-3', selector sun.nio.ch.EPollSelectorImpl@17710edf
18:09:05.686 [XNIO-16 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-16 I/O-6', selector sun.nio.ch.EPollSelectorImpl@3c0de34f
18:09:05.691 [XNIO-16 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-16 I/O-8', selector sun.nio.ch.EPollSelectorImpl@5ef03673
18:09:05.683 [XNIO-16 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-16 I/O-4', selector sun.nio.ch.EPollSelectorImpl@67b4f7b4
18:09:05.692 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:05.692 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:05.686 [XNIO-16 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-16 I/O-7', selector sun.nio.ch.EPollSelectorImpl@176fc9e4
18:09:05.693 [XNIO-16 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-16 Accept', selector sun.nio.ch.EPollSelectorImpl@140937c5
18:09:05.714 [XNIO-16 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:41269]}, cookies=[:]): <empty> }
18:09:05.715 [XNIO-16 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.727 [XNIO-16 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:41269]}, cookies=[:]): <empty> }
18:09:05.728 [XNIO-16 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.731 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@4a5c11ef
18:09:05.740 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@ea5099e
18:09:05.741 [XNIO-17 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-17 I/O-1', selector sun.nio.ch.EPollSelectorImpl@5ad185d5
18:09:05.751 [XNIO-17 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-17 I/O-2', selector sun.nio.ch.EPollSelectorImpl@54c2fe6f
18:09:05.754 [XNIO-17 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-17 I/O-3', selector sun.nio.ch.EPollSelectorImpl@24573ab5
18:09:05.767 [XNIO-17 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-17 I/O-4', selector sun.nio.ch.EPollSelectorImpl@778012b4
18:09:05.770 [XNIO-17 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-17 I/O-7', selector sun.nio.ch.EPollSelectorImpl@d66a00f
18:09:05.767 [XNIO-17 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-17 I/O-5', selector sun.nio.ch.EPollSelectorImpl@74d5be61
18:09:05.771 [XNIO-17 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-17 I/O-6', selector sun.nio.ch.EPollSelectorImpl@1048ff79
18:09:05.772 [XNIO-17 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-17 I/O-8', selector sun.nio.ch.EPollSelectorImpl@33a95f72
18:09:05.775 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:05.775 [XNIO-17 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-17 Accept', selector sun.nio.ch.EPollSelectorImpl@7b64bd34
18:09:05.795 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:05.811 [XNIO-17 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:45769]}, cookies=[:]): <empty> }
18:09:05.812 [XNIO-17 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.828 [XNIO-17 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:45769]}, cookies=[:]): <empty> }
18:09:05.830 [XNIO-17 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.832 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@ea5099e
18:09:05.835 [XNIO-17 I/O-8] DEBUG ersatz.undertow - Failed to schedule task ersatz.undertow.server.protocol.ParseTimeoutUpdater@5782f7b as worker is shutting down
java.util.concurrent.RejectedExecutionException: XNIO007007: Thread is terminating
at ersatz.xnio.nio.WorkerThread.executeAfter(WorkerThread.java:617)
at ersatz.undertow.util.WorkerUtils.executeAfter(WorkerUtils.java:46)
at ersatz.undertow.server.protocol.ParseTimeoutUpdater.handleSchedule(ParseTimeoutUpdater.java:107)
at ersatz.undertow.server.protocol.ParseTimeoutUpdater.connectionIdle(ParseTimeoutUpdater.java:85)
at ersatz.undertow.server.protocol.http.HttpReadListener.newRequest(HttpReadListener.java:118)
at ersatz.undertow.server.protocol.http.HttpReadListener.exchangeComplete(HttpReadListener.java:310)
at ersatz.undertow.server.protocol.http.HttpServerConnection.exchangeComplete(HttpServerConnection.java:232)
at ersatz.undertow.server.HttpServerExchange.invokeExchangeCompleteListeners(HttpServerExchange.java:1261)
at ersatz.undertow.server.HttpServerExchange.closeAndFlushResponse(HttpServerExchange.java:1675)
at ersatz.undertow.server.HttpServerExchange.endExchange(HttpServerExchange.java:1665)
at ersatz.undertow.server.AbstractServerConnection$CloseSetter.handleEvent(AbstractServerConnection.java:316)
at ersatz.undertow.server.AbstractServerConnection$CloseSetter.handleEvent(AbstractServerConnection.java:296)
at ersatz.xnio.ChannelListeners.invokeChannelListener(ChannelListeners.java:92)
at ersatz.xnio.StreamConnection.invokeCloseListener(StreamConnection.java:80)
at ersatz.xnio.Connection.writeClosed(Connection.java:117)
at ersatz.xnio.nio.AbstractNioStreamConnection.writeClosed(AbstractNioStreamConnection.java:47)
at ersatz.xnio.nio.NioSocketConduit.terminateWrites(NioSocketConduit.java:214)
at ersatz.xnio.nio.NioSocketConduit.truncateWrites(NioSocketConduit.java:228)
at ersatz.undertow.server.protocol.http.HttpResponseConduit.truncateWrites(HttpResponseConduit.java:774)
at ersatz.xnio.conduits.AbstractSinkConduit.truncateWrites(AbstractSinkConduit.java:82)
at ersatz.xnio.conduits.ConduitStreamSinkChannel.close(ConduitStreamSinkChannel.java:186)
at ersatz.xnio.IoUtils.safeClose(IoUtils.java:134)
at ersatz.xnio.conduits.WriteReadyHandler$ChannelListenerHandler.forceTermination(WriteReadyHandler.java:57)
at ersatz.xnio.nio.NioSocketConduit.forceTermination(NioSocketConduit.java:107)
at ersatz.xnio.nio.WorkerThread.run(WorkerThread.java:494)
18:09:05.842 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@6dfe3d7e
18:09:05.844 [XNIO-18 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-18 I/O-1', selector sun.nio.ch.EPollSelectorImpl@744df422
18:09:05.859 [XNIO-18 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-18 I/O-3', selector sun.nio.ch.EPollSelectorImpl@203b51a
18:09:05.863 [XNIO-18 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-18 I/O-2', selector sun.nio.ch.EPollSelectorImpl@3df48baf
18:09:05.863 [XNIO-18 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-18 I/O-4', selector sun.nio.ch.EPollSelectorImpl@6189f0b6
18:09:05.869 [XNIO-18 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-18 I/O-6', selector sun.nio.ch.EPollSelectorImpl@2e7cd283
18:09:05.870 [XNIO-18 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-18 I/O-7', selector sun.nio.ch.EPollSelectorImpl@224feb25
18:09:05.863 [XNIO-18 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-18 I/O-5', selector sun.nio.ch.EPollSelectorImpl@5d053c6b
18:09:05.871 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:05.900 [XNIO-18 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-18 Accept', selector sun.nio.ch.EPollSelectorImpl@190e7d51
18:09:05.901 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:05.901 [XNIO-18 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-18 I/O-8', selector sun.nio.ch.EPollSelectorImpl@28dd4db8
18:09:05.912 [XNIO-18 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], hat=[fedora], Host=[localhost:36793]}, cookies=[:]): <empty> }
18:09:05.914 [XNIO-18 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.931 [XNIO-18 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /alpha (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], hat=[fedora], Host=[localhost:36793]}, cookies=[:]): <empty> }
18:09:05.932 [XNIO-18 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({X-Something=[Testing]}): <empty>
18:09:05.934 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@6dfe3d7e
18:09:05.935 [XNIO-18 I/O-4] DEBUG ersatz.undertow - Failed to schedule task ersatz.undertow.server.protocol.ParseTimeoutUpdater@d151f4 as worker is shutting down
java.util.concurrent.RejectedExecutionException: XNIO007007: Thread is terminating
at ersatz.xnio.nio.WorkerThread.executeAfter(WorkerThread.java:617)
at ersatz.undertow.util.WorkerUtils.executeAfter(WorkerUtils.java:46)
at ersatz.undertow.server.protocol.ParseTimeoutUpdater.handleSchedule(ParseTimeoutUpdater.java:107)
at ersatz.undertow.server.protocol.ParseTimeoutUpdater.connectionIdle(ParseTimeoutUpdater.java:85)
at ersatz.undertow.server.protocol.http.HttpReadListener.newRequest(HttpReadListener.java:118)
at ersatz.undertow.server.protocol.http.HttpReadListener.exchangeComplete(HttpReadListener.java:310)
at ersatz.undertow.server.protocol.http.HttpServerConnection.exchangeComplete(HttpServerConnection.java:232)
at ersatz.undertow.server.HttpServerExchange.invokeExchangeCompleteListeners(HttpServerExchange.java:1261)
at ersatz.undertow.server.HttpServerExchange.closeAndFlushResponse(HttpServerExchange.java:1675)
at ersatz.undertow.server.HttpServerExchange.endExchange(HttpServerExchange.java:1665)
at ersatz.undertow.server.AbstractServerConnection$CloseSetter.handleEvent(AbstractServerConnection.java:316)
at ersatz.undertow.server.AbstractServerConnection$CloseSetter.handleEvent(AbstractServerConnection.java:296)
at ersatz.xnio.ChannelListeners.invokeChannelListener(ChannelListeners.java:92)
at ersatz.xnio.StreamConnection.invokeCloseListener(StreamConnection.java:80)
at ersatz.xnio.Connection.writeClosed(Connection.java:117)
at ersatz.xnio.nio.AbstractNioStreamConnection.writeClosed(AbstractNioStreamConnection.java:47)
at ersatz.xnio.nio.NioSocketConduit.terminateWrites(NioSocketConduit.java:214)
at ersatz.xnio.nio.NioSocketConduit.truncateWrites(NioSocketConduit.java:228)
at ersatz.undertow.server.protocol.http.HttpResponseConduit.truncateWrites(HttpResponseConduit.java:774)
at ersatz.xnio.conduits.AbstractSinkConduit.truncateWrites(AbstractSinkConduit.java:82)
at ersatz.xnio.conduits.ConduitStreamSinkChannel.close(ConduitStreamSinkChannel.java:186)
at ersatz.xnio.IoUtils.safeClose(IoUtils.java:134)
at ersatz.xnio.conduits.WriteReadyHandler$ChannelListenerHandler.forceTermination(WriteReadyHandler.java:57)
at ersatz.xnio.nio.NioSocketConduit.forceTermination(NioSocketConduit.java:107)
at ersatz.xnio.nio.WorkerThread.run(WorkerThread.java:494)
18:09:05.978 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@64b5634a
18:09:05.980 [XNIO-19 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-19 I/O-1', selector sun.nio.ch.EPollSelectorImpl@62ad2011
18:09:05.981 [XNIO-19 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-19 I/O-2', selector sun.nio.ch.EPollSelectorImpl@4aa33ace
18:09:05.988 [XNIO-19 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-19 I/O-3', selector sun.nio.ch.EPollSelectorImpl@14843bb3
18:09:05.991 [XNIO-19 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-19 I/O-4', selector sun.nio.ch.EPollSelectorImpl@4d1e5f4e
18:09:05.993 [XNIO-19 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-19 I/O-5', selector sun.nio.ch.EPollSelectorImpl@624c5525
18:09:05.999 [XNIO-19 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-19 I/O-6', selector sun.nio.ch.EPollSelectorImpl@1b81ac8e
18:09:06.004 [XNIO-19 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-19 I/O-7', selector sun.nio.ch.EPollSelectorImpl@4fdd7a9f
18:09:06.007 [XNIO-19 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-19 I/O-8', selector sun.nio.ch.EPollSelectorImpl@78fcb58e
18:09:06.008 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:06.008 [XNIO-19 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-19 Accept', selector sun.nio.ch.EPollSelectorImpl@14814f42
18:09:06.013 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:06.038 [XNIO-19 task-1] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic YWRtaW46JDNjcjN0], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:42733]} response {}}
18:09:06.039 [XNIO-19 task-1] DEBUG ersatz.undertow.request.security - Attempting to authenticate /basic, authentication required: true
18:09:06.047 [XNIO-19 task-1] DEBUG ersatz.undertow.request.security - Found basic auth header admin:$3cr3t (decoded using charset UTF-8) in HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic YWRtaW46JDNjcjN0], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:42733]} response {}}
18:09:06.065 [XNIO-19 task-1] DEBUG ersatz.undertow.request.security - Authenticated as admin, roles [TESTER]
18:09:06.078 [XNIO-19 task-1] DEBUG ersatz.undertow.request.security - Authentication outcome was AUTHENTICATED with method ersatz.undertow.security.impl.BasicAuthenticationMechanism@4c0a5bff for /basic
18:09:06.078 [XNIO-19 task-1] DEBUG ersatz.undertow.request.security - Authentication result was AUTHENTICATED for /basic
18:09:06.078 [XNIO-19 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /basic (query=[:], headers={Connection=[Keep-Alive], Authorization=[Basic YWRtaW46JDNjcjN0], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:42733]}, cookies=[:]): <empty> }
18:09:06.079 [XNIO-19 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:06.084 [XNIO-19 task-2] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic YWRtaW46JDNjcjN0], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:42733]} response {}}
18:09:06.084 [XNIO-19 task-2] DEBUG ersatz.undertow.request.security - Attempting to authenticate /basic, authentication required: true
18:09:06.084 [XNIO-19 task-2] DEBUG ersatz.undertow.request.security - Found basic auth header admin:$3cr3t (decoded using charset UTF-8) in HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic YWRtaW46JDNjcjN0], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:42733]} response {}}
18:09:06.085 [XNIO-19 task-2] DEBUG ersatz.undertow.request.security - Authenticated as admin, roles [TESTER]
18:09:06.085 [XNIO-19 task-2] DEBUG ersatz.undertow.request.security - Authentication outcome was AUTHENTICATED with method ersatz.undertow.security.impl.BasicAuthenticationMechanism@4c0a5bff for /basic
18:09:06.085 [XNIO-19 task-2] DEBUG ersatz.undertow.request.security - Authentication result was AUTHENTICATED for /basic
18:09:06.085 [XNIO-19 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /basic (query=[:], headers={Connection=[Keep-Alive], Authorization=[Basic YWRtaW46JDNjcjN0], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:42733]}, cookies=[:]): <empty> }
18:09:06.091 [XNIO-19 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:06.092 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@64b5634a
18:09:06.098 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@4a915244
18:09:06.100 [XNIO-20 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-20 I/O-1', selector sun.nio.ch.EPollSelectorImpl@5a956376
18:09:06.101 [XNIO-20 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-20 I/O-2', selector sun.nio.ch.EPollSelectorImpl@5d1d184e
18:09:06.107 [XNIO-20 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-20 I/O-3', selector sun.nio.ch.EPollSelectorImpl@3dd73c88
18:09:06.111 [XNIO-20 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-20 I/O-4', selector sun.nio.ch.EPollSelectorImpl@16237d7
18:09:06.136 [XNIO-20 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-20 I/O-6', selector sun.nio.ch.EPollSelectorImpl@5a91166e
18:09:06.139 [XNIO-20 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-20 I/O-5', selector sun.nio.ch.EPollSelectorImpl@8c906b4
18:09:06.142 [XNIO-20 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-20 I/O-7', selector sun.nio.ch.EPollSelectorImpl@35fd6830
18:09:06.143 [XNIO-20 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-20 I/O-8', selector sun.nio.ch.EPollSelectorImpl@5b9f6a35
18:09:06.143 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:06.144 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:06.144 [XNIO-20 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-20 Accept', selector sun.nio.ch.EPollSelectorImpl@62aef883
18:09:06.247 [XNIO-20 task-3] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic YWRtaW46JDNjcjN0], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:35289]} response {}}
18:09:06.247 [XNIO-20 task-3] DEBUG ersatz.undertow.request.security - Attempting to authenticate /basic, authentication required: true
18:09:06.247 [XNIO-20 task-3] DEBUG ersatz.undertow.request.security - Found basic auth header admin:$3cr3t (decoded using charset UTF-8) in HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic YWRtaW46JDNjcjN0], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:35289]} response {}}
18:09:06.247 [XNIO-20 task-3] DEBUG ersatz.undertow.request.security - Authenticated as admin, roles [TESTER]
18:09:06.248 [XNIO-20 task-3] DEBUG ersatz.undertow.request.security - Authentication outcome was AUTHENTICATED with method ersatz.undertow.security.impl.BasicAuthenticationMechanism@7475cf49 for /basic
18:09:06.248 [XNIO-20 task-3] DEBUG ersatz.undertow.request.security - Authentication result was AUTHENTICATED for /basic
18:09:06.248 [XNIO-20 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /basic (query=[:], headers={Connection=[Keep-Alive], Authorization=[Basic YWRtaW46JDNjcjN0], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:35289]}, cookies=[:]): <empty> }
18:09:06.248 [XNIO-20 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:06.254 [XNIO-20 task-4] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic YWRtaW46JDNjcjN0], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:35289]} response {}}
18:09:06.254 [XNIO-20 task-4] DEBUG ersatz.undertow.request.security - Attempting to authenticate /basic, authentication required: true
18:09:06.254 [XNIO-20 task-4] DEBUG ersatz.undertow.request.security - Found basic auth header admin:$3cr3t (decoded using charset UTF-8) in HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic YWRtaW46JDNjcjN0], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:35289]} response {}}
18:09:06.254 [XNIO-20 task-4] DEBUG ersatz.undertow.request.security - Authenticated as admin, roles [TESTER]
18:09:06.254 [XNIO-20 task-4] DEBUG ersatz.undertow.request.security - Authentication outcome was AUTHENTICATED with method ersatz.undertow.security.impl.BasicAuthenticationMechanism@7475cf49 for /basic
18:09:06.254 [XNIO-20 task-4] DEBUG ersatz.undertow.request.security - Authentication result was AUTHENTICATED for /basic
18:09:06.254 [XNIO-20 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /basic (query=[:], headers={Connection=[Keep-Alive], Authorization=[Basic YWRtaW46JDNjcjN0], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:35289]}, cookies=[:]): <empty> }
18:09:06.255 [XNIO-20 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:06.257 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@4a915244
18:09:06.266 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@1ad1ee1a
18:09:06.271 [XNIO-21 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-21 I/O-1', selector sun.nio.ch.EPollSelectorImpl@7716fbe6
18:09:06.278 [XNIO-21 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-21 I/O-2', selector sun.nio.ch.EPollSelectorImpl@42868223
18:09:06.278 [XNIO-21 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-21 I/O-4', selector sun.nio.ch.EPollSelectorImpl@75a861d0
18:09:06.278 [XNIO-21 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-21 I/O-5', selector sun.nio.ch.EPollSelectorImpl@38322542
18:09:06.278 [XNIO-21 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-21 I/O-3', selector sun.nio.ch.EPollSelectorImpl@12458597
18:09:06.283 [XNIO-21 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-21 I/O-6', selector sun.nio.ch.EPollSelectorImpl@6c0e7874
18:09:06.283 [XNIO-21 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-21 I/O-7', selector sun.nio.ch.EPollSelectorImpl@18eb0ce3
18:09:06.288 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:06.288 [XNIO-21 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-21 I/O-8', selector sun.nio.ch.EPollSelectorImpl@17bc36d7
18:09:06.290 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:06.288 [XNIO-21 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-21 Accept', selector sun.nio.ch.EPollSelectorImpl@36ba3182
18:09:06.303 [XNIO-21 task-1] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:36613]} response {}}
18:09:06.303 [XNIO-21 task-1] DEBUG ersatz.undertow.request.security - Attempting to authenticate /basic, authentication required: true
18:09:06.303 [XNIO-21 task-1] DEBUG ersatz.undertow.request.security - Found basic auth header guest:blah (decoded using charset UTF-8) in HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:36613]} response {}}
18:09:06.304 [XNIO-21 task-1] DEBUG ersatz.undertow.request.security - Authentication failed with message UT000038: Authentication failed, requested user name 'guest' and mechanism BASIC for HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:36613]} response {}}
18:09:06.305 [XNIO-21 task-1] DEBUG ersatz.undertow.request.security - Authentication outcome was NOT_AUTHENTICATED with method ersatz.undertow.security.impl.BasicAuthenticationMechanism@534c558a for /basic
18:09:06.305 [XNIO-21 task-1] DEBUG ersatz.undertow.request.security - Sending authentication challenge for HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:36613]} response {}}
18:09:06.306 [XNIO-21 task-1] DEBUG ersatz.undertow.request.security - Sending basic auth challenge Basic realm="BasicTesting" for HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:36613]} response {WWW-Authenticate=[Basic realm="BasicTesting"]}}
18:09:06.307 [XNIO-21 task-1] DEBUG ersatz.undertow.request.security - Authentication result was CHALLENGE_SENT for /basic
18:09:06.327 [XNIO-21 task-2] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:36613]} response {}}
18:09:06.327 [XNIO-21 task-2] DEBUG ersatz.undertow.request.security - Attempting to authenticate /basic, authentication required: true
18:09:06.327 [XNIO-21 task-2] DEBUG ersatz.undertow.request.security - Found basic auth header guest:blah (decoded using charset UTF-8) in HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:36613]} response {}}
18:09:06.327 [XNIO-21 task-2] DEBUG ersatz.undertow.request.security - Authentication failed with message UT000038: Authentication failed, requested user name 'guest' and mechanism BASIC for HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:36613]} response {}}
18:09:06.327 [XNIO-21 task-2] DEBUG ersatz.undertow.request.security - Authentication outcome was NOT_AUTHENTICATED with method ersatz.undertow.security.impl.BasicAuthenticationMechanism@534c558a for /basic
18:09:06.327 [XNIO-21 task-2] DEBUG ersatz.undertow.request.security - Sending authentication challenge for HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:36613]} response {}}
18:09:06.328 [XNIO-21 task-2] DEBUG ersatz.undertow.request.security - Sending basic auth challenge Basic realm="BasicTesting" for HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:36613]} response {WWW-Authenticate=[Basic realm="BasicTesting"]}}
18:09:06.328 [XNIO-21 task-2] DEBUG ersatz.undertow.request.security - Authentication result was CHALLENGE_SENT for /basic
18:09:06.331 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@1ad1ee1a
18:09:06.337 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@4821c923
18:09:06.339 [XNIO-22 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-22 I/O-1', selector sun.nio.ch.EPollSelectorImpl@1efc65a6
18:09:06.340 [XNIO-22 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-22 I/O-3', selector sun.nio.ch.EPollSelectorImpl@6af574eb
18:09:06.339 [XNIO-22 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-22 I/O-2', selector sun.nio.ch.EPollSelectorImpl@6262b87c
18:09:06.341 [XNIO-22 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-22 I/O-4', selector sun.nio.ch.EPollSelectorImpl@32a6a05e
18:09:06.346 [XNIO-22 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-22 I/O-5', selector sun.nio.ch.EPollSelectorImpl@44890e76
18:09:06.347 [XNIO-22 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-22 I/O-6', selector sun.nio.ch.EPollSelectorImpl@707f743e
18:09:06.347 [XNIO-22 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-22 I/O-7', selector sun.nio.ch.EPollSelectorImpl@2ad8d03f
18:09:06.347 [XNIO-22 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-22 I/O-8', selector sun.nio.ch.EPollSelectorImpl@7ad70a22
18:09:06.347 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:06.347 [XNIO-22 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-22 Accept', selector sun.nio.ch.EPollSelectorImpl@7919fafe
18:09:06.348 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:06.435 [XNIO-22 task-3] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:39235]} response {}}
18:09:06.435 [XNIO-22 task-3] DEBUG ersatz.undertow.request.security - Attempting to authenticate /basic, authentication required: true
18:09:06.436 [XNIO-22 task-3] DEBUG ersatz.undertow.request.security - Found basic auth header guest:blah (decoded using charset UTF-8) in HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:39235]} response {}}
18:09:06.437 [XNIO-22 task-3] DEBUG ersatz.undertow.request.security - Authentication failed with message UT000038: Authentication failed, requested user name 'guest' and mechanism BASIC for HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:39235]} response {}}
18:09:06.437 [XNIO-22 task-3] DEBUG ersatz.undertow.request.security - Authentication outcome was NOT_AUTHENTICATED with method ersatz.undertow.security.impl.BasicAuthenticationMechanism@686f03d0 for /basic
18:09:06.437 [XNIO-22 task-3] DEBUG ersatz.undertow.request.security - Sending authentication challenge for HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:39235]} response {}}
18:09:06.437 [XNIO-22 task-3] DEBUG ersatz.undertow.request.security - Sending basic auth challenge Basic realm="BasicTesting" for HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:39235]} response {WWW-Authenticate=[Basic realm="BasicTesting"]}}
18:09:06.437 [XNIO-22 task-3] DEBUG ersatz.undertow.request.security - Authentication result was CHALLENGE_SENT for /basic
18:09:06.443 [XNIO-22 task-4] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:39235]} response {}}
18:09:06.443 [XNIO-22 task-4] DEBUG ersatz.undertow.request.security - Attempting to authenticate /basic, authentication required: true
18:09:06.443 [XNIO-22 task-4] DEBUG ersatz.undertow.request.security - Found basic auth header guest:blah (decoded using charset UTF-8) in HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:39235]} response {}}
18:09:06.444 [XNIO-22 task-4] DEBUG ersatz.undertow.request.security - Authentication failed with message UT000038: Authentication failed, requested user name 'guest' and mechanism BASIC for HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:39235]} response {}}
18:09:06.444 [XNIO-22 task-4] DEBUG ersatz.undertow.request.security - Authentication outcome was NOT_AUTHENTICATED with method ersatz.undertow.security.impl.BasicAuthenticationMechanism@686f03d0 for /basic
18:09:06.444 [XNIO-22 task-4] DEBUG ersatz.undertow.request.security - Sending authentication challenge for HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:39235]} response {}}
18:09:06.444 [XNIO-22 task-4] DEBUG ersatz.undertow.request.security - Sending basic auth challenge Basic realm="BasicTesting" for HttpServerExchange{ HEAD /basic request {Connection=[Keep-Alive], Authorization=[Basic Z3Vlc3Q6YmxhaA==], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:39235]} response {WWW-Authenticate=[Basic realm="BasicTesting"]}}
18:09:06.444 [XNIO-22 task-4] DEBUG ersatz.undertow.request.security - Authentication result was CHALLENGE_SENT for /basic
18:09:06.445 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@4821c923
18:09:06.469 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@6f8d153b
18:09:06.471 [XNIO-23 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-23 I/O-1', selector sun.nio.ch.EPollSelectorImpl@1721a594
18:09:06.471 [XNIO-23 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-23 I/O-2', selector sun.nio.ch.EPollSelectorImpl@779377be
18:09:06.471 [XNIO-23 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-23 I/O-5', selector sun.nio.ch.EPollSelectorImpl@668562a4
18:09:06.471 [XNIO-23 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-23 I/O-3', selector sun.nio.ch.EPollSelectorImpl@78054743
18:09:06.477 [XNIO-23 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-23 I/O-7', selector sun.nio.ch.EPollSelectorImpl@68e06871
18:09:06.477 [XNIO-23 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-23 I/O-8', selector sun.nio.ch.EPollSelectorImpl@7cd8400b
18:09:06.477 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:06.478 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:06.471 [XNIO-23 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-23 I/O-4', selector sun.nio.ch.EPollSelectorImpl@47ea6608
18:09:06.477 [XNIO-23 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-23 I/O-6', selector sun.nio.ch.EPollSelectorImpl@2219ef68
18:09:06.487 [XNIO-23 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-23 Accept', selector sun.nio.ch.EPollSelectorImpl@3ec2859b
18:09:06.494 [XNIO-23 task-1] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:46735]} response {}}
18:09:06.495 [XNIO-23 task-1] DEBUG ersatz.undertow.request.security - Attempting to authenticate /digest, authentication required: true
18:09:06.496 [XNIO-23 task-1] DEBUG ersatz.undertow.request.security - Authentication outcome was NOT_ATTEMPTED with method ersatz.undertow.security.impl.DigestAuthenticationMechanism@4f2bb468 for /digest
18:09:06.496 [XNIO-23 task-1] DEBUG ersatz.undertow.request.security - Sending authentication challenge for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:46735]} response {}}
18:09:06.498 [XNIO-23 task-1] DEBUG ersatz.undertow.request.security - Authentication result was CHALLENGE_SENT for /digest
18:09:06.509 [XNIO-23 task-2] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="admin", realm="DigestTesting", nonce="Zj0BhHpOMcINMTU2NTQ3ODU0NjQ5N/DaZb9hYw7EStvY9dg3jVk=", uri="/digest", response="7d9e0ca6feaca2556dba67974c6e7460", qop=auth, nc=00000001, cnonce="e1f12ee4f4b461ff", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:46735]} response {}}
18:09:06.510 [XNIO-23 task-2] DEBUG ersatz.undertow.request.security - Attempting to authenticate /digest, authentication required: true
18:09:06.513 [XNIO-23 task-2] DEBUG ersatz.undertow.request.security - Found digest header Digest username="admin", realm="DigestTesting", nonce="Zj0BhHpOMcINMTU2NTQ3ODU0NjQ5N/DaZb9hYw7EStvY9dg3jVk=", uri="/digest", response="7d9e0ca6feaca2556dba67974c6e7460", qop=auth, nc=00000001, cnonce="e1f12ee4f4b461ff", algorithm=MD5, opaque="00000000000000000000000000000000" in HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="admin", realm="DigestTesting", nonce="Zj0BhHpOMcINMTU2NTQ3ODU0NjQ5N/DaZb9hYw7EStvY9dg3jVk=", uri="/digest", response="7d9e0ca6feaca2556dba67974c6e7460", qop=auth, nc=00000001, cnonce="e1f12ee4f4b461ff", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:46735]} response {}}
18:09:06.519 [XNIO-23 task-2] DEBUG ersatz.undertow.request.security - Authenticated as admin, roles [TESTER]
18:09:06.519 [XNIO-23 task-2] DEBUG ersatz.undertow.request.security - Authentication outcome was AUTHENTICATED with method ersatz.undertow.security.impl.DigestAuthenticationMechanism@4f2bb468 for /digest
18:09:06.519 [XNIO-23 task-2] DEBUG ersatz.undertow.request.security - Authentication result was AUTHENTICATED for /digest
18:09:06.519 [XNIO-23 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /digest (query=[:], headers={Connection=[Keep-Alive], Authorization=[Digest username="admin", realm="DigestTesting", nonce="Zj0BhHpOMcINMTU2NTQ3ODU0NjQ5N/DaZb9hYw7EStvY9dg3jVk=", uri="/digest", response="7d9e0ca6feaca2556dba67974c6e7460", qop=auth, nc=00000001, cnonce="e1f12ee4f4b461ff", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:46735]}, cookies=[:]): <empty> }
18:09:06.520 [XNIO-23 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({Authentication-Info=[nextnonce="Zj0BhHpOMcINMTU2NTQ3ODU0NjQ5N/DaZb9hYw7EStvY9dg3jVk=",qop="auth",rspauth="925ac59c3ca46bacf65acfe061b02215",cnonce="e1f12ee4f4b461ff",nc=00000001]}): <empty>
18:09:06.524 [XNIO-23 task-3] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="admin", realm="DigestTesting", nonce="Zj0BhHpOMcINMTU2NTQ3ODU0NjQ5N/DaZb9hYw7EStvY9dg3jVk=", uri="/digest", response="ebca0ebd7998fd1bb8481d1b18347f88", qop=auth, nc=00000002, cnonce="e1f12ee4f4b461ff", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:46735]} response {}}
18:09:06.524 [XNIO-23 task-3] DEBUG ersatz.undertow.request.security - Attempting to authenticate /digest, authentication required: true
18:09:06.524 [XNIO-23 task-3] DEBUG ersatz.undertow.request.security - Found digest header Digest username="admin", realm="DigestTesting", nonce="Zj0BhHpOMcINMTU2NTQ3ODU0NjQ5N/DaZb9hYw7EStvY9dg3jVk=", uri="/digest", response="ebca0ebd7998fd1bb8481d1b18347f88", qop=auth, nc=00000002, cnonce="e1f12ee4f4b461ff", algorithm=MD5, opaque="00000000000000000000000000000000" in HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="admin", realm="DigestTesting", nonce="Zj0BhHpOMcINMTU2NTQ3ODU0NjQ5N/DaZb9hYw7EStvY9dg3jVk=", uri="/digest", response="ebca0ebd7998fd1bb8481d1b18347f88", qop=auth, nc=00000002, cnonce="e1f12ee4f4b461ff", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:46735]} response {}}
18:09:06.527 [XNIO-23 task-3] DEBUG ersatz.undertow.request.security - Authenticated as admin, roles [TESTER]
18:09:06.528 [XNIO-23 task-3] DEBUG ersatz.undertow.request.security - Authentication outcome was AUTHENTICATED with method ersatz.undertow.security.impl.DigestAuthenticationMechanism@4f2bb468 for /digest
18:09:06.528 [XNIO-23 task-3] DEBUG ersatz.undertow.request.security - Authentication result was AUTHENTICATED for /digest
18:09:06.528 [XNIO-23 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /digest (query=[:], headers={Connection=[Keep-Alive], Authorization=[Digest username="admin", realm="DigestTesting", nonce="Zj0BhHpOMcINMTU2NTQ3ODU0NjQ5N/DaZb9hYw7EStvY9dg3jVk=", uri="/digest", response="ebca0ebd7998fd1bb8481d1b18347f88", qop=auth, nc=00000002, cnonce="e1f12ee4f4b461ff", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:46735]}, cookies=[:]): <empty> }
18:09:06.529 [XNIO-23 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Response({Authentication-Info=[nextnonce="Zj0BhHpOMcINMTU2NTQ3ODU0NjQ5N/DaZb9hYw7EStvY9dg3jVk=",qop="auth",rspauth="9628207d6a80604469a715b6f7d1e507",cnonce="e1f12ee4f4b461ff",nc=00000002]}): <empty>
18:09:06.530 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@6f8d153b
18:09:06.534 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@1ee1dd69
18:09:06.542 [XNIO-24 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-24 I/O-1', selector sun.nio.ch.EPollSelectorImpl@5c6195b9
18:09:06.542 [XNIO-24 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-24 I/O-2', selector sun.nio.ch.EPollSelectorImpl@1bbe6c13
18:09:06.543 [XNIO-24 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-24 I/O-3', selector sun.nio.ch.EPollSelectorImpl@25014d0e
18:09:06.547 [XNIO-24 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-24 I/O-4', selector sun.nio.ch.EPollSelectorImpl@1c3936f6
18:09:06.551 [XNIO-24 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-24 I/O-5', selector sun.nio.ch.EPollSelectorImpl@3d939231
18:09:06.552 [XNIO-24 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-24 I/O-6', selector sun.nio.ch.EPollSelectorImpl@1c78ace0
18:09:06.553 [XNIO-24 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-24 I/O-7', selector sun.nio.ch.EPollSelectorImpl@74c13a48
18:09:06.554 [XNIO-24 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-24 I/O-8', selector sun.nio.ch.EPollSelectorImpl@35718916
18:09:06.554 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:06.555 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:06.557 [XNIO-24 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-24 Accept', selector sun.nio.ch.EPollSelectorImpl@4ca56704
18:09:06.646 [XNIO-24 task-3] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:40969]} response {}}
18:09:06.646 [XNIO-24 task-3] DEBUG ersatz.undertow.request.security - Attempting to authenticate /digest, authentication required: true
18:09:06.646 [XNIO-24 task-3] DEBUG ersatz.undertow.request.security - Authentication outcome was NOT_ATTEMPTED with method ersatz.undertow.security.impl.DigestAuthenticationMechanism@7518aba9 for /digest
18:09:06.646 [XNIO-24 task-3] DEBUG ersatz.undertow.request.security - Sending authentication challenge for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:40969]} response {}}
18:09:06.646 [XNIO-24 task-3] DEBUG ersatz.undertow.request.security - Authentication result was CHALLENGE_SENT for /digest
18:09:06.656 [XNIO-24 task-4] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="admin", realm="DigestTesting", nonce="g20cPt7eP4ANMTU2NTQ3ODU0NjY0NvwuqLybJcaWSyk1YGwfvn0=", uri="/digest", response="d6d706332d595686976b5cce90596cbd", qop=auth, nc=00000001, cnonce="4375f28dd8d85e34", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:40969]} response {}}
18:09:06.656 [XNIO-24 task-4] DEBUG ersatz.undertow.request.security - Attempting to authenticate /digest, authentication required: true
18:09:06.656 [XNIO-24 task-4] DEBUG ersatz.undertow.request.security - Found digest header Digest username="admin", realm="DigestTesting", nonce="g20cPt7eP4ANMTU2NTQ3ODU0NjY0NvwuqLybJcaWSyk1YGwfvn0=", uri="/digest", response="d6d706332d595686976b5cce90596cbd", qop=auth, nc=00000001, cnonce="4375f28dd8d85e34", algorithm=MD5, opaque="00000000000000000000000000000000" in HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="admin", realm="DigestTesting", nonce="g20cPt7eP4ANMTU2NTQ3ODU0NjY0NvwuqLybJcaWSyk1YGwfvn0=", uri="/digest", response="d6d706332d595686976b5cce90596cbd", qop=auth, nc=00000001, cnonce="4375f28dd8d85e34", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:40969]} response {}}
18:09:06.657 [XNIO-24 task-4] DEBUG ersatz.undertow.request.security - Authenticated as admin, roles [TESTER]
18:09:06.658 [XNIO-24 task-4] DEBUG ersatz.undertow.request.security - Authentication outcome was AUTHENTICATED with method ersatz.undertow.security.impl.DigestAuthenticationMechanism@7518aba9 for /digest
18:09:06.658 [XNIO-24 task-4] DEBUG ersatz.undertow.request.security - Authentication result was AUTHENTICATED for /digest
18:09:06.658 [XNIO-24 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /digest (query=[:], headers={Connection=[Keep-Alive], Authorization=[Digest username="admin", realm="DigestTesting", nonce="g20cPt7eP4ANMTU2NTQ3ODU0NjY0NvwuqLybJcaWSyk1YGwfvn0=", uri="/digest", response="d6d706332d595686976b5cce90596cbd", qop=auth, nc=00000001, cnonce="4375f28dd8d85e34", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:40969]}, cookies=[:]): <empty> }
18:09:06.658 [XNIO-24 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Response({Authentication-Info=[nextnonce="g20cPt7eP4ANMTU2NTQ3ODU0NjY0NvwuqLybJcaWSyk1YGwfvn0=",qop="auth",rspauth="72d213b310879a25107872c8cc19096b",cnonce="4375f28dd8d85e34",nc=00000001]}): <empty>
18:09:06.665 [XNIO-24 task-5] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="admin", realm="DigestTesting", nonce="g20cPt7eP4ANMTU2NTQ3ODU0NjY0NvwuqLybJcaWSyk1YGwfvn0=", uri="/digest", response="23c996ad2b474d3e74f794b029cc8cd6", qop=auth, nc=00000002, cnonce="4375f28dd8d85e34", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:40969]} response {}}
18:09:06.665 [XNIO-24 task-5] DEBUG ersatz.undertow.request.security - Attempting to authenticate /digest, authentication required: true
18:09:06.666 [XNIO-24 task-5] DEBUG ersatz.undertow.request.security - Found digest header Digest username="admin", realm="DigestTesting", nonce="g20cPt7eP4ANMTU2NTQ3ODU0NjY0NvwuqLybJcaWSyk1YGwfvn0=", uri="/digest", response="23c996ad2b474d3e74f794b029cc8cd6", qop=auth, nc=00000002, cnonce="4375f28dd8d85e34", algorithm=MD5, opaque="00000000000000000000000000000000" in HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="admin", realm="DigestTesting", nonce="g20cPt7eP4ANMTU2NTQ3ODU0NjY0NvwuqLybJcaWSyk1YGwfvn0=", uri="/digest", response="23c996ad2b474d3e74f794b029cc8cd6", qop=auth, nc=00000002, cnonce="4375f28dd8d85e34", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:40969]} response {}}
18:09:06.668 [XNIO-24 task-5] DEBUG ersatz.undertow.request.security - Authenticated as admin, roles [TESTER]
18:09:06.668 [XNIO-24 task-5] DEBUG ersatz.undertow.request.security - Authentication outcome was AUTHENTICATED with method ersatz.undertow.security.impl.DigestAuthenticationMechanism@7518aba9 for /digest
18:09:06.668 [XNIO-24 task-5] DEBUG ersatz.undertow.request.security - Authentication result was AUTHENTICATED for /digest
18:09:06.668 [XNIO-24 task-5] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /digest (query=[:], headers={Connection=[Keep-Alive], Authorization=[Digest username="admin", realm="DigestTesting", nonce="g20cPt7eP4ANMTU2NTQ3ODU0NjY0NvwuqLybJcaWSyk1YGwfvn0=", uri="/digest", response="23c996ad2b474d3e74f794b029cc8cd6", qop=auth, nc=00000002, cnonce="4375f28dd8d85e34", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:40969]}, cookies=[:]): <empty> }
18:09:06.669 [XNIO-24 task-5] DEBUG com.stehno.ersatz.ErsatzServer - Response({Authentication-Info=[nextnonce="g20cPt7eP4ANMTU2NTQ3ODU0NjY0NvwuqLybJcaWSyk1YGwfvn0=",qop="auth",rspauth="c09f68b8b096762e61ccf7c0a79ec14a",cnonce="4375f28dd8d85e34",nc=00000002]}): <empty>
18:09:06.671 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@1ee1dd69
18:09:06.684 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@7b6bb25a
18:09:06.686 [XNIO-25 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-25 I/O-1', selector sun.nio.ch.EPollSelectorImpl@483df3ae
18:09:06.686 [XNIO-25 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-25 I/O-2', selector sun.nio.ch.EPollSelectorImpl@49944
18:09:06.687 [XNIO-25 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-25 I/O-3', selector sun.nio.ch.EPollSelectorImpl@77e180a4
18:09:06.691 [XNIO-25 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-25 I/O-4', selector sun.nio.ch.EPollSelectorImpl@5a6e6ca4
18:09:06.699 [XNIO-25 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-25 I/O-5', selector sun.nio.ch.EPollSelectorImpl@5b792e69
18:09:06.700 [XNIO-25 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-25 I/O-6', selector sun.nio.ch.EPollSelectorImpl@7e954d7e
18:09:06.701 [XNIO-25 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-25 I/O-7', selector sun.nio.ch.EPollSelectorImpl@2aab1791
18:09:06.701 [XNIO-25 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-25 I/O-8', selector sun.nio.ch.EPollSelectorImpl@565a5478
18:09:06.701 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:06.701 [XNIO-25 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-25 Accept', selector sun.nio.ch.EPollSelectorImpl@5835b175
18:09:06.702 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:06.727 [XNIO-25 task-1] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37737]} response {}}
18:09:06.728 [XNIO-25 task-1] DEBUG ersatz.undertow.request.security - Attempting to authenticate /digest, authentication required: true
18:09:06.728 [XNIO-25 task-1] DEBUG ersatz.undertow.request.security - Authentication outcome was NOT_ATTEMPTED with method ersatz.undertow.security.impl.DigestAuthenticationMechanism@3465ab28 for /digest
18:09:06.728 [XNIO-25 task-1] DEBUG ersatz.undertow.request.security - Sending authentication challenge for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37737]} response {}}
18:09:06.728 [XNIO-25 task-1] DEBUG ersatz.undertow.request.security - Authentication result was CHALLENGE_SENT for /digest
18:09:06.737 [XNIO-25 task-2] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="GNIdJ1HQsx8NMTU2NTQ3ODU0NjcyOB4VhFc/FVs9B2LlKYVgiak=", uri="/digest", response="102067b2e8883844f0a82c83d0d1a5d1", qop=auth, nc=00000001, cnonce="dd75aabfc8350da0", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37737]} response {}}
18:09:06.737 [XNIO-25 task-2] DEBUG ersatz.undertow.request.security - Attempting to authenticate /digest, authentication required: true
18:09:06.737 [XNIO-25 task-2] DEBUG ersatz.undertow.request.security - Found digest header Digest username="nobody", realm="DigestTesting", nonce="GNIdJ1HQsx8NMTU2NTQ3ODU0NjcyOB4VhFc/FVs9B2LlKYVgiak=", uri="/digest", response="102067b2e8883844f0a82c83d0d1a5d1", qop=auth, nc=00000001, cnonce="dd75aabfc8350da0", algorithm=MD5, opaque="00000000000000000000000000000000" in HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="GNIdJ1HQsx8NMTU2NTQ3ODU0NjcyOB4VhFc/FVs9B2LlKYVgiak=", uri="/digest", response="102067b2e8883844f0a82c83d0d1a5d1", qop=auth, nc=00000001, cnonce="dd75aabfc8350da0", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37737]} response {}}
18:09:06.738 [XNIO-25 task-2] DEBUG ersatz.undertow.request.security - Authentication failed with message UT000038: Authentication failed, requested user name 'nobody' and mechanism DIGEST for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="GNIdJ1HQsx8NMTU2NTQ3ODU0NjcyOB4VhFc/FVs9B2LlKYVgiak=", uri="/digest", response="102067b2e8883844f0a82c83d0d1a5d1", qop=auth, nc=00000001, cnonce="dd75aabfc8350da0", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37737]} response {}}
18:09:06.738 [XNIO-25 task-2] DEBUG ersatz.undertow.request.security - Authentication outcome was NOT_AUTHENTICATED with method ersatz.undertow.security.impl.DigestAuthenticationMechanism@3465ab28 for /digest
18:09:06.738 [XNIO-25 task-2] DEBUG ersatz.undertow.request.security - Sending authentication challenge for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="GNIdJ1HQsx8NMTU2NTQ3ODU0NjcyOB4VhFc/FVs9B2LlKYVgiak=", uri="/digest", response="102067b2e8883844f0a82c83d0d1a5d1", qop=auth, nc=00000001, cnonce="dd75aabfc8350da0", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37737]} response {}}
18:09:06.738 [XNIO-25 task-2] DEBUG ersatz.undertow.request.security - Authentication result was CHALLENGE_SENT for /digest
18:09:06.745 [XNIO-25 task-3] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="q6aEY2WOTysNMTU2NTQ3ODU0NjczOLVg/sXkJdGMHk2B5PDRydU=", uri="/digest", response="4d74275ceb5208fed4cffdc21f863c81", qop=auth, nc=00000001, cnonce="defa1ef8e8c91364", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37737]} response {}}
18:09:06.745 [XNIO-25 task-3] DEBUG ersatz.undertow.request.security - Attempting to authenticate /digest, authentication required: true
18:09:06.745 [XNIO-25 task-3] DEBUG ersatz.undertow.request.security - Found digest header Digest username="nobody", realm="DigestTesting", nonce="q6aEY2WOTysNMTU2NTQ3ODU0NjczOLVg/sXkJdGMHk2B5PDRydU=", uri="/digest", response="4d74275ceb5208fed4cffdc21f863c81", qop=auth, nc=00000001, cnonce="defa1ef8e8c91364", algorithm=MD5, opaque="00000000000000000000000000000000" in HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="q6aEY2WOTysNMTU2NTQ3ODU0NjczOLVg/sXkJdGMHk2B5PDRydU=", uri="/digest", response="4d74275ceb5208fed4cffdc21f863c81", qop=auth, nc=00000001, cnonce="defa1ef8e8c91364", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37737]} response {}}
18:09:06.745 [XNIO-25 task-3] DEBUG ersatz.undertow.request.security - Authentication failed with message UT000038: Authentication failed, requested user name 'nobody' and mechanism DIGEST for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="q6aEY2WOTysNMTU2NTQ3ODU0NjczOLVg/sXkJdGMHk2B5PDRydU=", uri="/digest", response="4d74275ceb5208fed4cffdc21f863c81", qop=auth, nc=00000001, cnonce="defa1ef8e8c91364", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37737]} response {}}
18:09:06.745 [XNIO-25 task-3] DEBUG ersatz.undertow.request.security - Authentication outcome was NOT_AUTHENTICATED with method ersatz.undertow.security.impl.DigestAuthenticationMechanism@3465ab28 for /digest
18:09:06.746 [XNIO-25 task-3] DEBUG ersatz.undertow.request.security - Sending authentication challenge for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="q6aEY2WOTysNMTU2NTQ3ODU0NjczOLVg/sXkJdGMHk2B5PDRydU=", uri="/digest", response="4d74275ceb5208fed4cffdc21f863c81", qop=auth, nc=00000001, cnonce="defa1ef8e8c91364", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37737]} response {}}
18:09:06.746 [XNIO-25 task-3] DEBUG ersatz.undertow.request.security - Authentication result was CHALLENGE_SENT for /digest
18:09:06.755 [XNIO-25 task-4] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37737]} response {}}
18:09:06.755 [XNIO-25 task-4] DEBUG ersatz.undertow.request.security - Attempting to authenticate /digest, authentication required: true
18:09:06.755 [XNIO-25 task-4] DEBUG ersatz.undertow.request.security - Authentication outcome was NOT_ATTEMPTED with method ersatz.undertow.security.impl.DigestAuthenticationMechanism@3465ab28 for /digest
18:09:06.755 [XNIO-25 task-4] DEBUG ersatz.undertow.request.security - Sending authentication challenge for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37737]} response {}}
18:09:06.756 [XNIO-25 task-4] DEBUG ersatz.undertow.request.security - Authentication result was CHALLENGE_SENT for /digest
18:09:06.763 [XNIO-25 task-5] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="cs2smTzJuYUNMTU2NTQ3ODU0Njc1NjdsTkePuBKbz2oohm5GEqo=", uri="/digest", response="60ff8cc31d657607577be6005d3fb4c7", qop=auth, nc=00000001, cnonce="e40146c5baf19906", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37737]} response {}}
18:09:06.763 [XNIO-25 task-5] DEBUG ersatz.undertow.request.security - Attempting to authenticate /digest, authentication required: true
18:09:06.763 [XNIO-25 task-5] DEBUG ersatz.undertow.request.security - Found digest header Digest username="nobody", realm="DigestTesting", nonce="cs2smTzJuYUNMTU2NTQ3ODU0Njc1NjdsTkePuBKbz2oohm5GEqo=", uri="/digest", response="60ff8cc31d657607577be6005d3fb4c7", qop=auth, nc=00000001, cnonce="e40146c5baf19906", algorithm=MD5, opaque="00000000000000000000000000000000" in HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="cs2smTzJuYUNMTU2NTQ3ODU0Njc1NjdsTkePuBKbz2oohm5GEqo=", uri="/digest", response="60ff8cc31d657607577be6005d3fb4c7", qop=auth, nc=00000001, cnonce="e40146c5baf19906", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37737]} response {}}
18:09:06.763 [XNIO-25 task-5] DEBUG ersatz.undertow.request.security - Authentication failed with message UT000038: Authentication failed, requested user name 'nobody' and mechanism DIGEST for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="cs2smTzJuYUNMTU2NTQ3ODU0Njc1NjdsTkePuBKbz2oohm5GEqo=", uri="/digest", response="60ff8cc31d657607577be6005d3fb4c7", qop=auth, nc=00000001, cnonce="e40146c5baf19906", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37737]} response {}}
18:09:06.763 [XNIO-25 task-5] DEBUG ersatz.undertow.request.security - Authentication outcome was NOT_AUTHENTICATED with method ersatz.undertow.security.impl.DigestAuthenticationMechanism@3465ab28 for /digest
18:09:06.763 [XNIO-25 task-5] DEBUG ersatz.undertow.request.security - Sending authentication challenge for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="cs2smTzJuYUNMTU2NTQ3ODU0Njc1NjdsTkePuBKbz2oohm5GEqo=", uri="/digest", response="60ff8cc31d657607577be6005d3fb4c7", qop=auth, nc=00000001, cnonce="e40146c5baf19906", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37737]} response {}}
18:09:06.764 [XNIO-25 task-5] DEBUG ersatz.undertow.request.security - Authentication result was CHALLENGE_SENT for /digest
18:09:06.766 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@7b6bb25a
18:09:06.771 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@d3d5415
18:09:06.773 [XNIO-26 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-26 I/O-1', selector sun.nio.ch.EPollSelectorImpl@1a163e35
18:09:06.777 [XNIO-26 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-26 I/O-2', selector sun.nio.ch.EPollSelectorImpl@3bf4bef0
18:09:06.777 [XNIO-26 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-26 I/O-3', selector sun.nio.ch.EPollSelectorImpl@386645b5
18:09:06.777 [XNIO-26 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-26 I/O-4', selector sun.nio.ch.EPollSelectorImpl@5c82c835
18:09:06.802 [XNIO-26 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-26 I/O-5', selector sun.nio.ch.EPollSelectorImpl@40f5911e
18:09:06.803 [XNIO-26 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-26 I/O-6', selector sun.nio.ch.EPollSelectorImpl@738b4c9
18:09:06.803 [XNIO-26 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-26 I/O-7', selector sun.nio.ch.EPollSelectorImpl@323ff8d2
18:09:06.806 [XNIO-26 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-26 I/O-8', selector sun.nio.ch.EPollSelectorImpl@16e8f6cd
18:09:06.807 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:06.807 [XNIO-26 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-26 Accept', selector sun.nio.ch.EPollSelectorImpl@fb0e82d
18:09:06.808 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:06.883 [XNIO-26 task-3] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38673]} response {}}
18:09:06.883 [XNIO-26 task-3] DEBUG ersatz.undertow.request.security - Attempting to authenticate /digest, authentication required: true
18:09:06.883 [XNIO-26 task-3] DEBUG ersatz.undertow.request.security - Authentication outcome was NOT_ATTEMPTED with method ersatz.undertow.security.impl.DigestAuthenticationMechanism@29eb45f7 for /digest
18:09:06.883 [XNIO-26 task-3] DEBUG ersatz.undertow.request.security - Sending authentication challenge for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38673]} response {}}
18:09:06.883 [XNIO-26 task-3] DEBUG ersatz.undertow.request.security - Authentication result was CHALLENGE_SENT for /digest
18:09:06.887 [XNIO-26 task-4] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="hpfn6ria1FkNMTU2NTQ3ODU0Njg4M43ljJJjkQgdWjydIbjfmAM=", uri="/digest", response="da130d7e2ba37c05a215b164acf0753b", qop=auth, nc=00000001, cnonce="3e1ca42d0869535c", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38673]} response {}}
18:09:06.888 [XNIO-26 task-4] DEBUG ersatz.undertow.request.security - Attempting to authenticate /digest, authentication required: true
18:09:06.888 [XNIO-26 task-4] DEBUG ersatz.undertow.request.security - Found digest header Digest username="nobody", realm="DigestTesting", nonce="hpfn6ria1FkNMTU2NTQ3ODU0Njg4M43ljJJjkQgdWjydIbjfmAM=", uri="/digest", response="da130d7e2ba37c05a215b164acf0753b", qop=auth, nc=00000001, cnonce="3e1ca42d0869535c", algorithm=MD5, opaque="00000000000000000000000000000000" in HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="hpfn6ria1FkNMTU2NTQ3ODU0Njg4M43ljJJjkQgdWjydIbjfmAM=", uri="/digest", response="da130d7e2ba37c05a215b164acf0753b", qop=auth, nc=00000001, cnonce="3e1ca42d0869535c", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38673]} response {}}
18:09:06.888 [XNIO-26 task-4] DEBUG ersatz.undertow.request.security - Authentication failed with message UT000038: Authentication failed, requested user name 'nobody' and mechanism DIGEST for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="hpfn6ria1FkNMTU2NTQ3ODU0Njg4M43ljJJjkQgdWjydIbjfmAM=", uri="/digest", response="da130d7e2ba37c05a215b164acf0753b", qop=auth, nc=00000001, cnonce="3e1ca42d0869535c", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38673]} response {}}
18:09:06.888 [XNIO-26 task-4] DEBUG ersatz.undertow.request.security - Authentication outcome was NOT_AUTHENTICATED with method ersatz.undertow.security.impl.DigestAuthenticationMechanism@29eb45f7 for /digest
18:09:06.888 [XNIO-26 task-4] DEBUG ersatz.undertow.request.security - Sending authentication challenge for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="hpfn6ria1FkNMTU2NTQ3ODU0Njg4M43ljJJjkQgdWjydIbjfmAM=", uri="/digest", response="da130d7e2ba37c05a215b164acf0753b", qop=auth, nc=00000001, cnonce="3e1ca42d0869535c", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38673]} response {}}
18:09:06.889 [XNIO-26 task-4] DEBUG ersatz.undertow.request.security - Authentication result was CHALLENGE_SENT for /digest
18:09:06.897 [XNIO-26 task-5] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="BV0mjZI6v0wNMTU2NTQ3ODU0Njg4OI4WOZSQwJPFM9pfAxLVnos=", uri="/digest", response="bc346ca8236f07d5fb93dac3f3b84008", qop=auth, nc=00000001, cnonce="e81c1b362d8d669e", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38673]} response {}}
18:09:06.897 [XNIO-26 task-5] DEBUG ersatz.undertow.request.security - Attempting to authenticate /digest, authentication required: true
18:09:06.898 [XNIO-26 task-5] DEBUG ersatz.undertow.request.security - Found digest header Digest username="nobody", realm="DigestTesting", nonce="BV0mjZI6v0wNMTU2NTQ3ODU0Njg4OI4WOZSQwJPFM9pfAxLVnos=", uri="/digest", response="bc346ca8236f07d5fb93dac3f3b84008", qop=auth, nc=00000001, cnonce="e81c1b362d8d669e", algorithm=MD5, opaque="00000000000000000000000000000000" in HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="BV0mjZI6v0wNMTU2NTQ3ODU0Njg4OI4WOZSQwJPFM9pfAxLVnos=", uri="/digest", response="bc346ca8236f07d5fb93dac3f3b84008", qop=auth, nc=00000001, cnonce="e81c1b362d8d669e", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38673]} response {}}
18:09:06.898 [XNIO-26 task-5] DEBUG ersatz.undertow.request.security - Authentication failed with message UT000038: Authentication failed, requested user name 'nobody' and mechanism DIGEST for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="BV0mjZI6v0wNMTU2NTQ3ODU0Njg4OI4WOZSQwJPFM9pfAxLVnos=", uri="/digest", response="bc346ca8236f07d5fb93dac3f3b84008", qop=auth, nc=00000001, cnonce="e81c1b362d8d669e", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38673]} response {}}
18:09:06.898 [XNIO-26 task-5] DEBUG ersatz.undertow.request.security - Authentication outcome was NOT_AUTHENTICATED with method ersatz.undertow.security.impl.DigestAuthenticationMechanism@29eb45f7 for /digest
18:09:06.898 [XNIO-26 task-5] DEBUG ersatz.undertow.request.security - Sending authentication challenge for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="BV0mjZI6v0wNMTU2NTQ3ODU0Njg4OI4WOZSQwJPFM9pfAxLVnos=", uri="/digest", response="bc346ca8236f07d5fb93dac3f3b84008", qop=auth, nc=00000001, cnonce="e81c1b362d8d669e", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38673]} response {}}
18:09:06.900 [XNIO-26 task-5] DEBUG ersatz.undertow.request.security - Authentication result was CHALLENGE_SENT for /digest
18:09:06.905 [XNIO-26 task-6] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38673]} response {}}
18:09:06.905 [XNIO-26 task-6] DEBUG ersatz.undertow.request.security - Attempting to authenticate /digest, authentication required: true
18:09:06.905 [XNIO-26 task-6] DEBUG ersatz.undertow.request.security - Authentication outcome was NOT_ATTEMPTED with method ersatz.undertow.security.impl.DigestAuthenticationMechanism@29eb45f7 for /digest
18:09:06.905 [XNIO-26 task-6] DEBUG ersatz.undertow.request.security - Sending authentication challenge for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38673]} response {}}
18:09:06.905 [XNIO-26 task-6] DEBUG ersatz.undertow.request.security - Authentication result was CHALLENGE_SENT for /digest
18:09:06.911 [XNIO-26 task-7] DEBUG ersatz.undertow.request.security - Setting authentication required for exchange HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="6Y82Nv1d6ywNMTU2NTQ3ODU0NjkwNSYSoPnhxbaGMMr1XuRYD6E=", uri="/digest", response="ec4d9bd282e19d92cccd60d23d1f7a08", qop=auth, nc=00000001, cnonce="2cbafebd7b90ea29", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38673]} response {}}
18:09:06.911 [XNIO-26 task-7] DEBUG ersatz.undertow.request.security - Attempting to authenticate /digest, authentication required: true
18:09:06.911 [XNIO-26 task-7] DEBUG ersatz.undertow.request.security - Found digest header Digest username="nobody", realm="DigestTesting", nonce="6Y82Nv1d6ywNMTU2NTQ3ODU0NjkwNSYSoPnhxbaGMMr1XuRYD6E=", uri="/digest", response="ec4d9bd282e19d92cccd60d23d1f7a08", qop=auth, nc=00000001, cnonce="2cbafebd7b90ea29", algorithm=MD5, opaque="00000000000000000000000000000000" in HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="6Y82Nv1d6ywNMTU2NTQ3ODU0NjkwNSYSoPnhxbaGMMr1XuRYD6E=", uri="/digest", response="ec4d9bd282e19d92cccd60d23d1f7a08", qop=auth, nc=00000001, cnonce="2cbafebd7b90ea29", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38673]} response {}}
18:09:06.912 [XNIO-26 task-7] DEBUG ersatz.undertow.request.security - Authentication failed with message UT000038: Authentication failed, requested user name 'nobody' and mechanism DIGEST for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="6Y82Nv1d6ywNMTU2NTQ3ODU0NjkwNSYSoPnhxbaGMMr1XuRYD6E=", uri="/digest", response="ec4d9bd282e19d92cccd60d23d1f7a08", qop=auth, nc=00000001, cnonce="2cbafebd7b90ea29", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38673]} response {}}
18:09:06.912 [XNIO-26 task-7] DEBUG ersatz.undertow.request.security - Authentication outcome was NOT_AUTHENTICATED with method ersatz.undertow.security.impl.DigestAuthenticationMechanism@29eb45f7 for /digest
18:09:06.912 [XNIO-26 task-7] DEBUG ersatz.undertow.request.security - Sending authentication challenge for HttpServerExchange{ HEAD /digest request {Connection=[Keep-Alive], Authorization=[Digest username="nobody", realm="DigestTesting", nonce="6Y82Nv1d6ywNMTU2NTQ3ODU0NjkwNSYSoPnhxbaGMMr1XuRYD6E=", uri="/digest", response="ec4d9bd282e19d92cccd60d23d1f7a08", qop=auth, nc=00000001, cnonce="2cbafebd7b90ea29", algorithm=MD5, opaque="00000000000000000000000000000000"], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38673]} response {}}
18:09:06.912 [XNIO-26 task-7] DEBUG ersatz.undertow.request.security - Authentication result was CHALLENGE_SENT for /digest
18:09:06.914 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@d3d5415
18:09:06.921 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@1d960537
18:09:06.923 [XNIO-27 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-27 I/O-1', selector sun.nio.ch.EPollSelectorImpl@49f93e7f
18:09:06.930 [XNIO-27 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-27 I/O-2', selector sun.nio.ch.EPollSelectorImpl@19466bed
18:09:06.930 [XNIO-27 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-27 I/O-3', selector sun.nio.ch.EPollSelectorImpl@fb53cc
18:09:06.933 [XNIO-27 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-27 I/O-5', selector sun.nio.ch.EPollSelectorImpl@358cb1db
18:09:06.930 [XNIO-27 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-27 I/O-4', selector sun.nio.ch.EPollSelectorImpl@13a90b96
18:09:06.933 [XNIO-27 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-27 I/O-7', selector sun.nio.ch.EPollSelectorImpl@6a2e153a
18:09:06.933 [XNIO-27 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-27 I/O-6', selector sun.nio.ch.EPollSelectorImpl@7ef8d0c9
18:09:06.934 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:06.934 [XNIO-27 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-27 Accept', selector sun.nio.ch.EPollSelectorImpl@6717e4f0
18:09:06.935 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:06.942 [XNIO-27 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-27 I/O-8', selector sun.nio.ch.EPollSelectorImpl@60ffbe1a
18:09:06.960 [XNIO-27 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /pass (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:39591]}, cookies=[:]): <empty> }
18:09:06.961 [XNIO-27 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:06.964 [XNIO-27 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /pass (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:39591]}, cookies=[:]): <empty> }
18:09:06.965 [XNIO-27 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:06.966 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@1d960537
18:09:06.976 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@2afeb8e
18:09:06.978 [XNIO-28 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-28 I/O-1', selector sun.nio.ch.EPollSelectorImpl@3fcb117d
18:09:06.978 [XNIO-28 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-28 I/O-2', selector sun.nio.ch.EPollSelectorImpl@499c156f
18:09:06.982 [XNIO-28 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-28 I/O-3', selector sun.nio.ch.EPollSelectorImpl@57ab7b2a
18:09:06.983 [XNIO-28 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-28 I/O-4', selector sun.nio.ch.EPollSelectorImpl@2508e674
18:09:06.990 [XNIO-28 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-28 I/O-5', selector sun.nio.ch.EPollSelectorImpl@12a8a589
18:09:06.991 [XNIO-28 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-28 I/O-6', selector sun.nio.ch.EPollSelectorImpl@4b192c55
18:09:06.991 [XNIO-28 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-28 I/O-7', selector sun.nio.ch.EPollSelectorImpl@313a61d0
18:09:06.995 [XNIO-28 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-28 I/O-8', selector sun.nio.ch.EPollSelectorImpl@2063bfef
18:09:06.995 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:06.996 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:06.999 [XNIO-28 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-28 Accept', selector sun.nio.ch.EPollSelectorImpl@67ae1fbb
18:09:07.011 [XNIO-28 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:45453]}, cookies=[:]): <empty> }
18:09:07.012 [XNIO-28 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.019 [XNIO-28 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:45453]}, cookies=[:]): <empty> }
18:09:07.020 [XNIO-28 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.021 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@2afeb8e
18:09:07.025 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@e5dab8f
18:09:07.028 [XNIO-29 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-29 I/O-2', selector sun.nio.ch.EPollSelectorImpl@4631e0b4
18:09:07.028 [XNIO-29 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-29 I/O-1', selector sun.nio.ch.EPollSelectorImpl@5d78a303
18:09:07.031 [XNIO-29 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-29 I/O-3', selector sun.nio.ch.EPollSelectorImpl@5366d448
18:09:07.035 [XNIO-29 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-29 I/O-4', selector sun.nio.ch.EPollSelectorImpl@ba37f1c
18:09:07.035 [XNIO-29 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-29 I/O-5', selector sun.nio.ch.EPollSelectorImpl@6626583d
18:09:07.036 [XNIO-29 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-29 I/O-6', selector sun.nio.ch.EPollSelectorImpl@4a52a6a2
18:09:07.039 [XNIO-29 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-29 I/O-7', selector sun.nio.ch.EPollSelectorImpl@42cba77d
18:09:07.041 [XNIO-29 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-29 I/O-8', selector sun.nio.ch.EPollSelectorImpl@cf0f518
18:09:07.041 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:07.042 [XNIO-29 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-29 Accept', selector sun.nio.ch.EPollSelectorImpl@14e1fe89
18:09:07.067 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:07.091 [XNIO-29 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:34121]}, cookies=[:]): <empty> }
18:09:07.091 [XNIO-29 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.099 [XNIO-29 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:34121]}, cookies=[:]): <empty> }
18:09:07.100 [XNIO-29 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.101 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@e5dab8f
18:09:07.105 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@79dc515f
18:09:07.114 [XNIO-30 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-30 I/O-1', selector sun.nio.ch.EPollSelectorImpl@2932704a
18:09:07.127 [XNIO-30 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-30 I/O-2', selector sun.nio.ch.EPollSelectorImpl@2a37916f
18:09:07.131 [XNIO-30 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-30 I/O-3', selector sun.nio.ch.EPollSelectorImpl@16126825
18:09:07.133 [XNIO-30 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-30 I/O-4', selector sun.nio.ch.EPollSelectorImpl@271ef918
18:09:07.134 [XNIO-30 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-30 I/O-5', selector sun.nio.ch.EPollSelectorImpl@7291512d
18:09:07.135 [XNIO-30 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-30 I/O-6', selector sun.nio.ch.EPollSelectorImpl@33072f23
18:09:07.136 [XNIO-30 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-30 I/O-7', selector sun.nio.ch.EPollSelectorImpl@32382de7
18:09:07.139 [XNIO-30 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-30 I/O-8', selector sun.nio.ch.EPollSelectorImpl@9cd2fa
18:09:07.149 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:07.149 [XNIO-30 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-30 Accept', selector sun.nio.ch.EPollSelectorImpl@508c1e7
18:09:07.150 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:07.175 [XNIO-30 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:44787]}, cookies=[:]): <empty> }
18:09:07.175 [XNIO-30 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.187 [XNIO-30 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:44787]}, cookies=[:]): <empty> }
18:09:07.187 [XNIO-30 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.189 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@79dc515f
18:09:07.196 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@1c0dca57
18:09:07.199 [XNIO-31 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-31 I/O-1', selector sun.nio.ch.EPollSelectorImpl@1c1b1c08
18:09:07.207 [XNIO-31 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-31 I/O-2', selector sun.nio.ch.EPollSelectorImpl@28396092
18:09:07.208 [XNIO-31 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-31 I/O-3', selector sun.nio.ch.EPollSelectorImpl@2f29c7ae
18:09:07.211 [XNIO-31 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-31 I/O-5', selector sun.nio.ch.EPollSelectorImpl@77dabb1e
18:09:07.211 [XNIO-31 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-31 I/O-4', selector sun.nio.ch.EPollSelectorImpl@2615203c
18:09:07.218 [XNIO-31 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-31 I/O-6', selector sun.nio.ch.EPollSelectorImpl@1799d3f
18:09:07.223 [XNIO-31 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-31 I/O-7', selector sun.nio.ch.EPollSelectorImpl@226b731a
18:09:07.231 [XNIO-31 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-31 I/O-8', selector sun.nio.ch.EPollSelectorImpl@6d1d3f3e
18:09:07.231 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:07.231 [XNIO-31 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-31 Accept', selector sun.nio.ch.EPollSelectorImpl@1374ff9e
18:09:07.233 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:07.250 [XNIO-31 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:40871]}, cookies=[:]): <empty> }
18:09:07.251 [XNIO-31 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.258 [XNIO-31 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:40871]}, cookies=[:]): <empty> }
18:09:07.259 [XNIO-31 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.263 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@1c0dca57
18:09:07.269 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@53ebbab4
18:09:07.272 [XNIO-32 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-32 I/O-1', selector sun.nio.ch.EPollSelectorImpl@c17fc87
18:09:07.274 [XNIO-32 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-32 I/O-2', selector sun.nio.ch.EPollSelectorImpl@1b480850
18:09:07.277 [XNIO-32 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-32 I/O-3', selector sun.nio.ch.EPollSelectorImpl@237d63d3
18:09:07.278 [XNIO-32 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-32 I/O-4', selector sun.nio.ch.EPollSelectorImpl@29ecc79
18:09:07.279 [XNIO-32 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-32 I/O-5', selector sun.nio.ch.EPollSelectorImpl@6fbecdc6
18:09:07.285 [XNIO-32 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-32 I/O-6', selector sun.nio.ch.EPollSelectorImpl@1d1bfc3a
18:09:07.289 [XNIO-32 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-32 I/O-7', selector sun.nio.ch.EPollSelectorImpl@36ac96dd
18:09:07.291 [XNIO-32 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-32 I/O-8', selector sun.nio.ch.EPollSelectorImpl@7709ad5
18:09:07.301 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:07.301 [XNIO-32 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-32 Accept', selector sun.nio.ch.EPollSelectorImpl@4fd57ee
18:09:07.302 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:07.307 [XNIO-32 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37677]}, cookies=[:]): <empty> }
18:09:07.308 [XNIO-32 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.311 [XNIO-32 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37677]}, cookies=[:]): <empty> }
18:09:07.312 [XNIO-32 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.313 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@53ebbab4
18:09:07.318 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@4b4d3b49
18:09:07.325 [XNIO-33 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-33 I/O-1', selector sun.nio.ch.EPollSelectorImpl@113f2963
18:09:07.325 [XNIO-33 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-33 I/O-2', selector sun.nio.ch.EPollSelectorImpl@6c348c91
18:09:07.327 [XNIO-33 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-33 I/O-3', selector sun.nio.ch.EPollSelectorImpl@76982ff0
18:09:07.328 [XNIO-33 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-33 I/O-4', selector sun.nio.ch.EPollSelectorImpl@7457991a
18:09:07.328 [XNIO-33 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-33 I/O-5', selector sun.nio.ch.EPollSelectorImpl@7d074bc6
18:09:07.331 [XNIO-33 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-33 I/O-7', selector sun.nio.ch.EPollSelectorImpl@5547425f
18:09:07.331 [XNIO-33 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-33 I/O-6', selector sun.nio.ch.EPollSelectorImpl@17d14d64
18:09:07.331 [XNIO-33 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-33 I/O-8', selector sun.nio.ch.EPollSelectorImpl@2afffda3
18:09:07.331 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:07.331 [XNIO-33 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-33 Accept', selector sun.nio.ch.EPollSelectorImpl@7e14ee95
18:09:07.332 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:07.339 [XNIO-33 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:33033]}, cookies=[:]): <empty> }
18:09:07.340 [XNIO-33 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.346 [XNIO-33 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:33033]}, cookies=[:]): <empty> }
18:09:07.347 [XNIO-33 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.349 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@4b4d3b49
18:09:07.364 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@10c46308
18:09:07.368 [XNIO-34 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-34 I/O-1', selector sun.nio.ch.EPollSelectorImpl@1a1f708f
18:09:07.371 [XNIO-34 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-34 I/O-2', selector sun.nio.ch.EPollSelectorImpl@1b0d0407
18:09:07.378 [XNIO-34 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-34 I/O-3', selector sun.nio.ch.EPollSelectorImpl@21ab5619
18:09:07.379 [XNIO-34 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-34 I/O-4', selector sun.nio.ch.EPollSelectorImpl@4c1094f
18:09:07.379 [XNIO-34 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-34 I/O-5', selector sun.nio.ch.EPollSelectorImpl@6214e170
18:09:07.383 [XNIO-34 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-34 I/O-6', selector sun.nio.ch.EPollSelectorImpl@14105adc
18:09:07.387 [XNIO-34 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-34 I/O-7', selector sun.nio.ch.EPollSelectorImpl@68192296
18:09:07.391 [XNIO-34 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-34 I/O-8', selector sun.nio.ch.EPollSelectorImpl@34bac53c
18:09:07.397 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:07.398 [XNIO-34 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-34 Accept', selector sun.nio.ch.EPollSelectorImpl@6577aa8a
18:09:07.399 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:07.419 [XNIO-34 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:42183]}, cookies=[:]): <empty> }
18:09:07.420 [XNIO-34 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.427 [XNIO-34 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:42183]}, cookies=[:]): <empty> }
18:09:07.427 [XNIO-34 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.429 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@10c46308
18:09:07.442 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@6b87e073
18:09:07.444 [XNIO-35 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-35 I/O-1', selector sun.nio.ch.EPollSelectorImpl@63ed2536
18:09:07.447 [XNIO-35 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-35 I/O-2', selector sun.nio.ch.EPollSelectorImpl@6e1b717b
18:09:07.451 [XNIO-35 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-35 I/O-3', selector sun.nio.ch.EPollSelectorImpl@c6a7f07
18:09:07.451 [XNIO-35 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-35 I/O-5', selector sun.nio.ch.EPollSelectorImpl@5e0a2fa0
18:09:07.451 [XNIO-35 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-35 I/O-6', selector sun.nio.ch.EPollSelectorImpl@750b87ed
18:09:07.451 [XNIO-35 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-35 I/O-4', selector sun.nio.ch.EPollSelectorImpl@125108ee
18:09:07.455 [XNIO-35 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-35 I/O-8', selector sun.nio.ch.EPollSelectorImpl@115c1b0a
18:09:07.455 [XNIO-35 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-35 I/O-7', selector sun.nio.ch.EPollSelectorImpl@7250169b
18:09:07.455 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:07.455 [XNIO-35 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-35 Accept', selector sun.nio.ch.EPollSelectorImpl@6752f647
18:09:07.456 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:07.471 [XNIO-35 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38429]}, cookies=[:]): <empty> }
18:09:07.471 [XNIO-35 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.479 [XNIO-35 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38429]}, cookies=[:]): <empty> }
18:09:07.479 [XNIO-35 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.480 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@6b87e073
18:09:07.487 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@20abbca7
18:09:07.490 [XNIO-36 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-36 I/O-1', selector sun.nio.ch.EPollSelectorImpl@2d179c5e
18:09:07.491 [XNIO-36 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-36 I/O-2', selector sun.nio.ch.EPollSelectorImpl@660a85fd
18:09:07.492 [XNIO-36 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-36 I/O-3', selector sun.nio.ch.EPollSelectorImpl@55c9909a
18:09:07.492 [XNIO-36 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-36 I/O-4', selector sun.nio.ch.EPollSelectorImpl@1d382e95
18:09:07.499 [XNIO-36 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-36 I/O-5', selector sun.nio.ch.EPollSelectorImpl@433beb6a
18:09:07.505 [XNIO-36 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-36 I/O-6', selector sun.nio.ch.EPollSelectorImpl@41985c7b
18:09:07.507 [XNIO-36 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-36 I/O-7', selector sun.nio.ch.EPollSelectorImpl@435f2e01
18:09:07.508 [XNIO-36 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-36 I/O-8', selector sun.nio.ch.EPollSelectorImpl@127820b3
18:09:07.508 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:07.509 [XNIO-36 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-36 Accept', selector sun.nio.ch.EPollSelectorImpl@14105148
18:09:07.509 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:07.525 [XNIO-36 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:42621]}, cookies=[:]): <empty> }
18:09:07.526 [XNIO-36 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.531 [XNIO-36 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:42621]}, cookies=[:]): <empty> }
18:09:07.532 [XNIO-36 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.533 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@20abbca7
18:09:07.537 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@180b15e6
18:09:07.540 [XNIO-36 task-2] DEBUG ersatz.undertow.request.io - UT005013: An IOException occurred
java.nio.channels.ClosedChannelException: null
at sun.nio.ch.SocketChannelImpl.shutdownOutput(SocketChannelImpl.java:797)
at ersatz.xnio.nio.NioSocketConduit.write(NioSocketConduit.java:170)
at ersatz.undertow.server.protocol.http.HttpResponseConduit.processWrite(HttpResponseConduit.java:242)
at ersatz.undertow.server.protocol.http.HttpResponseConduit.flush(HttpResponseConduit.java:739)
at ersatz.undertow.conduits.HeadStreamSinkConduit.flush(HeadStreamSinkConduit.java:131)
at ersatz.xnio.conduits.ConduitStreamSinkChannel.flush(ConduitStreamSinkChannel.java:162)
at ersatz.undertow.channels.DetachableStreamSinkChannel.flush(DetachableStreamSinkChannel.java:119)
at ersatz.xnio.channels.Channels.flushBlocking(Channels.java:63)
at ersatz.undertow.io.UndertowOutputStream.close(UndertowOutputStream.java:344)
at ersatz.undertow.io.BlockingSenderImpl.close(BlockingSenderImpl.java:170)
at ersatz.undertow.io.DefaultIoCallback.onComplete(DefaultIoCallback.java:54)
at ersatz.undertow.io.BlockingSenderImpl.invokeOnComplete(BlockingSenderImpl.java:227)
at ersatz.undertow.io.BlockingSenderImpl.send(BlockingSenderImpl.java:95)
at ersatz.undertow.io.BlockingSenderImpl.send(BlockingSenderImpl.java:117)
at com.stehno.ersatz.ErsatzServer.send(ErsatzServer.groovy:494)
at com.stehno.ersatz.ErsatzServer.access$0(ErsatzServer.groovy)
at com.stehno.ersatz.ErsatzServer$1.handleRequest(ErsatzServer.groovy:370)
at ersatz.undertow.server.handlers.HttpTraceHandler.handleRequest(HttpTraceHandler.java:70)
at ersatz.undertow.server.handlers.encoding.EncodingHandler.handleRequest(EncodingHandler.java:72)
at ersatz.undertow.server.Connectors.executeRootHandler(Connectors.java:332)
at ersatz.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:812)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Suppressed: java.nio.channels.ClosedByInterruptException: null
at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:202)
at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:478)
at ersatz.xnio.nio.NioSocketConduit.write(NioSocketConduit.java:164)
... 22 common frames omitted
18:09:07.551 [XNIO-37 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-37 I/O-1', selector sun.nio.ch.EPollSelectorImpl@50455993
18:09:07.551 [XNIO-37 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-37 I/O-3', selector sun.nio.ch.EPollSelectorImpl@2854d413
18:09:07.553 [XNIO-37 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-37 I/O-4', selector sun.nio.ch.EPollSelectorImpl@62922f19
18:09:07.553 [XNIO-37 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-37 I/O-5', selector sun.nio.ch.EPollSelectorImpl@76deaea1
18:09:07.554 [XNIO-37 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-37 I/O-6', selector sun.nio.ch.EPollSelectorImpl@4e98a421
18:09:07.555 [XNIO-37 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-37 I/O-7', selector sun.nio.ch.EPollSelectorImpl@4c7795b3
18:09:07.551 [XNIO-37 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-37 I/O-2', selector sun.nio.ch.EPollSelectorImpl@d39a86d
18:09:07.557 [XNIO-37 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-37 I/O-8', selector sun.nio.ch.EPollSelectorImpl@3e5616c2
18:09:07.563 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:07.564 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:07.563 [XNIO-37 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-37 Accept', selector sun.nio.ch.EPollSelectorImpl@6ae72a32
18:09:07.571 [XNIO-37 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:40509]}, cookies=[:]): <empty> }
18:09:07.572 [XNIO-37 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.575 [XNIO-37 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:40509]}, cookies=[:]): <empty> }
18:09:07.575 [XNIO-37 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.576 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@180b15e6
18:09:07.577 [XNIO-37 I/O-2] DEBUG ersatz.undertow - Failed to schedule task ersatz.undertow.server.protocol.ParseTimeoutUpdater@279ff74 as worker is shutting down
java.util.concurrent.RejectedExecutionException: XNIO007007: Thread is terminating
at ersatz.xnio.nio.WorkerThread.executeAfter(WorkerThread.java:617)
at ersatz.undertow.util.WorkerUtils.executeAfter(WorkerUtils.java:46)
at ersatz.undertow.server.protocol.ParseTimeoutUpdater.handleSchedule(ParseTimeoutUpdater.java:107)
at ersatz.undertow.server.protocol.ParseTimeoutUpdater.connectionIdle(ParseTimeoutUpdater.java:85)
at ersatz.undertow.server.protocol.http.HttpReadListener.newRequest(HttpReadListener.java:118)
at ersatz.undertow.server.protocol.http.HttpReadListener.exchangeComplete(HttpReadListener.java:310)
at ersatz.undertow.server.protocol.http.HttpServerConnection.exchangeComplete(HttpServerConnection.java:232)
at ersatz.undertow.server.HttpServerExchange.invokeExchangeCompleteListeners(HttpServerExchange.java:1261)
at ersatz.undertow.server.HttpServerExchange.closeAndFlushResponse(HttpServerExchange.java:1675)
at ersatz.undertow.server.HttpServerExchange.endExchange(HttpServerExchange.java:1665)
at ersatz.undertow.server.AbstractServerConnection$CloseSetter.handleEvent(AbstractServerConnection.java:316)
at ersatz.undertow.server.AbstractServerConnection$CloseSetter.handleEvent(AbstractServerConnection.java:296)
at ersatz.xnio.ChannelListeners.invokeChannelListener(ChannelListeners.java:92)
at ersatz.xnio.StreamConnection.invokeCloseListener(StreamConnection.java:80)
at ersatz.xnio.Connection.writeClosed(Connection.java:117)
at ersatz.xnio.nio.AbstractNioStreamConnection.writeClosed(AbstractNioStreamConnection.java:47)
at ersatz.xnio.nio.NioSocketConduit.terminateWrites(NioSocketConduit.java:214)
at ersatz.xnio.nio.NioSocketConduit.truncateWrites(NioSocketConduit.java:228)
at ersatz.undertow.server.protocol.http.HttpResponseConduit.truncateWrites(HttpResponseConduit.java:774)
at ersatz.xnio.conduits.AbstractSinkConduit.truncateWrites(AbstractSinkConduit.java:82)
at ersatz.xnio.conduits.ConduitStreamSinkChannel.close(ConduitStreamSinkChannel.java:186)
at ersatz.xnio.IoUtils.safeClose(IoUtils.java:134)
at ersatz.xnio.conduits.WriteReadyHandler$ChannelListenerHandler.forceTermination(WriteReadyHandler.java:57)
at ersatz.xnio.nio.NioSocketConduit.forceTermination(NioSocketConduit.java:107)
at ersatz.xnio.nio.WorkerThread.run(WorkerThread.java:494)
18:09:07.582 [XNIO-37 task-2] ERROR ersatz.undertow.request - UT005071: Undertow request failed HttpServerExchange{ HEAD /handling request {Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:40509]} response {Connection=[keep-alive], Content-Length=[0], Date=[Sat, 10 Aug 2019 23:09:03 GMT]}}
java.lang.NullPointerException: null
at ersatz.undertow.server.protocol.http.HttpResponseConduit.bufferDone(HttpResponseConduit.java:286)
at ersatz.undertow.server.protocol.http.HttpResponseConduit.processWrite(HttpResponseConduit.java:264)
at ersatz.undertow.server.protocol.http.HttpResponseConduit.flush(HttpResponseConduit.java:739)
at ersatz.undertow.conduits.HeadStreamSinkConduit.flush(HeadStreamSinkConduit.java:131)
at ersatz.xnio.conduits.ConduitStreamSinkChannel.flush(ConduitStreamSinkChannel.java:162)
at ersatz.undertow.channels.DetachableStreamSinkChannel.flush(DetachableStreamSinkChannel.java:119)
at ersatz.xnio.channels.Channels.flushBlocking(Channels.java:63)
at ersatz.undertow.io.UndertowOutputStream.close(UndertowOutputStream.java:344)
at ersatz.undertow.io.BlockingSenderImpl.close(BlockingSenderImpl.java:170)
at ersatz.undertow.io.DefaultIoCallback.onComplete(DefaultIoCallback.java:54)
at ersatz.undertow.io.BlockingSenderImpl.invokeOnComplete(BlockingSenderImpl.java:227)
at ersatz.undertow.io.BlockingSenderImpl.send(BlockingSenderImpl.java:95)
at ersatz.undertow.io.BlockingSenderImpl.send(BlockingSenderImpl.java:117)
at com.stehno.ersatz.ErsatzServer.send(ErsatzServer.groovy:494)
at com.stehno.ersatz.ErsatzServer.access$0(ErsatzServer.groovy)
at com.stehno.ersatz.ErsatzServer$1.handleRequest(ErsatzServer.groovy:370)
at ersatz.undertow.server.handlers.HttpTraceHandler.handleRequest(HttpTraceHandler.java:70)
at ersatz.undertow.server.handlers.encoding.EncodingHandler.handleRequest(EncodingHandler.java:72)
at ersatz.undertow.server.Connectors.executeRootHandler(Connectors.java:332)
at ersatz.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:812)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
18:09:07.586 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@5daa98d4
18:09:07.591 [XNIO-38 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-38 I/O-1', selector sun.nio.ch.EPollSelectorImpl@10acf69c
18:09:07.595 [XNIO-38 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-38 I/O-2', selector sun.nio.ch.EPollSelectorImpl@3855067f
18:09:07.595 [XNIO-38 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-38 I/O-5', selector sun.nio.ch.EPollSelectorImpl@456c64f
18:09:07.595 [XNIO-38 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-38 I/O-3', selector sun.nio.ch.EPollSelectorImpl@2335dd3e
18:09:07.598 [XNIO-38 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-38 I/O-6', selector sun.nio.ch.EPollSelectorImpl@1b5d9399
18:09:07.595 [XNIO-38 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-38 I/O-4', selector sun.nio.ch.EPollSelectorImpl@21c59253
18:09:07.598 [XNIO-38 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-38 I/O-7', selector sun.nio.ch.EPollSelectorImpl@2994ddd4
18:09:07.598 [XNIO-38 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-38 I/O-8', selector sun.nio.ch.EPollSelectorImpl@407d5d65
18:09:07.598 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:07.598 [XNIO-38 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-38 Accept', selector sun.nio.ch.EPollSelectorImpl@7f152d9a
18:09:07.600 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:07.625 [XNIO-38 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38001]}, cookies=[:]): <empty> }
18:09:07.625 [XNIO-38 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.629 [XNIO-38 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:38001]}, cookies=[:]): <empty> }
18:09:07.629 [XNIO-38 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.630 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@5daa98d4
18:09:07.634 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@4e1eb73c
18:09:07.639 [XNIO-39 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-39 I/O-1', selector sun.nio.ch.EPollSelectorImpl@29271fae
18:09:07.645 [XNIO-39 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-39 I/O-2', selector sun.nio.ch.EPollSelectorImpl@41a370cf
18:09:07.646 [XNIO-39 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-39 I/O-3', selector sun.nio.ch.EPollSelectorImpl@1cea68c1
18:09:07.646 [XNIO-39 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-39 I/O-5', selector sun.nio.ch.EPollSelectorImpl@3cdb764f
18:09:07.646 [XNIO-39 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-39 I/O-4', selector sun.nio.ch.EPollSelectorImpl@1dfba85
18:09:07.647 [XNIO-39 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-39 I/O-6', selector sun.nio.ch.EPollSelectorImpl@398629f8
18:09:07.653 [XNIO-39 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-39 I/O-7', selector sun.nio.ch.EPollSelectorImpl@6cd0ea19
18:09:07.655 [XNIO-39 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-39 I/O-8', selector sun.nio.ch.EPollSelectorImpl@46f7778f
18:09:07.659 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:07.659 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:07.660 [XNIO-39 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-39 Accept', selector sun.nio.ch.EPollSelectorImpl@403245af
18:09:07.680 [XNIO-39 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:39095]}, cookies=[:]): <empty> }
18:09:07.680 [XNIO-39 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.684 [XNIO-39 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:39095]}, cookies=[:]): <empty> }
18:09:07.685 [XNIO-39 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.686 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@4e1eb73c
18:09:07.691 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@1e18adc0
18:09:07.693 [XNIO-40 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-40 I/O-1', selector sun.nio.ch.EPollSelectorImpl@5a63bff7
18:09:07.693 [XNIO-40 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-40 I/O-2', selector sun.nio.ch.EPollSelectorImpl@7b0dfac1
18:09:07.695 [XNIO-40 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-40 I/O-3', selector sun.nio.ch.EPollSelectorImpl@149e9f67
18:09:07.699 [XNIO-40 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-40 I/O-6', selector sun.nio.ch.EPollSelectorImpl@6b7397a1
18:09:07.695 [XNIO-40 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-40 I/O-4', selector sun.nio.ch.EPollSelectorImpl@164c6758
18:09:07.699 [XNIO-40 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-40 I/O-7', selector sun.nio.ch.EPollSelectorImpl@1e24af22
18:09:07.699 [XNIO-40 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-40 I/O-5', selector sun.nio.ch.EPollSelectorImpl@56e901a4
18:09:07.699 [XNIO-40 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-40 I/O-8', selector sun.nio.ch.EPollSelectorImpl@1d6e41ce
18:09:07.699 [XNIO-40 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-40 Accept', selector sun.nio.ch.EPollSelectorImpl@54959b95
18:09:07.699 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:07.700 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:07.712 [XNIO-40 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:41117]}, cookies=[:]): <empty> }
18:09:07.712 [XNIO-40 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.719 [XNIO-40 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:41117]}, cookies=[:]): <empty> }
18:09:07.719 [XNIO-40 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.720 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@1e18adc0
18:09:07.727 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@43149f6a
18:09:07.729 [XNIO-41 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-41 I/O-1', selector sun.nio.ch.EPollSelectorImpl@2479586
18:09:07.729 [XNIO-41 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-41 I/O-2', selector sun.nio.ch.EPollSelectorImpl@3fa81d35
18:09:07.739 [XNIO-41 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-41 I/O-3', selector sun.nio.ch.EPollSelectorImpl@3219d9c6
18:09:07.743 [XNIO-41 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-41 I/O-4', selector sun.nio.ch.EPollSelectorImpl@3eeac967
18:09:07.747 [XNIO-41 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-41 I/O-5', selector sun.nio.ch.EPollSelectorImpl@3a532d2b
18:09:07.751 [XNIO-41 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-41 I/O-8', selector sun.nio.ch.EPollSelectorImpl@185e08e9
18:09:07.747 [XNIO-41 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-41 I/O-6', selector sun.nio.ch.EPollSelectorImpl@697c8e0c
18:09:07.751 [XNIO-41 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-41 I/O-7', selector sun.nio.ch.EPollSelectorImpl@220930fe
18:09:07.751 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
18:09:07.751 [XNIO-41 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-41 Accept', selector sun.nio.ch.EPollSelectorImpl@13090425
18:09:07.753 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
18:09:07.759 [XNIO-41 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37875]}, cookies=[:]): <empty> }
18:09:07.760 [XNIO-41 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.767 [XNIO-41 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { HEAD /handling (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip], User-Agent=[okhttp/3.5.0], Host=[localhost:37875]}, cookies=[:]): <empty> }
18:09:07.767 [XNIO-41 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({}): <empty>
18:09:07.768 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@43149f6a