Where can I see log entries when I set PACT_BROKER_LOG_LEVEL=DEBUG?

[cgrim-cz]

Hi all,

Please, where can I find log entries (for example those described in https://docs.pact.io/pact_broker/webhooks/debugging_webhooks/#identifying-why-a-webhook-did-or-did-not-get-triggered) when I set PACT_BROKER_LOG_LEVEL=DEBUG?

I tried that and in STDOUT I can see lot of SQL commands and PACT BROKER CONFIGURATION section during startup, but then it stops and logs only cron messages like:

Sep 13 09:17:01 21c5e56669cb CRON[1159]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)

There is nothing about calling webhooks, validation, etc.

Thank you

[bethesque]

Use this docker-compose and run:

docker-compose up pact-broker

then in another terminal run:

docker-compose up publish

version: "3"

services:
  pact-broker:
    image: pactfoundation/pact-broker:2.84.0.0
    ports:
      - "9292:9292"
    environment:
      PACT_BROKER_DATABASE_URL: "sqlite:////tmp/pact_broker_database.sqlite3"
      PACT_BROKER_LOG_LEVEL: DEBUG
      PACT_BROKER_SQL_LOG_LEVEL: NONE

  publish:
    image: pactfoundation/pact-cli:0.50.0.4
    depends_on:
      - pact-broker
    environment:
      - PACT_BROKER_BASE_URL=http://pact-broker:9292
      - GIT_BRANCH=main
    command: broker publish /pact/example/pacts --consumer-app-version 1f7b544e19770c9e9f2df05947d1b168e229edaf --tag-with-git-branch

Here are the logs:

pact-broker_1  | 2021-09-13 22:46:16.390071 I [8:puma server threadpool 001] pact-broker -- Creating new pact publication with params {:consumer_name=>"docker-example-consumer", :provider_name=>"docker-example-provider", :revision_number=>nil, :consumer_version_number=>"1f7b544e19770c9e9f2df05947d1b168e229edaf", :pact_version_sha=>nil, :consumer_name_in_pact=>"docker-example-consumer", :provider_name_in_pact=>"docker-example-provider"}
pact-broker_1  | 2021-09-13 22:46:16.390362 D [8:puma server threadpool 001] pact-broker -- Content {"consumer":{"name":"docker-example-consumer"},"provider":{"name":"docker-example-provider"},"interactions":[{"description":"a request for bar","providerState":"bar exists","request":{"method":"GET","path":"/bar","headers":{"Accept":"application/json"}},"response":{"status":200,"headers":{"Content-Type":"application/json"},"body":{"id":"1631573174","name":"Bar"}}}],"metdata":{"pactSpecification":{"version":"2"}}}
pact-broker_1  | 2021-09-13 22:46:16.403342 I [8:puma server threadpool 001] pact-broker -- Event detected -- {:event_name=>"contract_published", :event_comment=>nil}
pact-broker_1  | 2021-09-13 22:46:16.403431 I [8:puma server threadpool 001] pact-broker -- No enabled webhooks found for event contract_published
pact-broker_1  | 2021-09-13 22:46:16.412366 I [8:puma server threadpool 001] pact-broker -- Event detected -- {:event_name=>"contract_content_changed", :event_comment=>"first time any pact published for this consumer with consumer version tagged main"}
pact-broker_1  | 2021-09-13 22:46:16.412447 I [8:puma server threadpool 001] pact-broker -- No enabled webhooks found for event contract_content_changed

[cgrim-cz]

Thank you very much. Now I see where is the problem.

We used dius/pact-broker docker image and it seemed that it is not logging at all. But now when I scroll more into the history, then I see these warnings and pact-broker restart after that:

pact_broker_app_1 | App 2371 output: /home/app/pact_broker/logger.rb:14: warning: already initialized constant PADRINO_LOGGER
pact_broker_app_1 | App 2371 output: /home/app/pact_broker/vendor/bundle/ruby/2.7.0/gems/pact_broker-2.83.0/lib/pact_broker/app.rb:2: warning: previous definition of PADRINO_LOGGER was here
pact_broker_app_1 | App 2371 output: 2021-09-13 12:41:15.843419 I [2371:3740] pact-broker -- PACT_BROKER_DATABASE_HOST=postgres
...

So, instead of logging it crashes and restarts and the end of the log seems as it was at the beginning.

dius/pact-broker is also not able to handle PACT_BROKER_SQL_LOG_LEVEL with value NONE:

pact_broker_app_1 | App 74 output: Error: The application encountered the following error: NoMethodError: undefined method `none' for #<PactBroker::DB::LogQuietener:0x00005603774c9458> (Sequel::DatabaseConnectionError)
pact_broker_app_1 | App 74 output:     /usr/local/rvm/rubies/ruby-2.7.2/lib/ruby/2.7.0/delegate.rb:87:in `method_missing'
pact_broker_app_1 | App 74 output:     /home/app/pact_broker/vendor/bundle/ruby/2.7.0/gems/sequel-5.47.0/lib/sequel/database/logging.rb:88:in `public_send'

When I try pactfoundation/pact-broker docker image from your answer it logs perfectly and also PACT_BROKER_SQL_LOG_LEVEL=NONE setting works 👍

[bethesque]

The dius image is not exactly deprecated, but all new features go into the pact-foundation one, and are only backported to the dius one if requested. See https://docs.pact.io/pact_broker/docker_images#which-docker-image-should-i-use for the differences.