How is this logline "INF Completed #..." produced?

[nlsrchtr]

Hi everyone,

I'm have the following in my application.rb and nothing related to logging in the related environment file, following the documentation:

if ENV['LOG_TO_CONSOLE'] || ENV['RAILS_LOG_TO_STDOUT']

  SemanticLogger.clear_appenders!
  $stdout.sync = true
  config.rails_semantic_logger.add_file_appender = false
  config.rails_semantic_logger.format = :json
  config.semantic_logger.add_appender(io: $stdout, formatter: config.rails_semantic_logger.format)
  config.semantic_logger.backtrace_level = :error

  config.rails_semantic_logger.ap_options   = { multiline: false }
  config.rails_semantic_logger.started      = false
  config.rails_semantic_logger.processing   = false
  config.rails_semantic_logger.rendered     = false
  config.rails_semantic_logger.semantic     = true
  config.rails_semantic_logger.quiet_assets = true

end

Within a kamal deployed docker container, this produces this log line:

INF Completed #sign_in | host=10.0.0.100-669bdea8ba5d application=XXX environment=staging timestamp=2025-02-14T18:38:43.457902Z level_index=2 pid=18 thread=puma srv tp 001 duration_ms=16.175292015075684 duration=16.2ms name=Portal::AuthenticationController payload={"controller":"Portal::AuthenticationController","action":"sign_in","params":{"subdomain":"XXX","domain":"XXX"},"format":"HTML","method":"GET","path":"/","status":200,"view_runtime":12.19,"db_runtime":0,"queries_count":0,"cached_queries_count":0,"level":"INFO","allocations":3497,"status_message":"OK"}

When I run the same docker image within docker compose, I get the following output:

{
  "host": "8fa67d39401e",
  "application": "XXX",
  "environment": "staging",
  "timestamp": "2025-02-14T18:24:00.986553Z",
  "level": "info",
  "level_index": 2,
  "pid": 23,
  "thread": "puma srv tp 005",
  "duration_ms": 47.8919580001384,
  "duration": "47.9ms",
  "name": "Portal::AuthenticationController",
  "message": "Completed #sign_in",
  "payload": {
    "controller": "Portal::AuthenticationController",
    "action": "sign_in",
    "params": {
      "subdomain": "XXX",
      "domain": "XXX"
    },
    "format": "HTML",
    "method": "GET",
    "path": "/",
    "status": 200,
    "view_runtime": 35.14,
    "db_runtime": 0,
    "queries_count": 0,
    "cached_queries_count": 0,
    "level": "INFO",
    "allocations": 3910,
    "status_message": "OK"
  }
}

I was wondering, why I get different results with the other container? It looks to me, that there might be another formatter active, after the semantic logger is done.

It would be great to get some support, since I'm out of ideas.

Thanks a lot!