Backport of [NET-4107][Supportability] Log Level set to TRACE and duration set to 5m for consul-debug into release/1.16.x by hc-github-team-consul-core · Pull Request #17728 · hashicorp/consul

hc-github-team-consul-core · 2023-06-14T06:40:26Z

Backport

This PR is auto-generated from #17596 to be assessed for backporting due to the inclusion of the label backport/1.16.

🚨

Warning automatic cherry-pick of commits failed. If the first commit failed,
you will see a blank no-op commit below. If at least one commit succeeded, you
will see the cherry-picked commits up to, not including, the commit where
the merge conflict occurred.

The person who merged in the original PR is:
@absolutelightning
This person should manually cherry-pick the original PR into a new backport PR,
and close this one when the manual backport PR is merged in.

merge conflict error: unable to process merge commit: "ff80dff9441502ad6b558fc9fce34384de39fbf4", automatic backport requires rebase workflow

The below text is copied from the body of the original PR.

Description

As per requirement of NET-4107

1- The expectation is to increase the default value to 5 minutes. 

2- The expectation is to capture the logs at TRACE level by default.

Testing

make dev

start the server agent - ./bin/consul agent --config-file=server.json
server.json -

{
  "node_name": "consul-server",
  "server": true,
  "bootstrap": true,
  "ui_config": {
    "enabled": true
  },
  "datacenter": "dc1",
  "data_dir": "consul/data",
  "log_level": "INFO",
  "addresses": {
    "http": "0.0.0.0"
  },
  "connect": {
    "enabled": true
  },
  "enable_debug": true
}

run ./bin/consul debug
Here is the output of the debug command

==> Starting debugger and capturing static information...
     Agent Version: '1.16.0'
          Interval: '30s'
          Duration: '5m0s'
            Output: 'consul-debug-2023-06-07T17-51-35+0530.tar.gz'
           Capture: 'metrics, logs, pprof, host, agent, members'
==> Beginning capture interval 2023-06-07 12:21:35.296995 +0000 UTC (0)
==> Capture successful 2023-06-07 12:21:35.306485 +0000 UTC (0)
==> Capture successful 2023-06-07 12:22:05.296537 +0000 UTC (1)
==> Capture successful 2023-06-07 12:22:35.295355 +0000 UTC (2)
==> Capture successful 2023-06-07 12:23:05.294538 +0000 UTC (3)
==> Capture successful 2023-06-07 12:23:35.294775 +0000 UTC (4)
==> Capture successful 2023-06-07 12:24:05.293996 +0000 UTC (5)
==> Capture successful 2023-06-07 12:24:35.292419 +0000 UTC (6)
==> Capture successful 2023-06-07 12:25:05.292378 +0000 UTC (7)
==> Capture successful 2023-06-07 12:25:35.290618 +0000 UTC (8)
==> Capture successful 2023-06-07 12:26:05.290166 +0000 UTC (9)
==> Capture successful 2023-06-07 12:26:35.289962 +0000 UTC (10)
    Saved debug archive: consul-debug-2023-06-07T17-51-35+0530.tar.gz

Verified duration is 5 ms as default.

Opening `consul-debug-2023-06-07T17-51-35+0530` and checking for `cat consul.log`
Here is the output - 
2023-06-07T17:51:36.783+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:51:38.783+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:51:40.780+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:51:40.783+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:51:42.783+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:51:44.783+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:51:46.783+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:51:48.782+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:51:49.780+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:51:50.783+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:51:51.450+0530 [TRACE] agent.server: rpc_server_call: method=Coordinate.Update errored=false request_type=write rpc_type=net/rpc leader=true target_datacenter=dc1 locality=local
2023-06-07T17:51:52.782+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:51:54.782+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:51:56.782+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:51:58.779+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:51:58.782+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:51:58.782+0530 [TRACE] agent.proxycfg.agent-state: syncing proxy services from local state
2023-06-07T17:52:00.782+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:02.782+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:04.782+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:05.556+0530 [TRACE] agent.server: rpc_server_call: method=Catalog.NodeServiceList errored=false request_type=read rpc_type=net/rpc leader=true allow_stale=true blocking=false target_datacenter=dc1 locality=local
2023-06-07T17:52:05.556+0530 [TRACE] agent.server: rpc_server_call: method=Health.NodeChecks errored=false request_type=read rpc_type=net/rpc leader=true allow_stale=true blocking=false target_datacenter=dc1 locality=local
2023-06-07T17:52:05.556+0530 [DEBUG] agent: Skipping remote check since it is managed automatically: check=serfHealth
2023-06-07T17:52:05.556+0530 [DEBUG] agent: Node info in sync
2023-06-07T17:52:06.782+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:07.779+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:52:08.058+0530 [TRACE] agent.grpc.balancer: shuffling server order: target=consul://dc1.77854165-9f5d-c115-4afd-656822c3615b/server.dc1
2023-06-07T17:52:08.058+0530 [DEBUG] agent.router.manager: Rebalanced servers, new active server: number_of_servers=1 active_server="consul-server.dc1 (Addr: tcp/192.168.1.7:8300) (DC: dc1)"
2023-06-07T17:52:08.782+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:08.991+0530 [TRACE] agent.server: rpc_server_call: method=Coordinate.Update errored=false request_type=write rpc_type=net/rpc leader=true target_datacenter=dc1 locality=local
2023-06-07T17:52:10.782+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:12.783+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:14.782+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:16.779+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:52:16.782+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:18.782+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:20.782+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:22.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:24.724+0530 [TRACE] agent.grpc.balancer: shuffling server order: target=consul://dc1.77854165-9f5d-c115-4afd-656822c3615b/server.dc1
2023-06-07T17:52:24.725+0530 [TRACE] agent.server: rpc_server_call: method=Status.Ping errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:24.725+0530 [DEBUG] agent.router.manager: Rebalanced servers, new active server: number_of_servers=1 active_server="consul-server (Addr: tcp/192.168.1.7:8300) (DC: dc1)"
2023-06-07T17:52:24.782+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:25.779+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:52:26.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:28.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:28.782+0530 [TRACE] agent.proxycfg.agent-state: syncing proxy services from local state
2023-06-07T17:52:30.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:32.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:34.778+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:52:34.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:36.735+0530 [TRACE] agent.server: rpc_server_call: method=Coordinate.Update errored=false request_type=write rpc_type=net/rpc leader=true target_datacenter=dc1 locality=local
2023-06-07T17:52:36.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:38.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:40.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:42.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:43.778+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:52:44.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:46.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:48.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:50.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:52.778+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:52:52.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:54.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:56.781+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:58.639+0530 [TRACE] agent.server: rpc_server_call: method=Coordinate.Update errored=false request_type=write rpc_type=net/rpc leader=true target_datacenter=dc1 locality=local
2023-06-07T17:52:58.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:52:58.781+0530 [TRACE] agent.proxycfg.agent-state: syncing proxy services from local state
2023-06-07T17:53:00.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:01.778+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:53:02.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:04.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:06.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:08.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:09.340+0530 [TRACE] agent.server: rpc_server_call: method=Catalog.NodeServiceList errored=false request_type=read rpc_type=net/rpc leader=true allow_stale=true blocking=false target_datacenter=dc1 locality=local
2023-06-07T17:53:09.340+0530 [TRACE] agent.server: rpc_server_call: method=Health.NodeChecks errored=false request_type=read rpc_type=net/rpc leader=true allow_stale=true blocking=false target_datacenter=dc1 locality=local
2023-06-07T17:53:09.340+0530 [DEBUG] agent: Skipping remote check since it is managed automatically: check=serfHealth
2023-06-07T17:53:09.340+0530 [DEBUG] agent: Node info in sync
2023-06-07T17:53:10.778+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:53:10.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:12.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:13.899+0530 [TRACE] agent.server: rpc_server_call: method=Coordinate.Update errored=false request_type=write rpc_type=net/rpc leader=true target_datacenter=dc1 locality=local
2023-06-07T17:53:14.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:16.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:18.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:19.777+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:53:20.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:22.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:24.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:26.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:28.777+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:53:28.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:28.780+0530 [TRACE] agent.proxycfg.agent-state: syncing proxy services from local state
2023-06-07T17:53:30.780+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:32.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:34.134+0530 [TRACE] agent.server: rpc_server_call: method=Coordinate.Update errored=false request_type=write rpc_type=net/rpc leader=true target_datacenter=dc1 locality=local
2023-06-07T17:53:34.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:36.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:37.777+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:53:38.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:40.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:42.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:44.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:46.777+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:53:46.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:48.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:50.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:52.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:54.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:55.776+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:53:56.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:58.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:53:58.780+0530 [TRACE] agent.proxycfg.agent-state: syncing proxy services from local state
2023-06-07T17:53:58.850+0530 [TRACE] agent.server: rpc_server_call: method=Coordinate.Update errored=false request_type=write rpc_type=net/rpc leader=true target_datacenter=dc1 locality=local
2023-06-07T17:54:00.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:02.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:04.776+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:54:04.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:06.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:08.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:08.919+0530 [TRACE] agent.grpc.balancer: shuffling server order: target=consul://dc1.77854165-9f5d-c115-4afd-656822c3615b/server.dc1
2023-06-07T17:54:08.919+0530 [DEBUG] agent.router.manager: Rebalanced servers, new active server: number_of_servers=1 active_server="consul-server.dc1 (Addr: tcp/192.168.1.7:8300) (DC: dc1)"
2023-06-07T17:54:10.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:12.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:13.776+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:54:14.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:16.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:18.779+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:20.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:22.776+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:54:22.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:24.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:26.175+0530 [TRACE] agent.server: rpc_server_call: method=Catalog.NodeServiceList errored=false request_type=read rpc_type=net/rpc leader=true allow_stale=true blocking=false target_datacenter=dc1 locality=local
2023-06-07T17:54:26.176+0530 [TRACE] agent.server: rpc_server_call: method=Health.NodeChecks errored=false request_type=read rpc_type=net/rpc leader=true allow_stale=true blocking=false target_datacenter=dc1 locality=local
2023-06-07T17:54:26.176+0530 [DEBUG] agent: Skipping remote check since it is managed automatically: check=serfHealth
2023-06-07T17:54:26.176+0530 [DEBUG] agent: Node info in sync
2023-06-07T17:54:26.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:27.368+0530 [TRACE] agent.server: rpc_server_call: method=Coordinate.Update errored=false request_type=write rpc_type=net/rpc leader=true target_datacenter=dc1 locality=local
2023-06-07T17:54:28.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:28.779+0530 [TRACE] agent.proxycfg.agent-state: syncing proxy services from local state
2023-06-07T17:54:30.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:31.775+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:54:32.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:34.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:36.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:38.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:40.775+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:54:40.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:42.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:44.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:46.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:48.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:49.775+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:54:50.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:52.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:54.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:55.221+0530 [TRACE] agent.server: rpc_server_call: method=Coordinate.Update errored=false request_type=write rpc_type=net/rpc leader=true target_datacenter=dc1 locality=local
2023-06-07T17:54:56.778+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:58.775+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:54:58.777+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:54:58.778+0530 [TRACE] agent.proxycfg.agent-state: syncing proxy services from local state
2023-06-07T17:55:00.777+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:02.777+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:04.777+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:06.777+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:07.774+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:55:08.777+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:10.777+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:12.777+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:14.777+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:16.774+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:55:16.777+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:18.704+0530 [TRACE] agent.grpc.balancer: shuffling server order: target=consul://dc1.77854165-9f5d-c115-4afd-656822c3615b/server.dc1
2023-06-07T17:55:18.705+0530 [TRACE] agent.server: rpc_server_call: method=Status.Ping errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:18.705+0530 [DEBUG] agent.router.manager: Rebalanced servers, new active server: number_of_servers=1 active_server="consul-server (Addr: tcp/192.168.1.7:8300) (DC: dc1)"
2023-06-07T17:55:18.777+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:20.777+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:22.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:23.828+0530 [TRACE] agent.server: rpc_server_call: method=Coordinate.Update errored=false request_type=write rpc_type=net/rpc leader=true target_datacenter=dc1 locality=local
2023-06-07T17:55:24.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:25.774+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:55:26.777+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:28.777+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:28.778+0530 [TRACE] agent.proxycfg.agent-state: syncing proxy services from local state
2023-06-07T17:55:30.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:32.777+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:34.773+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:55:34.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:36.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:38.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:40.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:42.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:42.840+0530 [TRACE] agent.server: rpc_server_call: method=Coordinate.Update errored=false request_type=write rpc_type=net/rpc leader=true target_datacenter=dc1 locality=local
2023-06-07T17:55:43.774+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:55:44.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:46.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:48.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:50.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:52.773+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:55:52.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:54.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:56.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:58.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:55:58.777+0530 [TRACE] agent.proxycfg.agent-state: syncing proxy services from local state
2023-06-07T17:56:00.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:01.773+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:56:02.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:04.286+0530 [TRACE] agent.server: rpc_server_call: method=Coordinate.Update errored=false request_type=write rpc_type=net/rpc leader=true target_datacenter=dc1 locality=local
2023-06-07T17:56:04.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:06.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:08.776+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:10.770+0530 [TRACE] agent.grpc.balancer: shuffling server order: target=consul://dc1.77854165-9f5d-c115-4afd-656822c3615b/server.dc1
2023-06-07T17:56:10.770+0530 [DEBUG] agent.router.manager: Rebalanced servers, new active server: number_of_servers=1 active_server="consul-server.dc1 (Addr: tcp/192.168.1.7:8300) (DC: dc1)"
2023-06-07T17:56:10.772+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:56:10.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:12.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:14.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:16.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:18.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:19.773+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:56:19.907+0530 [TRACE] agent.server: rpc_server_call: method=ConnectCA.Roots errored=false request_type=read rpc_type=net/rpc leader=true allow_stale=false blocking=true target_datacenter=dc1 locality=local
2023-06-07T17:56:20.492+0530 [TRACE] agent.server: rpc_server_call: method=Coordinate.Update errored=false request_type=write rpc_type=net/rpc leader=true target_datacenter=dc1 locality=local
2023-06-07T17:56:20.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:20.843+0530 [TRACE] agent.server: federation state anti-entropy sync skipped; already up to date
2023-06-07T17:56:22.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:24.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:25.811+0530 [TRACE] agent.server: rpc_server_call: method=Catalog.NodeServiceList errored=false request_type=read rpc_type=net/rpc leader=true allow_stale=true blocking=false target_datacenter=dc1 locality=local
2023-06-07T17:56:25.811+0530 [TRACE] agent.server: rpc_server_call: method=Health.NodeChecks errored=false request_type=read rpc_type=net/rpc leader=true allow_stale=true blocking=false target_datacenter=dc1 locality=local
2023-06-07T17:56:25.811+0530 [DEBUG] agent: Skipping remote check since it is managed automatically: check=serfHealth
2023-06-07T17:56:25.811+0530 [DEBUG] agent: Node info in sync
2023-06-07T17:56:26.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:28.772+0530 [TRACE] agent.server.usage_metrics: Starting usage run
2023-06-07T17:56:28.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:28.776+0530 [TRACE] agent.proxycfg.agent-state: syncing proxy services from local state
2023-06-07T17:56:30.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:32.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:34.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true
2023-06-07T17:56:36.775+0530 [TRACE] agent.server: rpc_server_call: method=Status.RaftStats errored=false request_type=read rpc_type=net/rpc leader=true

PR Checklist

updated test coverage
external facing docs updated
appropriate backport labels added
not a security concern

Overview of commits

9d72a26 - 9b9bb8d - ba44809 - ff80dff

github-team-consul-core-pr-approver

Auto approved Consul Bot automated PR

david-yu · 2023-06-14T17:30:01Z

@absolutelightning We should merge this PR into 1.16.x after GA since code freeze happened yesterday.

…og-level-duration-for-consul-debug/cleanly-vast-griffon

asheshvidyut · 2023-06-18T02:24:10Z

@david-yu Please let me know when can I merge this PR?

david-yu · 2023-06-20T20:48:21Z

We can merge, since this will go out in a patch release for 1.16 and not intended for 1.16 GA.

Ashesh Vidyut added 3 commits June 7, 2023 12:17

backport of commit 9d72a26

53f6f3e

backport of commit 9b9bb8d

b89f631

backport of commit ba44809

a6039ce

hc-github-team-consul-core force-pushed the backport/NET-4107-supportability-log-level-duration-for-consul-debug/cleanly-vast-griffon branch from b89f631 to b97c7da Compare June 14, 2023 06:40

hc-github-team-consul-core assigned asheshvidyut Jun 14, 2023

hc-github-team-consul-core force-pushed the backport/NET-4107-supportability-log-level-duration-for-consul-debug/cleanly-vast-griffon branch from 344a42c to a6039ce Compare June 14, 2023 06:40

hc-github-team-consul-core requested a review from asheshvidyut June 14, 2023 06:40

github-team-consul-core-pr-approver approved these changes Jun 14, 2023

View reviewed changes

github-actions bot added theme/cli Flags and documentation for the CLI interface type/docs Documentation needs to be created/updated/clarified labels Jun 14, 2023

vercel bot temporarily deployed to Preview – consul June 14, 2023 06:45 Inactive

asheshvidyut approved these changes Jun 18, 2023

View reviewed changes

Merge branch 'release/1.16.x' into backport/NET-4107-supportability-l…

b440fb1

…og-level-duration-for-consul-debug/cleanly-vast-griffon

asheshvidyut marked this pull request as ready for review June 18, 2023 02:00

asheshvidyut requested a review from a team as a code owner June 18, 2023 02:00

vercel bot deployed to Preview – consul June 18, 2023 02:05 View deployment

david-yu merged commit 0575758 into release/1.16.x Jun 20, 2023

david-yu deleted the backport/NET-4107-supportability-log-level-duration-for-consul-debug/cleanly-vast-griffon branch June 20, 2023 20:48

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Backport of [NET-4107][Supportability] Log Level set to TRACE and duration set to 5m for consul-debug into release/1.16.x#17728

Backport of [NET-4107][Supportability] Log Level set to TRACE and duration set to 5m for consul-debug into release/1.16.x#17728
david-yu merged 4 commits intorelease/1.16.xfrom
backport/NET-4107-supportability-log-level-duration-for-consul-debug/cleanly-vast-griffon

hc-github-team-consul-core commented Jun 14, 2023

Uh oh!

github-team-consul-core-pr-approver left a comment

Uh oh!

david-yu commented Jun 14, 2023

Uh oh!

asheshvidyut commented Jun 18, 2023

Uh oh!

david-yu commented Jun 20, 2023

Uh oh!

Reviewers

Assignees

Labels

Projects

Milestone

Development

Uh oh!

4 participants

Conversation

hc-github-team-consul-core commented Jun 14, 2023

Backport

Description

Testing

PR Checklist

Uh oh!

github-team-consul-core-pr-approver left a comment

Choose a reason for hiding this comment

Uh oh!

david-yu commented Jun 14, 2023

Uh oh!

asheshvidyut commented Jun 18, 2023

Uh oh!

david-yu commented Jun 20, 2023

Uh oh!

Reviewers

Assignees

Labels

Projects

Milestone

Development

Uh oh!

4 participants