Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

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

Merged
merged 4 commits into from
Jun 17, 2023
Merged

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

merged 4 commits into from
Jun 17, 2023

Conversation

hc-github-team-consul-core
Copy link
Collaborator

Backport

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

🚨

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

@hc-github-team-consul-core hc-github-team-consul-core force-pushed the backport/NET-4107-supportability-log-level-duration-for-consul-debug/totally-real-orca branch from 257eb80 to efa14ac Compare June 14, 2023 06:40
@hc-github-team-consul-core hc-github-team-consul-core force-pushed the backport/NET-4107-supportability-log-level-duration-for-consul-debug/totally-real-orca branch from 5af48b7 to 33725e4 Compare June 14, 2023 06:40
@github-actions 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
github-team-consul-core-pr-approver
github-team-consul-core-pr-approver approved these changes Jun 14, 2023
View reviewed changes
Copy link
Collaborator

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Auto approved Consul Bot automated PR

@absolutelightning
Merge branch 'release/1.15.x' into backport/NET-4107-supportability-l…
03872f0 
…og-level-duration-for-consul-debug/totally-real-orca
@absolutelightning absolutelightning marked this pull request as ready for review June 17, 2023 16:57
@absolutelightning absolutelightning requested a review from a team as a code owner June 17, 2023 16:57
@absolutelightning absolutelightning enabled auto-merge (squash) June 17, 2023 17:00
@absolutelightning absolutelightning merged commit 633b1cd into release/1.15.x Jun 17, 2023
106 checks passed
@absolutelightning absolutelightning deleted the backport/NET-4107-supportability-log-level-duration-for-consul-debug/totally-real-orca branch June 17, 2023 17:11
@jjti jjti mentioned this pull request Aug 28, 2023
Closed
@jira jira bot mentioned this pull request Dec 1, 2023
Closed
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Reviewers

@github-team-consul-core-pr-approver github-team-consul-core-pr-approver github-team-consul-core-pr-approver approved these changes

@absolutelightning absolutelightning absolutelightning approved these changes

Assignees

@absolutelightning absolutelightning

Labels
theme/cli Flags and documentation for the CLI interface type/docs Documentation needs to be created/updated/clarified
Projects
None yet
Milestone
No milestone
Development

Successfully merging this pull request may close these issues.

None yet
3 participants
@hc-github-team-consul-core @github-team-consul-core-pr-approver @absolutelightning