19:16:07.784 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@29e4aed9
19:16:07.790 [XNIO-110 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-110 I/O-1', selector sun.nio.ch.EPollSelectorImpl@689f3b6b
19:16:07.791 [XNIO-110 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-110 I/O-2', selector sun.nio.ch.EPollSelectorImpl@2f772062
19:16:07.792 [XNIO-110 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-110 I/O-4', selector sun.nio.ch.EPollSelectorImpl@6a09fb5a
19:16:07.792 [XNIO-110 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-110 I/O-3', selector sun.nio.ch.EPollSelectorImpl@7e423e94
19:16:07.794 [XNIO-110 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-110 I/O-5', selector sun.nio.ch.EPollSelectorImpl@c99486b
19:16:07.798 [XNIO-110 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-110 I/O-6', selector sun.nio.ch.EPollSelectorImpl@41f2cb3e
19:16:07.801 [XNIO-110 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-110 I/O-7', selector sun.nio.ch.EPollSelectorImpl@731a6a04
19:16:07.801 [XNIO-110 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-110 I/O-8', selector sun.nio.ch.EPollSelectorImpl@1cef8b63
19:16:07.803 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
19:16:07.803 [XNIO-110 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-110 Accept', selector sun.nio.ch.EPollSelectorImpl@75ffa003
19:16:07.804 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
19:16:07.813 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:07.813 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:07.813 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:34881
19:16:07.813 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {}->http://localhost:34881
19:16:07.813 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:34881
19:16:07.813 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:38680<->127.0.0.1:34881
19:16:07.813 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:07.813 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:07.813 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:07.813 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 >> OPTIONS /foo HTTP/1.1
19:16:07.813 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 >> Host: localhost:34881
19:16:07.813 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 >> Connection: Keep-Alive
19:16:07.813 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:07.813 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 >> Accept-Encoding: gzip,deflate
19:16:07.813 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:07.813 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 >> "Host: localhost:34881[\r][\n]"
19:16:07.813 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 >> "Connection: Keep-Alive[\r][\n]"
19:16:07.813 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:07.813 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:07.813 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 >> "[\r][\n]"
19:16:07.818 [XNIO-110 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:34881]}, cookies=[:]): <empty> }
19:16:07.819 [XNIO-110 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:07.819 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 << "HTTP/1.1 200 OK[\r][\n]"
19:16:07.819 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 << "Allow: GET[\r][\n]"
19:16:07.819 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 << "Allow: HEAD[\r][\n]"
19:16:07.819 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 << "Allow: POST[\r][\n]"
19:16:07.819 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 << "Connection: keep-alive[\r][\n]"
19:16:07.819 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 << "Content-Length: 0[\r][\n]"
19:16:07.819 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:07.819 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 << "[\r][\n]"
19:16:07.819 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 << HTTP/1.1 200 OK
19:16:07.820 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 << Allow: GET
19:16:07.820 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 << Allow: HEAD
19:16:07.820 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 << Allow: POST
19:16:07.820 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 << Connection: keep-alive
19:16:07.820 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 << Content-Length: 0
19:16:07.820 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:07.820 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:07.820 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:38680<->127.0.0.1:34881
19:16:07.820 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:07.831 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:07.831 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:07.831 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:34881
19:16:07.831 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:07.831 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:07.831 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:07.831 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 >> OPTIONS /foo HTTP/1.1
19:16:07.831 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 >> Host: localhost:34881
19:16:07.831 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 >> Connection: Keep-Alive
19:16:07.831 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:07.831 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 >> Accept-Encoding: gzip,deflate
19:16:07.831 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:07.831 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 >> "Host: localhost:34881[\r][\n]"
19:16:07.831 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 >> "Connection: Keep-Alive[\r][\n]"
19:16:07.831 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:07.831 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:07.831 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 >> "[\r][\n]"
19:16:07.836 [XNIO-110 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:34881]}, cookies=[:]): <empty> }
19:16:07.837 [XNIO-110 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:07.837 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 << "HTTP/1.1 200 OK[\r][\n]"
19:16:07.837 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 << "Allow: GET[\r][\n]"
19:16:07.837 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 << "Allow: HEAD[\r][\n]"
19:16:07.837 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 << "Allow: POST[\r][\n]"
19:16:07.837 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 << "Connection: keep-alive[\r][\n]"
19:16:07.837 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 << "Content-Length: 0[\r][\n]"
19:16:07.837 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:07.837 [Test worker] DEBUG org.apache.http.wire - http-outgoing-109 << "[\r][\n]"
19:16:07.837 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 << HTTP/1.1 200 OK
19:16:07.837 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 << Allow: GET
19:16:07.837 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 << Allow: HEAD
19:16:07.837 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 << Allow: POST
19:16:07.837 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 << Connection: keep-alive
19:16:07.837 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 << Content-Length: 0
19:16:07.837 [Test worker] DEBUG org.apache.http.headers - http-outgoing-109 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:07.837 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:07.837 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:38680<->127.0.0.1:34881
19:16:07.837 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:07.839 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@29e4aed9
19:16:07.842 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@1549f41c
19:16:07.845 [XNIO-111 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-111 I/O-1', selector sun.nio.ch.EPollSelectorImpl@12f840b
19:16:07.845 [XNIO-111 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-111 I/O-2', selector sun.nio.ch.EPollSelectorImpl@3af3fc30
19:16:07.845 [XNIO-111 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-111 I/O-3', selector sun.nio.ch.EPollSelectorImpl@12584fe0
19:16:07.846 [XNIO-111 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-111 I/O-4', selector sun.nio.ch.EPollSelectorImpl@3668c47
19:16:07.846 [XNIO-111 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-111 I/O-5', selector sun.nio.ch.EPollSelectorImpl@401840c7
19:16:07.850 [XNIO-111 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-111 I/O-6', selector sun.nio.ch.EPollSelectorImpl@45f40de
19:16:07.854 [XNIO-111 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-111 I/O-7', selector sun.nio.ch.EPollSelectorImpl@7f1b57ab
19:16:07.857 [XNIO-111 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-111 I/O-8', selector sun.nio.ch.EPollSelectorImpl@45e4b50
19:16:07.857 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
19:16:07.857 [XNIO-111 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-111 Accept', selector sun.nio.ch.EPollSelectorImpl@44cfb242
19:16:07.858 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
19:16:07.860 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:07.860 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:07.860 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:33095
19:16:07.860 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {s}->https://localhost:33095
19:16:07.860 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:33095
19:16:07.860 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Connecting socket to localhost/127.0.0.1:33095 with timeout 0
19:16:07.861 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Enabled protocols: [TLSv1, TLSv1.1, TLSv1.2]
19:16:07.861 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Enabled cipher suites:[TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA384, TLS_ECDH_RSA_WITH_AES_256_CBC_SHA384, TLS_DHE_RSA_WITH_AES_256_CBC_SHA256, TLS_DHE_DSS_WITH_AES_256_CBC_SHA256, TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA, TLS_ECDH_RSA_WITH_AES_256_CBC_SHA, TLS_DHE_RSA_WITH_AES_256_CBC_SHA, TLS_DHE_DSS_WITH_AES_256_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_DSS_WITH_AES_128_CBC_SHA256, TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_DSS_WITH_AES_128_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDH_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDH_RSA_WITH_AES_256_GCM_SHA384, TLS_DHE_RSA_WITH_AES_256_GCM_SHA384, TLS_DHE_DSS_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_DSS_WITH_AES_128_GCM_SHA256, TLS_EMPTY_RENEGOTIATION_INFO_SCSV]
19:16:07.861 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Starting handshake
19:16:07.888 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Secure session established
19:16:07.888 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - negotiated protocol: TLSv1.2
19:16:07.888 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - negotiated cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
19:16:07.888 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - peer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
19:16:07.888 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - issuer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
19:16:07.888 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:34720<->127.0.0.1:33095
19:16:07.888 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:07.888 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:07.888 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:07.888 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 >> OPTIONS /foo HTTP/1.1
19:16:07.889 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 >> Host: localhost:33095
19:16:07.889 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 >> Connection: Keep-Alive
19:16:07.889 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:07.889 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 >> Accept-Encoding: gzip,deflate
19:16:07.889 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:07.889 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 >> "Host: localhost:33095[\r][\n]"
19:16:07.889 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 >> "Connection: Keep-Alive[\r][\n]"
19:16:07.889 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:07.889 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:07.889 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 >> "[\r][\n]"
19:16:07.890 [XNIO-111 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:33095]}, cookies=[:]): <empty> }
19:16:07.891 [XNIO-111 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:07.891 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 << "HTTP/1.1 200 OK[\r][\n]"
19:16:07.891 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 << "Allow: GET[\r][\n]"
19:16:07.891 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 << "Allow: HEAD[\r][\n]"
19:16:07.891 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 << "Allow: POST[\r][\n]"
19:16:07.891 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 << "Connection: keep-alive[\r][\n]"
19:16:07.891 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 << "Content-Length: 0[\r][\n]"
19:16:07.891 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:07.891 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 << "[\r][\n]"
19:16:07.892 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 << HTTP/1.1 200 OK
19:16:07.892 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 << Allow: GET
19:16:07.892 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 << Allow: HEAD
19:16:07.892 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 << Allow: POST
19:16:07.892 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 << Connection: keep-alive
19:16:07.892 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 << Content-Length: 0
19:16:07.892 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:07.892 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:07.892 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:34720<->127.0.0.1:33095
19:16:07.892 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:07.893 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:07.893 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:07.893 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:33095
19:16:07.893 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:07.893 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:07.893 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:07.893 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 >> OPTIONS /foo HTTP/1.1
19:16:07.893 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 >> Host: localhost:33095
19:16:07.893 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 >> Connection: Keep-Alive
19:16:07.893 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:07.893 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 >> Accept-Encoding: gzip,deflate
19:16:07.893 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:07.893 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 >> "Host: localhost:33095[\r][\n]"
19:16:07.893 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 >> "Connection: Keep-Alive[\r][\n]"
19:16:07.893 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:07.893 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:07.893 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 >> "[\r][\n]"
19:16:07.898 [XNIO-111 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:33095]}, cookies=[:]): <empty> }
19:16:07.899 [XNIO-111 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:07.899 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 << "HTTP/1.1 200 OK[\r][\n]"
19:16:07.899 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 << "Allow: GET[\r][\n]"
19:16:07.899 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 << "Allow: HEAD[\r][\n]"
19:16:07.899 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 << "Allow: POST[\r][\n]"
19:16:07.899 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 << "Connection: keep-alive[\r][\n]"
19:16:07.899 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 << "Content-Length: 0[\r][\n]"
19:16:07.899 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:07.899 [Test worker] DEBUG org.apache.http.wire - http-outgoing-110 << "[\r][\n]"
19:16:07.899 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 << HTTP/1.1 200 OK
19:16:07.899 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 << Allow: GET
19:16:07.899 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 << Allow: HEAD
19:16:07.899 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 << Allow: POST
19:16:07.899 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 << Connection: keep-alive
19:16:07.899 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 << Content-Length: 0
19:16:07.899 [Test worker] DEBUG org.apache.http.headers - http-outgoing-110 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:07.900 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:07.900 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:34720<->127.0.0.1:33095
19:16:07.900 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:07.901 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@1549f41c
19:16:07.904 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@7301a520
19:16:07.907 [XNIO-112 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-112 I/O-2', selector sun.nio.ch.EPollSelectorImpl@14064b29
19:16:07.907 [XNIO-111 task-4] 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.protocols.ssl.SslConduit.doWrap(SslConduit.java:892)
at ersatz.undertow.protocols.ssl.SslConduit.write(SslConduit.java:371)
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.AbstractFixedLengthStreamSinkConduit.flush(AbstractFixedLengthStreamSinkConduit.java:229)
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)
... 24 common frames omitted
19:16:07.910 [XNIO-112 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-112 I/O-3', selector sun.nio.ch.EPollSelectorImpl@71dc5c78
19:16:07.914 [XNIO-112 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-112 I/O-4', selector sun.nio.ch.EPollSelectorImpl@3f713914
19:16:07.914 [XNIO-112 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-112 I/O-5', selector sun.nio.ch.EPollSelectorImpl@7e300dea
19:16:07.914 [XNIO-112 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-112 I/O-6', selector sun.nio.ch.EPollSelectorImpl@6591fbc4
19:16:07.910 [XNIO-112 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-112 I/O-1', selector sun.nio.ch.EPollSelectorImpl@41cfea70
19:16:07.915 [XNIO-112 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-112 I/O-7', selector sun.nio.ch.EPollSelectorImpl@3fc4d500
19:16:07.915 [XNIO-112 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-112 I/O-8', selector sun.nio.ch.EPollSelectorImpl@7bc517af
19:16:07.915 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
19:16:07.915 [XNIO-112 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-112 Accept', selector sun.nio.ch.EPollSelectorImpl@2fcc4597
19:16:07.916 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
19:16:07.919 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:07.919 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:07.919 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:36751
19:16:07.919 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {}->http://localhost:36751
19:16:07.919 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:36751
19:16:07.919 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:37422<->127.0.0.1:36751
19:16:07.919 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:07.919 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:07.919 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:07.919 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 >> OPTIONS /foo HTTP/1.1
19:16:07.919 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 >> Host: localhost:36751
19:16:07.919 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 >> Connection: Keep-Alive
19:16:07.919 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:07.919 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 >> Accept-Encoding: gzip,deflate
19:16:07.919 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:07.919 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 >> "Host: localhost:36751[\r][\n]"
19:16:07.920 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 >> "Connection: Keep-Alive[\r][\n]"
19:16:07.920 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:07.920 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:07.920 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 >> "[\r][\n]"
19:16:07.920 [XNIO-112 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:36751]}, cookies=[:]): <empty> }
19:16:07.920 [XNIO-112 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:07.921 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 << "HTTP/1.1 200 OK[\r][\n]"
19:16:07.921 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 << "Allow: GET[\r][\n]"
19:16:07.921 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 << "Allow: HEAD[\r][\n]"
19:16:07.921 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 << "Allow: POST[\r][\n]"
19:16:07.921 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 << "Connection: keep-alive[\r][\n]"
19:16:07.921 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 << "Content-Length: 0[\r][\n]"
19:16:07.921 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:07.921 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 << "[\r][\n]"
19:16:07.921 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 << HTTP/1.1 200 OK
19:16:07.921 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 << Allow: GET
19:16:07.921 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 << Allow: HEAD
19:16:07.921 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 << Allow: POST
19:16:07.921 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 << Connection: keep-alive
19:16:07.921 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 << Content-Length: 0
19:16:07.921 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:07.921 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:07.921 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:37422<->127.0.0.1:36751
19:16:07.921 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:07.923 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:07.923 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:07.923 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:36751
19:16:07.923 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:07.923 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:07.923 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:07.923 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 >> OPTIONS /foo HTTP/1.1
19:16:07.923 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 >> Host: localhost:36751
19:16:07.923 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 >> Connection: Keep-Alive
19:16:07.923 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:07.923 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 >> Accept-Encoding: gzip,deflate
19:16:07.923 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:07.923 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 >> "Host: localhost:36751[\r][\n]"
19:16:07.923 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 >> "Connection: Keep-Alive[\r][\n]"
19:16:07.923 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:07.923 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:07.923 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 >> "[\r][\n]"
19:16:07.923 [XNIO-112 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:36751]}, cookies=[:]): <empty> }
19:16:07.924 [XNIO-112 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:07.924 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 << "HTTP/1.1 200 OK[\r][\n]"
19:16:07.924 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 << "Allow: GET[\r][\n]"
19:16:07.924 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 << "Allow: HEAD[\r][\n]"
19:16:07.924 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 << "Allow: POST[\r][\n]"
19:16:07.924 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 << "Connection: keep-alive[\r][\n]"
19:16:07.924 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 << "Content-Length: 0[\r][\n]"
19:16:07.924 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:07.924 [Test worker] DEBUG org.apache.http.wire - http-outgoing-111 << "[\r][\n]"
19:16:07.924 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 << HTTP/1.1 200 OK
19:16:07.924 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 << Allow: GET
19:16:07.924 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 << Allow: HEAD
19:16:07.924 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 << Allow: POST
19:16:07.924 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 << Connection: keep-alive
19:16:07.924 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 << Content-Length: 0
19:16:07.924 [Test worker] DEBUG org.apache.http.headers - http-outgoing-111 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:07.924 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:07.924 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:37422<->127.0.0.1:36751
19:16:07.924 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:07.925 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@7301a520
19:16:07.927 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@17895a9e
19:16:07.928 [XNIO-113 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-113 I/O-3', selector sun.nio.ch.EPollSelectorImpl@4286473
19:16:07.928 [XNIO-113 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-113 I/O-5', selector sun.nio.ch.EPollSelectorImpl@5ed45bed
19:16:07.928 [XNIO-113 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-113 I/O-4', selector sun.nio.ch.EPollSelectorImpl@1135c707
19:16:07.928 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
19:16:07.928 [XNIO-113 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-113 I/O-7', selector sun.nio.ch.EPollSelectorImpl@85c91a
19:16:07.928 [XNIO-113 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-113 I/O-8', selector sun.nio.ch.EPollSelectorImpl@2b9e5251
19:16:07.929 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
19:16:07.928 [XNIO-113 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-113 I/O-2', selector sun.nio.ch.EPollSelectorImpl@7abe4d1b
19:16:07.928 [XNIO-113 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-113 I/O-1', selector sun.nio.ch.EPollSelectorImpl@17c208eb
19:16:07.928 [XNIO-113 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-113 I/O-6', selector sun.nio.ch.EPollSelectorImpl@745ae17d
19:16:07.928 [XNIO-113 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-113 Accept', selector sun.nio.ch.EPollSelectorImpl@53baa2b9
19:16:07.930 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:07.930 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:07.930 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:39679
19:16:07.931 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {s}->https://localhost:39679
19:16:07.931 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:39679
19:16:07.931 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Connecting socket to localhost/127.0.0.1:39679 with timeout 0
19:16:07.931 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Enabled protocols: [TLSv1, TLSv1.1, TLSv1.2]
19:16:07.931 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Enabled cipher suites:[TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA384, TLS_ECDH_RSA_WITH_AES_256_CBC_SHA384, TLS_DHE_RSA_WITH_AES_256_CBC_SHA256, TLS_DHE_DSS_WITH_AES_256_CBC_SHA256, TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA, TLS_ECDH_RSA_WITH_AES_256_CBC_SHA, TLS_DHE_RSA_WITH_AES_256_CBC_SHA, TLS_DHE_DSS_WITH_AES_256_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_DSS_WITH_AES_128_CBC_SHA256, TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_DSS_WITH_AES_128_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDH_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDH_RSA_WITH_AES_256_GCM_SHA384, TLS_DHE_RSA_WITH_AES_256_GCM_SHA384, TLS_DHE_DSS_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_DSS_WITH_AES_128_GCM_SHA256, TLS_EMPTY_RENEGOTIATION_INFO_SCSV]
19:16:07.931 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Starting handshake
19:16:07.957 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Secure session established
19:16:07.957 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - negotiated protocol: TLSv1.2
19:16:07.957 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - negotiated cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
19:16:07.957 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - peer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
19:16:07.957 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - issuer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
19:16:07.957 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:45092<->127.0.0.1:39679
19:16:07.957 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:07.957 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:07.957 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:07.957 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 >> OPTIONS /foo HTTP/1.1
19:16:07.957 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 >> Host: localhost:39679
19:16:07.957 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 >> Connection: Keep-Alive
19:16:07.957 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:07.957 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 >> Accept-Encoding: gzip,deflate
19:16:07.957 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:07.957 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 >> "Host: localhost:39679[\r][\n]"
19:16:07.957 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 >> "Connection: Keep-Alive[\r][\n]"
19:16:07.957 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:07.957 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:07.957 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 >> "[\r][\n]"
19:16:07.959 [XNIO-113 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:39679]}, cookies=[:]): <empty> }
19:16:07.960 [XNIO-113 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:07.960 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 << "HTTP/1.1 200 OK[\r][\n]"
19:16:07.960 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 << "Allow: GET[\r][\n]"
19:16:07.960 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 << "Allow: HEAD[\r][\n]"
19:16:07.960 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 << "Allow: POST[\r][\n]"
19:16:07.960 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 << "Connection: keep-alive[\r][\n]"
19:16:07.960 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 << "Content-Length: 0[\r][\n]"
19:16:07.960 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:07.960 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 << "[\r][\n]"
19:16:07.960 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 << HTTP/1.1 200 OK
19:16:07.960 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 << Allow: GET
19:16:07.960 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 << Allow: HEAD
19:16:07.960 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 << Allow: POST
19:16:07.960 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 << Connection: keep-alive
19:16:07.960 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 << Content-Length: 0
19:16:07.960 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:07.961 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:07.961 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:45092<->127.0.0.1:39679
19:16:07.961 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:07.962 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:07.962 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:07.962 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:39679
19:16:07.962 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:07.962 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:07.962 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:07.962 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 >> OPTIONS /foo HTTP/1.1
19:16:07.962 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 >> Host: localhost:39679
19:16:07.962 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 >> Connection: Keep-Alive
19:16:07.962 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:07.962 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 >> Accept-Encoding: gzip,deflate
19:16:07.962 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:07.962 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 >> "Host: localhost:39679[\r][\n]"
19:16:07.962 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 >> "Connection: Keep-Alive[\r][\n]"
19:16:07.962 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:07.962 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:07.962 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 >> "[\r][\n]"
19:16:07.966 [XNIO-113 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:39679]}, cookies=[:]): <empty> }
19:16:07.967 [XNIO-113 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:07.967 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 << "HTTP/1.1 200 OK[\r][\n]"
19:16:07.967 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 << "Allow: GET[\r][\n]"
19:16:07.967 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 << "Allow: HEAD[\r][\n]"
19:16:07.967 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 << "Allow: POST[\r][\n]"
19:16:07.967 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 << "Connection: keep-alive[\r][\n]"
19:16:07.967 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 << "Content-Length: 0[\r][\n]"
19:16:07.967 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:07.967 [Test worker] DEBUG org.apache.http.wire - http-outgoing-112 << "[\r][\n]"
19:16:07.968 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 << HTTP/1.1 200 OK
19:16:07.968 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 << Allow: GET
19:16:07.968 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 << Allow: HEAD
19:16:07.968 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 << Allow: POST
19:16:07.968 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 << Connection: keep-alive
19:16:07.968 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 << Content-Length: 0
19:16:07.968 [Test worker] DEBUG org.apache.http.headers - http-outgoing-112 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:07.968 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:07.968 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:45092<->127.0.0.1:39679
19:16:07.968 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:07.968 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@17895a9e
19:16:07.975 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@5a929a82
19:16:07.978 [XNIO-114 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-114 I/O-1', selector sun.nio.ch.EPollSelectorImpl@325eaf03
19:16:07.978 [XNIO-114 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-114 I/O-2', selector sun.nio.ch.EPollSelectorImpl@37bffdc2
19:16:07.979 [XNIO-114 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-114 I/O-3', selector sun.nio.ch.EPollSelectorImpl@15313fd3
19:16:07.979 [XNIO-114 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-114 I/O-4', selector sun.nio.ch.EPollSelectorImpl@3ca78aa4
19:16:07.979 [XNIO-114 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-114 I/O-5', selector sun.nio.ch.EPollSelectorImpl@1e5c6797
19:16:07.979 [XNIO-114 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-114 I/O-6', selector sun.nio.ch.EPollSelectorImpl@68a95ac
19:16:07.982 [XNIO-114 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-114 I/O-7', selector sun.nio.ch.EPollSelectorImpl@689237c3
19:16:07.984 [XNIO-114 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-114 I/O-8', selector sun.nio.ch.EPollSelectorImpl@6aa48ae8
19:16:07.985 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
19:16:07.985 [XNIO-114 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-114 Accept', selector sun.nio.ch.EPollSelectorImpl@23d59e85
19:16:07.986 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
19:16:07.991 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:07.991 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:07.991 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:40767
19:16:07.991 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {}->http://localhost:40767
19:16:07.992 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:40767
19:16:07.992 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:36542<->127.0.0.1:40767
19:16:07.992 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:07.992 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:07.992 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:07.992 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 >> OPTIONS /foo HTTP/1.1
19:16:07.992 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 >> Host: localhost:40767
19:16:07.992 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 >> Connection: Keep-Alive
19:16:07.992 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:07.992 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 >> Accept-Encoding: gzip,deflate
19:16:07.992 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:07.992 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 >> "Host: localhost:40767[\r][\n]"
19:16:07.992 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 >> "Connection: Keep-Alive[\r][\n]"
19:16:07.992 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:07.992 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:07.992 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 >> "[\r][\n]"
19:16:07.993 [XNIO-114 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:40767]}, cookies=[:]): <empty> }
19:16:07.993 [XNIO-114 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:07.993 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 << "HTTP/1.1 200 OK[\r][\n]"
19:16:07.993 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 << "Allow: GET[\r][\n]"
19:16:07.993 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 << "Allow: HEAD[\r][\n]"
19:16:07.993 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 << "Allow: POST[\r][\n]"
19:16:07.993 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 << "Connection: keep-alive[\r][\n]"
19:16:07.993 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 << "Content-Length: 0[\r][\n]"
19:16:07.993 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:07.993 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 << "[\r][\n]"
19:16:07.993 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 << HTTP/1.1 200 OK
19:16:07.993 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 << Allow: GET
19:16:07.993 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 << Allow: HEAD
19:16:07.993 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 << Allow: POST
19:16:07.993 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 << Connection: keep-alive
19:16:07.993 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 << Content-Length: 0
19:16:07.993 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:07.994 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:07.994 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:36542<->127.0.0.1:40767
19:16:07.994 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:07.995 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:07.995 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:07.995 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:40767
19:16:07.995 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:07.995 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:07.995 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:07.995 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 >> OPTIONS /foo HTTP/1.1
19:16:07.995 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 >> Host: localhost:40767
19:16:07.995 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 >> Connection: Keep-Alive
19:16:07.995 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:07.995 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 >> Accept-Encoding: gzip,deflate
19:16:07.995 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:07.995 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 >> "Host: localhost:40767[\r][\n]"
19:16:07.995 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 >> "Connection: Keep-Alive[\r][\n]"
19:16:07.995 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:07.995 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:07.995 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 >> "[\r][\n]"
19:16:07.998 [XNIO-114 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:40767]}, cookies=[:]): <empty> }
19:16:07.999 [XNIO-114 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:07.999 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 << "HTTP/1.1 200 OK[\r][\n]"
19:16:07.999 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 << "Allow: GET[\r][\n]"
19:16:07.999 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 << "Allow: HEAD[\r][\n]"
19:16:07.999 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 << "Allow: POST[\r][\n]"
19:16:07.999 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 << "Connection: keep-alive[\r][\n]"
19:16:07.999 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 << "Content-Length: 0[\r][\n]"
19:16:07.999 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:07.999 [Test worker] DEBUG org.apache.http.wire - http-outgoing-113 << "[\r][\n]"
19:16:07.999 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 << HTTP/1.1 200 OK
19:16:07.999 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 << Allow: GET
19:16:07.999 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 << Allow: HEAD
19:16:07.999 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 << Allow: POST
19:16:07.999 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 << Connection: keep-alive
19:16:07.999 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 << Content-Length: 0
19:16:07.999 [Test worker] DEBUG org.apache.http.headers - http-outgoing-113 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:07.999 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:07.999 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:36542<->127.0.0.1:40767
19:16:07.999 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:08.000 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@5a929a82
19:16:08.003 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@31bc50ea
19:16:08.010 [XNIO-115 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-115 I/O-2', selector sun.nio.ch.EPollSelectorImpl@561916f2
19:16:08.010 [XNIO-115 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-115 I/O-1', selector sun.nio.ch.EPollSelectorImpl@19f8115f
19:16:08.011 [XNIO-115 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-115 I/O-3', selector sun.nio.ch.EPollSelectorImpl@4533347
19:16:08.012 [XNIO-115 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-115 I/O-4', selector sun.nio.ch.EPollSelectorImpl@56e98184
19:16:08.012 [XNIO-115 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-115 I/O-5', selector sun.nio.ch.EPollSelectorImpl@5acb3ec3
19:16:08.012 [XNIO-115 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-115 I/O-7', selector sun.nio.ch.EPollSelectorImpl@1cc85f88
19:16:08.012 [XNIO-115 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-115 I/O-6', selector sun.nio.ch.EPollSelectorImpl@1c605e6e
19:16:08.012 [XNIO-115 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-115 I/O-8', selector sun.nio.ch.EPollSelectorImpl@f2f72d6
19:16:08.012 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
19:16:08.012 [XNIO-115 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-115 Accept', selector sun.nio.ch.EPollSelectorImpl@4891ba36
19:16:08.013 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
19:16:08.014 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:08.015 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:08.015 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:34479
19:16:08.015 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {s}->https://localhost:34479
19:16:08.015 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:34479
19:16:08.015 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Connecting socket to localhost/127.0.0.1:34479 with timeout 0
19:16:08.015 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Enabled protocols: [TLSv1, TLSv1.1, TLSv1.2]
19:16:08.015 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Enabled cipher suites:[TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA384, TLS_ECDH_RSA_WITH_AES_256_CBC_SHA384, TLS_DHE_RSA_WITH_AES_256_CBC_SHA256, TLS_DHE_DSS_WITH_AES_256_CBC_SHA256, TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA, TLS_ECDH_RSA_WITH_AES_256_CBC_SHA, TLS_DHE_RSA_WITH_AES_256_CBC_SHA, TLS_DHE_DSS_WITH_AES_256_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_DSS_WITH_AES_128_CBC_SHA256, TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_DSS_WITH_AES_128_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDH_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDH_RSA_WITH_AES_256_GCM_SHA384, TLS_DHE_RSA_WITH_AES_256_GCM_SHA384, TLS_DHE_DSS_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_DSS_WITH_AES_128_GCM_SHA256, TLS_EMPTY_RENEGOTIATION_INFO_SCSV]
19:16:08.015 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Starting handshake
19:16:08.029 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Secure session established
19:16:08.029 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - negotiated protocol: TLSv1.2
19:16:08.029 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - negotiated cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
19:16:08.029 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - peer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
19:16:08.029 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - issuer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
19:16:08.029 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:57320<->127.0.0.1:34479
19:16:08.029 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:08.029 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:08.029 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:08.029 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 >> OPTIONS /foo HTTP/1.1
19:16:08.029 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 >> Host: localhost:34479
19:16:08.029 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 >> Connection: Keep-Alive
19:16:08.029 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:08.029 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 >> Accept-Encoding: gzip,deflate
19:16:08.029 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:08.029 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 >> "Host: localhost:34479[\r][\n]"
19:16:08.029 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 >> "Connection: Keep-Alive[\r][\n]"
19:16:08.029 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:08.029 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:08.029 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 >> "[\r][\n]"
19:16:08.030 [XNIO-115 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:34479]}, cookies=[:]): <empty> }
19:16:08.030 [XNIO-115 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:08.031 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 << "HTTP/1.1 200 OK[\r][\n]"
19:16:08.031 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 << "Allow: GET[\r][\n]"
19:16:08.031 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 << "Allow: HEAD[\r][\n]"
19:16:08.031 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 << "Allow: POST[\r][\n]"
19:16:08.031 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 << "Connection: keep-alive[\r][\n]"
19:16:08.031 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 << "Content-Length: 0[\r][\n]"
19:16:08.031 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:08.031 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 << "[\r][\n]"
19:16:08.031 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 << HTTP/1.1 200 OK
19:16:08.031 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 << Allow: GET
19:16:08.031 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 << Allow: HEAD
19:16:08.031 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 << Allow: POST
19:16:08.031 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 << Connection: keep-alive
19:16:08.031 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 << Content-Length: 0
19:16:08.031 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:08.031 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:08.031 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:57320<->127.0.0.1:34479
19:16:08.031 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:08.032 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:08.032 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:08.032 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:34479
19:16:08.032 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:08.032 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:08.032 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:08.032 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 >> OPTIONS /foo HTTP/1.1
19:16:08.032 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 >> Host: localhost:34479
19:16:08.032 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 >> Connection: Keep-Alive
19:16:08.032 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:08.032 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 >> Accept-Encoding: gzip,deflate
19:16:08.032 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:08.032 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 >> "Host: localhost:34479[\r][\n]"
19:16:08.032 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 >> "Connection: Keep-Alive[\r][\n]"
19:16:08.032 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:08.032 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:08.032 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 >> "[\r][\n]"
19:16:08.033 [XNIO-115 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:34479]}, cookies=[:]): <empty> }
19:16:08.033 [XNIO-115 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:08.033 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 << "HTTP/1.1 200 OK[\r][\n]"
19:16:08.033 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 << "Allow: GET[\r][\n]"
19:16:08.033 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 << "Allow: HEAD[\r][\n]"
19:16:08.033 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 << "Allow: POST[\r][\n]"
19:16:08.033 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 << "Connection: keep-alive[\r][\n]"
19:16:08.033 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 << "Content-Length: 0[\r][\n]"
19:16:08.033 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:08.033 [Test worker] DEBUG org.apache.http.wire - http-outgoing-114 << "[\r][\n]"
19:16:08.033 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 << HTTP/1.1 200 OK
19:16:08.033 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 << Allow: GET
19:16:08.033 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 << Allow: HEAD
19:16:08.033 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 << Allow: POST
19:16:08.033 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 << Connection: keep-alive
19:16:08.033 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 << Content-Length: 0
19:16:08.033 [Test worker] DEBUG org.apache.http.headers - http-outgoing-114 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:08.034 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:08.034 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:57320<->127.0.0.1:34479
19:16:08.034 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:08.034 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@31bc50ea
19:16:08.037 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@1cf4f03d
19:16:08.038 [XNIO-116 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-116 I/O-1', selector sun.nio.ch.EPollSelectorImpl@8f58d21
19:16:08.038 [XNIO-116 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-116 I/O-2', selector sun.nio.ch.EPollSelectorImpl@4dfb1fd7
19:16:08.038 [XNIO-116 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-116 I/O-4', selector sun.nio.ch.EPollSelectorImpl@4c61033c
19:16:08.038 [XNIO-116 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-116 I/O-3', selector sun.nio.ch.EPollSelectorImpl@19eb9dd1
19:16:08.038 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
19:16:08.038 [XNIO-116 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-116 I/O-8', selector sun.nio.ch.EPollSelectorImpl@76f5589
19:16:08.038 [XNIO-116 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-116 I/O-5', selector sun.nio.ch.EPollSelectorImpl@6de2fa19
19:16:08.038 [XNIO-116 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-116 I/O-7', selector sun.nio.ch.EPollSelectorImpl@2e32b0b1
19:16:08.038 [XNIO-116 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-116 I/O-6', selector sun.nio.ch.EPollSelectorImpl@675cfcda
19:16:08.039 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
19:16:08.039 [XNIO-116 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-116 Accept', selector sun.nio.ch.EPollSelectorImpl@6b3af925
19:16:08.041 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:08.041 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:08.042 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:41119
19:16:08.042 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {}->http://localhost:41119
19:16:08.042 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:41119
19:16:08.042 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:37456<->127.0.0.1:41119
19:16:08.042 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:08.042 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:08.042 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:08.042 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 >> OPTIONS /foo HTTP/1.1
19:16:08.042 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 >> Host: localhost:41119
19:16:08.042 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 >> Connection: Keep-Alive
19:16:08.042 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:08.042 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 >> Accept-Encoding: gzip,deflate
19:16:08.042 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:08.042 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 >> "Host: localhost:41119[\r][\n]"
19:16:08.042 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 >> "Connection: Keep-Alive[\r][\n]"
19:16:08.042 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:08.042 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:08.042 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 >> "[\r][\n]"
19:16:08.046 [XNIO-116 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:41119]}, cookies=[:]): <empty> }
19:16:08.047 [XNIO-116 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:08.047 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 << "HTTP/1.1 200 OK[\r][\n]"
19:16:08.047 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 << "Allow: GET[\r][\n]"
19:16:08.047 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 << "Allow: HEAD[\r][\n]"
19:16:08.047 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 << "Allow: POST[\r][\n]"
19:16:08.047 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 << "Connection: keep-alive[\r][\n]"
19:16:08.047 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 << "Content-Length: 0[\r][\n]"
19:16:08.047 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:08.047 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 << "[\r][\n]"
19:16:08.047 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 << HTTP/1.1 200 OK
19:16:08.047 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 << Allow: GET
19:16:08.047 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 << Allow: HEAD
19:16:08.047 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 << Allow: POST
19:16:08.047 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 << Connection: keep-alive
19:16:08.047 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 << Content-Length: 0
19:16:08.047 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:08.047 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:08.047 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:37456<->127.0.0.1:41119
19:16:08.047 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:08.049 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:08.049 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:08.049 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:41119
19:16:08.049 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:08.049 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:08.049 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:08.049 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 >> OPTIONS /foo HTTP/1.1
19:16:08.049 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 >> Host: localhost:41119
19:16:08.050 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 >> Connection: Keep-Alive
19:16:08.050 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:08.050 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 >> Accept-Encoding: gzip,deflate
19:16:08.050 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:08.050 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 >> "Host: localhost:41119[\r][\n]"
19:16:08.050 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 >> "Connection: Keep-Alive[\r][\n]"
19:16:08.050 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:08.050 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:08.050 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 >> "[\r][\n]"
19:16:08.050 [XNIO-116 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:41119]}, cookies=[:]): <empty> }
19:16:08.051 [XNIO-116 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:08.051 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 << "HTTP/1.1 200 OK[\r][\n]"
19:16:08.051 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 << "Allow: GET[\r][\n]"
19:16:08.051 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 << "Allow: HEAD[\r][\n]"
19:16:08.051 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 << "Allow: POST[\r][\n]"
19:16:08.051 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 << "Connection: keep-alive[\r][\n]"
19:16:08.051 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 << "Content-Length: 0[\r][\n]"
19:16:08.051 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:08.051 [Test worker] DEBUG org.apache.http.wire - http-outgoing-115 << "[\r][\n]"
19:16:08.051 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 << HTTP/1.1 200 OK
19:16:08.051 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 << Allow: GET
19:16:08.051 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 << Allow: HEAD
19:16:08.051 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 << Allow: POST
19:16:08.051 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 << Connection: keep-alive
19:16:08.051 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 << Content-Length: 0
19:16:08.051 [Test worker] DEBUG org.apache.http.headers - http-outgoing-115 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:08.051 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:08.051 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:37456<->127.0.0.1:41119
19:16:08.051 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:08.052 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@1cf4f03d
19:16:08.055 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@5108ff8
19:16:08.058 [XNIO-117 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-117 I/O-1', selector sun.nio.ch.EPollSelectorImpl@15397951
19:16:08.059 [XNIO-117 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-117 I/O-2', selector sun.nio.ch.EPollSelectorImpl@736987c0
19:16:08.059 [XNIO-117 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-117 I/O-4', selector sun.nio.ch.EPollSelectorImpl@3d047e92
19:16:08.059 [XNIO-117 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-117 I/O-5', selector sun.nio.ch.EPollSelectorImpl@13a939aa
19:16:08.059 [XNIO-117 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-117 I/O-3', selector sun.nio.ch.EPollSelectorImpl@631790d7
19:16:08.059 [XNIO-117 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-117 I/O-7', selector sun.nio.ch.EPollSelectorImpl@4f8f96a
19:16:08.059 [XNIO-117 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-117 I/O-6', selector sun.nio.ch.EPollSelectorImpl@75f7734
19:16:08.059 [XNIO-117 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-117 I/O-8', selector sun.nio.ch.EPollSelectorImpl@5fed6c1c
19:16:08.059 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
19:16:08.059 [XNIO-117 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-117 Accept', selector sun.nio.ch.EPollSelectorImpl@79564ab9
19:16:08.060 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
19:16:08.062 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:08.062 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:08.062 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:44547
19:16:08.062 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {s}->https://localhost:44547
19:16:08.062 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:44547
19:16:08.062 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Connecting socket to localhost/127.0.0.1:44547 with timeout 0
19:16:08.063 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Enabled protocols: [TLSv1, TLSv1.1, TLSv1.2]
19:16:08.063 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Enabled cipher suites:[TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA384, TLS_ECDH_RSA_WITH_AES_256_CBC_SHA384, TLS_DHE_RSA_WITH_AES_256_CBC_SHA256, TLS_DHE_DSS_WITH_AES_256_CBC_SHA256, TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA, TLS_ECDH_RSA_WITH_AES_256_CBC_SHA, TLS_DHE_RSA_WITH_AES_256_CBC_SHA, TLS_DHE_DSS_WITH_AES_256_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_DSS_WITH_AES_128_CBC_SHA256, TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_DSS_WITH_AES_128_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDH_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDH_RSA_WITH_AES_256_GCM_SHA384, TLS_DHE_RSA_WITH_AES_256_GCM_SHA384, TLS_DHE_DSS_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_DSS_WITH_AES_128_GCM_SHA256, TLS_EMPTY_RENEGOTIATION_INFO_SCSV]
19:16:08.063 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Starting handshake
19:16:08.083 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Secure session established
19:16:08.084 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - negotiated protocol: TLSv1.2
19:16:08.084 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - negotiated cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
19:16:08.084 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - peer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
19:16:08.084 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - issuer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
19:16:08.084 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:44716<->127.0.0.1:44547
19:16:08.084 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:08.084 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:08.084 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:08.084 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 >> OPTIONS /foo HTTP/1.1
19:16:08.084 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 >> Host: localhost:44547
19:16:08.084 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 >> Connection: Keep-Alive
19:16:08.084 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:08.084 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 >> Accept-Encoding: gzip,deflate
19:16:08.084 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:08.084 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 >> "Host: localhost:44547[\r][\n]"
19:16:08.084 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 >> "Connection: Keep-Alive[\r][\n]"
19:16:08.084 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:08.084 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:08.084 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 >> "[\r][\n]"
19:16:08.090 [XNIO-117 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:44547]}, cookies=[:]): <empty> }
19:16:08.091 [XNIO-117 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:08.092 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 << "HTTP/1.1 200 OK[\r][\n]"
19:16:08.092 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 << "Allow: GET[\r][\n]"
19:16:08.092 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 << "Allow: HEAD[\r][\n]"
19:16:08.092 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 << "Allow: POST[\r][\n]"
19:16:08.092 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 << "Connection: keep-alive[\r][\n]"
19:16:08.092 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 << "Content-Length: 0[\r][\n]"
19:16:08.092 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:08.092 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 << "[\r][\n]"
19:16:08.092 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 << HTTP/1.1 200 OK
19:16:08.092 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 << Allow: GET
19:16:08.092 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 << Allow: HEAD
19:16:08.092 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 << Allow: POST
19:16:08.092 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 << Connection: keep-alive
19:16:08.092 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 << Content-Length: 0
19:16:08.092 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:08.092 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:08.092 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:44716<->127.0.0.1:44547
19:16:08.092 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:08.093 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:08.093 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:08.093 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:44547
19:16:08.093 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:08.093 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:08.093 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:08.093 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 >> OPTIONS /foo HTTP/1.1
19:16:08.093 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 >> Host: localhost:44547
19:16:08.093 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 >> Connection: Keep-Alive
19:16:08.093 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:08.093 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 >> Accept-Encoding: gzip,deflate
19:16:08.093 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:08.093 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 >> "Host: localhost:44547[\r][\n]"
19:16:08.093 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 >> "Connection: Keep-Alive[\r][\n]"
19:16:08.093 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:08.093 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:08.093 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 >> "[\r][\n]"
19:16:08.098 [XNIO-117 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:44547]}, cookies=[:]): <empty> }
19:16:08.098 [XNIO-117 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:08.098 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 << "HTTP/1.1 200 OK[\r][\n]"
19:16:08.099 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 << "Allow: GET[\r][\n]"
19:16:08.099 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 << "Allow: HEAD[\r][\n]"
19:16:08.099 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 << "Allow: POST[\r][\n]"
19:16:08.099 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 << "Connection: keep-alive[\r][\n]"
19:16:08.099 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 << "Content-Length: 0[\r][\n]"
19:16:08.099 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:08.099 [Test worker] DEBUG org.apache.http.wire - http-outgoing-116 << "[\r][\n]"
19:16:08.099 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 << HTTP/1.1 200 OK
19:16:08.099 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 << Allow: GET
19:16:08.099 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 << Allow: HEAD
19:16:08.099 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 << Allow: POST
19:16:08.099 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 << Connection: keep-alive
19:16:08.099 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 << Content-Length: 0
19:16:08.099 [Test worker] DEBUG org.apache.http.headers - http-outgoing-116 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:08.099 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:08.099 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:44716<->127.0.0.1:44547
19:16:08.099 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:08.100 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@5108ff8
19:16:08.103 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@6dc99cdb
19:16:08.106 [XNIO-118 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-118 I/O-1', selector sun.nio.ch.EPollSelectorImpl@4403fe4
19:16:08.106 [XNIO-118 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-118 I/O-3', selector sun.nio.ch.EPollSelectorImpl@4e1635b5
19:16:08.106 [XNIO-118 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-118 I/O-2', selector sun.nio.ch.EPollSelectorImpl@25a92511
19:16:08.111 [XNIO-118 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-118 I/O-4', selector sun.nio.ch.EPollSelectorImpl@286e7556
19:16:08.112 [XNIO-118 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-118 I/O-5', selector sun.nio.ch.EPollSelectorImpl@316c97a0
19:16:08.114 [XNIO-118 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-118 I/O-6', selector sun.nio.ch.EPollSelectorImpl@84b5f07
19:16:08.117 [XNIO-118 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-118 I/O-7', selector sun.nio.ch.EPollSelectorImpl@4cabe5e9
19:16:08.117 [XNIO-118 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-118 I/O-8', selector sun.nio.ch.EPollSelectorImpl@1192a815
19:16:08.118 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
19:16:08.118 [XNIO-118 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-118 Accept', selector sun.nio.ch.EPollSelectorImpl@22267351
19:16:08.119 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
19:16:08.122 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:08.123 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:08.123 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:37677
19:16:08.123 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {}->http://localhost:37677
19:16:08.123 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:37677
19:16:08.123 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:52266<->127.0.0.1:37677
19:16:08.123 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:08.123 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:08.123 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:08.123 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 >> OPTIONS /foo HTTP/1.1
19:16:08.123 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 >> Host: localhost:37677
19:16:08.123 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 >> Connection: Keep-Alive
19:16:08.123 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:08.123 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 >> Accept-Encoding: gzip,deflate
19:16:08.123 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:08.123 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 >> "Host: localhost:37677[\r][\n]"
19:16:08.123 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 >> "Connection: Keep-Alive[\r][\n]"
19:16:08.123 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:08.123 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:08.123 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 >> "[\r][\n]"
19:16:08.124 [XNIO-118 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:37677]}, cookies=[:]): <empty> }
19:16:08.125 [XNIO-118 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:08.125 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 << "HTTP/1.1 200 OK[\r][\n]"
19:16:08.125 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 << "Allow: GET[\r][\n]"
19:16:08.125 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 << "Allow: HEAD[\r][\n]"
19:16:08.125 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 << "Allow: POST[\r][\n]"
19:16:08.125 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 << "Connection: keep-alive[\r][\n]"
19:16:08.125 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 << "Content-Length: 0[\r][\n]"
19:16:08.125 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:08.125 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 << "[\r][\n]"
19:16:08.125 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 << HTTP/1.1 200 OK
19:16:08.125 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 << Allow: GET
19:16:08.125 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 << Allow: HEAD
19:16:08.125 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 << Allow: POST
19:16:08.125 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 << Connection: keep-alive
19:16:08.125 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 << Content-Length: 0
19:16:08.125 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:08.125 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:08.125 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:52266<->127.0.0.1:37677
19:16:08.125 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:08.127 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:08.127 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:08.127 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:37677
19:16:08.127 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:08.127 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:08.127 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:08.127 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 >> OPTIONS /foo HTTP/1.1
19:16:08.127 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 >> Host: localhost:37677
19:16:08.127 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 >> Connection: Keep-Alive
19:16:08.127 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:08.127 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 >> Accept-Encoding: gzip,deflate
19:16:08.127 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:08.127 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 >> "Host: localhost:37677[\r][\n]"
19:16:08.127 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 >> "Connection: Keep-Alive[\r][\n]"
19:16:08.127 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:08.127 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:08.127 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 >> "[\r][\n]"
19:16:08.130 [XNIO-118 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:37677]}, cookies=[:]): <empty> }
19:16:08.131 [XNIO-118 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:08.131 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 << "HTTP/1.1 200 OK[\r][\n]"
19:16:08.131 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 << "Allow: GET[\r][\n]"
19:16:08.131 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 << "Allow: HEAD[\r][\n]"
19:16:08.131 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 << "Allow: POST[\r][\n]"
19:16:08.131 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 << "Connection: keep-alive[\r][\n]"
19:16:08.131 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 << "Content-Length: 0[\r][\n]"
19:16:08.131 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:08.131 [Test worker] DEBUG org.apache.http.wire - http-outgoing-117 << "[\r][\n]"
19:16:08.131 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 << HTTP/1.1 200 OK
19:16:08.131 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 << Allow: GET
19:16:08.131 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 << Allow: HEAD
19:16:08.131 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 << Allow: POST
19:16:08.131 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 << Connection: keep-alive
19:16:08.131 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 << Content-Length: 0
19:16:08.131 [Test worker] DEBUG org.apache.http.headers - http-outgoing-117 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:08.131 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:08.131 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:52266<->127.0.0.1:37677
19:16:08.131 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:08.132 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@6dc99cdb
19:16:08.134 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@24613416
19:16:08.135 [XNIO-119 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-119 I/O-1', selector sun.nio.ch.EPollSelectorImpl@3034db30
19:16:08.135 [XNIO-119 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-119 I/O-2', selector sun.nio.ch.EPollSelectorImpl@532bb92f
19:16:08.135 [XNIO-119 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-119 I/O-3', selector sun.nio.ch.EPollSelectorImpl@77c9079d
19:16:08.139 [XNIO-119 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-119 I/O-4', selector sun.nio.ch.EPollSelectorImpl@44194233
19:16:08.149 [XNIO-119 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-119 I/O-6', selector sun.nio.ch.EPollSelectorImpl@45da681f
19:16:08.149 [XNIO-119 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-119 I/O-8', selector sun.nio.ch.EPollSelectorImpl@2a5cb161
19:16:08.149 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
19:16:08.149 [XNIO-119 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-119 Accept', selector sun.nio.ch.EPollSelectorImpl@51bd0b5f
19:16:08.150 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
19:16:08.151 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:08.152 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:08.152 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:36723
19:16:08.152 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {s}->https://localhost:36723
19:16:08.152 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:36723
19:16:08.152 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Connecting socket to localhost/127.0.0.1:36723 with timeout 0
19:16:08.152 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Enabled protocols: [TLSv1, TLSv1.1, TLSv1.2]
19:16:08.152 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Enabled cipher suites:[TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA384, TLS_ECDH_RSA_WITH_AES_256_CBC_SHA384, TLS_DHE_RSA_WITH_AES_256_CBC_SHA256, TLS_DHE_DSS_WITH_AES_256_CBC_SHA256, TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA, TLS_ECDH_RSA_WITH_AES_256_CBC_SHA, TLS_DHE_RSA_WITH_AES_256_CBC_SHA, TLS_DHE_DSS_WITH_AES_256_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_DSS_WITH_AES_128_CBC_SHA256, TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_DSS_WITH_AES_128_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDH_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDH_RSA_WITH_AES_256_GCM_SHA384, TLS_DHE_RSA_WITH_AES_256_GCM_SHA384, TLS_DHE_DSS_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_DSS_WITH_AES_128_GCM_SHA256, TLS_EMPTY_RENEGOTIATION_INFO_SCSV]
19:16:08.152 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Starting handshake
19:16:08.149 [XNIO-119 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-119 I/O-5', selector sun.nio.ch.EPollSelectorImpl@4790b5fb
19:16:08.149 [XNIO-119 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-119 I/O-7', selector sun.nio.ch.EPollSelectorImpl@2169c41c
19:16:08.170 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Secure session established
19:16:08.170 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - negotiated protocol: TLSv1.2
19:16:08.170 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - negotiated cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
19:16:08.170 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - peer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
19:16:08.170 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - issuer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
19:16:08.170 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:44346<->127.0.0.1:36723
19:16:08.170 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:08.170 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:08.170 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:08.170 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 >> OPTIONS /foo HTTP/1.1
19:16:08.170 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 >> Host: localhost:36723
19:16:08.170 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 >> Connection: Keep-Alive
19:16:08.170 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:08.170 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 >> Accept-Encoding: gzip,deflate
19:16:08.170 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:08.170 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 >> "Host: localhost:36723[\r][\n]"
19:16:08.170 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 >> "Connection: Keep-Alive[\r][\n]"
19:16:08.170 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:08.170 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:08.170 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 >> "[\r][\n]"
19:16:08.171 [XNIO-119 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:36723]}, cookies=[:]): <empty> }
19:16:08.171 [XNIO-119 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:08.172 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 << "HTTP/1.1 200 OK[\r][\n]"
19:16:08.172 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 << "Allow: GET[\r][\n]"
19:16:08.172 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 << "Allow: HEAD[\r][\n]"
19:16:08.172 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 << "Allow: POST[\r][\n]"
19:16:08.172 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 << "Connection: keep-alive[\r][\n]"
19:16:08.172 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 << "Content-Length: 0[\r][\n]"
19:16:08.172 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:08.172 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 << "[\r][\n]"
19:16:08.172 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 << HTTP/1.1 200 OK
19:16:08.172 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 << Allow: GET
19:16:08.172 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 << Allow: HEAD
19:16:08.172 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 << Allow: POST
19:16:08.172 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 << Connection: keep-alive
19:16:08.172 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 << Content-Length: 0
19:16:08.172 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:08.172 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:08.172 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:44346<->127.0.0.1:36723
19:16:08.172 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:08.173 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
19:16:08.173 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
19:16:08.173 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:36723
19:16:08.173 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
19:16:08.173 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
19:16:08.173 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
19:16:08.173 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 >> OPTIONS /foo HTTP/1.1
19:16:08.173 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 >> Host: localhost:36723
19:16:08.173 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 >> Connection: Keep-Alive
19:16:08.173 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)
19:16:08.173 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 >> Accept-Encoding: gzip,deflate
19:16:08.173 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
19:16:08.173 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 >> "Host: localhost:36723[\r][\n]"
19:16:08.173 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 >> "Connection: Keep-Alive[\r][\n]"
19:16:08.173 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_222)[\r][\n]"
19:16:08.173 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 >> "Accept-Encoding: gzip,deflate[\r][\n]"
19:16:08.173 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 >> "[\r][\n]"
19:16:08.174 [XNIO-119 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Request: { OPTIONS /foo (query=[:], headers={Connection=[Keep-Alive], Accept-Encoding=[gzip,deflate], User-Agent=[Apache-HttpClient/4.5.2 (Java/1.8.0_222)], Host=[localhost:36723]}, cookies=[:]): <empty> }
19:16:08.174 [XNIO-119 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
19:16:08.175 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 << "HTTP/1.1 200 OK[\r][\n]"
19:16:08.175 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 << "Allow: GET[\r][\n]"
19:16:08.175 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 << "Allow: HEAD[\r][\n]"
19:16:08.175 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 << "Allow: POST[\r][\n]"
19:16:08.175 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 << "Connection: keep-alive[\r][\n]"
19:16:08.175 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 << "Content-Length: 0[\r][\n]"
19:16:08.175 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 << "Date: Sun, 11 Aug 2019 00:15:57 GMT[\r][\n]"
19:16:08.175 [Test worker] DEBUG org.apache.http.wire - http-outgoing-118 << "[\r][\n]"
19:16:08.175 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 << HTTP/1.1 200 OK
19:16:08.175 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 << Allow: GET
19:16:08.175 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 << Allow: HEAD
19:16:08.175 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 << Allow: POST
19:16:08.175 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 << Connection: keep-alive
19:16:08.175 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 << Content-Length: 0
19:16:08.175 [Test worker] DEBUG org.apache.http.headers - http-outgoing-118 << Date: Sun, 11 Aug 2019 00:15:57 GMT
19:16:08.175 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
19:16:08.175 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:44346<->127.0.0.1:36723
19:16:08.175 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
19:16:08.175 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@24613416