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.14.x #17729

Merged
merged 3 commits into from
Jun 18, 2023
Merged

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

merged 3 commits into from
Jun 18, 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.14.

🚨

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/fully-included-goblin branch from 7076783 to 5dd9e43 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/fully-included-goblin branch from 5dd9e43 to 7076783 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:44 Inactive
@vercel vercel bot temporarily deployed to Preview – consul June 14, 2023 06:45 Inactive
@absolutelightning absolutelightning marked this pull request as ready for review June 18, 2023 01:58
@absolutelightning absolutelightning requested a review from a team as a code owner June 18, 2023 01:58
@absolutelightning absolutelightning merged commit b1febc6 into release/1.14.x Jun 18, 2023
@absolutelightning absolutelightning deleted the backport/NET-4107-supportability-log-level-duration-for-consul-debug/fully-included-goblin branch June 18, 2023 01:59
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Reviewers

@absolutelightning absolutelightning absolutelightning approved these changes

@github-team-consul-core-pr-approver github-team-consul-core-pr-approver github-team-consul-core-pr-approver 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.
3 participants
@hc-github-team-consul-core @github-team-consul-core-pr-approver @absolutelightning