Class groovyx.net.http.ApacheHttpOptionsSpec

10

tests

0

failures

0

ignored

4.312s

duration

100%

successful

Tests

Test Duration Result
options(): HTTP 3.002s passed
options(): HTTPS 0.357s passed
options(Class,Closure): HTTP 0.099s passed
options(Class,Closure): HTTPS 0.078s passed
options(Class,Consumer): HTTP 0.057s passed
options(Class,Consumer): HTTPS 0.158s passed
options(Closure): HTTP 0.161s passed
options(Closure): HTTPS 0.172s passed
options(Consumer): HTTP 0.088s passed
options(Consumer): HTTPS 0.140s passed

Standard output

06:24:08.763 [Test worker] DEBUG ersatz.jboss.logging - Logging Provider: ersatz.jboss.logging.Slf4jLoggerProvider
06:24:08.820 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@67d3c80c
06:24:08.864 [Test worker] INFO ersatz.xnio - XNIO version 3.3.8.Final
06:24:08.923 [Test worker] INFO ersatz.xnio.nio - XNIO NIO Implementation Version 3.3.8.Final
06:24:09.118 [XNIO-1 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 I/O-1', selector sun.nio.ch.EPollSelectorImpl@5ca7cb95
06:24:09.123 [XNIO-1 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 I/O-2', selector sun.nio.ch.EPollSelectorImpl@3bf16b74
06:24:09.129 [XNIO-1 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 I/O-3', selector sun.nio.ch.EPollSelectorImpl@457ad108
06:24:09.143 [XNIO-1 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 I/O-4', selector sun.nio.ch.EPollSelectorImpl@49be5971
06:24:09.162 [XNIO-1 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 I/O-5', selector sun.nio.ch.EPollSelectorImpl@42af1547
06:24:09.174 [XNIO-1 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 I/O-6', selector sun.nio.ch.EPollSelectorImpl@637d3a78
06:24:09.190 [XNIO-1 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 I/O-8', selector sun.nio.ch.EPollSelectorImpl@5d4ccdd
06:24:09.198 [XNIO-1 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 I/O-7', selector sun.nio.ch.EPollSelectorImpl@5eefdf56
06:24:09.208 [XNIO-1 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-1 Accept', selector sun.nio.ch.EPollSelectorImpl@5352df4e
06:24:09.212 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
06:24:09.301 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
06:24:10.154 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:10.188 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:10.192 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:36451
06:24:10.242 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {}->http://localhost:36451
06:24:10.245 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:36451
06:24:10.248 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:49932<->127.0.0.1:36451
06:24:10.248 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:10.248 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:10.249 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:10.263 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> OPTIONS /foo HTTP/1.1
06:24:10.264 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> Host: localhost:36451
06:24:10.264 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> Connection: Keep-Alive
06:24:10.264 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:10.264 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> Accept-Encoding: gzip,deflate
06:24:10.265 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:10.265 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "Host: localhost:36451[\r][\n]"
06:24:10.267 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "Connection: Keep-Alive[\r][\n]"
06:24:10.267 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:10.267 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:10.267 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "[\r][\n]"
06:24:10.451 [XNIO-1 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_131)], Host=[localhost:36451]}, cookies=[:]): <empty> }
06:24:10.539 [XNIO-1 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:10.605 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "HTTP/1.1 200 OK[\r][\n]"
06:24:10.605 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "Allow: GET[\r][\n]"
06:24:10.605 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "Allow: HEAD[\r][\n]"
06:24:10.606 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "Allow: POST[\r][\n]"
06:24:10.606 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "Connection: keep-alive[\r][\n]"
06:24:10.606 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "Content-Length: 0[\r][\n]"
06:24:10.606 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:10.606 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "[\r][\n]"
06:24:10.617 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 << HTTP/1.1 200 OK
06:24:10.618 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 << Allow: GET
06:24:10.618 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 << Allow: HEAD
06:24:10.618 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 << Allow: POST
06:24:10.618 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 << Connection: keep-alive
06:24:10.619 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 << Content-Length: 0
06:24:10.621 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:10.649 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:10.650 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:49932<->127.0.0.1:36451
06:24:10.651 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:10.825 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:10.825 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:10.829 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:36451
06:24:10.830 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:10.831 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:10.831 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:10.832 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> OPTIONS /foo HTTP/1.1
06:24:10.832 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> Host: localhost:36451
06:24:10.832 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> Connection: Keep-Alive
06:24:10.832 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:10.832 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> Accept-Encoding: gzip,deflate
06:24:10.832 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:10.832 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "Host: localhost:36451[\r][\n]"
06:24:10.832 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "Connection: Keep-Alive[\r][\n]"
06:24:10.832 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:10.832 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:10.833 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "[\r][\n]"
06:24:10.835 [XNIO-1 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_131)], Host=[localhost:36451]}, cookies=[:]): <empty> }
06:24:10.881 [XNIO-1 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:10.881 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "HTTP/1.1 200 OK[\r][\n]"
06:24:10.882 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "Allow: GET[\r][\n]"
06:24:10.882 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "Allow: HEAD[\r][\n]"
06:24:10.882 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "Allow: POST[\r][\n]"
06:24:10.882 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "Connection: keep-alive[\r][\n]"
06:24:10.885 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "Content-Length: 0[\r][\n]"
06:24:10.885 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:10.885 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "[\r][\n]"
06:24:10.885 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 << HTTP/1.1 200 OK
06:24:10.885 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 << Allow: GET
06:24:10.885 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 << Allow: HEAD
06:24:10.885 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 << Allow: POST
06:24:10.885 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 << Connection: keep-alive
06:24:10.885 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 << Content-Length: 0
06:24:10.886 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:10.886 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:10.886 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:49932<->127.0.0.1:36451
06:24:10.886 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:10.924 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@67d3c80c
06:24:10.988 [Finalizer] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Shutting down connection
06:24:10.989 [Finalizer] DEBUG org.apache.http.impl.conn.DefaultManagedHttpClientConnection - http-outgoing-0: Shutdown connection
06:24:10.989 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@2def28de
06:24:10.998 [XNIO-2 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 I/O-1', selector sun.nio.ch.EPollSelectorImpl@64352fc1
06:24:11.000 [XNIO-2 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 I/O-2', selector sun.nio.ch.EPollSelectorImpl@6b8a764c
06:24:11.002 [XNIO-2 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 I/O-3', selector sun.nio.ch.EPollSelectorImpl@2438e5b2
06:24:11.010 [XNIO-2 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 I/O-5', selector sun.nio.ch.EPollSelectorImpl@6c244a73
06:24:11.011 [XNIO-2 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 I/O-4', selector sun.nio.ch.EPollSelectorImpl@183a368a
06:24:11.011 [XNIO-2 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 I/O-7', selector sun.nio.ch.EPollSelectorImpl@ada705
06:24:11.011 [XNIO-2 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 I/O-6', selector sun.nio.ch.EPollSelectorImpl@44126964
06:24:11.012 [XNIO-2 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 I/O-8', selector sun.nio.ch.EPollSelectorImpl@1039fc06
06:24:11.014 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
06:24:11.016 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
06:24:11.017 [XNIO-2 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-2 Accept', selector sun.nio.ch.EPollSelectorImpl@18427179
06:24:11.022 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:11.025 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:11.028 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:42095
06:24:11.028 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {s}->https://localhost:42095
06:24:11.028 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:42095
06:24:11.028 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Connecting socket to localhost/127.0.0.1:42095 with timeout 0
06:24:11.074 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Enabled protocols: [TLSv1, TLSv1.1, TLSv1.2]
06:24:11.074 [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_ECDHE_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_RSA_WITH_3DES_EDE_CBC_SHA, SSL_DHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_DHE_DSS_WITH_3DES_EDE_CBC_SHA, TLS_EMPTY_RENEGOTIATION_INFO_SCSV]
06:24:11.074 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Starting handshake
06:24:11.267 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Secure session established
06:24:11.268 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  negotiated protocol: TLSv1.2
06:24:11.268 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  negotiated cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
06:24:11.269 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  peer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
06:24:11.269 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  issuer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
06:24:11.269 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:55758<->127.0.0.1:42095
06:24:11.269 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:11.269 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:11.278 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:11.279 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> OPTIONS /foo HTTP/1.1
06:24:11.279 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> Host: localhost:42095
06:24:11.279 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> Connection: Keep-Alive
06:24:11.279 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:11.279 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> Accept-Encoding: gzip,deflate
06:24:11.279 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:11.280 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "Host: localhost:42095[\r][\n]"
06:24:11.280 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "Connection: Keep-Alive[\r][\n]"
06:24:11.280 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:11.280 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:11.280 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "[\r][\n]"
06:24:11.291 [XNIO-2 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_131)], Host=[localhost:42095]}, cookies=[:]): <empty> }
06:24:11.292 [XNIO-2 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:11.294 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "HTTP/1.1 200 OK[\r][\n]"
06:24:11.294 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "Allow: GET[\r][\n]"
06:24:11.294 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "Allow: HEAD[\r][\n]"
06:24:11.294 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "Allow: POST[\r][\n]"
06:24:11.294 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "Connection: keep-alive[\r][\n]"
06:24:11.294 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "Content-Length: 0[\r][\n]"
06:24:11.294 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:11.294 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "[\r][\n]"
06:24:11.294 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << HTTP/1.1 200 OK
06:24:11.294 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << Allow: GET
06:24:11.294 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << Allow: HEAD
06:24:11.294 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << Allow: POST
06:24:11.294 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << Connection: keep-alive
06:24:11.294 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << Content-Length: 0
06:24:11.294 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:11.295 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:11.295 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:55758<->127.0.0.1:42095
06:24:11.295 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:11.296 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:11.297 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:11.297 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:42095
06:24:11.297 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:11.297 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:11.297 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:11.297 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> OPTIONS /foo HTTP/1.1
06:24:11.297 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> Host: localhost:42095
06:24:11.297 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> Connection: Keep-Alive
06:24:11.297 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:11.297 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> Accept-Encoding: gzip,deflate
06:24:11.297 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:11.297 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "Host: localhost:42095[\r][\n]"
06:24:11.297 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "Connection: Keep-Alive[\r][\n]"
06:24:11.298 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:11.298 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:11.298 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "[\r][\n]"
06:24:11.309 [XNIO-2 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_131)], Host=[localhost:42095]}, cookies=[:]): <empty> }
06:24:11.310 [XNIO-2 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:11.312 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "HTTP/1.1 200 OK[\r][\n]"
06:24:11.312 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "Allow: GET[\r][\n]"
06:24:11.312 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "Allow: HEAD[\r][\n]"
06:24:11.312 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "Allow: POST[\r][\n]"
06:24:11.312 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "Connection: keep-alive[\r][\n]"
06:24:11.312 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "Content-Length: 0[\r][\n]"
06:24:11.312 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:11.312 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "[\r][\n]"
06:24:11.312 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << HTTP/1.1 200 OK
06:24:11.312 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << Allow: GET
06:24:11.312 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << Allow: HEAD
06:24:11.313 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << Allow: POST
06:24:11.313 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << Connection: keep-alive
06:24:11.313 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << Content-Length: 0
06:24:11.313 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:11.313 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:11.313 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:55758<->127.0.0.1:42095
06:24:11.313 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:11.314 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@2def28de
06:24:11.348 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@158310d7
06:24:11.367 [XNIO-3 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 I/O-1', selector sun.nio.ch.EPollSelectorImpl@69eff651
06:24:11.382 [XNIO-3 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 I/O-2', selector sun.nio.ch.EPollSelectorImpl@3a27330
06:24:11.383 [XNIO-3 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 I/O-3', selector sun.nio.ch.EPollSelectorImpl@2cbf199f
06:24:11.389 [XNIO-3 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 I/O-4', selector sun.nio.ch.EPollSelectorImpl@80d2495
06:24:11.406 [XNIO-3 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 I/O-5', selector sun.nio.ch.EPollSelectorImpl@380208d5
06:24:11.408 [XNIO-3 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 I/O-6', selector sun.nio.ch.EPollSelectorImpl@60c51c5a
06:24:11.410 [XNIO-3 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 I/O-7', selector sun.nio.ch.EPollSelectorImpl@4e3e865c
06:24:11.413 [XNIO-3 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 I/O-8', selector sun.nio.ch.EPollSelectorImpl@3f27bb37
06:24:11.422 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
06:24:11.423 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
06:24:11.424 [XNIO-3 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-3 Accept', selector sun.nio.ch.EPollSelectorImpl@60ecdce
06:24:11.457 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:11.457 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:11.457 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:43581
06:24:11.458 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {}->http://localhost:43581
06:24:11.458 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:43581
06:24:11.458 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:47776<->127.0.0.1:43581
06:24:11.458 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:11.458 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:11.458 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:11.458 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> OPTIONS /foo HTTP/1.1
06:24:11.458 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> Host: localhost:43581
06:24:11.458 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> Connection: Keep-Alive
06:24:11.458 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:11.458 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> Accept-Encoding: gzip,deflate
06:24:11.458 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:11.459 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "Host: localhost:43581[\r][\n]"
06:24:11.459 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "Connection: Keep-Alive[\r][\n]"
06:24:11.459 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:11.459 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:11.459 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "[\r][\n]"
06:24:11.462 [XNIO-3 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_131)], Host=[localhost:43581]}, cookies=[:]): <empty> }
06:24:11.462 [XNIO-3 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:11.463 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "HTTP/1.1 200 OK[\r][\n]"
06:24:11.463 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "Allow: GET[\r][\n]"
06:24:11.463 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "Allow: HEAD[\r][\n]"
06:24:11.463 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "Allow: POST[\r][\n]"
06:24:11.463 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "Connection: keep-alive[\r][\n]"
06:24:11.463 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "Content-Length: 0[\r][\n]"
06:24:11.463 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:11.464 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "[\r][\n]"
06:24:11.464 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << HTTP/1.1 200 OK
06:24:11.464 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << Allow: GET
06:24:11.464 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << Allow: HEAD
06:24:11.464 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << Allow: POST
06:24:11.464 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << Connection: keep-alive
06:24:11.464 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << Content-Length: 0
06:24:11.465 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:11.465 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:11.465 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:47776<->127.0.0.1:43581
06:24:11.465 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:11.467 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:11.468 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:11.468 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:43581
06:24:11.468 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:11.468 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:11.468 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:11.468 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> OPTIONS /foo HTTP/1.1
06:24:11.469 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> Host: localhost:43581
06:24:11.469 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> Connection: Keep-Alive
06:24:11.469 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:11.470 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> Accept-Encoding: gzip,deflate
06:24:11.470 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:11.471 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "Host: localhost:43581[\r][\n]"
06:24:11.471 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "Connection: Keep-Alive[\r][\n]"
06:24:11.471 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:11.471 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:11.471 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "[\r][\n]"
06:24:11.478 [XNIO-3 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_131)], Host=[localhost:43581]}, cookies=[:]): <empty> }
06:24:11.479 [XNIO-3 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:11.479 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "HTTP/1.1 200 OK[\r][\n]"
06:24:11.480 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "Allow: GET[\r][\n]"
06:24:11.480 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "Allow: HEAD[\r][\n]"
06:24:11.480 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "Allow: POST[\r][\n]"
06:24:11.480 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "Connection: keep-alive[\r][\n]"
06:24:11.480 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "Content-Length: 0[\r][\n]"
06:24:11.480 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:11.480 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "[\r][\n]"
06:24:11.480 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << HTTP/1.1 200 OK
06:24:11.480 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << Allow: GET
06:24:11.480 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << Allow: HEAD
06:24:11.480 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << Allow: POST
06:24:11.480 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << Connection: keep-alive
06:24:11.480 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << Content-Length: 0
06:24:11.480 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:11.480 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:11.481 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:47776<->127.0.0.1:43581
06:24:11.481 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:11.482 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@158310d7
06:24:11.508 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@5751b192
06:24:11.520 [XNIO-4 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 I/O-1', selector sun.nio.ch.EPollSelectorImpl@5ea53f4f
06:24:11.522 [XNIO-4 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 I/O-4', selector sun.nio.ch.EPollSelectorImpl@13e11d12
06:24:11.535 [XNIO-4 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 I/O-5', selector sun.nio.ch.EPollSelectorImpl@5adfeb8
06:24:11.544 [XNIO-4 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 I/O-7', selector sun.nio.ch.EPollSelectorImpl@3ea19c14
06:24:11.545 [XNIO-4 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 I/O-8', selector sun.nio.ch.EPollSelectorImpl@292d05d9
06:24:11.554 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
06:24:11.556 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
06:24:11.520 [XNIO-4 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 I/O-2', selector sun.nio.ch.EPollSelectorImpl@f88c0b3
06:24:11.520 [XNIO-4 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 I/O-3', selector sun.nio.ch.EPollSelectorImpl@c3b0875
06:24:11.536 [XNIO-4 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 I/O-6', selector sun.nio.ch.EPollSelectorImpl@444ee366
06:24:11.554 [XNIO-4 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-4 Accept', selector sun.nio.ch.EPollSelectorImpl@30ca4589
06:24:11.562 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:11.562 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:11.562 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:38601
06:24:11.562 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {s}->https://localhost:38601
06:24:11.563 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:38601
06:24:11.563 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Connecting socket to localhost/127.0.0.1:38601 with timeout 0
06:24:11.563 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Enabled protocols: [TLSv1, TLSv1.1, TLSv1.2]
06:24:11.563 [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_ECDHE_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_RSA_WITH_3DES_EDE_CBC_SHA, SSL_DHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_DHE_DSS_WITH_3DES_EDE_CBC_SHA, TLS_EMPTY_RENEGOTIATION_INFO_SCSV]
06:24:11.564 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Starting handshake
06:24:11.632 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Secure session established
06:24:11.633 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  negotiated protocol: TLSv1.2
06:24:11.633 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  negotiated cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
06:24:11.633 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  peer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
06:24:11.633 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  issuer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
06:24:11.633 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:49470<->127.0.0.1:38601
06:24:11.633 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:11.633 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:11.633 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:11.633 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> OPTIONS /foo HTTP/1.1
06:24:11.633 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> Host: localhost:38601
06:24:11.633 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> Connection: Keep-Alive
06:24:11.633 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:11.633 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> Accept-Encoding: gzip,deflate
06:24:11.633 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:11.633 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "Host: localhost:38601[\r][\n]"
06:24:11.633 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "Connection: Keep-Alive[\r][\n]"
06:24:11.633 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:11.633 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:11.633 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "[\r][\n]"
06:24:11.640 [XNIO-4 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_131)], Host=[localhost:38601]}, cookies=[:]): <empty> }
06:24:11.643 [XNIO-4 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:11.643 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "HTTP/1.1 200 OK[\r][\n]"
06:24:11.644 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "Allow: GET[\r][\n]"
06:24:11.644 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "Allow: HEAD[\r][\n]"
06:24:11.644 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "Allow: POST[\r][\n]"
06:24:11.644 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "Connection: keep-alive[\r][\n]"
06:24:11.644 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "Content-Length: 0[\r][\n]"
06:24:11.644 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:11.644 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "[\r][\n]"
06:24:11.644 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 << HTTP/1.1 200 OK
06:24:11.644 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 << Allow: GET
06:24:11.644 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 << Allow: HEAD
06:24:11.644 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 << Allow: POST
06:24:11.644 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 << Connection: keep-alive
06:24:11.644 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 << Content-Length: 0
06:24:11.645 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:11.645 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:11.645 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:49470<->127.0.0.1:38601
06:24:11.645 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:11.647 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:11.647 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:11.647 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:38601
06:24:11.647 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:11.647 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:11.648 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:11.648 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> OPTIONS /foo HTTP/1.1
06:24:11.648 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> Host: localhost:38601
06:24:11.648 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> Connection: Keep-Alive
06:24:11.648 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:11.648 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> Accept-Encoding: gzip,deflate
06:24:11.648 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:11.648 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "Host: localhost:38601[\r][\n]"
06:24:11.648 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "Connection: Keep-Alive[\r][\n]"
06:24:11.648 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:11.648 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:11.648 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "[\r][\n]"
06:24:11.651 [XNIO-4 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_131)], Host=[localhost:38601]}, cookies=[:]): <empty> }
06:24:11.660 [XNIO-4 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:11.661 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "HTTP/1.1 200 OK[\r][\n]"
06:24:11.661 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "Allow: GET[\r][\n]"
06:24:11.661 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "Allow: HEAD[\r][\n]"
06:24:11.661 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "Allow: POST[\r][\n]"
06:24:11.661 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "Connection: keep-alive[\r][\n]"
06:24:11.661 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "Content-Length: 0[\r][\n]"
06:24:11.661 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:11.661 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "[\r][\n]"
06:24:11.662 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 << HTTP/1.1 200 OK
06:24:11.662 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 << Allow: GET
06:24:11.662 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 << Allow: HEAD
06:24:11.662 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 << Allow: POST
06:24:11.662 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 << Connection: keep-alive
06:24:11.662 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 << Content-Length: 0
06:24:11.662 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:11.662 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:11.662 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:49470<->127.0.0.1:38601
06:24:11.662 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:11.663 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@5751b192
06:24:11.680 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@57eac9c3
06:24:11.688 [XNIO-4 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
06:24:11.700 [XNIO-5 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 I/O-2', selector sun.nio.ch.EPollSelectorImpl@187096c4
06:24:11.702 [XNIO-5 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 I/O-4', selector sun.nio.ch.EPollSelectorImpl@75791d68
06:24:11.710 [XNIO-5 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 I/O-5', selector sun.nio.ch.EPollSelectorImpl@3ca376df
06:24:11.702 [XNIO-5 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 I/O-1', selector sun.nio.ch.EPollSelectorImpl@58e44c6d
06:24:11.711 [XNIO-5 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 I/O-6', selector sun.nio.ch.EPollSelectorImpl@72974c01
06:24:11.712 [XNIO-5 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 I/O-7', selector sun.nio.ch.EPollSelectorImpl@34744b01
06:24:11.719 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
06:24:11.702 [XNIO-5 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 I/O-3', selector sun.nio.ch.EPollSelectorImpl@1e023997
06:24:11.719 [XNIO-5 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 I/O-8', selector sun.nio.ch.EPollSelectorImpl@424188e1
06:24:11.720 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
06:24:11.721 [XNIO-5 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-5 Accept', selector sun.nio.ch.EPollSelectorImpl@42d23a50
06:24:11.739 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:11.739 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:11.739 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:33305
06:24:11.739 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {}->http://localhost:33305
06:24:11.739 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:33305
06:24:11.740 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:58520<->127.0.0.1:33305
06:24:11.740 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:11.740 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:11.740 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:11.740 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 >> OPTIONS /foo HTTP/1.1
06:24:11.740 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 >> Host: localhost:33305
06:24:11.740 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 >> Connection: Keep-Alive
06:24:11.740 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:11.740 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 >> Accept-Encoding: gzip,deflate
06:24:11.740 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:11.740 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 >> "Host: localhost:33305[\r][\n]"
06:24:11.741 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 >> "Connection: Keep-Alive[\r][\n]"
06:24:11.741 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:11.741 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:11.741 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 >> "[\r][\n]"
06:24:11.741 [XNIO-5 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_131)], Host=[localhost:33305]}, cookies=[:]): <empty> }
06:24:11.742 [XNIO-5 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:11.742 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 << "HTTP/1.1 200 OK[\r][\n]"
06:24:11.743 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 << "Allow: GET[\r][\n]"
06:24:11.743 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 << "Allow: HEAD[\r][\n]"
06:24:11.743 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 << "Allow: POST[\r][\n]"
06:24:11.743 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 << "Connection: keep-alive[\r][\n]"
06:24:11.743 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 << "Content-Length: 0[\r][\n]"
06:24:11.743 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:11.743 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 << "[\r][\n]"
06:24:11.743 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 << HTTP/1.1 200 OK
06:24:11.744 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 << Allow: GET
06:24:11.744 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 << Allow: HEAD
06:24:11.744 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 << Allow: POST
06:24:11.744 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 << Connection: keep-alive
06:24:11.744 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 << Content-Length: 0
06:24:11.745 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:11.745 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:11.745 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:58520<->127.0.0.1:33305
06:24:11.745 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:11.747 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:11.748 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:11.748 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:33305
06:24:11.748 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:11.748 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:11.749 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:11.750 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 >> OPTIONS /foo HTTP/1.1
06:24:11.751 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 >> Host: localhost:33305
06:24:11.751 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 >> Connection: Keep-Alive
06:24:11.751 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:11.751 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 >> Accept-Encoding: gzip,deflate
06:24:11.751 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:11.751 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 >> "Host: localhost:33305[\r][\n]"
06:24:11.751 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 >> "Connection: Keep-Alive[\r][\n]"
06:24:11.751 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:11.751 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:11.751 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 >> "[\r][\n]"
06:24:11.754 [XNIO-5 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_131)], Host=[localhost:33305]}, cookies=[:]): <empty> }
06:24:11.755 [XNIO-5 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:11.755 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 << "HTTP/1.1 200 OK[\r][\n]"
06:24:11.755 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 << "Allow: GET[\r][\n]"
06:24:11.756 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 << "Allow: HEAD[\r][\n]"
06:24:11.756 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 << "Allow: POST[\r][\n]"
06:24:11.756 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 << "Connection: keep-alive[\r][\n]"
06:24:11.756 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 << "Content-Length: 0[\r][\n]"
06:24:11.756 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:11.756 [Test worker] DEBUG org.apache.http.wire - http-outgoing-4 << "[\r][\n]"
06:24:11.756 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 << HTTP/1.1 200 OK
06:24:11.756 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 << Allow: GET
06:24:11.756 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 << Allow: HEAD
06:24:11.757 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 << Allow: POST
06:24:11.757 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 << Connection: keep-alive
06:24:11.757 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 << Content-Length: 0
06:24:11.757 [Test worker] DEBUG org.apache.http.headers - http-outgoing-4 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:11.757 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:11.757 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:58520<->127.0.0.1:33305
06:24:11.758 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:11.759 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@57eac9c3
06:24:11.790 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@4a5880a0
06:24:11.813 [XNIO-6 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 I/O-1', selector sun.nio.ch.EPollSelectorImpl@548483fb
06:24:11.831 [XNIO-6 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 I/O-3', selector sun.nio.ch.EPollSelectorImpl@5e73e4c0
06:24:11.831 [XNIO-6 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 I/O-2', selector sun.nio.ch.EPollSelectorImpl@4c4ef8dd
06:24:11.832 [XNIO-6 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 I/O-4', selector sun.nio.ch.EPollSelectorImpl@6c8ad53
06:24:11.832 [XNIO-6 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 I/O-5', selector sun.nio.ch.EPollSelectorImpl@3f60f155
06:24:11.833 [XNIO-6 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 I/O-6', selector sun.nio.ch.EPollSelectorImpl@253cb5e9
06:24:11.836 [XNIO-6 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 I/O-7', selector sun.nio.ch.EPollSelectorImpl@3561e517
06:24:11.854 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
06:24:11.854 [XNIO-6 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 Accept', selector sun.nio.ch.EPollSelectorImpl@1438f282
06:24:11.854 [XNIO-6 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-6 I/O-8', selector sun.nio.ch.EPollSelectorImpl@3acb7c0e
06:24:11.855 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
06:24:11.863 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:11.864 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:11.865 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:36345
06:24:11.865 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {s}->https://localhost:36345
06:24:11.870 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:36345
06:24:11.870 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Connecting socket to localhost/127.0.0.1:36345 with timeout 0
06:24:11.870 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Enabled protocols: [TLSv1, TLSv1.1, TLSv1.2]
06:24:11.870 [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_ECDHE_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_RSA_WITH_3DES_EDE_CBC_SHA, SSL_DHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_DHE_DSS_WITH_3DES_EDE_CBC_SHA, TLS_EMPTY_RENEGOTIATION_INFO_SCSV]
06:24:11.871 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Starting handshake
06:24:11.908 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Secure session established
06:24:11.908 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  negotiated protocol: TLSv1.2
06:24:11.908 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  negotiated cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
06:24:11.908 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  peer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
06:24:11.908 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  issuer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
06:24:11.908 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:46392<->127.0.0.1:36345
06:24:11.908 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:11.908 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:11.908 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:11.909 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 >> OPTIONS /foo HTTP/1.1
06:24:11.909 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 >> Host: localhost:36345
06:24:11.909 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 >> Connection: Keep-Alive
06:24:11.909 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:11.909 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 >> Accept-Encoding: gzip,deflate
06:24:11.909 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:11.909 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 >> "Host: localhost:36345[\r][\n]"
06:24:11.909 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 >> "Connection: Keep-Alive[\r][\n]"
06:24:11.909 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:11.909 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:11.909 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 >> "[\r][\n]"
06:24:11.910 [XNIO-6 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_131)], Host=[localhost:36345]}, cookies=[:]): <empty> }
06:24:11.911 [XNIO-6 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:11.912 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 << "HTTP/1.1 200 OK[\r][\n]"
06:24:11.912 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 << "Allow: GET[\r][\n]"
06:24:11.912 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 << "Allow: HEAD[\r][\n]"
06:24:11.912 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 << "Allow: POST[\r][\n]"
06:24:11.912 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 << "Connection: keep-alive[\r][\n]"
06:24:11.912 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 << "Content-Length: 0[\r][\n]"
06:24:11.912 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:11.912 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 << "[\r][\n]"
06:24:11.912 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 << HTTP/1.1 200 OK
06:24:11.912 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 << Allow: GET
06:24:11.912 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 << Allow: HEAD
06:24:11.912 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 << Allow: POST
06:24:11.912 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 << Connection: keep-alive
06:24:11.912 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 << Content-Length: 0
06:24:11.912 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:11.912 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:11.912 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:46392<->127.0.0.1:36345
06:24:11.912 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:11.914 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:11.914 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:11.914 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:36345
06:24:11.914 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:11.914 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:11.914 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:11.914 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 >> OPTIONS /foo HTTP/1.1
06:24:11.914 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 >> Host: localhost:36345
06:24:11.914 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 >> Connection: Keep-Alive
06:24:11.914 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:11.914 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 >> Accept-Encoding: gzip,deflate
06:24:11.914 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:11.914 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 >> "Host: localhost:36345[\r][\n]"
06:24:11.914 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 >> "Connection: Keep-Alive[\r][\n]"
06:24:11.914 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:11.914 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:11.914 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 >> "[\r][\n]"
06:24:11.922 [XNIO-6 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_131)], Host=[localhost:36345]}, cookies=[:]): <empty> }
06:24:11.923 [XNIO-6 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:11.924 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 << "HTTP/1.1 200 OK[\r][\n]"
06:24:11.924 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 << "Allow: GET[\r][\n]"
06:24:11.924 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 << "Allow: HEAD[\r][\n]"
06:24:11.924 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 << "Allow: POST[\r][\n]"
06:24:11.924 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 << "Connection: keep-alive[\r][\n]"
06:24:11.924 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 << "Content-Length: 0[\r][\n]"
06:24:11.924 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:11.924 [Test worker] DEBUG org.apache.http.wire - http-outgoing-5 << "[\r][\n]"
06:24:11.924 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 << HTTP/1.1 200 OK
06:24:11.924 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 << Allow: GET
06:24:11.924 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 << Allow: HEAD
06:24:11.924 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 << Allow: POST
06:24:11.924 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 << Connection: keep-alive
06:24:11.924 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 << Content-Length: 0
06:24:11.924 [Test worker] DEBUG org.apache.http.headers - http-outgoing-5 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:11.924 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:11.924 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:46392<->127.0.0.1:36345
06:24:11.924 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:11.925 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@4a5880a0
06:24:11.932 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@50daceb3
06:24:11.934 [XNIO-7 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 I/O-1', selector sun.nio.ch.EPollSelectorImpl@73aad57a
06:24:11.934 [XNIO-7 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 I/O-2', selector sun.nio.ch.EPollSelectorImpl@eab1ad5
06:24:11.946 [XNIO-7 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 I/O-3', selector sun.nio.ch.EPollSelectorImpl@67fffb7d
06:24:11.946 [XNIO-7 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 I/O-4', selector sun.nio.ch.EPollSelectorImpl@576b41df
06:24:11.946 [XNIO-7 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 I/O-5', selector sun.nio.ch.EPollSelectorImpl@7219d2f2
06:24:11.949 [XNIO-7 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 I/O-6', selector sun.nio.ch.EPollSelectorImpl@5695275f
06:24:11.972 [XNIO-7 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 I/O-7', selector sun.nio.ch.EPollSelectorImpl@2c529165
06:24:11.972 [XNIO-7 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 I/O-8', selector sun.nio.ch.EPollSelectorImpl@1a7a89ed
06:24:11.979 [XNIO-7 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-7 Accept', selector sun.nio.ch.EPollSelectorImpl@41975bf6
06:24:11.979 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
06:24:11.981 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
06:24:11.989 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:11.989 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:11.989 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:38799
06:24:11.990 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {}->http://localhost:38799
06:24:11.990 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:38799
06:24:11.990 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:56670<->127.0.0.1:38799
06:24:11.990 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:11.990 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:11.990 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:11.990 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 >> OPTIONS /foo HTTP/1.1
06:24:11.990 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 >> Host: localhost:38799
06:24:11.990 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 >> Connection: Keep-Alive
06:24:11.990 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:11.990 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 >> Accept-Encoding: gzip,deflate
06:24:11.990 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:11.990 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 >> "Host: localhost:38799[\r][\n]"
06:24:11.990 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 >> "Connection: Keep-Alive[\r][\n]"
06:24:11.990 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:11.990 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:11.990 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 >> "[\r][\n]"
06:24:11.995 [XNIO-7 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_131)], Host=[localhost:38799]}, cookies=[:]): <empty> }
06:24:12.002 [XNIO-7 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:12.002 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 << "HTTP/1.1 200 OK[\r][\n]"
06:24:12.002 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 << "Allow: GET[\r][\n]"
06:24:12.002 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 << "Allow: HEAD[\r][\n]"
06:24:12.002 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 << "Allow: POST[\r][\n]"
06:24:12.002 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 << "Connection: keep-alive[\r][\n]"
06:24:12.002 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 << "Content-Length: 0[\r][\n]"
06:24:12.002 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:12.002 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 << "[\r][\n]"
06:24:12.002 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 << HTTP/1.1 200 OK
06:24:12.002 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 << Allow: GET
06:24:12.002 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 << Allow: HEAD
06:24:12.002 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 << Allow: POST
06:24:12.002 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 << Connection: keep-alive
06:24:12.003 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 << Content-Length: 0
06:24:12.003 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:12.003 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:12.003 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:56670<->127.0.0.1:38799
06:24:12.003 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:12.005 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:12.005 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:12.005 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:38799
06:24:12.005 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:12.005 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:12.005 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:12.006 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 >> OPTIONS /foo HTTP/1.1
06:24:12.006 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 >> Host: localhost:38799
06:24:12.006 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 >> Connection: Keep-Alive
06:24:12.006 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:12.006 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 >> Accept-Encoding: gzip,deflate
06:24:12.006 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:12.006 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 >> "Host: localhost:38799[\r][\n]"
06:24:12.006 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 >> "Connection: Keep-Alive[\r][\n]"
06:24:12.006 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:12.006 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:12.006 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 >> "[\r][\n]"
06:24:12.018 [XNIO-7 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_131)], Host=[localhost:38799]}, cookies=[:]): <empty> }
06:24:12.019 [XNIO-7 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:12.019 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 << "HTTP/1.1 200 OK[\r][\n]"
06:24:12.019 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 << "Allow: GET[\r][\n]"
06:24:12.019 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 << "Allow: HEAD[\r][\n]"
06:24:12.019 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 << "Allow: POST[\r][\n]"
06:24:12.019 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 << "Connection: keep-alive[\r][\n]"
06:24:12.019 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 << "Content-Length: 0[\r][\n]"
06:24:12.019 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:12.019 [Test worker] DEBUG org.apache.http.wire - http-outgoing-6 << "[\r][\n]"
06:24:12.019 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 << HTTP/1.1 200 OK
06:24:12.019 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 << Allow: GET
06:24:12.019 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 << Allow: HEAD
06:24:12.019 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 << Allow: POST
06:24:12.019 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 << Connection: keep-alive
06:24:12.019 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 << Content-Length: 0
06:24:12.019 [Test worker] DEBUG org.apache.http.headers - http-outgoing-6 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:12.020 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:12.020 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:56670<->127.0.0.1:38799
06:24:12.020 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:12.020 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@50daceb3
06:24:12.036 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@55169836
06:24:12.038 [XNIO-8 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 I/O-1', selector sun.nio.ch.EPollSelectorImpl@7cb52749
06:24:12.040 [XNIO-8 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 I/O-2', selector sun.nio.ch.EPollSelectorImpl@2218c8a0
06:24:12.042 [XNIO-8 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 I/O-3', selector sun.nio.ch.EPollSelectorImpl@3c5545d0
06:24:12.046 [XNIO-8 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 I/O-4', selector sun.nio.ch.EPollSelectorImpl@d5de33b
06:24:12.047 [XNIO-8 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 I/O-5', selector sun.nio.ch.EPollSelectorImpl@9dbf901
06:24:12.057 [XNIO-8 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 I/O-6', selector sun.nio.ch.EPollSelectorImpl@6a1e272d
06:24:12.058 [XNIO-8 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 Accept', selector sun.nio.ch.EPollSelectorImpl@3b946a43
06:24:12.057 [XNIO-8 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 I/O-7', selector sun.nio.ch.EPollSelectorImpl@58107486
06:24:12.058 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
06:24:12.059 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
06:24:12.058 [XNIO-8 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-8 I/O-8', selector sun.nio.ch.EPollSelectorImpl@32483d63
06:24:12.062 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:12.062 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:12.063 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:39461
06:24:12.063 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {s}->https://localhost:39461
06:24:12.063 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:39461
06:24:12.063 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Connecting socket to localhost/127.0.0.1:39461 with timeout 0
06:24:12.063 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Enabled protocols: [TLSv1, TLSv1.1, TLSv1.2]
06:24:12.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_ECDHE_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_RSA_WITH_3DES_EDE_CBC_SHA, SSL_DHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_DHE_DSS_WITH_3DES_EDE_CBC_SHA, TLS_EMPTY_RENEGOTIATION_INFO_SCSV]
06:24:12.063 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Starting handshake
06:24:12.100 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Secure session established
06:24:12.100 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  negotiated protocol: TLSv1.2
06:24:12.100 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  negotiated cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
06:24:12.100 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  peer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
06:24:12.100 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  issuer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
06:24:12.100 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:47212<->127.0.0.1:39461
06:24:12.100 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:12.100 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:12.100 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:12.100 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 >> OPTIONS /foo HTTP/1.1
06:24:12.100 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 >> Host: localhost:39461
06:24:12.100 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 >> Connection: Keep-Alive
06:24:12.100 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:12.100 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 >> Accept-Encoding: gzip,deflate
06:24:12.100 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:12.100 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 >> "Host: localhost:39461[\r][\n]"
06:24:12.100 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 >> "Connection: Keep-Alive[\r][\n]"
06:24:12.100 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:12.100 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:12.100 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 >> "[\r][\n]"
06:24:12.101 [XNIO-8 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_131)], Host=[localhost:39461]}, cookies=[:]): <empty> }
06:24:12.102 [XNIO-8 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:12.102 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 << "HTTP/1.1 200 OK[\r][\n]"
06:24:12.102 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 << "Allow: GET[\r][\n]"
06:24:12.102 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 << "Allow: HEAD[\r][\n]"
06:24:12.102 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 << "Allow: POST[\r][\n]"
06:24:12.102 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 << "Connection: keep-alive[\r][\n]"
06:24:12.102 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 << "Content-Length: 0[\r][\n]"
06:24:12.103 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:12.103 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 << "[\r][\n]"
06:24:12.103 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 << HTTP/1.1 200 OK
06:24:12.103 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 << Allow: GET
06:24:12.103 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 << Allow: HEAD
06:24:12.103 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 << Allow: POST
06:24:12.103 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 << Connection: keep-alive
06:24:12.103 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 << Content-Length: 0
06:24:12.103 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:12.103 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:12.103 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:47212<->127.0.0.1:39461
06:24:12.103 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:12.104 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:12.104 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:12.104 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:39461
06:24:12.104 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:12.104 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:12.104 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:12.104 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 >> OPTIONS /foo HTTP/1.1
06:24:12.104 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 >> Host: localhost:39461
06:24:12.104 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 >> Connection: Keep-Alive
06:24:12.104 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:12.104 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 >> Accept-Encoding: gzip,deflate
06:24:12.104 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:12.104 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 >> "Host: localhost:39461[\r][\n]"
06:24:12.104 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 >> "Connection: Keep-Alive[\r][\n]"
06:24:12.104 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:12.104 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:12.104 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 >> "[\r][\n]"
06:24:12.106 [XNIO-8 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_131)], Host=[localhost:39461]}, cookies=[:]): <empty> }
06:24:12.108 [XNIO-8 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:12.108 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 << "HTTP/1.1 200 OK[\r][\n]"
06:24:12.108 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 << "Allow: GET[\r][\n]"
06:24:12.108 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 << "Allow: HEAD[\r][\n]"
06:24:12.108 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 << "Allow: POST[\r][\n]"
06:24:12.108 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 << "Connection: keep-alive[\r][\n]"
06:24:12.108 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 << "Content-Length: 0[\r][\n]"
06:24:12.108 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:12.108 [Test worker] DEBUG org.apache.http.wire - http-outgoing-7 << "[\r][\n]"
06:24:12.109 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 << HTTP/1.1 200 OK
06:24:12.109 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 << Allow: GET
06:24:12.109 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 << Allow: HEAD
06:24:12.109 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 << Allow: POST
06:24:12.109 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 << Connection: keep-alive
06:24:12.109 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 << Content-Length: 0
06:24:12.109 [Test worker] DEBUG org.apache.http.headers - http-outgoing-7 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:12.109 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:12.109 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:47212<->127.0.0.1:39461
06:24:12.109 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:12.110 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@55169836
06:24:12.115 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@165d269b
06:24:12.116 [XNIO-9 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 I/O-1', selector sun.nio.ch.EPollSelectorImpl@7ca41339
06:24:12.118 [XNIO-9 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 I/O-2', selector sun.nio.ch.EPollSelectorImpl@32654086
06:24:12.120 [XNIO-9 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 I/O-3', selector sun.nio.ch.EPollSelectorImpl@113a1c2a
06:24:12.121 [XNIO-9 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 I/O-4', selector sun.nio.ch.EPollSelectorImpl@6cb23c97
06:24:12.121 [XNIO-9 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 I/O-5', selector sun.nio.ch.EPollSelectorImpl@4c974e64
06:24:12.122 [XNIO-9 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 I/O-7', selector sun.nio.ch.EPollSelectorImpl@16fa33cb
06:24:12.123 [XNIO-9 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 I/O-8', selector sun.nio.ch.EPollSelectorImpl@37269527
06:24:12.122 [XNIO-9 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 I/O-6', selector sun.nio.ch.EPollSelectorImpl@2e8afdc6
06:24:12.130 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
06:24:12.130 [XNIO-9 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-9 Accept', selector sun.nio.ch.EPollSelectorImpl@75cb17ba
06:24:12.131 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
06:24:12.147 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:12.147 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:12.147 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:38737
06:24:12.147 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {}->http://localhost:38737
06:24:12.148 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:38737
06:24:12.148 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:59982<->127.0.0.1:38737
06:24:12.148 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:12.148 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:12.148 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:12.150 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 >> OPTIONS /foo HTTP/1.1
06:24:12.150 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 >> Host: localhost:38737
06:24:12.150 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 >> Connection: Keep-Alive
06:24:12.150 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:12.150 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 >> Accept-Encoding: gzip,deflate
06:24:12.151 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:12.151 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 >> "Host: localhost:38737[\r][\n]"
06:24:12.151 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 >> "Connection: Keep-Alive[\r][\n]"
06:24:12.151 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:12.151 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:12.151 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 >> "[\r][\n]"
06:24:12.158 [XNIO-9 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_131)], Host=[localhost:38737]}, cookies=[:]): <empty> }
06:24:12.160 [XNIO-9 task-1] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:12.161 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 << "HTTP/1.1 200 OK[\r][\n]"
06:24:12.161 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 << "Allow: GET[\r][\n]"
06:24:12.161 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 << "Allow: HEAD[\r][\n]"
06:24:12.161 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 << "Allow: POST[\r][\n]"
06:24:12.161 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 << "Connection: keep-alive[\r][\n]"
06:24:12.161 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 << "Content-Length: 0[\r][\n]"
06:24:12.161 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:12.161 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 << "[\r][\n]"
06:24:12.161 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 << HTTP/1.1 200 OK
06:24:12.161 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 << Allow: GET
06:24:12.161 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 << Allow: HEAD
06:24:12.162 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 << Allow: POST
06:24:12.162 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 << Connection: keep-alive
06:24:12.162 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 << Content-Length: 0
06:24:12.162 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:12.162 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:12.162 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:59982<->127.0.0.1:38737
06:24:12.162 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:12.165 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:12.165 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:12.165 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {}->http://localhost:38737
06:24:12.165 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:12.165 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:12.165 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:12.165 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 >> OPTIONS /foo HTTP/1.1
06:24:12.166 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 >> Host: localhost:38737
06:24:12.166 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 >> Connection: Keep-Alive
06:24:12.166 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:12.166 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 >> Accept-Encoding: gzip,deflate
06:24:12.166 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:12.166 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 >> "Host: localhost:38737[\r][\n]"
06:24:12.166 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 >> "Connection: Keep-Alive[\r][\n]"
06:24:12.166 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:12.166 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:12.166 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 >> "[\r][\n]"
06:24:12.166 [XNIO-9 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_131)], Host=[localhost:38737]}, cookies=[:]): <empty> }
06:24:12.167 [XNIO-9 task-2] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:12.167 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 << "HTTP/1.1 200 OK[\r][\n]"
06:24:12.167 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 << "Allow: GET[\r][\n]"
06:24:12.167 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 << "Allow: HEAD[\r][\n]"
06:24:12.167 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 << "Allow: POST[\r][\n]"
06:24:12.167 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 << "Connection: keep-alive[\r][\n]"
06:24:12.167 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 << "Content-Length: 0[\r][\n]"
06:24:12.167 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:12.167 [Test worker] DEBUG org.apache.http.wire - http-outgoing-8 << "[\r][\n]"
06:24:12.167 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 << HTTP/1.1 200 OK
06:24:12.167 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 << Allow: GET
06:24:12.167 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 << Allow: HEAD
06:24:12.167 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 << Allow: POST
06:24:12.167 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 << Connection: keep-alive
06:24:12.167 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 << Content-Length: 0
06:24:12.167 [Test worker] DEBUG org.apache.http.headers - http-outgoing-8 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:12.167 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:12.167 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:59982<->127.0.0.1:38737
06:24:12.167 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:12.168 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@165d269b
06:24:12.188 [Test worker] DEBUG ersatz.undertow - starting undertow server ersatz.undertow.Undertow@1081e5de
06:24:12.196 [XNIO-10 I/O-1] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 I/O-1', selector sun.nio.ch.EPollSelectorImpl@1b23aca7
06:24:12.210 [XNIO-10 I/O-2] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 I/O-2', selector sun.nio.ch.EPollSelectorImpl@14be38aa
06:24:12.222 [XNIO-10 I/O-3] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 I/O-3', selector sun.nio.ch.EPollSelectorImpl@19320e6a
06:24:12.222 [XNIO-10 I/O-4] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 I/O-4', selector sun.nio.ch.EPollSelectorImpl@73807ee
06:24:12.223 [XNIO-10 I/O-6] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 I/O-6', selector sun.nio.ch.EPollSelectorImpl@401ff0bc
06:24:12.223 [XNIO-10 I/O-5] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 I/O-5', selector sun.nio.ch.EPollSelectorImpl@366e0237
06:24:12.238 [XNIO-10 I/O-7] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 I/O-7', selector sun.nio.ch.EPollSelectorImpl@3fd8b83e
06:24:12.254 [XNIO-10 I/O-8] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 I/O-8', selector sun.nio.ch.EPollSelectorImpl@1d37372a
06:24:12.272 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTP for interface localhost and port 0
06:24:12.272 [XNIO-10 Accept] DEBUG ersatz.xnio.nio - Started channel thread 'XNIO-10 Accept', selector sun.nio.ch.EPollSelectorImpl@41834223
06:24:12.275 [Test worker] DEBUG ersatz.undertow - Configuring listener with protocol HTTPS for interface localhost and port 0
06:24:12.278 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:12.279 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:12.279 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:40529
06:24:12.280 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {s}->https://localhost:40529
06:24:12.281 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:40529
06:24:12.281 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Connecting socket to localhost/127.0.0.1:40529 with timeout 0
06:24:12.282 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Enabled protocols: [TLSv1, TLSv1.1, TLSv1.2]
06:24:12.282 [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_ECDHE_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_RSA_WITH_3DES_EDE_CBC_SHA, SSL_DHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_DHE_DSS_WITH_3DES_EDE_CBC_SHA, TLS_EMPTY_RENEGOTIATION_INFO_SCSV]
06:24:12.282 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Starting handshake
06:24:12.305 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory - Secure session established
06:24:12.306 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  negotiated protocol: TLSv1.2
06:24:12.306 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  negotiated cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
06:24:12.306 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  peer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
06:24:12.306 [Test worker] DEBUG org.apache.http.conn.ssl.SSLConnectionSocketFactory -  issuer principal: CN=Ersatz, OU=Ersatz, O=Ersatz, L=Nowhere, ST=Nowhere, C=US
06:24:12.306 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:51950<->127.0.0.1:40529
06:24:12.306 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:12.306 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:12.306 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:12.306 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 >> OPTIONS /foo HTTP/1.1
06:24:12.306 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 >> Host: localhost:40529
06:24:12.306 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 >> Connection: Keep-Alive
06:24:12.306 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:12.306 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 >> Accept-Encoding: gzip,deflate
06:24:12.306 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:12.306 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 >> "Host: localhost:40529[\r][\n]"
06:24:12.306 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 >> "Connection: Keep-Alive[\r][\n]"
06:24:12.306 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:12.306 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:12.306 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 >> "[\r][\n]"
06:24:12.307 [XNIO-10 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_131)], Host=[localhost:40529]}, cookies=[:]): <empty> }
06:24:12.308 [XNIO-10 task-3] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:12.308 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 << "HTTP/1.1 200 OK[\r][\n]"
06:24:12.309 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 << "Allow: GET[\r][\n]"
06:24:12.309 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 << "Allow: HEAD[\r][\n]"
06:24:12.309 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 << "Allow: POST[\r][\n]"
06:24:12.309 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 << "Connection: keep-alive[\r][\n]"
06:24:12.309 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 << "Content-Length: 0[\r][\n]"
06:24:12.309 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:12.309 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 << "[\r][\n]"
06:24:12.309 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 << HTTP/1.1 200 OK
06:24:12.309 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 << Allow: GET
06:24:12.309 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 << Allow: HEAD
06:24:12.309 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 << Allow: POST
06:24:12.309 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 << Connection: keep-alive
06:24:12.309 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 << Content-Length: 0
06:24:12.309 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:12.309 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:12.309 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:51950<->127.0.0.1:40529
06:24:12.309 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:12.311 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
06:24:12.311 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
06:24:12.311 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Get connection for route {s}->https://localhost:40529
06:24:12.311 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request OPTIONS /foo HTTP/1.1
06:24:12.311 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Target auth state: UNCHALLENGED
06:24:12.311 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
06:24:12.311 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 >> OPTIONS /foo HTTP/1.1
06:24:12.311 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 >> Host: localhost:40529
06:24:12.311 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 >> Connection: Keep-Alive
06:24:12.311 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 >> User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)
06:24:12.311 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 >> Accept-Encoding: gzip,deflate
06:24:12.311 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 >> "OPTIONS /foo HTTP/1.1[\r][\n]"
06:24:12.311 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 >> "Host: localhost:40529[\r][\n]"
06:24:12.311 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 >> "Connection: Keep-Alive[\r][\n]"
06:24:12.311 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 >> "User-Agent: Apache-HttpClient/4.5.2 (Java/1.8.0_131)[\r][\n]"
06:24:12.311 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 >> "Accept-Encoding: gzip,deflate[\r][\n]"
06:24:12.311 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 >> "[\r][\n]"
06:24:12.326 [XNIO-10 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_131)], Host=[localhost:40529]}, cookies=[:]): <empty> }
06:24:12.327 [XNIO-10 task-4] DEBUG com.stehno.ersatz.ErsatzServer - Response({Allow=[GET, HEAD, POST]}): <empty>
06:24:12.327 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 << "HTTP/1.1 200 OK[\r][\n]"
06:24:12.327 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 << "Allow: GET[\r][\n]"
06:24:12.327 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 << "Allow: HEAD[\r][\n]"
06:24:12.327 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 << "Allow: POST[\r][\n]"
06:24:12.327 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 << "Connection: keep-alive[\r][\n]"
06:24:12.327 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 << "Content-Length: 0[\r][\n]"
06:24:12.327 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 << "Date: Mon, 20 Nov 2017 12:24:10 GMT[\r][\n]"
06:24:12.327 [Test worker] DEBUG org.apache.http.wire - http-outgoing-9 << "[\r][\n]"
06:24:12.328 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 << HTTP/1.1 200 OK
06:24:12.328 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 << Allow: GET
06:24:12.328 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 << Allow: HEAD
06:24:12.328 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 << Allow: POST
06:24:12.328 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 << Connection: keep-alive
06:24:12.328 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 << Content-Length: 0
06:24:12.328 [Test worker] DEBUG org.apache.http.headers - http-outgoing-9 << Date: Mon, 20 Nov 2017 12:24:10 GMT
06:24:12.328 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
06:24:12.328 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Releasing connection 127.0.0.1:51950<->127.0.0.1:40529
06:24:12.328 [Test worker] DEBUG org.apache.http.impl.conn.BasicHttpClientConnectionManager - Connection can be kept alive indefinitely
06:24:12.329 [Test worker] DEBUG ersatz.undertow - stopping undertow server ersatz.undertow.Undertow@1081e5de