06:22:37,394 |-INFO in LogbackRequestLog - Will use configuration file [/home/runner/work/commercetools-payone-integration/commercetools-payone-integration/service/build/resources/main/logback-access.xml]
06:22:37,400 |-INFO in ch.qos.logback.access.joran.action.ConfigurationAction - debug attribute not set
06:22:37,400 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
06:22:37,400 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [console]
06:22:37,401 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [net.logstash.logback.composite.accessevent.AccessEventJsonProviders] for [providers] property
06:22:37,404 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [net.logstash.logback.composite.accessevent.AccessEventFormattedTimestampJsonProvider] for [timestamp] property
06:22:37,405 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [net.logstash.logback.composite.accessevent.MethodJsonProvider] for [method] property
06:22:37,405 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [net.logstash.logback.composite.accessevent.ProtocolJsonProvider] for [protocol] property
06:22:37,406 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [net.logstash.logback.composite.accessevent.StatusCodeJsonProvider] for [statusCode] property
06:22:37,406 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [net.logstash.logback.composite.accessevent.RequestedUrlJsonProvider] for [requestedUrl] property
06:22:37,406 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [net.logstash.logback.composite.accessevent.RequestedUriJsonProvider] for [requestedUri] property
06:22:37,406 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [net.logstash.logback.composite.accessevent.RemoteHostJsonProvider] for [remoteHost] property
06:22:37,407 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [net.logstash.logback.composite.accessevent.ContentLengthJsonProvider] for [contentLength] property
06:22:37,407 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [net.logstash.logback.composite.accessevent.ElapsedTimeJsonProvider] for [elapsedTime] property
06:22:37,408 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [net.logstash.logback.composite.accessevent.AccessEventPatternJsonProvider] for [pattern] property
06:22:37,409 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [net.logstash.logback.composite.accessevent.RequestHeadersJsonProvider] for [requestHeaders] property
06:22:37,410 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [net.logstash.logback.composite.accessevent.IncludeExcludeHeaderFilter] for [filter] property
06:22:37,443 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [console] to null
06:22:37,443 |-INFO in ch.qos.logback.access.joran.action.ConfigurationAction - End of configuration.
06:22:37,443 |-INFO in ch.qos.logback.access.joran.JoranConfigurator@1e16c0aa - Registering current configuration as safe fallback point
{"message":"Register /health URL","timestamp":"2023-03-25T06:22:38.127Z","loggerName":"com.commercetools.pspadapter.payone.IntegrationService","severity":"INFO"}
{"message":"Use /health?pretty to pretty-print output JSON","timestamp":"2023-03-25T06:22:38.132Z","loggerName":"com.commercetools.pspadapter.payone.IntegrationService","severity":"INFO"}
{"message":"Logging initialized @2056ms to org.eclipse.jetty.util.log.Slf4jLog","timestamp":"2023-03-25T06:22:38.153Z","loggerName":"org.eclipse.jetty.util.log","severity":"INFO"}
{"message":"== Spark has ignited ...","timestamp":"2023-03-25T06:22:38.256Z","loggerName":"spark.embeddedserver.jetty.EmbeddedJettyServer","severity":"INFO"}
{"message":">> Listening on 0.0.0.0:8080","timestamp":"2023-03-25T06:22:38.257Z","loggerName":"spark.embeddedserver.jetty.EmbeddedJettyServer","severity":"INFO"}
{"message":"jetty-9.4.41.v20210516; built: 2021-05-16T23:56:28.993Z; git: 98607f93c7833e7dc59489b13f3cb0a114fb9f4c; jvm 1.8.0_362-b09","timestamp":"2023-03-25T06:22:38.263Z","loggerName":"org.eclipse.jetty.server.Server","severity":"INFO"}
{"message":"DefaultSessionIdManager workerName=node0","timestamp":"2023-03-25T06:22:38.291Z","loggerName":"org.eclipse.jetty.server.session","severity":"INFO"}
{"message":"No SessionScavenger set, using defaults","timestamp":"2023-03-25T06:22:38.291Z","loggerName":"org.eclipse.jetty.server.session","severity":"INFO"}
{"message":"node0 Scavenging every 600000ms","timestamp":"2023-03-25T06:22:38.296Z","loggerName":"org.eclipse.jetty.server.session","severity":"INFO"}
{"message":"Started ServerConnector@45b325c3{HTTP/1.1, (http/1.1)}{0.0.0.0:8080}","timestamp":"2023-03-25T06:22:38.307Z","loggerName":"org.eclipse.jetty.server.AbstractConnector","severity":"INFO"}
{"message":"Started @2212ms","timestamp":"2023-03-25T06:22:38.308Z","loggerName":"org.eclipse.jetty.server.Server","severity":"INFO"}
{"message":"Register payment handler URL /FIRST_TENANT/commercetools/handle/payments/:id","timestamp":"2023-03-25T06:22:40.745Z","loggerName":"com.commercetools.pspadapter.payone.IntegrationService","severity":"INFO"}
{"message":"Register start session URL /FIRST_TENANT/commercetools/start/session/:id","timestamp":"2023-03-25T06:22:40.746Z","loggerName":"com.commercetools.pspadapter.payone.IntegrationService","severity":"INFO"}
{"message":"Register payone notification URL /FIRST_TENANT/payone/notification","timestamp":"2023-03-25T06:22:40.747Z","loggerName":"com.commercetools.pspadapter.payone.IntegrationService","severity":"INFO"}
{"message":"Using custom MathContext: precision=256, roundingMode=HALF_EVEN","timestamp":"2023-03-25T06:22:40.804Z","loggerName":"org.javamoney.moneta.DefaultMonetaryContextFactory","severity":"INFO"}
{"timestamp":"2023-03-25T06:22:44.875Z","method":"GET","protocol":"HTTP/1.1","statusCode":200,"requestedUrl":"GET /FIRST_TENANT/commercetools/handle/payments/47dc274b-d6a6-47ed-b837-d358b34630d8 HTTP/1.1","requestedUri":"/FIRST_TENANT/commercetools/handle/payments/47dc274b-d6a6-47ed-b837-d358b34630d8","remoteHost":"127.0.0.1","contentLength":0,"elapsedTime":1940,"queryString":"","request_headers":{"user-agent":"Apache-HttpClient/4.5.13 (Java/1.8.0_362)"}}
{"timestamp":"2023-03-25T06:22:46.609Z","method":"GET","protocol":"HTTP/1.1","statusCode":400,"requestedUrl":"GET /FIRST_TENANT/commercetools/start/session/bb58ac21-a77f-4ede-ad88-feaa895cb991 HTTP/1.1","requestedUri":"/FIRST_TENANT/commercetools/start/session/bb58ac21-a77f-4ede-ad88-feaa895cb991","remoteHost":"127.0.0.1","contentLength":132,"elapsedTime":964,"queryString":"","request_headers":{"user-agent":"Apache-HttpClient/4.5.13 (Java/1.8.0_362)"}}
{"timestamp":"2023-03-25T06:22:49.942Z","method":"GET","protocol":"HTTP/1.1","statusCode":200,"requestedUrl":"GET /FIRST_TENANT/commercetools/handle/payments/c89ca61c-2660-43a5-a1fc-08604a456a87 HTTP/1.1","requestedUri":"/FIRST_TENANT/commercetools/handle/payments/c89ca61c-2660-43a5-a1fc-08604a456a87","remoteHost":"127.0.0.1","contentLength":0,"elapsedTime":2491,"queryString":"","request_headers":{"user-agent":"Apache-HttpClient/4.5.13 (Java/1.8.0_362)"}}