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.13.x #17726

Merged
merged 5 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.13.x #17726

merged 5 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.13.

🚨

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: POST https://api.github.com/repos/hashicorp/consul/merges: 409 Merge conflict []

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/instantly-topical-anteater branch from 9bebaf9 to 27fdf11 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/instantly-topical-anteater branch from 27fdf11 to 9bebaf9 Compare June 14, 2023 06:40
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

@github-actions github-actions bot added the theme/cli Flags and documentation for the CLI interface label Jun 14, 2023
@vercel vercel bot temporarily deployed to Preview – consul-ui-staging June 14, 2023 06:43 Inactive
@vercel vercel bot temporarily deployed to Preview – consul June 14, 2023 06:43 Inactive
@absolutelightning
Merge branch 'release/1.13.x' into backport/NET-4107-supportability-l…
eb076fc 
…og-level-duration-for-consul-debug/instantly-topical-anteater
absolutelightning and others added 2 commits June 17, 2023 22:13
@absolutelightning
merge commit cherry picked
be18ac6
@absolutelightning
Merge branch 'backport/NET-4107-supportability-log-level-duration-for…
02da707 
…-consul-debug/instantly-topical-anteater' of ssh://github.com/hashicorp/consul into backport/NET-4107-supportability-log-level-duration-for-consul-debug/instantly-topical-anteater
@absolutelightning absolutelightning marked this pull request as ready for review June 17, 2023 16:44
@absolutelightning absolutelightning requested a review from a team as a code owner June 17, 2023 16:44
@absolutelightning absolutelightning merged commit 7663062 into release/1.13.x Jun 17, 2023
94 checks passed
@absolutelightning absolutelightning deleted the backport/NET-4107-supportability-log-level-duration-for-consul-debug/instantly-topical-anteater branch June 17, 2023 17:01
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
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