09:12:57.399 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - Found @PactTestFor annotation on test method void edu.obya.blueprint.customer.cdc.pact.consumer.CustomerPactConsumerATest.removeCustomer(au.com.dius.pact.consumer.MockServer)
09:12:57.430 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - providerInfo = ProviderInfo(providerName=, hostInterface=, port=, pactVersion=null, providerType=null, https=false, mockServerImplementation=Default, keyStorePath=, keyStoreAlias=, keyStorePassword=, privateKeyPassword=)
09:12:57.436 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - Looking for @Pact method named 'removeCustomer' for provider 'default'
09:12:57.440 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - Invoking method 'removeCustomer' to get Pact for the test 'removeCustomer'
09:12:57.633 [Test worker] DEBUG io.pact.plugins.jvm.core.CatalogueManager - Core catalogue entries:
core/content-matcher/xml
core/content-matcher/json
core/content-matcher/text
core/content-matcher/multipart-form-data
core/content-matcher/form-urlencoded
core/matcher/v1-equality
core/matcher/v2-regex
core/matcher/v2-type
core/matcher/v2-min-type
core/matcher/v2-max-type
core/matcher/v2-minmax-type
core/matcher/v3-number-type
core/matcher/v3-integer-type
core/matcher/v3-decimal-type
core/matcher/v3-date
core/matcher/v3-time
core/matcher/v3-datetime
core/matcher/v3-includes
core/matcher/v3-null
core/matcher/v3-content-type
core/matcher/v4-equals-ignore-order
core/matcher/v4-min-equals-ignore-order
core/matcher/v4-max-equals-ignore-order
core/matcher/v4-minmax-equals-ignore-order
core/matcher/v4-array-contains
core/matcher/v4-notempty
core/matcher/v4-semver
core/matcher/v4-each-key
core/matcher/v4-each-value
core/transport/http
core/transport/https
core/interaction/message
core/interaction/synchronous-message
core/content-generator/json
09:12:57.713 [Test worker] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Starting mock server
09:12:57.715 [Test worker] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Mock server started: /127.0.0.1:46271
09:12:57.972 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000001 preparing request execution
09:12:57.992 [Test worker] DEBUG org.apache.hc.client5.http.protocol.RequestAddCookies - ex-0000000001 Cookie spec selected: strict
09:12:57.995 [Test worker] DEBUG org.apache.hc.client5.http.protocol.RequestAuthCache - ex-0000000001 Auth cache not set in the context
09:12:57.996 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.ProtocolExec - ex-0000000001 target auth state: UNCHALLENGED
09:12:57.996 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.ProtocolExec - ex-0000000001 proxy auth state: UNCHALLENGED
09:12:57.996 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.ConnectExec - ex-0000000001 acquiring connection with route {}->http://localhost:46271
09:12:57.997 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000001 acquiring endpoint (3 MINUTES)
09:12:57.998 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.BasicHttpClientConnectionManager - ep-0000000000 Get connection for route {}->http://localhost:46271
09:12:58.008 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000001 acquired endpoint InternalConnectionEndpoint-1a28b346
09:12:58.008 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.ConnectExec - ex-0000000001 opening connection {}->http://localhost:46271
09:12:58.009 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - InternalConnectionEndpoint-1a28b346 connecting endpoint (3 MINUTES)
09:12:58.011 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.DefaultHttpClientConnectionOperator - http-outgoing-0 connecting to localhost/127.0.0.1:46271
09:12:58.013 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.DefaultHttpClientConnectionOperator - http-outgoing-0 connection established 127.0.0.1:44600<->127.0.0.1:46271
09:12:58.013 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - InternalConnectionEndpoint-1a28b346 endpoint connected
09:12:58.014 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.MainClientExec - ex-0000000001 executing OPTIONS / HTTP/1.1
09:12:58.014 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - InternalConnectionEndpoint-1a28b346 start execution ex-0000000001
09:12:58.014 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.BasicHttpClientConnectionManager - ep-0000000000 Executing exchange ex-0000000001
09:12:58.014 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-0 >> OPTIONS / HTTP/1.1
09:12:58.014 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-0 >> X-PACT-BOOTCHECK: true
09:12:58.014 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-0 >> Accept-Encoding: gzip, x-gzip, deflate
09:12:58.015 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-0 >> Host: localhost:46271
09:12:58.015 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-0 >> Connection: keep-alive
09:12:58.015 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-0 >> User-Agent: Apache-HttpClient/5.1.4 (Java/17.0.7)
09:12:58.015 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-0 >> "OPTIONS / HTTP/1.1[\r][\n]"
09:12:58.015 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-0 >> "X-PACT-BOOTCHECK: true[\r][\n]"
09:12:58.015 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-0 >> "Accept-Encoding: gzip, x-gzip, deflate[\r][\n]"
09:12:58.015 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-0 >> "Host: localhost:46271[\r][\n]"
09:12:58.015 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-0 >> "Connection: keep-alive[\r][\n]"
09:12:58.015 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-0 >> "User-Agent: Apache-HttpClient/5.1.4 (Java/17.0.7)[\r][\n]"
09:12:58.015 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-0 >> "[\r][\n]"
09:12:58.038 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-0 << "HTTP/1.1 200 OK[\r][\n]"
09:12:58.039 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-0 << "Date: Mon, 22 May 2023 09:12:58 GMT[\r][\n]"
09:12:58.039 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-0 << "Transfer-encoding: chunked[\r][\n]"
09:12:58.039 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-0 << "X-pact-bootcheck: true[\r][\n]"
09:12:58.039 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-0 << "[\r][\n]"
09:12:58.041 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-0 << HTTP/1.1 200 OK
09:12:58.041 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-0 << Date: Mon, 22 May 2023 09:12:58 GMT
09:12:58.041 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-0 << Transfer-encoding: chunked
09:12:58.041 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-0 << X-pact-bootcheck: true
09:12:58.044 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.MainClientExec - ex-0000000001 connection can be kept alive for 3 MINUTES
09:12:58.048 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-0 << "0[\r][\n]"
09:12:58.048 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-0 << "[\r][\n]"
09:12:58.048 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - InternalConnectionEndpoint-1a28b346 releasing valid endpoint
09:12:58.048 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.BasicHttpClientConnectionManager - ep-0000000000 Releasing connection 127.0.0.1:44600<->127.0.0.1:46271
09:12:58.048 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.BasicHttpClientConnectionManager - ep-0000000000 Connection can be kept alive for 3 MINUTES
09:12:58.328 [Test worker] DEBUG org.springframework.web.client.RestTemplate - HTTP DELETE http://localhost:46271/customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd
09:12:58.338 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
09:12:58.343 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
09:12:58.345 [Test worker] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection request: [route: {}->http://localhost:46271][total available: 0; route allocated: 0 of 5; total allocated: 0 of 10]
09:12:58.353 [Test worker] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection leased: [id: 0][route: {}->http://localhost:46271][total available: 0; route allocated: 1 of 5; total allocated: 1 of 10]
09:12:58.354 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {}->http://localhost:46271
09:12:58.356 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:46271
09:12:58.357 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:44606<->127.0.0.1:46271
09:12:58.357 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request DELETE /customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd HTTP/1.1
09:12:58.357 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
09:12:58.358 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> DELETE /customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd HTTP/1.1
09:12:58.359 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> Authorization: Basic YWRtaW46YWRtaW4=
09:12:58.359 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> Content-Type: application/json
09:12:58.359 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> Content-Length: 0
09:12:58.359 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> Host: localhost:46271
09:12:58.359 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> Connection: Keep-Alive
09:12:58.360 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> User-Agent: Apache-HttpClient/4.5.14 (Java/17.0.7)
09:12:58.360 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 >> Accept-Encoding: gzip,deflate
09:12:58.360 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "DELETE /customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd HTTP/1.1[\r][\n]"
09:12:58.360 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "Authorization: Basic YWRtaW46YWRtaW4=[\r][\n]"
09:12:58.361 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "Content-Type: application/json[\r][\n]"
09:12:58.361 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "Content-Length: 0[\r][\n]"
09:12:58.361 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "Host: localhost:46271[\r][\n]"
09:12:58.361 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "Connection: Keep-Alive[\r][\n]"
09:12:58.361 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "User-Agent: Apache-HttpClient/4.5.14 (Java/17.0.7)[\r][\n]"
09:12:58.361 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "Accept-Encoding: gzip,deflate[\r][\n]"
09:12:58.361 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 >> "[\r][\n]"
09:12:58.372 [HTTP-Dispatcher] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Received request: method: DELETE
path: /customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd
query: {}
headers: {Accept-encoding=[gzip,deflate], Connection=[Keep-Alive], Host=[localhost:46271], User-agent=[Apache-HttpClient/4.5.14 (Java/17.0.7)], Authorization=[Basic YWRtaW46YWRtaW4=], Content-type=[application/json], Content-length=[0]}
matchers: MatchingRules(rules={})
generators: Generators(categories={})
body: EMPTY
09:12:58.376 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.RequestMatching - comparing to expected request:
HttpRequest(method=DELETE, path=/customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd, query={}, headers={Authorization=[Basic YWRtaW46YWRtaW4=]}, body=MISSING, matchingRules=MatchingRules(rules={path=MatchingRuleCategory(name=path, matchingRules={=MatchingRuleGroup(rules=[RegexMatcher(regex=^/customers/[a-f0-9]{8}-[a-f0-9]{4}-[a-f0-9]{4}-[a-f0-9]{4}-[a-f0-9]{12}$, example=null)], ruleLogic=AND, cascaded=false)}), header=MatchingRuleCategory(name=header, matchingRules={Authorization=MatchingRuleGroup(rules=[RegexMatcher(regex=Basic (?:[A-Za-z0-9+/]{4})*(?:[A-Za-z0-9+/]{2}==|[A-Za-z0-9+/]{3}=)?, example=null)], ruleLogic=AND, cascaded=false)})}), generators=Generators(categories={}))
09:12:58.377 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.RequestMatching - pluginConfiguration={}
09:12:58.384 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.MatcherExecutor - comparing '/customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd' with regexp ^/customers/[a-f0-9]{8}-[a-f0-9]{4}-[a-f0-9]{4}-[a-f0-9]{4}-[a-f0-9]{12}$ at [] -> true
09:12:58.386 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.HeaderMatcher - Comparing header 'Authorization': 'Basic YWRtaW46YWRtaW4=' to 'Basic YWRtaW46YWRtaW4='
09:12:58.387 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.MatcherExecutor - comparing 'Basic YWRtaW46YWRtaW4=' with regexp Basic (?:[A-Za-z0-9+/]{4})*(?:[A-Za-z0-9+/]{2}==|[A-Za-z0-9+/]{3}=)? at [Authorization] -> true
09:12:58.390 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.Matching - matchBody: context=MatchingContext(matchers=MatchingRuleCategory(name=body, matchingRules={}), allowUnexpectedKeys=false, pluginConfiguration={})
09:12:58.395 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.RequestMatching - Request mismatch: RequestMatchResult(method=null, path=null, query=[], cookie=null, headers=[HeaderMatchResult(key=Authorization, result=[])], body=BodyMatchResult(typeMismatch=null, bodyResults=[]))
09:12:58.401 [HTTP-Dispatcher] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Generating response: HttpResponse(status=204, headers={}, body=MISSING, matchingRules=MatchingRules(rules={status=MatchingRuleCategory(name=status, matchingRules={}), header=MatchingRuleCategory(name=header, matchingRules={})}), generators=Generators(categories={}))
09:12:58.409 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "HTTP/1.1 204 No Content[\r][\n]"
09:12:58.409 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "Date: Mon, 22 May 2023 09:12:58 GMT[\r][\n]"
09:12:58.409 [Test worker] DEBUG org.apache.http.wire - http-outgoing-0 << "[\r][\n]"
09:12:58.411 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 << HTTP/1.1 204 No Content
09:12:58.411 [Test worker] DEBUG org.apache.http.headers - http-outgoing-0 << Date: Mon, 22 May 2023 09:12:58 GMT
09:12:58.413 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
09:12:58.413 [Test worker] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection [id: 0][route: {}->http://localhost:46271] can be kept alive indefinitely
09:12:58.414 [Test worker] DEBUG org.apache.http.impl.conn.DefaultManagedHttpClientConnection - http-outgoing-0: set socket timeout to 0
09:12:58.414 [Test worker] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection released: [id: 0][route: {}->http://localhost:46271][total available: 1; route allocated: 1 of 5; total allocated: 1 of 10]
09:12:58.417 [Test worker] DEBUG org.springframework.web.client.RestTemplate - Response 204 NO_CONTENT
09:12:58.421 [Thread-4] WARN au.com.dius.pact.core.support.Metrics -
Please note: we are tracking events anonymously to gather important usage statistics like JVM version
and operating system. To disable tracking, set the 'pact_do_not_track' system property or environment
variable to 'true'.
09:12:58.451 [Thread-4] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000002 preparing request execution
09:12:58.452 [Thread-4] DEBUG org.apache.hc.client5.http.protocol.RequestAddCookies - ex-0000000002 Cookie spec selected: strict
09:12:58.452 [Thread-4] DEBUG org.apache.hc.client5.http.protocol.RequestAuthCache - ex-0000000002 Auth cache not set in the context
09:12:58.452 [Thread-4] DEBUG org.apache.hc.client5.http.impl.classic.ProtocolExec - ex-0000000002 target auth state: UNCHALLENGED
09:12:58.452 [Thread-4] DEBUG org.apache.hc.client5.http.impl.classic.ProtocolExec - ex-0000000002 proxy auth state: UNCHALLENGED
09:12:58.452 [Thread-4] DEBUG org.apache.hc.client5.http.impl.classic.ConnectExec - ex-0000000002 acquiring connection with route {s}->https://www.google-analytics.com:443
09:12:58.452 [Thread-4] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000002 acquiring endpoint (3 MINUTES)
09:12:58.453 [Thread-4] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ex-0000000002 endpoint lease request (3 MINUTES) [route: {s}->https://www.google-analytics.com:443][total available: 0; route allocated: 0 of 100; total allocated: 0 of 200]
09:12:58.456 [Thread-4] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ex-0000000002 endpoint leased [route: {s}->https://www.google-analytics.com:443][total available: 0; route allocated: 1 of 100; total allocated: 1 of 200]
09:12:58.456 [Thread-4] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ex-0000000002 acquired ep-0000000001
09:12:58.456 [Thread-4] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000002 acquired endpoint ep-0000000001
09:12:58.456 [Thread-4] DEBUG org.apache.hc.client5.http.impl.classic.ConnectExec - ex-0000000002 opening connection {s}->https://www.google-analytics.com:443
09:12:58.457 [Thread-4] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ep-0000000001 connecting endpoint (3 MINUTES)
09:12:58.457 [Thread-4] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000001 connecting endpoint to https://www.google-analytics.com:443 (3 MINUTES)
09:12:58.467 [Thread-4] DEBUG org.apache.hc.client5.http.impl.io.DefaultHttpClientConnectionOperator - http-outgoing-1 connecting to www.google-analytics.com/142.251.16.101:443
09:12:58.467 [Thread-4] DEBUG org.apache.hc.client5.http.ssl.SSLConnectionSocketFactory - Connecting socket to www.google-analytics.com/142.251.16.101:443 with timeout 3 MINUTES
09:12:58.505 [Thread-4] DEBUG org.apache.hc.client5.http.ssl.SSLConnectionSocketFactory - Enabled protocols: [TLSv1.3, TLSv1.2]
09:12:58.506 [Thread-4] DEBUG org.apache.hc.client5.http.ssl.SSLConnectionSocketFactory - Enabled cipher suites: [TLS_AES_256_GCM_SHA384, TLS_AES_128_GCM_SHA256, TLS_CHACHA20_POLY1305_SHA256, TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_CHACHA20_POLY1305_SHA256, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_RSA_WITH_AES_256_GCM_SHA384, TLS_DHE_RSA_WITH_CHACHA20_POLY1305_SHA256, TLS_DHE_DSS_WITH_AES_256_GCM_SHA384, TLS_DHE_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_DSS_WITH_AES_128_GCM_SHA256, TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_RSA_WITH_AES_256_CBC_SHA256, TLS_DHE_DSS_WITH_AES_256_CBC_SHA256, TLS_DHE_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_DSS_WITH_AES_128_CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_256_GCM_SHA384, TLS_ECDH_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDH_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA384, TLS_ECDH_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_RSA_WITH_AES_256_CBC_SHA, TLS_DHE_DSS_WITH_AES_256_CBC_SHA, TLS_DHE_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_DSS_WITH_AES_128_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA, TLS_ECDH_RSA_WITH_AES_256_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_EMPTY_RENEGOTIATION_INFO_SCSV]
09:12:58.506 [Thread-4] DEBUG org.apache.hc.client5.http.ssl.SSLConnectionSocketFactory - Starting handshake
09:12:58.521 [Test worker] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Mock server shutdown
09:12:58.668 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - Found @PactTestFor annotation on test method void edu.obya.blueprint.customer.cdc.pact.consumer.CustomerPactConsumerATest.addCustomer(au.com.dius.pact.consumer.MockServer)
09:12:58.668 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - providerInfo = ProviderInfo(providerName=, hostInterface=, port=, pactVersion=null, providerType=null, https=false, mockServerImplementation=Default, keyStorePath=, keyStoreAlias=, keyStorePassword=, privateKeyPassword=)
09:12:58.669 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - Looking for @Pact method named 'addCustomer' for provider 'default'
09:12:58.669 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - Invoking method 'addCustomer' to get Pact for the test 'addCustomer'
09:12:58.669 [Test worker] DEBUG io.pact.plugins.jvm.core.CatalogueManager - Core catalogue entries:
core/content-matcher/xml
core/content-matcher/json
core/content-matcher/text
core/content-matcher/multipart-form-data
core/content-matcher/form-urlencoded
core/matcher/v1-equality
core/matcher/v2-regex
core/matcher/v2-type
core/matcher/v2-min-type
core/matcher/v2-max-type
core/matcher/v2-minmax-type
core/matcher/v3-number-type
core/matcher/v3-integer-type
core/matcher/v3-decimal-type
core/matcher/v3-date
core/matcher/v3-time
core/matcher/v3-datetime
core/matcher/v3-includes
core/matcher/v3-null
core/matcher/v3-content-type
core/matcher/v4-equals-ignore-order
core/matcher/v4-min-equals-ignore-order
core/matcher/v4-max-equals-ignore-order
core/matcher/v4-minmax-equals-ignore-order
core/matcher/v4-array-contains
core/matcher/v4-notempty
core/matcher/v4-semver
core/matcher/v4-each-key
core/matcher/v4-each-value
core/transport/http
core/transport/https
core/interaction/message
core/interaction/synchronous-message
core/content-generator/json
09:12:58.688 [Thread-4] DEBUG org.apache.hc.client5.http.ssl.SSLConnectionSocketFactory - Secure session established
09:12:58.689 [Thread-4] DEBUG org.apache.hc.client5.http.ssl.SSLConnectionSocketFactory - negotiated protocol: TLSv1.3
09:12:58.690 [Thread-4] DEBUG org.apache.hc.client5.http.ssl.SSLConnectionSocketFactory - negotiated cipher suite: TLS_AES_256_GCM_SHA384
09:12:58.690 [Thread-4] DEBUG org.apache.hc.client5.http.ssl.SSLConnectionSocketFactory - peer principal: CN=*.google-analytics.com
09:12:58.691 [Thread-4] DEBUG org.apache.hc.client5.http.ssl.SSLConnectionSocketFactory - peer alternative names: [*.google-analytics.com, region1.app-measurement.com, app-measurement.com, google-analytics.com, region1.google-analytics.com, ssl.google-analytics.com, www.google-analytics.com, region1.analytics.google.com, googletagmanager.com, www.googletagmanager.com, urchin.com, service.urchin.com, fps.goog, *.fps.goog, googleoptimize.com, www.googleoptimize.com]
09:12:58.691 [Thread-4] DEBUG org.apache.hc.client5.http.ssl.SSLConnectionSocketFactory - issuer principal: CN=GTS CA 1C3, O=Google Trust Services LLC, C=US
09:12:58.692 [Thread-4] DEBUG org.apache.hc.client5.http.impl.io.DefaultHttpClientConnectionOperator - http-outgoing-1 connection established 10.1.0.77:51296<->142.251.16.101:443
09:12:58.693 [Test worker] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Starting mock server
09:12:58.693 [Thread-4] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000001 connected http-outgoing-1
09:12:58.693 [Thread-4] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ep-0000000001 endpoint connected
09:12:58.694 [Thread-4] DEBUG org.apache.hc.client5.http.impl.classic.MainClientExec - ex-0000000002 executing POST /collect HTTP/1.1
09:12:58.694 [Thread-4] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ep-0000000001 start execution ex-0000000002
09:12:58.694 [Thread-4] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000001 executing exchange ex-0000000002 over http-outgoing-1
09:12:58.695 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> POST /collect HTTP/1.1
09:12:58.695 [Test worker] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Mock server started: /127.0.0.1:36897
09:12:58.695 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Accept-Encoding: gzip, x-gzip, deflate
09:12:58.696 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Content-Length: 234
09:12:58.696 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Content-Type: application/x-www-form-urlencoded; charset=ISO-8859-1
09:12:58.696 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Host: www.google-analytics.com
09:12:58.696 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Connection: keep-alive
09:12:58.696 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000003 preparing request execution
09:12:58.696 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> User-Agent: Apache-HttpClient/5.1.4 (Java/17.0.7)
09:12:58.697 [Test worker] DEBUG org.apache.hc.client5.http.protocol.RequestAddCookies - ex-0000000003 Cookie spec selected: strict
09:12:58.698 [Test worker] DEBUG org.apache.hc.client5.http.protocol.RequestAuthCache - ex-0000000003 Auth cache not set in the context
09:12:58.698 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.ProtocolExec - ex-0000000003 target auth state: UNCHALLENGED
09:12:58.698 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.ProtocolExec - ex-0000000003 proxy auth state: UNCHALLENGED
09:12:58.698 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.ConnectExec - ex-0000000003 acquiring connection with route {}->http://localhost:36897
09:12:58.698 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000003 acquiring endpoint (3 MINUTES)
09:12:58.698 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.BasicHttpClientConnectionManager - ep-0000000001 Get connection for route {}->http://localhost:36897
09:12:58.698 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000003 acquired endpoint InternalConnectionEndpoint-6cd15072
09:12:58.698 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.ConnectExec - ex-0000000003 opening connection {}->http://localhost:36897
09:12:58.698 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - InternalConnectionEndpoint-6cd15072 connecting endpoint (3 MINUTES)
09:12:58.698 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.DefaultHttpClientConnectionOperator - http-outgoing-2 connecting to localhost/127.0.0.1:36897
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.DefaultHttpClientConnectionOperator - http-outgoing-2 connection established 127.0.0.1:60286<->127.0.0.1:36897
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - InternalConnectionEndpoint-6cd15072 endpoint connected
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.MainClientExec - ex-0000000003 executing OPTIONS / HTTP/1.1
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - InternalConnectionEndpoint-6cd15072 start execution ex-0000000003
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.BasicHttpClientConnectionManager - ep-0000000001 Executing exchange ex-0000000003
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-2 >> OPTIONS / HTTP/1.1
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-2 >> X-PACT-BOOTCHECK: true
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-2 >> Accept-Encoding: gzip, x-gzip, deflate
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-2 >> Host: localhost:36897
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-2 >> Connection: keep-alive
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-2 >> User-Agent: Apache-HttpClient/5.1.4 (Java/17.0.7)
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-2 >> "OPTIONS / HTTP/1.1[\r][\n]"
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-2 >> "X-PACT-BOOTCHECK: true[\r][\n]"
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-2 >> "Accept-Encoding: gzip, x-gzip, deflate[\r][\n]"
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-2 >> "Host: localhost:36897[\r][\n]"
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-2 >> "Connection: keep-alive[\r][\n]"
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-2 >> "User-Agent: Apache-HttpClient/5.1.4 (Java/17.0.7)[\r][\n]"
09:12:58.699 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-2 >> "[\r][\n]"
09:12:58.700 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-2 << "HTTP/1.1 200 OK[\r][\n]"
09:12:58.700 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-2 << "Date: Mon, 22 May 2023 09:12:58 GMT[\r][\n]"
09:12:58.700 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-2 << "Transfer-encoding: chunked[\r][\n]"
09:12:58.700 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-2 << "X-pact-bootcheck: true[\r][\n]"
09:12:58.700 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-2 << "[\r][\n]"
09:12:58.700 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-2 << HTTP/1.1 200 OK
09:12:58.700 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-2 << Date: Mon, 22 May 2023 09:12:58 GMT
09:12:58.700 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-2 << Transfer-encoding: chunked
09:12:58.700 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-2 << X-pact-bootcheck: true
09:12:58.701 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.MainClientExec - ex-0000000003 connection can be kept alive for 3 MINUTES
09:12:58.701 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-2 << "0[\r][\n]"
09:12:58.701 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-2 << "[\r][\n]"
09:12:58.701 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - InternalConnectionEndpoint-6cd15072 releasing valid endpoint
09:12:58.701 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.BasicHttpClientConnectionManager - ep-0000000001 Releasing connection 127.0.0.1:60286<->127.0.0.1:36897
09:12:58.701 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.BasicHttpClientConnectionManager - ep-0000000001 Connection can be kept alive for 3 MINUTES
09:12:58.705 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "POST /collect HTTP/1.1[\r][\n]"
09:12:58.705 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Accept-Encoding: gzip, x-gzip, deflate[\r][\n]"
09:12:58.705 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Content-Length: 234[\r][\n]"
09:12:58.705 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Content-Type: application/x-www-form-urlencoded; charset=ISO-8859-1[\r][\n]"
09:12:58.705 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Host: www.google-analytics.com[\r][\n]"
09:12:58.706 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Connection: keep-alive[\r][\n]"
09:12:58.706 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "User-Agent: Apache-HttpClient/5.1.4 (Java/17.0.7)[\r][\n]"
09:12:58.706 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "[\r][\n]"
09:12:58.706 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "v=1&t=event&tid=UA-117778936-1&cid=4547fca33e88207b4eec2aa6a7059045&an=pact-jvm&aid=pact-jvm&av=4.5.5&aip=true&ds=client&cd2=CI&cd3=linux-amd64&cd6=junit5&cd7=17.0.7%2B7-LTS&el=Pact+consumer+tests+ran&ec=ConsumerTest&ea=Completed&ev=1"
09:12:58.711 [Test worker] DEBUG org.springframework.web.client.RestTemplate - HTTP POST http://localhost:36897/customers
09:12:58.713 [Test worker] DEBUG org.springframework.web.client.RestTemplate - Accept=[text/plain, application/json, application/*+json, */*]
09:12:58.722 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "HTTP/1.1 200 OK[\r][\n]"
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Access-Control-Allow-Origin: *[\r][\n]"
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Date: Mon, 22 May 2023 09:12:58 GMT[\r][\n]"
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Pragma: no-cache[\r][\n]"
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Expires: Fri, 01 Jan 1990 00:00:00 GMT[\r][\n]"
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Cache-Control: no-cache, no-store, must-revalidate[\r][\n]"
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Last-Modified: Sun, 17 May 1998 03:00:00 GMT[\r][\n]"
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "X-Content-Type-Options: nosniff[\r][\n]"
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Content-Type: image/gif[\r][\n]"
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Cross-Origin-Resource-Policy: cross-origin[\r][\n]"
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Server: Golfe2[\r][\n]"
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Content-Length: 35[\r][\n]"
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Alt-Svc: h3=":443"; ma=2592000,h3-29=":443"; ma=2592000[\r][\n]"
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "[\r][\n]"
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "GIF89a[0x1][0x0][0x1][0x0][0xffffff80][0xffffffff][0x0][0xffffffff][0xffffffff][0xffffffff][0x0][0x0][0x0],[0x0][0x0][0x0][0x0][0x1][0x0][0x1][0x0][0x0][0x2][0x2]D[0x1][0x0];"
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << HTTP/1.1 200 OK
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Access-Control-Allow-Origin: *
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Date: Mon, 22 May 2023 09:12:58 GMT
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Pragma: no-cache
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Expires: Fri, 01 Jan 1990 00:00:00 GMT
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Cache-Control: no-cache, no-store, must-revalidate
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Last-Modified: Sun, 17 May 1998 03:00:00 GMT
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << X-Content-Type-Options: nosniff
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Content-Type: image/gif
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Cross-Origin-Resource-Policy: cross-origin
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Server: Golfe2
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Content-Length: 35
09:12:58.723 [Thread-4] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Alt-Svc: h3=":443"; ma=2592000,h3-29=":443"; ma=2592000
09:12:58.724 [Thread-4] DEBUG org.apache.hc.client5.http.impl.classic.MainClientExec - ex-0000000002 connection can be kept alive for 3 MINUTES
09:12:58.725 [Thread-4] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ep-0000000001 releasing valid endpoint
09:12:58.725 [Thread-4] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000001 releasing endpoint
09:12:58.726 [Thread-4] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000001 connection http-outgoing-1 can be kept alive for 3 MINUTES
09:12:58.726 [Thread-4] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000001 connection released [route: {s}->https://www.google-analytics.com:443][total available: 1; route allocated: 1 of 100; total allocated: 1 of 200]
09:12:58.778 [Test worker] DEBUG org.springframework.web.client.RestTemplate - Writing [CustomerDto(firstName=John, lastName=Doe)] with org.springframework.http.converter.json.MappingJackson2HttpMessageConverter
09:12:58.786 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
09:12:58.787 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
09:12:58.787 [Test worker] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection request: [route: {}->http://localhost:36897][total available: 0; route allocated: 0 of 5; total allocated: 0 of 10]
09:12:58.787 [Test worker] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection leased: [id: 1][route: {}->http://localhost:36897][total available: 0; route allocated: 1 of 5; total allocated: 1 of 10]
09:12:58.787 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {}->http://localhost:36897
09:12:58.787 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:36897
09:12:58.787 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:60300<->127.0.0.1:36897
09:12:58.787 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request POST /customers HTTP/1.1
09:12:58.787 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
09:12:58.788 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> POST /customers HTTP/1.1
09:12:58.788 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> Authorization: Basic dGVzdDp0ZXN0
09:12:58.788 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> Content-Type: application/json
09:12:58.788 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> Accept: text/plain, application/json, application/*+json, */*
09:12:58.788 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> Content-Length: 37
09:12:58.788 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> Host: localhost:36897
09:12:58.788 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> Connection: Keep-Alive
09:12:58.788 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> User-Agent: Apache-HttpClient/4.5.14 (Java/17.0.7)
09:12:58.788 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 >> Accept-Encoding: gzip,deflate
09:12:58.791 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "POST /customers HTTP/1.1[\r][\n]"
09:12:58.792 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "Authorization: Basic dGVzdDp0ZXN0[\r][\n]"
09:12:58.794 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "Content-Type: application/json[\r][\n]"
09:12:58.796 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "Accept: text/plain, application/json, application/*+json, */*[\r][\n]"
09:12:58.797 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "Content-Length: 37[\r][\n]"
09:12:58.798 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "Host: localhost:36897[\r][\n]"
09:12:58.801 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "Connection: Keep-Alive[\r][\n]"
09:12:58.801 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "User-Agent: Apache-HttpClient/4.5.14 (Java/17.0.7)[\r][\n]"
09:12:58.801 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "Accept-Encoding: gzip,deflate[\r][\n]"
09:12:58.802 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "[\r][\n]"
09:12:58.802 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 >> "{"firstName":"John","lastName":"Doe"}"
09:12:58.803 [HTTP-Dispatcher] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Received request: method: POST
path: /customers
query: {}
headers: {Accept-encoding=[gzip,deflate], Accept=[text/plain, application/json, application/*+json, */*], Connection=[Keep-Alive], Host=[localhost:36897], User-agent=[Apache-HttpClient/4.5.14 (Java/17.0.7)], Authorization=[Basic dGVzdDp0ZXN0], Content-type=[application/json], Content-length=[37]}
matchers: MatchingRules(rules={})
generators: Generators(categories={})
body: PRESENT({"firstName":"John","lastName":"Doe"})
09:12:58.803 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.RequestMatching - comparing to expected request:
HttpRequest(method=POST, path=/customers, query={}, headers={Content-Type=[application/json], Authorization=[Basic dGVzdDp0ZXN0]}, body=PRESENT({"firstName":"John","lastName":"Doe"}), matchingRules=MatchingRules(rules={header=MatchingRuleCategory(name=header, matchingRules={Content-Type=MatchingRuleGroup(rules=[RegexMatcher(regex=application/json, example=null)], ruleLogic=AND, cascaded=false), Authorization=MatchingRuleGroup(rules=[RegexMatcher(regex=Basic (?:[A-Za-z0-9+/]{4})*(?:[A-Za-z0-9+/]{2}==|[A-Za-z0-9+/]{3}=)?, example=null)], ruleLogic=AND, cascaded=false)}), body=MatchingRuleCategory(name=body, matchingRules={$.firstName=MatchingRuleGroup(rules=[au.com.dius.pact.core.model.matchingrules.TypeMatcher@72c7a360], ruleLogic=AND, cascaded=false), $.lastName=MatchingRuleGroup(rules=[au.com.dius.pact.core.model.matchingrules.TypeMatcher@72c7a360], ruleLogic=AND, cascaded=false)})}), generators=Generators(categories={}))
09:12:58.803 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.RequestMatching - pluginConfiguration={}
09:12:58.803 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.HeaderMatcher - Comparing header 'Authorization': 'Basic dGVzdDp0ZXN0' to 'Basic dGVzdDp0ZXN0'
09:12:58.804 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.MatcherExecutor - comparing 'Basic dGVzdDp0ZXN0' with regexp Basic (?:[A-Za-z0-9+/]{4})*(?:[A-Za-z0-9+/]{2}==|[A-Za-z0-9+/]{3}=)? at [Authorization] -> true
09:12:58.804 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.HeaderMatcher - Comparing header 'Content-Type': 'application/json' to 'application/json'
09:12:58.804 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.MatcherExecutor - comparing 'application/json' with regexp application/json at [Content-Type] -> true
09:12:58.804 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.Matching - matchBody: context=MatchingContext(matchers=MatchingRuleCategory(name=body, matchingRules={$.firstName=MatchingRuleGroup(rules=[au.com.dius.pact.core.model.matchingrules.TypeMatcher@72c7a360], ruleLogic=AND, cascaded=false), $.lastName=MatchingRuleGroup(rules=[au.com.dius.pact.core.model.matchingrules.TypeMatcher@72c7a360], ruleLogic=AND, cascaded=false)}), allowUnexpectedKeys=false, pluginConfiguration={})
09:12:58.830 [Finalizer] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection manager is shutting down
09:12:58.831 [Finalizer] DEBUG org.apache.http.impl.conn.DefaultManagedHttpClientConnection - http-outgoing-0: Close connection
09:12:58.831 [Finalizer] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection manager shut down
09:12:59.060 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.Matching - Found a matcher for application/json -> au.com.dius.pact.core.matchers.JsonContentMatcher@58f537a1
09:12:59.073 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.JsonContentMatcher - compareValues: Matcher defined for path [$, firstName]
09:12:59.081 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.MatcherExecutor - comparing type of "John" (String) to "John" (String) at [$, firstName]
09:12:59.081 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.JsonContentMatcher - compareValues: Matcher defined for path [$, lastName]
09:12:59.081 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.MatcherExecutor - comparing type of "Doe" (String) to "Doe" (String) at [$, lastName]
09:12:59.082 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.RequestMatching - Request mismatch: RequestMatchResult(method=null, path=null, query=[], cookie=null, headers=[HeaderMatchResult(key=Authorization, result=[]), HeaderMatchResult(key=Content-Type, result=[])], body=BodyMatchResult(typeMismatch=null, bodyResults=[BodyItemMatchResult(key=$.firstName, result=[]), BodyItemMatchResult(key=$.lastName, result=[])]))
09:12:59.082 [HTTP-Dispatcher] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Generating response: HttpResponse(status=201, headers={Content-Type=[text/plain]}, body=PRESENT(64a0f7d1-7b25-412d-b1e0-abacde3c21cd), matchingRules=MatchingRules(rules={header=MatchingRuleCategory(name=header, matchingRules={Content-Type=MatchingRuleGroup(rules=[RegexMatcher(regex=text/plain, example=null)], ruleLogic=AND, cascaded=false)}), status=MatchingRuleCategory(name=status, matchingRules={}), body=MatchingRuleCategory(name=body, matchingRules={})}), generators=Generators(categories={}))
09:12:59.083 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "HTTP/1.1 201 Created[\r][\n]"
09:12:59.083 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "Date: Mon, 22 May 2023 09:12:59 GMT[\r][\n]"
09:12:59.083 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "Content-length: 36[\r][\n]"
09:12:59.083 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "Content-Type: text/plain[\r][\n]"
09:12:59.083 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "[\r][\n]"
09:12:59.083 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << HTTP/1.1 201 Created
09:12:59.083 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << Date: Mon, 22 May 2023 09:12:59 GMT
09:12:59.083 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << Content-length: 36
09:12:59.083 [Test worker] DEBUG org.apache.http.headers - http-outgoing-1 << Content-Type: text/plain
09:12:59.083 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
09:12:59.084 [Test worker] DEBUG org.springframework.web.client.RestTemplate - Response 201 CREATED
09:12:59.085 [Test worker] DEBUG org.apache.http.wire - http-outgoing-1 << "64a0f7d1-7b25-412d-b1e0-abacde3c21cd"
09:12:59.085 [Test worker] DEBUG org.springframework.web.client.RestTemplate - Reading to [java.lang.String] as "text/plain"
09:12:59.086 [Test worker] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection [id: 1][route: {}->http://localhost:36897] can be kept alive indefinitely
09:12:59.086 [Test worker] DEBUG org.apache.http.impl.conn.DefaultManagedHttpClientConnection - http-outgoing-1: set socket timeout to 0
09:12:59.086 [Test worker] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection released: [id: 1][route: {}->http://localhost:36897][total available: 1; route allocated: 1 of 5; total allocated: 1 of 10]
09:12:59.133 [Thread-6] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000004 preparing request execution
09:12:59.133 [Thread-6] DEBUG org.apache.hc.client5.http.protocol.RequestAddCookies - ex-0000000004 Cookie spec selected: strict
09:12:59.133 [Thread-6] DEBUG org.apache.hc.client5.http.protocol.RequestAuthCache - ex-0000000004 Auth cache not set in the context
09:12:59.133 [Thread-6] DEBUG org.apache.hc.client5.http.impl.classic.ProtocolExec - ex-0000000004 target auth state: UNCHALLENGED
09:12:59.133 [Thread-6] DEBUG org.apache.hc.client5.http.impl.classic.ProtocolExec - ex-0000000004 proxy auth state: UNCHALLENGED
09:12:59.133 [Thread-6] DEBUG org.apache.hc.client5.http.impl.classic.ConnectExec - ex-0000000004 acquiring connection with route {s}->https://www.google-analytics.com:443
09:12:59.133 [Thread-6] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000004 acquiring endpoint (3 MINUTES)
09:12:59.133 [Thread-6] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ex-0000000004 endpoint lease request (3 MINUTES) [route: {s}->https://www.google-analytics.com:443][total available: 1; route allocated: 1 of 100; total allocated: 1 of 200]
09:12:59.133 [Thread-6] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ex-0000000004 endpoint leased [route: {s}->https://www.google-analytics.com:443][total available: 0; route allocated: 1 of 100; total allocated: 1 of 200]
09:12:59.133 [Thread-6] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ex-0000000004 acquired ep-0000000002
09:12:59.133 [Thread-6] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000004 acquired endpoint ep-0000000002
09:12:59.133 [Thread-6] DEBUG org.apache.hc.client5.http.impl.classic.MainClientExec - ex-0000000004 executing POST /collect HTTP/1.1
09:12:59.133 [Thread-6] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ep-0000000002 start execution ex-0000000004
09:12:59.133 [Thread-6] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000002 executing exchange ex-0000000004 over http-outgoing-1
09:12:59.134 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> POST /collect HTTP/1.1
09:12:59.134 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Accept-Encoding: gzip, x-gzip, deflate
09:12:59.134 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Content-Length: 234
09:12:59.134 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Content-Type: application/x-www-form-urlencoded; charset=ISO-8859-1
09:12:59.134 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Host: www.google-analytics.com
09:12:59.134 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Connection: keep-alive
09:12:59.134 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> User-Agent: Apache-HttpClient/5.1.4 (Java/17.0.7)
09:12:59.134 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "POST /collect HTTP/1.1[\r][\n]"
09:12:59.134 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Accept-Encoding: gzip, x-gzip, deflate[\r][\n]"
09:12:59.134 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Content-Length: 234[\r][\n]"
09:12:59.134 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Content-Type: application/x-www-form-urlencoded; charset=ISO-8859-1[\r][\n]"
09:12:59.134 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Host: www.google-analytics.com[\r][\n]"
09:12:59.134 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Connection: keep-alive[\r][\n]"
09:12:59.134 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "User-Agent: Apache-HttpClient/5.1.4 (Java/17.0.7)[\r][\n]"
09:12:59.134 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "[\r][\n]"
09:12:59.134 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "v=1&t=event&tid=UA-117778936-1&cid=4547fca33e88207b4eec2aa6a7059045&an=pact-jvm&aid=pact-jvm&av=4.5.5&aip=true&ds=client&cd2=CI&cd3=linux-amd64&cd6=junit5&cd7=17.0.7%2B7-LTS&el=Pact+consumer+tests+ran&ec=ConsumerTest&ea=Completed&ev=1"
09:12:59.149 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "HTTP/1.1 200 OK[\r][\n]"
09:12:59.150 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Access-Control-Allow-Origin: *[\r][\n]"
09:12:59.150 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Date: Mon, 22 May 2023 09:12:59 GMT[\r][\n]"
09:12:59.150 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Pragma: no-cache[\r][\n]"
09:12:59.150 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Expires: Fri, 01 Jan 1990 00:00:00 GMT[\r][\n]"
09:12:59.150 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Cache-Control: no-cache, no-store, must-revalidate[\r][\n]"
09:12:59.150 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Last-Modified: Sun, 17 May 1998 03:00:00 GMT[\r][\n]"
09:12:59.150 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "X-Content-Type-Options: nosniff[\r][\n]"
09:12:59.150 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Content-Type: image/gif[\r][\n]"
09:12:59.150 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Cross-Origin-Resource-Policy: cross-origin[\r][\n]"
09:12:59.150 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Server: Golfe2[\r][\n]"
09:12:59.150 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Content-Length: 35[\r][\n]"
09:12:59.150 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Alt-Svc: h3=":443"; ma=2592000,h3-29=":443"; ma=2592000[\r][\n]"
09:12:59.150 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "[\r][\n]"
09:12:59.150 [Thread-6] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "GIF89a[0x1][0x0][0x1][0x0][0xffffff80][0xffffffff][0x0][0xffffffff][0xffffffff][0xffffffff][0x0][0x0][0x0],[0x0][0x0][0x0][0x0][0x1][0x0][0x1][0x0][0x0][0x2][0x2]D[0x1][0x0];"
09:12:59.150 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << HTTP/1.1 200 OK
09:12:59.150 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Access-Control-Allow-Origin: *
09:12:59.151 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Date: Mon, 22 May 2023 09:12:59 GMT
09:12:59.151 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Pragma: no-cache
09:12:59.151 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Expires: Fri, 01 Jan 1990 00:00:00 GMT
09:12:59.151 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Cache-Control: no-cache, no-store, must-revalidate
09:12:59.151 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Last-Modified: Sun, 17 May 1998 03:00:00 GMT
09:12:59.151 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << X-Content-Type-Options: nosniff
09:12:59.151 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Content-Type: image/gif
09:12:59.151 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Cross-Origin-Resource-Policy: cross-origin
09:12:59.151 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Server: Golfe2
09:12:59.151 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Content-Length: 35
09:12:59.151 [Thread-6] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Alt-Svc: h3=":443"; ma=2592000,h3-29=":443"; ma=2592000
09:12:59.151 [Thread-6] DEBUG org.apache.hc.client5.http.impl.classic.MainClientExec - ex-0000000004 connection can be kept alive for 3 MINUTES
09:12:59.151 [Thread-6] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ep-0000000002 releasing valid endpoint
09:12:59.151 [Thread-6] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000002 releasing endpoint
09:12:59.151 [Thread-6] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000002 connection http-outgoing-1 can be kept alive for 3 MINUTES
09:12:59.151 [Thread-6] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000002 connection released [route: {s}->https://www.google-analytics.com:443][total available: 1; route allocated: 1 of 100; total allocated: 1 of 200]
09:12:59.229 [Test worker] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Mock server shutdown
09:12:59.239 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - Found @PactTestFor annotation on test method void edu.obya.blueprint.customer.cdc.pact.consumer.CustomerPactConsumerATest.getCustomer_whenExists(au.com.dius.pact.consumer.MockServer)
09:12:59.240 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - providerInfo = ProviderInfo(providerName=, hostInterface=, port=, pactVersion=null, providerType=null, https=false, mockServerImplementation=Default, keyStorePath=, keyStoreAlias=, keyStorePassword=, privateKeyPassword=)
09:12:59.241 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - Looking for @Pact method named 'getCustomer' for provider 'default'
09:12:59.241 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - Invoking method 'getCustomer' to get Pact for the test 'getCustomer_whenExists'
09:12:59.241 [Test worker] DEBUG io.pact.plugins.jvm.core.CatalogueManager - Core catalogue entries:
core/content-matcher/xml
core/content-matcher/json
core/content-matcher/text
core/content-matcher/multipart-form-data
core/content-matcher/form-urlencoded
core/matcher/v1-equality
core/matcher/v2-regex
core/matcher/v2-type
core/matcher/v2-min-type
core/matcher/v2-max-type
core/matcher/v2-minmax-type
core/matcher/v3-number-type
core/matcher/v3-integer-type
core/matcher/v3-decimal-type
core/matcher/v3-date
core/matcher/v3-time
core/matcher/v3-datetime
core/matcher/v3-includes
core/matcher/v3-null
core/matcher/v3-content-type
core/matcher/v4-equals-ignore-order
core/matcher/v4-min-equals-ignore-order
core/matcher/v4-max-equals-ignore-order
core/matcher/v4-minmax-equals-ignore-order
core/matcher/v4-array-contains
core/matcher/v4-notempty
core/matcher/v4-semver
core/matcher/v4-each-key
core/matcher/v4-each-value
core/transport/http
core/transport/https
core/interaction/message
core/interaction/synchronous-message
core/content-generator/json
09:12:59.247 [Test worker] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Starting mock server
09:12:59.248 [Test worker] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Mock server started: /127.0.0.1:33179
09:12:59.249 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000005 preparing request execution
09:12:59.249 [Test worker] DEBUG org.apache.hc.client5.http.protocol.RequestAddCookies - ex-0000000005 Cookie spec selected: strict
09:12:59.249 [Test worker] DEBUG org.apache.hc.client5.http.protocol.RequestAuthCache - ex-0000000005 Auth cache not set in the context
09:12:59.249 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.ProtocolExec - ex-0000000005 target auth state: UNCHALLENGED
09:12:59.249 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.ProtocolExec - ex-0000000005 proxy auth state: UNCHALLENGED
09:12:59.249 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.ConnectExec - ex-0000000005 acquiring connection with route {}->http://localhost:33179
09:12:59.249 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000005 acquiring endpoint (3 MINUTES)
09:12:59.249 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.BasicHttpClientConnectionManager - ep-0000000002 Get connection for route {}->http://localhost:33179
09:12:59.249 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000005 acquired endpoint InternalConnectionEndpoint-83bb0f
09:12:59.249 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.ConnectExec - ex-0000000005 opening connection {}->http://localhost:33179
09:12:59.249 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - InternalConnectionEndpoint-83bb0f connecting endpoint (3 MINUTES)
09:12:59.249 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.DefaultHttpClientConnectionOperator - http-outgoing-3 connecting to localhost/127.0.0.1:33179
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.DefaultHttpClientConnectionOperator - http-outgoing-3 connection established 127.0.0.1:55992<->127.0.0.1:33179
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - InternalConnectionEndpoint-83bb0f endpoint connected
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.MainClientExec - ex-0000000005 executing OPTIONS / HTTP/1.1
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - InternalConnectionEndpoint-83bb0f start execution ex-0000000005
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.BasicHttpClientConnectionManager - ep-0000000002 Executing exchange ex-0000000005
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-3 >> OPTIONS / HTTP/1.1
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-3 >> X-PACT-BOOTCHECK: true
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-3 >> Accept-Encoding: gzip, x-gzip, deflate
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-3 >> Host: localhost:33179
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-3 >> Connection: keep-alive
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-3 >> User-Agent: Apache-HttpClient/5.1.4 (Java/17.0.7)
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-3 >> "OPTIONS / HTTP/1.1[\r][\n]"
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-3 >> "X-PACT-BOOTCHECK: true[\r][\n]"
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-3 >> "Accept-Encoding: gzip, x-gzip, deflate[\r][\n]"
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-3 >> "Host: localhost:33179[\r][\n]"
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-3 >> "Connection: keep-alive[\r][\n]"
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-3 >> "User-Agent: Apache-HttpClient/5.1.4 (Java/17.0.7)[\r][\n]"
09:12:59.250 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-3 >> "[\r][\n]"
09:12:59.251 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-3 << "HTTP/1.1 200 OK[\r][\n]"
09:12:59.251 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-3 << "Date: Mon, 22 May 2023 09:12:59 GMT[\r][\n]"
09:12:59.251 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-3 << "Transfer-encoding: chunked[\r][\n]"
09:12:59.251 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-3 << "X-pact-bootcheck: true[\r][\n]"
09:12:59.251 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-3 << "[\r][\n]"
09:12:59.252 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-3 << HTTP/1.1 200 OK
09:12:59.252 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-3 << Date: Mon, 22 May 2023 09:12:59 GMT
09:12:59.252 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-3 << Transfer-encoding: chunked
09:12:59.252 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-3 << X-pact-bootcheck: true
09:12:59.252 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.MainClientExec - ex-0000000005 connection can be kept alive for 3 MINUTES
09:12:59.252 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-3 << "0[\r][\n]"
09:12:59.252 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-3 << "[\r][\n]"
09:12:59.252 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - InternalConnectionEndpoint-83bb0f releasing valid endpoint
09:12:59.252 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.BasicHttpClientConnectionManager - ep-0000000002 Releasing connection 127.0.0.1:55992<->127.0.0.1:33179
09:12:59.252 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.BasicHttpClientConnectionManager - ep-0000000002 Connection can be kept alive for 3 MINUTES
09:12:59.257 [Test worker] DEBUG org.springframework.web.client.RestTemplate - HTTP GET http://localhost:33179/customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd
09:12:59.271 [Test worker] DEBUG org.springframework.web.client.RestTemplate - Accept=[application/json, application/*+json]
09:12:59.272 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
09:12:59.272 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
09:12:59.272 [Test worker] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection request: [route: {}->http://localhost:33179][total available: 0; route allocated: 0 of 5; total allocated: 0 of 10]
09:12:59.273 [Test worker] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection leased: [id: 2][route: {}->http://localhost:33179][total available: 0; route allocated: 1 of 5; total allocated: 1 of 10]
09:12:59.273 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {}->http://localhost:33179
09:12:59.273 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:33179
09:12:59.273 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:55996<->127.0.0.1:33179
09:12:59.273 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request GET /customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd HTTP/1.1
09:12:59.273 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
09:12:59.274 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> GET /customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd HTTP/1.1
09:12:59.274 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> Authorization: Basic dGVzdDp0ZXN0
09:12:59.274 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> Content-Type: application/json
09:12:59.274 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> Accept: application/json, application/*+json
09:12:59.274 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> Host: localhost:33179
09:12:59.274 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> Connection: Keep-Alive
09:12:59.274 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> User-Agent: Apache-HttpClient/4.5.14 (Java/17.0.7)
09:12:59.274 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 >> Accept-Encoding: gzip,deflate
09:12:59.274 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "GET /customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd HTTP/1.1[\r][\n]"
09:12:59.274 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "Authorization: Basic dGVzdDp0ZXN0[\r][\n]"
09:12:59.274 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "Content-Type: application/json[\r][\n]"
09:12:59.274 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "Accept: application/json, application/*+json[\r][\n]"
09:12:59.274 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "Host: localhost:33179[\r][\n]"
09:12:59.274 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "Connection: Keep-Alive[\r][\n]"
09:12:59.274 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "User-Agent: Apache-HttpClient/4.5.14 (Java/17.0.7)[\r][\n]"
09:12:59.274 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "Accept-Encoding: gzip,deflate[\r][\n]"
09:12:59.274 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 >> "[\r][\n]"
09:12:59.275 [HTTP-Dispatcher] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Received request: method: GET
path: /customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd
query: {}
headers: {Accept-encoding=[gzip,deflate], Accept=[application/json, application/*+json], Connection=[Keep-Alive], Host=[localhost:33179], User-agent=[Apache-HttpClient/4.5.14 (Java/17.0.7)], Authorization=[Basic dGVzdDp0ZXN0], Content-type=[application/json]}
matchers: MatchingRules(rules={})
generators: Generators(categories={})
body: EMPTY
09:12:59.275 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.RequestMatching - comparing to expected request:
HttpRequest(method=GET, path=/customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd, query={}, headers={Authorization=[Basic dGVzdDp0ZXN0]}, body=MISSING, matchingRules=MatchingRules(rules={path=MatchingRuleCategory(name=path, matchingRules={=MatchingRuleGroup(rules=[RegexMatcher(regex=^/customers/[a-f0-9]{8}-[a-f0-9]{4}-[a-f0-9]{4}-[a-f0-9]{4}-[a-f0-9]{12}$, example=null)], ruleLogic=AND, cascaded=false)}), header=MatchingRuleCategory(name=header, matchingRules={Authorization=MatchingRuleGroup(rules=[RegexMatcher(regex=Basic (?:[A-Za-z0-9+/]{4})*(?:[A-Za-z0-9+/]{2}==|[A-Za-z0-9+/]{3}=)?, example=null)], ruleLogic=AND, cascaded=false)})}), generators=Generators(categories={}))
09:12:59.275 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.RequestMatching - pluginConfiguration={}
09:12:59.275 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.MatcherExecutor - comparing '/customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd' with regexp ^/customers/[a-f0-9]{8}-[a-f0-9]{4}-[a-f0-9]{4}-[a-f0-9]{4}-[a-f0-9]{12}$ at [] -> true
09:12:59.275 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.HeaderMatcher - Comparing header 'Authorization': 'Basic dGVzdDp0ZXN0' to 'Basic dGVzdDp0ZXN0'
09:12:59.275 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.MatcherExecutor - comparing 'Basic dGVzdDp0ZXN0' with regexp Basic (?:[A-Za-z0-9+/]{4})*(?:[A-Za-z0-9+/]{2}==|[A-Za-z0-9+/]{3}=)? at [Authorization] -> true
09:12:59.275 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.Matching - matchBody: context=MatchingContext(matchers=MatchingRuleCategory(name=body, matchingRules={}), allowUnexpectedKeys=false, pluginConfiguration={})
09:12:59.275 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.RequestMatching - Request mismatch: RequestMatchResult(method=null, path=null, query=[], cookie=null, headers=[HeaderMatchResult(key=Authorization, result=[])], body=BodyMatchResult(typeMismatch=null, bodyResults=[]))
09:12:59.276 [HTTP-Dispatcher] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Generating response: HttpResponse(status=200, headers={Content-Type=[application/json]}, body=PRESENT({"firstName":"John","fullName":"John Doe","id":"64a0f7d1-7b25-412d-b1e0-abacde3c21cd","lastName":"Doe"}), matchingRules=MatchingRules(rules={header=MatchingRuleCategory(name=header, matchingRules={Content-Type=MatchingRuleGroup(rules=[RegexMatcher(regex=application/json, example=null)], ruleLogic=AND, cascaded=false)}), body=MatchingRuleCategory(name=body, matchingRules={$.id=MatchingRuleGroup(rules=[RegexMatcher(regex=[0-9a-f]{8}-[0-9a-f]{4}-[0-9a-f]{4}-[0-9a-f]{4}-[0-9a-f]{12}, example=null)], ruleLogic=AND, cascaded=false), $.firstName=MatchingRuleGroup(rules=[au.com.dius.pact.core.model.matchingrules.TypeMatcher@72c7a360], ruleLogic=AND, cascaded=false), $.lastName=MatchingRuleGroup(rules=[au.com.dius.pact.core.model.matchingrules.TypeMatcher@72c7a360], ruleLogic=AND, cascaded=false), $.fullName=MatchingRuleGroup(rules=[au.com.dius.pact.core.model.matchingrules.TypeMatcher@72c7a360], ruleLogic=AND, cascaded=false)}), status=MatchingRuleCategory(name=status, matchingRules={})}), generators=Generators(categories={}))
09:12:59.276 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "HTTP/1.1 200 OK[\r][\n]"
09:12:59.277 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "Date: Mon, 22 May 2023 09:12:59 GMT[\r][\n]"
09:12:59.277 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "Content-length: 103[\r][\n]"
09:12:59.277 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "Content-Type: application/json[\r][\n]"
09:12:59.277 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "[\r][\n]"
09:12:59.277 [Test worker] DEBUG org.apache.http.wire - http-outgoing-2 << "{"firstName":"John","fullName":"John Doe","id":"64a0f7d1-7b25-412d-b1e0-abacde3c21cd","lastName":"Doe"}"
09:12:59.277 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << HTTP/1.1 200 OK
09:12:59.277 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << Date: Mon, 22 May 2023 09:12:59 GMT
09:12:59.277 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << Content-length: 103
09:12:59.277 [Test worker] DEBUG org.apache.http.headers - http-outgoing-2 << Content-Type: application/json
09:12:59.278 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
09:12:59.278 [Test worker] DEBUG org.springframework.web.client.RestTemplate - Response 200 OK
09:12:59.282 [Test worker] DEBUG org.springframework.web.client.RestTemplate - Reading to [edu.obya.blueprint.customer.adapter.rest.CustomerSummary]
09:12:59.289 [Test worker] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection [id: 2][route: {}->http://localhost:33179] can be kept alive indefinitely
09:12:59.290 [Test worker] DEBUG org.apache.http.impl.conn.DefaultManagedHttpClientConnection - http-outgoing-2: set socket timeout to 0
09:12:59.290 [Test worker] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection released: [id: 2][route: {}->http://localhost:33179][total available: 1; route allocated: 1 of 5; total allocated: 1 of 10]
09:12:59.294 [Thread-8] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000006 preparing request execution
09:12:59.294 [Thread-8] DEBUG org.apache.hc.client5.http.protocol.RequestAddCookies - ex-0000000006 Cookie spec selected: strict
09:12:59.294 [Thread-8] DEBUG org.apache.hc.client5.http.protocol.RequestAuthCache - ex-0000000006 Auth cache not set in the context
09:12:59.294 [Thread-8] DEBUG org.apache.hc.client5.http.impl.classic.ProtocolExec - ex-0000000006 target auth state: UNCHALLENGED
09:12:59.294 [Thread-8] DEBUG org.apache.hc.client5.http.impl.classic.ProtocolExec - ex-0000000006 proxy auth state: UNCHALLENGED
09:12:59.294 [Thread-8] DEBUG org.apache.hc.client5.http.impl.classic.ConnectExec - ex-0000000006 acquiring connection with route {s}->https://www.google-analytics.com:443
09:12:59.294 [Thread-8] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000006 acquiring endpoint (3 MINUTES)
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ex-0000000006 endpoint lease request (3 MINUTES) [route: {s}->https://www.google-analytics.com:443][total available: 1; route allocated: 1 of 100; total allocated: 1 of 200]
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ex-0000000006 endpoint leased [route: {s}->https://www.google-analytics.com:443][total available: 0; route allocated: 1 of 100; total allocated: 1 of 200]
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ex-0000000006 acquired ep-0000000003
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000006 acquired endpoint ep-0000000003
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.impl.classic.MainClientExec - ex-0000000006 executing POST /collect HTTP/1.1
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ep-0000000003 start execution ex-0000000006
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000003 executing exchange ex-0000000006 over http-outgoing-1
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> POST /collect HTTP/1.1
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Accept-Encoding: gzip, x-gzip, deflate
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Content-Length: 234
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Content-Type: application/x-www-form-urlencoded; charset=ISO-8859-1
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Host: www.google-analytics.com
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Connection: keep-alive
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> User-Agent: Apache-HttpClient/5.1.4 (Java/17.0.7)
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "POST /collect HTTP/1.1[\r][\n]"
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Accept-Encoding: gzip, x-gzip, deflate[\r][\n]"
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Content-Length: 234[\r][\n]"
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Content-Type: application/x-www-form-urlencoded; charset=ISO-8859-1[\r][\n]"
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Host: www.google-analytics.com[\r][\n]"
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Connection: keep-alive[\r][\n]"
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "User-Agent: Apache-HttpClient/5.1.4 (Java/17.0.7)[\r][\n]"
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "[\r][\n]"
09:12:59.295 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "v=1&t=event&tid=UA-117778936-1&cid=4547fca33e88207b4eec2aa6a7059045&an=pact-jvm&aid=pact-jvm&av=4.5.5&aip=true&ds=client&cd2=CI&cd3=linux-amd64&cd6=junit5&cd7=17.0.7%2B7-LTS&el=Pact+consumer+tests+ran&ec=ConsumerTest&ea=Completed&ev=1"
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "HTTP/1.1 200 OK[\r][\n]"
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Access-Control-Allow-Origin: *[\r][\n]"
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Date: Mon, 22 May 2023 09:12:59 GMT[\r][\n]"
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Pragma: no-cache[\r][\n]"
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Expires: Fri, 01 Jan 1990 00:00:00 GMT[\r][\n]"
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Cache-Control: no-cache, no-store, must-revalidate[\r][\n]"
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Last-Modified: Sun, 17 May 1998 03:00:00 GMT[\r][\n]"
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "X-Content-Type-Options: nosniff[\r][\n]"
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Content-Type: image/gif[\r][\n]"
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Cross-Origin-Resource-Policy: cross-origin[\r][\n]"
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Server: Golfe2[\r][\n]"
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Content-Length: 35[\r][\n]"
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Alt-Svc: h3=":443"; ma=2592000,h3-29=":443"; ma=2592000[\r][\n]"
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "[\r][\n]"
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "GIF89a[0x1][0x0][0x1][0x0][0xffffff80][0xffffffff][0x0][0xffffffff][0xffffffff][0xffffffff][0x0][0x0][0x0],[0x0][0x0][0x0][0x0][0x1][0x0][0x1][0x0][0x0][0x2][0x2]D[0x1][0x0];"
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << HTTP/1.1 200 OK
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Access-Control-Allow-Origin: *
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Date: Mon, 22 May 2023 09:12:59 GMT
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Pragma: no-cache
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Expires: Fri, 01 Jan 1990 00:00:00 GMT
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Cache-Control: no-cache, no-store, must-revalidate
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Last-Modified: Sun, 17 May 1998 03:00:00 GMT
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << X-Content-Type-Options: nosniff
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Content-Type: image/gif
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Cross-Origin-Resource-Policy: cross-origin
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Server: Golfe2
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Content-Length: 35
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Alt-Svc: h3=":443"; ma=2592000,h3-29=":443"; ma=2592000
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.impl.classic.MainClientExec - ex-0000000006 connection can be kept alive for 3 MINUTES
09:12:59.311 [Thread-8] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ep-0000000003 releasing valid endpoint
09:12:59.312 [Thread-8] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000003 releasing endpoint
09:12:59.312 [Thread-8] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000003 connection http-outgoing-1 can be kept alive for 3 MINUTES
09:12:59.312 [Thread-8] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000003 connection released [route: {s}->https://www.google-analytics.com:443][total available: 1; route allocated: 1 of 100; total allocated: 1 of 200]
09:12:59.391 [Test worker] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Mock server shutdown
09:12:59.398 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - Found @PactTestFor annotation on test method void edu.obya.blueprint.customer.cdc.pact.consumer.CustomerPactConsumerATest.replaceCustomer(au.com.dius.pact.consumer.MockServer)
09:12:59.398 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - providerInfo = ProviderInfo(providerName=, hostInterface=, port=, pactVersion=null, providerType=null, https=false, mockServerImplementation=Default, keyStorePath=, keyStoreAlias=, keyStorePassword=, privateKeyPassword=)
09:12:59.399 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - Looking for @Pact method named 'replaceCustomer' for provider 'default'
09:12:59.399 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - Invoking method 'replaceCustomer' to get Pact for the test 'replaceCustomer'
09:12:59.400 [Test worker] DEBUG io.pact.plugins.jvm.core.CatalogueManager - Core catalogue entries:
core/content-matcher/xml
core/content-matcher/json
core/content-matcher/text
core/content-matcher/multipart-form-data
core/content-matcher/form-urlencoded
core/matcher/v1-equality
core/matcher/v2-regex
core/matcher/v2-type
core/matcher/v2-min-type
core/matcher/v2-max-type
core/matcher/v2-minmax-type
core/matcher/v3-number-type
core/matcher/v3-integer-type
core/matcher/v3-decimal-type
core/matcher/v3-date
core/matcher/v3-time
core/matcher/v3-datetime
core/matcher/v3-includes
core/matcher/v3-null
core/matcher/v3-content-type
core/matcher/v4-equals-ignore-order
core/matcher/v4-min-equals-ignore-order
core/matcher/v4-max-equals-ignore-order
core/matcher/v4-minmax-equals-ignore-order
core/matcher/v4-array-contains
core/matcher/v4-notempty
core/matcher/v4-semver
core/matcher/v4-each-key
core/matcher/v4-each-value
core/transport/http
core/transport/https
core/interaction/message
core/interaction/synchronous-message
core/content-generator/json
09:12:59.407 [Test worker] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Starting mock server
09:12:59.408 [Test worker] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Mock server started: /127.0.0.1:39675
09:12:59.409 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000007 preparing request execution
09:12:59.409 [Test worker] DEBUG org.apache.hc.client5.http.protocol.RequestAddCookies - ex-0000000007 Cookie spec selected: strict
09:12:59.409 [Test worker] DEBUG org.apache.hc.client5.http.protocol.RequestAuthCache - ex-0000000007 Auth cache not set in the context
09:12:59.409 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.ProtocolExec - ex-0000000007 target auth state: UNCHALLENGED
09:12:59.409 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.ProtocolExec - ex-0000000007 proxy auth state: UNCHALLENGED
09:12:59.409 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.ConnectExec - ex-0000000007 acquiring connection with route {}->http://localhost:39675
09:12:59.409 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000007 acquiring endpoint (3 MINUTES)
09:12:59.409 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.BasicHttpClientConnectionManager - ep-0000000003 Get connection for route {}->http://localhost:39675
09:12:59.409 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000007 acquired endpoint InternalConnectionEndpoint-35d60381
09:12:59.409 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.ConnectExec - ex-0000000007 opening connection {}->http://localhost:39675
09:12:59.409 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - InternalConnectionEndpoint-35d60381 connecting endpoint (3 MINUTES)
09:12:59.410 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.DefaultHttpClientConnectionOperator - http-outgoing-4 connecting to localhost/127.0.0.1:39675
09:12:59.410 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.DefaultHttpClientConnectionOperator - http-outgoing-4 connection established 127.0.0.1:42462<->127.0.0.1:39675
09:12:59.410 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - InternalConnectionEndpoint-35d60381 endpoint connected
09:12:59.410 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.MainClientExec - ex-0000000007 executing OPTIONS / HTTP/1.1
09:12:59.410 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - InternalConnectionEndpoint-35d60381 start execution ex-0000000007
09:12:59.410 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.BasicHttpClientConnectionManager - ep-0000000003 Executing exchange ex-0000000007
09:12:59.410 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-4 >> OPTIONS / HTTP/1.1
09:12:59.410 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-4 >> X-PACT-BOOTCHECK: true
09:12:59.410 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-4 >> Accept-Encoding: gzip, x-gzip, deflate
09:12:59.410 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-4 >> Host: localhost:39675
09:12:59.410 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-4 >> Connection: keep-alive
09:12:59.410 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-4 >> User-Agent: Apache-HttpClient/5.1.4 (Java/17.0.7)
09:12:59.410 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-4 >> "OPTIONS / HTTP/1.1[\r][\n]"
09:12:59.410 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-4 >> "X-PACT-BOOTCHECK: true[\r][\n]"
09:12:59.410 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-4 >> "Accept-Encoding: gzip, x-gzip, deflate[\r][\n]"
09:12:59.410 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-4 >> "Host: localhost:39675[\r][\n]"
09:12:59.411 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-4 >> "Connection: keep-alive[\r][\n]"
09:12:59.411 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-4 >> "User-Agent: Apache-HttpClient/5.1.4 (Java/17.0.7)[\r][\n]"
09:12:59.411 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-4 >> "[\r][\n]"
09:12:59.414 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-4 << "HTTP/1.1 200 OK[\r][\n]"
09:12:59.414 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-4 << "Date: Mon, 22 May 2023 09:12:59 GMT[\r][\n]"
09:12:59.414 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-4 << "Transfer-encoding: chunked[\r][\n]"
09:12:59.414 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-4 << "X-pact-bootcheck: true[\r][\n]"
09:12:59.414 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-4 << "[\r][\n]"
09:12:59.414 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-4 << HTTP/1.1 200 OK
09:12:59.414 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-4 << Date: Mon, 22 May 2023 09:12:59 GMT
09:12:59.414 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-4 << Transfer-encoding: chunked
09:12:59.414 [Test worker] DEBUG org.apache.hc.client5.http.headers - http-outgoing-4 << X-pact-bootcheck: true
09:12:59.415 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.MainClientExec - ex-0000000007 connection can be kept alive for 3 MINUTES
09:12:59.416 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-4 << "0[\r][\n]"
09:12:59.416 [Test worker] DEBUG org.apache.hc.client5.http.wire - http-outgoing-4 << "[\r][\n]"
09:12:59.416 [Test worker] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - InternalConnectionEndpoint-35d60381 releasing valid endpoint
09:12:59.416 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.BasicHttpClientConnectionManager - ep-0000000003 Releasing connection 127.0.0.1:42462<->127.0.0.1:39675
09:12:59.416 [Test worker] DEBUG org.apache.hc.client5.http.impl.io.BasicHttpClientConnectionManager - ep-0000000003 Connection can be kept alive for 3 MINUTES
09:12:59.422 [Test worker] DEBUG org.springframework.web.client.RestTemplate - HTTP PUT http://localhost:39675/customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd
09:12:59.423 [Test worker] DEBUG org.springframework.web.client.RestTemplate - Writing [CustomerDto(firstName=John, lastName=Doe)] with org.springframework.http.converter.json.MappingJackson2HttpMessageConverter
09:12:59.424 [Test worker] DEBUG org.apache.http.client.protocol.RequestAddCookies - CookieSpec selected: default
09:12:59.425 [Test worker] DEBUG org.apache.http.client.protocol.RequestAuthCache - Auth cache not set in the context
09:12:59.425 [Test worker] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection request: [route: {}->http://localhost:39675][total available: 0; route allocated: 0 of 5; total allocated: 0 of 10]
09:12:59.425 [Test worker] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection leased: [id: 3][route: {}->http://localhost:39675][total available: 0; route allocated: 1 of 5; total allocated: 1 of 10]
09:12:59.425 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Opening connection {}->http://localhost:39675
09:12:59.425 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connecting to localhost/127.0.0.1:39675
09:12:59.425 [Test worker] DEBUG org.apache.http.impl.conn.DefaultHttpClientConnectionOperator - Connection established 127.0.0.1:42468<->127.0.0.1:39675
09:12:59.425 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Executing request PUT /customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd HTTP/1.1
09:12:59.426 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Proxy auth state: UNCHALLENGED
09:12:59.426 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> PUT /customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd HTTP/1.1
09:12:59.426 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> Authorization: Basic dGVzdDp0ZXN0
09:12:59.426 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> Content-Type: application/json
09:12:59.426 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> Content-Length: 37
09:12:59.426 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> Host: localhost:39675
09:12:59.426 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> Connection: Keep-Alive
09:12:59.426 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> User-Agent: Apache-HttpClient/4.5.14 (Java/17.0.7)
09:12:59.426 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 >> Accept-Encoding: gzip,deflate
09:12:59.426 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "PUT /customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd HTTP/1.1[\r][\n]"
09:12:59.426 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "Authorization: Basic dGVzdDp0ZXN0[\r][\n]"
09:12:59.426 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "Content-Type: application/json[\r][\n]"
09:12:59.426 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "Content-Length: 37[\r][\n]"
09:12:59.426 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "Host: localhost:39675[\r][\n]"
09:12:59.426 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "Connection: Keep-Alive[\r][\n]"
09:12:59.426 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "User-Agent: Apache-HttpClient/4.5.14 (Java/17.0.7)[\r][\n]"
09:12:59.426 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "Accept-Encoding: gzip,deflate[\r][\n]"
09:12:59.427 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "[\r][\n]"
09:12:59.427 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 >> "{"firstName":"John","lastName":"Doe"}"
09:12:59.427 [HTTP-Dispatcher] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Received request: method: PUT
path: /customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd
query: {}
headers: {Accept-encoding=[gzip,deflate], Connection=[Keep-Alive], Host=[localhost:39675], User-agent=[Apache-HttpClient/4.5.14 (Java/17.0.7)], Authorization=[Basic dGVzdDp0ZXN0], Content-type=[application/json], Content-length=[37]}
matchers: MatchingRules(rules={})
generators: Generators(categories={})
body: PRESENT({"firstName":"John","lastName":"Doe"})
09:12:59.427 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.RequestMatching - comparing to expected request:
HttpRequest(method=PUT, path=/customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd, query={}, headers={Content-Type=[application/json], Authorization=[Basic dGVzdDp0ZXN0]}, body=PRESENT({"firstName":"John","lastName":"Doe"}), matchingRules=MatchingRules(rules={path=MatchingRuleCategory(name=path, matchingRules={=MatchingRuleGroup(rules=[RegexMatcher(regex=^/customers/[a-f0-9]{8}-[a-f0-9]{4}-[a-f0-9]{4}-[a-f0-9]{4}-[a-f0-9]{12}$, example=null)], ruleLogic=AND, cascaded=false)}), header=MatchingRuleCategory(name=header, matchingRules={Content-Type=MatchingRuleGroup(rules=[RegexMatcher(regex=application/json, example=null)], ruleLogic=AND, cascaded=false), Authorization=MatchingRuleGroup(rules=[RegexMatcher(regex=Basic (?:[A-Za-z0-9+/]{4})*(?:[A-Za-z0-9+/]{2}==|[A-Za-z0-9+/]{3}=)?, example=null)], ruleLogic=AND, cascaded=false)}), body=MatchingRuleCategory(name=body, matchingRules={$.firstName=MatchingRuleGroup(rules=[au.com.dius.pact.core.model.matchingrules.TypeMatcher@72c7a360], ruleLogic=AND, cascaded=false), $.lastName=MatchingRuleGroup(rules=[au.com.dius.pact.core.model.matchingrules.TypeMatcher@72c7a360], ruleLogic=AND, cascaded=false)})}), generators=Generators(categories={}))
09:12:59.427 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.RequestMatching - pluginConfiguration={}
09:12:59.428 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.MatcherExecutor - comparing '/customers/64a0f7d1-7b25-412d-b1e0-abacde3c21cd' with regexp ^/customers/[a-f0-9]{8}-[a-f0-9]{4}-[a-f0-9]{4}-[a-f0-9]{4}-[a-f0-9]{12}$ at [] -> true
09:12:59.428 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.HeaderMatcher - Comparing header 'Authorization': 'Basic dGVzdDp0ZXN0' to 'Basic dGVzdDp0ZXN0'
09:12:59.428 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.MatcherExecutor - comparing 'Basic dGVzdDp0ZXN0' with regexp Basic (?:[A-Za-z0-9+/]{4})*(?:[A-Za-z0-9+/]{2}==|[A-Za-z0-9+/]{3}=)? at [Authorization] -> true
09:12:59.428 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.HeaderMatcher - Comparing header 'Content-Type': 'application/json' to 'application/json'
09:12:59.428 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.MatcherExecutor - comparing 'application/json' with regexp application/json at [Content-Type] -> true
09:12:59.428 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.Matching - matchBody: context=MatchingContext(matchers=MatchingRuleCategory(name=body, matchingRules={$.firstName=MatchingRuleGroup(rules=[au.com.dius.pact.core.model.matchingrules.TypeMatcher@72c7a360], ruleLogic=AND, cascaded=false), $.lastName=MatchingRuleGroup(rules=[au.com.dius.pact.core.model.matchingrules.TypeMatcher@72c7a360], ruleLogic=AND, cascaded=false)}), allowUnexpectedKeys=false, pluginConfiguration={})
09:12:59.429 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.Matching - Found a matcher for application/json -> au.com.dius.pact.core.matchers.JsonContentMatcher@58f537a1
09:12:59.429 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.JsonContentMatcher - compareValues: Matcher defined for path [$, firstName]
09:12:59.430 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.MatcherExecutor - comparing type of "John" (String) to "John" (String) at [$, firstName]
09:12:59.430 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.JsonContentMatcher - compareValues: Matcher defined for path [$, lastName]
09:12:59.430 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.MatcherExecutor - comparing type of "Doe" (String) to "Doe" (String) at [$, lastName]
09:12:59.430 [HTTP-Dispatcher] DEBUG au.com.dius.pact.core.matchers.RequestMatching - Request mismatch: RequestMatchResult(method=null, path=null, query=[], cookie=null, headers=[HeaderMatchResult(key=Authorization, result=[]), HeaderMatchResult(key=Content-Type, result=[])], body=BodyMatchResult(typeMismatch=null, bodyResults=[BodyItemMatchResult(key=$.firstName, result=[]), BodyItemMatchResult(key=$.lastName, result=[])]))
09:12:59.430 [HTTP-Dispatcher] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Generating response: HttpResponse(status=204, headers={}, body=MISSING, matchingRules=MatchingRules(rules={status=MatchingRuleCategory(name=status, matchingRules={}), header=MatchingRuleCategory(name=header, matchingRules={})}), generators=Generators(categories={}))
09:12:59.431 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "HTTP/1.1 204 No Content[\r][\n]"
09:12:59.431 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "Date: Mon, 22 May 2023 09:12:59 GMT[\r][\n]"
09:12:59.431 [Test worker] DEBUG org.apache.http.wire - http-outgoing-3 << "[\r][\n]"
09:12:59.431 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 << HTTP/1.1 204 No Content
09:12:59.431 [Test worker] DEBUG org.apache.http.headers - http-outgoing-3 << Date: Mon, 22 May 2023 09:12:59 GMT
09:12:59.432 [Test worker] DEBUG org.apache.http.impl.execchain.MainClientExec - Connection can be kept alive indefinitely
09:12:59.432 [Test worker] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection [id: 3][route: {}->http://localhost:39675] can be kept alive indefinitely
09:12:59.432 [Test worker] DEBUG org.apache.http.impl.conn.DefaultManagedHttpClientConnection - http-outgoing-3: set socket timeout to 0
09:12:59.432 [Test worker] DEBUG org.apache.http.impl.conn.PoolingHttpClientConnectionManager - Connection released: [id: 3][route: {}->http://localhost:39675][total available: 1; route allocated: 1 of 5; total allocated: 1 of 10]
09:12:59.432 [Test worker] DEBUG org.springframework.web.client.RestTemplate - Response 204 NO_CONTENT
09:12:59.438 [Thread-10] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000008 preparing request execution
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.protocol.RequestAddCookies - ex-0000000008 Cookie spec selected: strict
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.protocol.RequestAuthCache - ex-0000000008 Auth cache not set in the context
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.impl.classic.ProtocolExec - ex-0000000008 target auth state: UNCHALLENGED
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.impl.classic.ProtocolExec - ex-0000000008 proxy auth state: UNCHALLENGED
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.impl.classic.ConnectExec - ex-0000000008 acquiring connection with route {s}->https://www.google-analytics.com:443
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000008 acquiring endpoint (3 MINUTES)
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ex-0000000008 endpoint lease request (3 MINUTES) [route: {s}->https://www.google-analytics.com:443][total available: 1; route allocated: 1 of 100; total allocated: 1 of 200]
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ex-0000000008 endpoint leased [route: {s}->https://www.google-analytics.com:443][total available: 0; route allocated: 1 of 100; total allocated: 1 of 200]
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ex-0000000008 acquired ep-0000000004
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ex-0000000008 acquired endpoint ep-0000000004
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.impl.classic.MainClientExec - ex-0000000008 executing POST /collect HTTP/1.1
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ep-0000000004 start execution ex-0000000008
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000004 executing exchange ex-0000000008 over http-outgoing-1
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> POST /collect HTTP/1.1
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Accept-Encoding: gzip, x-gzip, deflate
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Content-Length: 234
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Content-Type: application/x-www-form-urlencoded; charset=ISO-8859-1
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Host: www.google-analytics.com
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> Connection: keep-alive
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 >> User-Agent: Apache-HttpClient/5.1.4 (Java/17.0.7)
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "POST /collect HTTP/1.1[\r][\n]"
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Accept-Encoding: gzip, x-gzip, deflate[\r][\n]"
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Content-Length: 234[\r][\n]"
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Content-Type: application/x-www-form-urlencoded; charset=ISO-8859-1[\r][\n]"
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Host: www.google-analytics.com[\r][\n]"
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "Connection: keep-alive[\r][\n]"
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "User-Agent: Apache-HttpClient/5.1.4 (Java/17.0.7)[\r][\n]"
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "[\r][\n]"
09:12:59.439 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 >> "v=1&t=event&tid=UA-117778936-1&cid=4547fca33e88207b4eec2aa6a7059045&an=pact-jvm&aid=pact-jvm&av=4.5.5&aip=true&ds=client&cd2=CI&cd3=linux-amd64&cd6=junit5&cd7=17.0.7%2B7-LTS&el=Pact+consumer+tests+ran&ec=ConsumerTest&ea=Completed&ev=1"
09:12:59.455 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "HTTP/1.1 200 OK[\r][\n]"
09:12:59.455 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Access-Control-Allow-Origin: *[\r][\n]"
09:12:59.455 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Date: Mon, 22 May 2023 09:12:59 GMT[\r][\n]"
09:12:59.455 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Pragma: no-cache[\r][\n]"
09:12:59.455 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Expires: Fri, 01 Jan 1990 00:00:00 GMT[\r][\n]"
09:12:59.455 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Cache-Control: no-cache, no-store, must-revalidate[\r][\n]"
09:12:59.455 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Last-Modified: Sun, 17 May 1998 03:00:00 GMT[\r][\n]"
09:12:59.455 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "X-Content-Type-Options: nosniff[\r][\n]"
09:12:59.455 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Content-Type: image/gif[\r][\n]"
09:12:59.455 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Cross-Origin-Resource-Policy: cross-origin[\r][\n]"
09:12:59.455 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Server: Golfe2[\r][\n]"
09:12:59.455 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Content-Length: 35[\r][\n]"
09:12:59.455 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "Alt-Svc: h3=":443"; ma=2592000,h3-29=":443"; ma=2592000[\r][\n]"
09:12:59.455 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "[\r][\n]"
09:12:59.455 [Thread-10] DEBUG org.apache.hc.client5.http.wire - http-outgoing-1 << "GIF89a[0x1][0x0][0x1][0x0][0xffffff80][0xffffffff][0x0][0xffffffff][0xffffffff][0xffffffff][0x0][0x0][0x0],[0x0][0x0][0x0][0x0][0x1][0x0][0x1][0x0][0x0][0x2][0x2]D[0x1][0x0];"
09:12:59.455 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << HTTP/1.1 200 OK
09:12:59.456 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Access-Control-Allow-Origin: *
09:12:59.456 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Date: Mon, 22 May 2023 09:12:59 GMT
09:12:59.456 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Pragma: no-cache
09:12:59.456 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Expires: Fri, 01 Jan 1990 00:00:00 GMT
09:12:59.456 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Cache-Control: no-cache, no-store, must-revalidate
09:12:59.456 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Last-Modified: Sun, 17 May 1998 03:00:00 GMT
09:12:59.456 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << X-Content-Type-Options: nosniff
09:12:59.456 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Content-Type: image/gif
09:12:59.456 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Cross-Origin-Resource-Policy: cross-origin
09:12:59.456 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Server: Golfe2
09:12:59.456 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Content-Length: 35
09:12:59.456 [Thread-10] DEBUG org.apache.hc.client5.http.headers - http-outgoing-1 << Alt-Svc: h3=":443"; ma=2592000,h3-29=":443"; ma=2592000
09:12:59.456 [Thread-10] DEBUG org.apache.hc.client5.http.impl.classic.MainClientExec - ex-0000000008 connection can be kept alive for 3 MINUTES
09:12:59.456 [Thread-10] DEBUG org.apache.hc.client5.http.impl.classic.InternalHttpClient - ep-0000000004 releasing valid endpoint
09:12:59.456 [Thread-10] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000004 releasing endpoint
09:12:59.457 [Thread-10] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000004 connection http-outgoing-1 can be kept alive for 3 MINUTES
09:12:59.457 [Thread-10] DEBUG org.apache.hc.client5.http.impl.io.PoolingHttpClientConnectionManager - ep-0000000004 connection released [route: {s}->https://www.google-analytics.com:443][total available: 1; route allocated: 1 of 100; total allocated: 1 of 200]
09:12:59.535 [Test worker] DEBUG au.com.dius.pact.consumer.BaseJdkMockServer - Mock server shutdown
09:12:59.542 [Test worker] INFO au.com.dius.pact.consumer.junit5.PactConsumerTestExt - Writing pacts out to directory from @PactDirectory annotation
09:12:59.543 [Test worker] DEBUG au.com.dius.pact.consumer.junit5.PactConsumerTestExt - Writing pact consumerA -> customerAPI to file src/contractTest/resources/customer/cdc/pact/consumerA-customerAPI.json