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.

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

Unhealthy task is only restarted once despite restart policy #9176

Closed
naag opened this issue Oct 26, 2020 · 13 comments · Fixed by #9869
Closed

Unhealthy task is only restarted once despite restart policy #9176

naag opened this issue Oct 26, 2020 · 13 comments · Fixed by #9869
Assignees
Labels
stage/accepted Confirmed, and intend to work on. No timeline committment though. theme/restart/reschedule type/bug
Milestone

Comments

@naag
Copy link

naag commented Oct 26, 2020

Nomad version

Nomad v0.12.6 (a8ea7c5f421297db434b45046fca7a9deef6df85)

Operating system and Environment details

$ lsb_release -a
Distributor ID:	Debian
Description:	Debian GNU/Linux 9.13 (stretch)
Release:	9.13
Codename:	stretch

$ uname -a
Linux nomad-server01 4.9.0-13-amd64 #1 SMP Debian 4.9.228-1 (2020-07-05) x86_64 GNU/Linux

$ consul version
Consul v1.8.4
Revision 12b16df32
Protocol 2 spoken by default, understands 2 to 3 (agent will automatically use protocol >2 when speaking to compatible agents)

Issue

We've observed that unhealthy tasks aren't restarted according to the check_restart and restart stanzas in Nomad 0.12.6, but since this is our very first Nomad installation, we cannot say if this is really limited to that particular version. For testing, we use the docker image thobe/fail_service to simulate a service that starts up healthy, stays healthy for 20 seconds, but then turns unhealthy for the next 120 seconds. Given our job file (see below), we assume that this would trigger the following sequence of events:

  1. Task starts
  2. After about 50 seconds (20 seconds healthy time + 3 * check interval), the task restarts in the same allocation
  3. Step 2 repeats two more times since the health check always goes down after 20s
  4. Allocation is destroyed and a new allocation is created because of the restart stanza with mode = "fail" in combination with the reschedule stanza, then the story resumes at step 1 indefinitely

Our hunch after some local debugging is that this is caused by the Consul health watcher being removed on task restart and never being reinstated. This in turn seems to be happening only when defining the service stanza at the group level. However when moving it to the task level, the check cannot find the port label defined in the network stanza at the group level:

Error submitting job: Unexpected response code: 500 (1 error occurred:
	* Task group api validation failed: 1 error occurred:
	* Task main validation failed: 1 error occurred:
	* 1 error occurred:
	* port label "http" referenced by services fail-service does not exist

Please note that we've also opened a thread at discuss.hashicorp.com, but after talking to some people on Gitter, it seems that this is not a misunderstanding of Nomad but more likely a bug somewhere, that's why we opted to also open an issue here.

Reproduction steps

Start Nomad, Consul and run the job:

$ nomad agent -dev
$ consul agent -dev
$ nomad job run fail_service.hcl

Then observe the output of nomad alloc status .... The task will eventually restart, but only once.

Job file

job "fail-service" {
  datacenters = ["dc1"]
  type = "service"

  reschedule {
    delay = "15s"
    delay_function = "constant"
    unlimited = true
  }

  group "api" {
    count = 1

    restart {
      attempts = 3
      interval = "30m"
      delay = "5s"
      mode = "fail"
    }

    network {
      mode = "bridge"
      port "http" {
        to = "8080"
      }
    }

    service {
      name = "fail-service"
      port = "http"

      check {
        type = "http"
        port = "http"
        path = "/health"
        interval = "10s"
        timeout = "2s"

        check_restart {
          limit = 4
          grace = "10s"
          ignore_warnings = false
        }
      }
    }

    task "main" {
      driver = "docker"

      config {
        image = "thobe/fail_service:v0.1.0"
        ports = ["http"]
      }

      env {
        HEALTHY_FOR = 20
        UNHEALTHY_FOR = 120
      }

      resources {
        cpu = 100
        memory = 128
      }
    }
  }
}

Nomad Client / Server logs

$ nomad agent -dev
==> No configuration files loaded
==> Starting Nomad agent...
==> Nomad agent configuration:

       Advertise Addrs: HTTP: 127.0.0.1:4646; RPC: 127.0.0.1:4647; Serf: 127.0.0.1:4648
            Bind Addrs: HTTP: 127.0.0.1:4646; RPC: 127.0.0.1:4647; Serf: 127.0.0.1:4648
                Client: true
             Log Level: DEBUG
                Region: global (DC: dc1)
                Server: true
               Version: 0.12.6

==> Nomad agent started! Log data will stream in below:

    2020-10-26T16:42:52.729+0100 [DEBUG] agent.plugin_loader.docker: using client connection initialized from environment: plugin_dir=
    2020-10-26T16:42:52.729+0100 [DEBUG] agent.plugin_loader.docker: using client connection initialized from environment: plugin_dir=
    2020-10-26T16:42:52.729+0100 [INFO]  agent: detected plugin: name=raw_exec type=driver plugin_version=0.1.0
    2020-10-26T16:42:52.729+0100 [INFO]  agent: detected plugin: name=exec type=driver plugin_version=0.1.0
    2020-10-26T16:42:52.729+0100 [INFO]  agent: detected plugin: name=qemu type=driver plugin_version=0.1.0
    2020-10-26T16:42:52.729+0100 [INFO]  agent: detected plugin: name=java type=driver plugin_version=0.1.0
    2020-10-26T16:42:52.729+0100 [INFO]  agent: detected plugin: name=docker type=driver plugin_version=0.1.0
    2020-10-26T16:42:52.729+0100 [INFO]  agent: detected plugin: name=nvidia-gpu type=device plugin_version=0.1.0
    2020-10-26T16:42:52.730+0100 [INFO]  nomad.raft: initial configuration: index=1 servers="[{Suffrage:Voter ID:127.0.0.1:4647 Address:127.0.0.1:4647}]"
    2020-10-26T16:42:52.730+0100 [INFO]  nomad.raft: entering follower state: follower="Node at 127.0.0.1:4647 [Follower]" leader=
    2020-10-26T16:42:52.731+0100 [INFO]  nomad: serf: EventMemberJoin: PC3776.global 127.0.0.1
    2020-10-26T16:42:52.731+0100 [INFO]  nomad: starting scheduling worker(s): num_workers=8 schedulers=[service, batch, system, _core]
    2020-10-26T16:42:52.731+0100 [INFO]  nomad: adding server: server="PC3776.global (Addr: 127.0.0.1:4647) (DC: dc1)"
    2020-10-26T16:42:52.731+0100 [INFO]  client: using state directory: state_dir=/tmp/NomadClient459616110
    2020-10-26T16:42:52.731+0100 [INFO]  client: using alloc directory: alloc_dir=/tmp/NomadClient843835893
    2020-10-26T16:42:52.734+0100 [DEBUG] client.fingerprint_mgr: built-in fingerprints: fingerprinters=[arch, bridge, cgroup, cni, consul, cpu, host, memory, network, nomad, signal, storage, vault, env_aws, env_gce]
    2020-10-26T16:42:52.735+0100 [DEBUG] consul.sync: sync complete: registered_services=3 deregistered_services=0 registered_checks=3 deregistered_checks=0
    2020-10-26T16:42:52.737+0100 [INFO]  client.fingerprint_mgr.cgroup: cgroups are available
    2020-10-26T16:42:52.737+0100 [DEBUG] client.fingerprint_mgr: CNI config dir is not set or does not exist, skipping: cni_config_dir=
    2020-10-26T16:42:52.737+0100 [DEBUG] client.fingerprint_mgr: fingerprinting periodically: fingerprinter=cgroup period=15s
    2020-10-26T16:42:52.739+0100 [INFO]  client.fingerprint_mgr.consul: consul agent is available
    2020-10-26T16:42:52.739+0100 [DEBUG] client.fingerprint_mgr: fingerprinting periodically: fingerprinter=consul period=15s
    2020-10-26T16:42:52.740+0100 [DEBUG] client.fingerprint_mgr.cpu: detected cpu frequency: MHz=4000
    2020-10-26T16:42:52.740+0100 [DEBUG] client.fingerprint_mgr.cpu: detected core count: cores=8
    2020-10-26T16:42:52.743+0100 [WARN]  client.fingerprint_mgr.network: unable to parse speed: path=/sbin/ethtool device=lo
    2020-10-26T16:42:52.743+0100 [DEBUG] client.fingerprint_mgr.network: unable to read link speed: path=/sys/class/net/lo/speed
    2020-10-26T16:42:52.743+0100 [DEBUG] client.fingerprint_mgr.network: link speed could not be detected and no speed specified by user, falling back to default speed: mbits=1000
    2020-10-26T16:42:52.743+0100 [DEBUG] client.fingerprint_mgr.network: detected interface IP: interface=lo IP=127.0.0.1
    2020-10-26T16:42:52.743+0100 [DEBUG] client.fingerprint_mgr.network: detected interface IP: interface=lo IP=::1
    2020-10-26T16:42:52.745+0100 [WARN]  client.fingerprint_mgr.network: unable to parse speed: path=/sbin/ethtool device=lo
    2020-10-26T16:42:52.745+0100 [DEBUG] client.fingerprint_mgr.network: unable to read link speed: path=/sys/class/net/lo/speed
    2020-10-26T16:42:52.745+0100 [DEBUG] client.fingerprint_mgr.network: link speed could not be detected, falling back to default speed: mbits=1000
    2020-10-26T16:42:52.745+0100 [WARN]  client.fingerprint_mgr.network: unable to parse speed: path=/sbin/ethtool device=enp0s31f6
    2020-10-26T16:42:52.745+0100 [DEBUG] client.fingerprint_mgr.network: unable to parse link speed: path=/sys/class/net/enp0s31f6/speed
    2020-10-26T16:42:52.745+0100 [DEBUG] client.fingerprint_mgr.network: link speed could not be detected, falling back to default speed: mbits=1000
    2020-10-26T16:42:52.746+0100 [WARN]  client.fingerprint_mgr.network: unable to parse speed: path=/sbin/ethtool device=wlp2s0
    2020-10-26T16:42:52.746+0100 [DEBUG] client.fingerprint_mgr.network: unable to read link speed: path=/sys/class/net/wlp2s0/speed
    2020-10-26T16:42:52.746+0100 [DEBUG] client.fingerprint_mgr.network: link speed could not be detected, falling back to default speed: mbits=1000
    2020-10-26T16:42:52.747+0100 [WARN]  client.fingerprint_mgr.network: unable to parse speed: path=/sbin/ethtool device=virbr0
    2020-10-26T16:42:52.747+0100 [DEBUG] client.fingerprint_mgr.network: unable to read link speed: path=/sys/class/net/virbr0/speed
    2020-10-26T16:42:52.747+0100 [DEBUG] client.fingerprint_mgr.network: link speed could not be detected, falling back to default speed: mbits=1000
    2020-10-26T16:42:52.748+0100 [WARN]  client.fingerprint_mgr.network: unable to parse speed: path=/sbin/ethtool device=br-480dfb2eba06
    2020-10-26T16:42:52.748+0100 [DEBUG] client.fingerprint_mgr.network: unable to read link speed: path=/sys/class/net/br-480dfb2eba06/speed
    2020-10-26T16:42:52.748+0100 [DEBUG] client.fingerprint_mgr.network: link speed could not be detected, falling back to default speed: mbits=1000
    2020-10-26T16:42:52.750+0100 [WARN]  client.fingerprint_mgr.network: unable to parse speed: path=/sbin/ethtool device=br-ca4858e1f2e6
    2020-10-26T16:42:52.750+0100 [DEBUG] client.fingerprint_mgr.network: unable to read link speed: path=/sys/class/net/br-ca4858e1f2e6/speed
    2020-10-26T16:42:52.750+0100 [DEBUG] client.fingerprint_mgr.network: link speed could not be detected, falling back to default speed: mbits=1000
    2020-10-26T16:42:52.751+0100 [WARN]  client.fingerprint_mgr.network: unable to parse speed: path=/sbin/ethtool device=br-0a692bccbda7
    2020-10-26T16:42:52.751+0100 [DEBUG] client.fingerprint_mgr.network: unable to read link speed: path=/sys/class/net/br-0a692bccbda7/speed
    2020-10-26T16:42:52.751+0100 [DEBUG] client.fingerprint_mgr.network: link speed could not be detected, falling back to default speed: mbits=1000
    2020-10-26T16:42:52.752+0100 [WARN]  client.fingerprint_mgr.network: unable to parse speed: path=/sbin/ethtool device=br-0ca4b915ce85    2020-10-26T16:42:57.878+0100 [DEBUG] client: updated allocations: index=1 total=0 pulled=0 filtered=0

    2020-10-26T16:42:52.752+0100 [DEBUG] client.fingerprint_mgr.network: unable to read link speed: path=/sys/class/net/br-0ca4b915ce85/speed
    2020-10-26T16:42:52.752+0100 [DEBUG] client.fingerprint_mgr.network: link speed could not be detected, falling back to default speed: mbits=1000
    2020-10-26T16:42:52.753+0100 [WARN]  client.fingerprint_mgr.network: unable to parse speed: path=/sbin/ethtool device=br-3c8a06b4e70f
    2020-10-26T16:42:52.753+0100 [DEBUG] client.fingerprint_mgr.network: unable to read link speed: path=/sys/class/net/br-3c8a06b4e70f/speed
    2020-10-26T16:42:52.753+0100 [DEBUG] client.fingerprint_mgr.network: link speed could not be detected, falling back to default speed: mbits=1000
    2020-10-26T16:42:52.754+0100 [WARN]  client.fingerprint_mgr.network: unable to parse speed: path=/sbin/ethtool device=br-7c0f06756237
    2020-10-26T16:42:52.754+0100 [DEBUG] client.fingerprint_mgr.network: unable to read link speed: path=/sys/class/net/br-7c0f06756237/speed
    2020-10-26T16:42:52.754+0100 [DEBUG] client.fingerprint_mgr.network: link speed could not be detected, falling back to default speed: mbits=1000
    2020-10-26T16:42:52.755+0100 [WARN]  client.fingerprint_mgr.network: unable to parse speed: path=/sbin/ethtool device=docker0
    2020-10-26T16:42:52.755+0100 [DEBUG] client.fingerprint_mgr.network: unable to read link speed: path=/sys/class/net/docker0/speed
    2020-10-26T16:42:52.755+0100 [DEBUG] client.fingerprint_mgr.network: link speed could not be detected, falling back to default speed: mbits=1000
    2020-10-26T16:42:52.755+0100 [WARN]  client.fingerprint_mgr.network: unable to parse speed: path=/sbin/ethtool device=br-4458bb17cc1e
    2020-10-26T16:42:52.756+0100 [DEBUG] client.fingerprint_mgr.network: unable to read link speed: path=/sys/class/net/br-4458bb17cc1e/speed
    2020-10-26T16:42:52.756+0100 [DEBUG] client.fingerprint_mgr.network: link speed could not be detected, falling back to default speed: mbits=1000
    2020-10-26T16:42:52.756+0100 [WARN]  client.fingerprint_mgr.network: unable to parse speed: path=/sbin/ethtool device=br-47c4a31e6521
    2020-10-26T16:42:52.756+0100 [DEBUG] client.fingerprint_mgr.network: unable to read link speed: path=/sys/class/net/br-47c4a31e6521/speed
    2020-10-26T16:42:52.756+0100 [DEBUG] client.fingerprint_mgr.network: link speed could not be detected, falling back to default speed: mbits=1000
    2020-10-26T16:42:52.757+0100 [WARN]  client.fingerprint_mgr.network: unable to parse speed: path=/sbin/ethtool device=br-65ac59e9fd7c
    2020-10-26T16:42:52.757+0100 [DEBUG] client.fingerprint_mgr.network: unable to read link speed: path=/sys/class/net/br-65ac59e9fd7c/speed
    2020-10-26T16:42:52.757+0100 [DEBUG] client.fingerprint_mgr.network: link speed could not be detected, falling back to default speed: mbits=1000
    2020-10-26T16:42:52.772+0100 [WARN]  client.fingerprint_mgr.network: unable to parse speed: path=/sbin/ethtool device=nomad
    2020-10-26T16:42:52.772+0100 [DEBUG] client.fingerprint_mgr.network: unable to read link speed: path=/sys/class/net/nomad/speed
    2020-10-26T16:42:52.772+0100 [DEBUG] client.fingerprint_mgr.network: link speed could not be detected, falling back to default speed: mbits=1000
    2020-10-26T16:42:52.773+0100 [DEBUG] client.fingerprint_mgr: fingerprinting periodically: fingerprinter=vault period=15s
    2020-10-26T16:42:54.356+0100 [WARN]  nomad.raft: heartbeat timeout reached, starting election: last-leader=
    2020-10-26T16:42:54.356+0100 [INFO]  nomad.raft: entering candidate state: node="Node at 127.0.0.1:4647 [Candidate]" term=2
    2020-10-26T16:42:54.356+0100 [DEBUG] nomad.raft: votes: needed=1
    2020-10-26T16:42:54.356+0100 [DEBUG] nomad.raft: vote granted: from=127.0.0.1:4647 term=2 tally=1
    2020-10-26T16:42:54.356+0100 [INFO]  nomad.raft: election won: tally=1
    2020-10-26T16:42:54.356+0100 [INFO]  nomad.raft: entering leader state: leader="Node at 127.0.0.1:4647 [Leader]"
    2020-10-26T16:42:54.356+0100 [INFO]  nomad: cluster leadership acquired
    2020-10-26T16:42:54.357+0100 [INFO]  nomad.core: established cluster id: cluster_id=abaf4fe5-6d61-f752-03e6-4b5b4b7f354e create_time=1603726974357256317
    2020-10-26T16:42:57.839+0100 [DEBUG] client.fingerprint_mgr.env_gce: could not read value for attribute: attribute=machine-type error="Get "http://169.254.169.254/computeMetadata/v1/instance/machine-type": dial tcp 169.254.169.254:80: i/o timeout (Client.Timeout exceeded while awaiting headers)"
    2020-10-26T16:42:57.839+0100 [DEBUG] client.fingerprint_mgr.env_gce: error querying GCE Metadata URL, skipping
    2020-10-26T16:42:57.839+0100 [DEBUG] client.fingerprint_mgr: detected fingerprints: node_attrs=[arch, bridge, cgroup, consul, cpu, host, network, nomad, signal, storage]
    2020-10-26T16:42:57.839+0100 [INFO]  client.plugin: starting plugin manager: plugin-type=csi
    2020-10-26T16:42:57.840+0100 [INFO]  client.plugin: starting plugin manager: plugin-type=driver
    2020-10-26T16:42:57.840+0100 [INFO]  client.plugin: starting plugin manager: plugin-type=device
    2020-10-26T16:42:57.840+0100 [DEBUG] client.plugin: waiting on plugin manager initial fingerprint: plugin-type=driver
    2020-10-26T16:42:57.840+0100 [DEBUG] client.plugin: waiting on plugin manager initial fingerprint: plugin-type=device
    2020-10-26T16:42:57.840+0100 [DEBUG] client.plugin: finished plugin manager initial fingerprint: plugin-type=device
    2020-10-26T16:42:57.840+0100 [DEBUG] client.driver_mgr: initial driver fingerprint: driver=raw_exec health=healthy description=Healthy
    2020-10-26T16:42:57.840+0100 [DEBUG] client.driver_mgr: initial driver fingerprint: driver=exec health=healthy description=Healthy
    2020-10-26T16:42:57.840+0100 [DEBUG] client.server_mgr: new server list: new_servers=[127.0.0.1:4647] old_servers=[]
    2020-10-26T16:42:57.855+0100 [DEBUG] client.driver_mgr: initial driver fingerprint: driver=qemu health=healthy description=Healthy
    2020-10-26T16:42:57.870+0100 [DEBUG] client.driver_mgr: initial driver fingerprint: driver=java health=healthy description=Healthy
    2020-10-26T16:42:57.877+0100 [DEBUG] client.driver_mgr: initial driver fingerprint: driver=docker health=healthy description=Healthy
    2020-10-26T16:42:57.877+0100 [DEBUG] client.driver_mgr: detected drivers: drivers="map[healthy:[raw_exec exec qemu java docker]]"
    2020-10-26T16:42:57.877+0100 [DEBUG] client.plugin: finished plugin manager initial fingerprint: plugin-type=driver
    2020-10-26T16:42:57.877+0100 [INFO]  client: started client: node_id=24d0f3af-d348-225b-ac21-72fa79a2f45b
    2020-10-26T16:42:57.878+0100 [DEBUG] client: allocation updates: added=0 removed=0 updated=0 ignored=0
    2020-10-26T16:42:57.878+0100 [DEBUG] client: allocation updates applied: added=0 removed=0 updated=0 ignored=0 errors=0
    2020-10-26T16:42:57.878+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48020
    2020-10-26T16:42:57.878+0100 [INFO]  client: node registration complete
    2020-10-26T16:42:57.879+0100 [DEBUG] client: state updated: node_status=ready
    2020-10-26T16:42:57.881+0100 [DEBUG] consul.sync: sync complete: registered_services=1 deregistered_services=0 registered_checks=1 deregistered_checks=0
    2020-10-26T16:42:58.879+0100 [DEBUG] client: state changed, updating node and re-registering
    2020-10-26T16:42:58.879+0100 [INFO]  client: node registration complete
    2020-10-26T16:43:05.084+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=client duration=564.619µs
==> Newer Nomad version available: 0.12.7 (currently running: 0.12.6)
    2020-10-26T16:43:06.890+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=server duration=163.11µs
    2020-10-26T16:43:07.879+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48032
    2020-10-26T16:43:15.087+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=client duration=295.906µs
    2020-10-26T16:43:16.892+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=server duration=172.865µs
    2020-10-26T16:43:17.879+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48044
    2020-10-26T16:43:25.088+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=client duration=334.349µs
    2020-10-26T16:43:26.893+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=server duration=114.85µs
    2020-10-26T16:43:27.879+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48056
    2020-10-26T16:43:29.259+0100 [DEBUG] worker: dequeued evaluation: eval_id=e4c91584-9410-f72a-e5bb-19260ebd3e94
    2020-10-26T16:43:29.259+0100 [DEBUG] http: request complete: method=PUT path=/v1/jobs duration=1.597441ms
    2020-10-26T16:43:29.259+0100 [DEBUG] worker.service_sched: reconciled current state with desired state: eval_id=e4c91584-9410-f72a-e5bb-19260ebd3e94 job_id=fail-service namespace=default results="Total changes: (place 1) (destructive 0) (inplace 0) (stop 0)
Created Deployment: "6b6552b2-1310-82eb-835d-15a91c467753"
Desired Changes for "api": (place 1) (inplace 0) (destructive 0) (stop 0) (migrate 0) (ignore 0) (canary 0)"
    2020-10-26T16:43:29.260+0100 [DEBUG] http: request complete: method=GET path=/v1/evaluation/e4c91584-9410-f72a-e5bb-19260ebd3e94 duration=161.988µs
    2020-10-26T16:43:29.260+0100 [DEBUG] worker: submitted plan for evaluation: eval_id=e4c91584-9410-f72a-e5bb-19260ebd3e94
    2020-10-26T16:43:29.260+0100 [DEBUG] worker.service_sched: setting eval status: eval_id=e4c91584-9410-f72a-e5bb-19260ebd3e94 job_id=fail-service namespace=default status=complete
    2020-10-26T16:43:29.260+0100 [DEBUG] worker: updated evaluation: eval="<Eval "e4c91584-9410-f72a-e5bb-19260ebd3e94" JobID: "fail-service" Namespace: "default">"
    2020-10-26T16:43:29.260+0100 [DEBUG] http: request complete: method=GET path=/v1/evaluation/e4c91584-9410-f72a-e5bb-19260ebd3e94/allocations duration=187.243µs
    2020-10-26T16:43:29.261+0100 [DEBUG] worker: ack evaluation: eval_id=e4c91584-9410-f72a-e5bb-19260ebd3e94
    2020-10-26T16:43:29.261+0100 [DEBUG] client: updated allocations: index=11 total=1 pulled=1 filtered=0
    2020-10-26T16:43:29.261+0100 [DEBUG] client: allocation updates: added=1 removed=0 updated=0 ignored=0
    2020-10-26T16:43:29.261+0100 [DEBUG] client: allocation updates applied: added=1 removed=0 updated=0 ignored=0 errors=0
    2020-10-26T16:43:29.873+0100 [DEBUG] client.driver_mgr.docker: docker pull succeeded: driver=docker image_ref=gcr.io/google_containers/pause-amd64:3.0
    2020-10-26T16:43:29.876+0100 [DEBUG] client.driver_mgr.docker: image reference count incremented: driver=docker image_name=gcr.io/google_containers/pause-amd64:3.0 image_id=sha256:99e59f495ffaa222bfeb67580213e8c28c1e885f1d245ab2bbe3b1b1ec3bd0b2 references=1
    2020-10-26T16:43:30.262+0100 [DEBUG] http: request complete: method=GET path=/v1/evaluation/e4c91584-9410-f72a-e5bb-19260ebd3e94 duration=353.515µs
    2020-10-26T16:43:30.262+0100 [DEBUG] http: request complete: method=GET path=/v1/evaluation/e4c91584-9410-f72a-e5bb-19260ebd3e94/allocations duration=129.294µs
    2020-10-26T16:43:30.634+0100 [DEBUG] consul.health: watching check: alloc_id=2812a91f-c63f-b564-69f3-71a6d2dec8dc task=group-api check="service: "fail-service-nomad" check"
    2020-10-26T16:43:30.635+0100 [DEBUG] client.alloc_runner.task_runner.task_hook.logmon: starting plugin: alloc_id=2812a91f-c63f-b564-69f3-71a6d2dec8dc task=main path=/home/buecker/bin/nomad args=[/home/buecker/bin/nomad, logmon]
    2020-10-26T16:43:30.637+0100 [DEBUG] consul.sync: sync complete: registered_services=1 deregistered_services=0 registered_checks=1 deregistered_checks=0
    2020-10-26T16:43:30.637+0100 [DEBUG] client.alloc_runner.task_runner.task_hook.logmon: plugin started: alloc_id=2812a91f-c63f-b564-69f3-71a6d2dec8dc task=main path=/home/buecker/bin/nomad pid=8766
    2020-10-26T16:43:30.637+0100 [DEBUG] client.alloc_runner.task_runner.task_hook.logmon: waiting for RPC address: alloc_id=2812a91f-c63f-b564-69f3-71a6d2dec8dc task=main path=/home/buecker/bin/nomad
    2020-10-26T16:43:30.642+0100 [DEBUG] client.alloc_runner.task_runner.task_hook.logmon.nomad: plugin address: alloc_id=2812a91f-c63f-b564-69f3-71a6d2dec8dc task=main @module=logmon address=/tmp/plugin674105061 network=unix timestamp=2020-10-26T16:43:30.641+0100
    2020-10-26T16:43:30.642+0100 [DEBUG] client.alloc_runner.task_runner.task_hook.logmon: using plugin: alloc_id=2812a91f-c63f-b564-69f3-71a6d2dec8dc task=main version=2
    2020-10-26T16:43:30.642+0100 [INFO]  client.alloc_runner.task_runner.task_hook.logmon.nomad: opening fifo: alloc_id=2812a91f-c63f-b564-69f3-71a6d2dec8dc task=main @module=logmon path=/tmp/NomadClient843835893/2812a91f-c63f-b564-69f3-71a6d2dec8dc/alloc/logs/.main.stdout.fifo timestamp=2020-10-26T16:43:30.642+0100
    2020-10-26T16:43:30.643+0100 [INFO]  client.alloc_runner.task_runner.task_hook.logmon.nomad: opening fifo: alloc_id=2812a91f-c63f-b564-69f3-71a6d2dec8dc task=main @module=logmon path=/tmp/NomadClient843835893/2812a91f-c63f-b564-69f3-71a6d2dec8dc/alloc/logs/.main.stderr.fifo timestamp=2020-10-26T16:43:30.643+0100
    2020-10-26T16:43:30.644+0100 [DEBUG] client.driver_mgr.docker: image reference count incremented: driver=docker image_name=thobe/fail_service:v0.1.0 image_id=sha256:215edc8c886d2277f80848cc9909ce3ebafc25318ef4f65f6d29b6196b8d2964 references=1
    2020-10-26T16:43:30.644+0100 [DEBUG] client.driver_mgr.docker: configured resources: driver=docker task_name=main memory=134217728 memory_reservation=0 cpu_shares=100 cpu_quota=0 cpu_period=0
    2020-10-26T16:43:30.644+0100 [DEBUG] client.driver_mgr.docker: binding directories: driver=docker task_name=main binds="[]string{"/tmp/NomadClient843835893/2812a91f-c63f-b564-69f3-71a6d2dec8dc/alloc:/alloc", "/tmp/NomadClient843835893/2812a91f-c63f-b564-69f3-71a6d2dec8dc/main/local:/local", "/tmp/NomadClient843835893/2812a91f-c63f-b564-69f3-71a6d2dec8dc/main/secrets:/secrets"}"
    2020-10-26T16:43:30.644+0100 [DEBUG] client.driver_mgr.docker: configuring network mode for task group: driver=docker task_name=main network_mode=container:aa04244f7014b1285a70d669fbf8fe7b719ff1e2fec8d21fa9e9fa661f60d95f
    2020-10-26T16:43:30.644+0100 [DEBUG] client.driver_mgr.docker: applied labels on the container: driver=docker task_name=main labels=map[com.hashicorp.nomad.alloc_id:2812a91f-c63f-b564-69f3-71a6d2dec8dc]
    2020-10-26T16:43:30.644+0100 [DEBUG] client.driver_mgr.docker: setting container name: driver=docker task_name=main container_name=main-2812a91f-c63f-b564-69f3-71a6d2dec8dc
    2020-10-26T16:43:30.728+0100 [DEBUG] client: updated allocations: index=13 total=1 pulled=0 filtered=1
    2020-10-26T16:43:30.728+0100 [DEBUG] client: allocation updates: added=0 removed=0 updated=0 ignored=1
    2020-10-26T16:43:30.728+0100 [DEBUG] client: allocation updates applied: added=0 removed=0 updated=0 ignored=1 errors=0
    2020-10-26T16:43:30.734+0100 [INFO]  client.driver_mgr.docker: created container: driver=docker container_id=97dc269ea8360793086447512642e348026e52a50091bfeedb39228696a1607c
    2020-10-26T16:43:30.961+0100 [INFO]  client.driver_mgr.docker: started container: driver=docker container_id=97dc269ea8360793086447512642e348026e52a50091bfeedb39228696a1607c
    2020-10-26T16:43:30.961+0100 [DEBUG] client.driver_mgr.docker.docker_logger: starting plugin: driver=docker path=/home/buecker/bin/nomad args=[/home/buecker/bin/nomad, docker_logger]
    2020-10-26T16:43:30.962+0100 [DEBUG] client.driver_mgr.docker.docker_logger: plugin started: driver=docker path=/home/buecker/bin/nomad pid=8833
    2020-10-26T16:43:30.962+0100 [DEBUG] client.driver_mgr.docker.docker_logger: waiting for RPC address: driver=docker path=/home/buecker/bin/nomad
    2020-10-26T16:43:30.966+0100 [DEBUG] client.driver_mgr.docker.docker_logger.nomad: plugin address: driver=docker @module=docker_logger address=/tmp/plugin215461573 network=unix timestamp=2020-10-26T16:43:30.966+0100
    2020-10-26T16:43:30.966+0100 [DEBUG] client.driver_mgr.docker.docker_logger: using plugin: driver=docker version=2
    2020-10-26T16:43:30.967+0100 [DEBUG] client.driver_mgr.docker.docker_logger.nomad: using client connection initialized from environment: driver=docker @module=docker_logger timestamp=2020-10-26T16:43:30.967+0100
    2020-10-26T16:43:31.128+0100 [DEBUG] client: updated allocations: index=14 total=1 pulled=0 filtered=1
    2020-10-26T16:43:31.129+0100 [DEBUG] client: allocation updates: added=0 removed=0 updated=0 ignored=1
    2020-10-26T16:43:31.129+0100 [DEBUG] client: allocation updates applied: added=0 removed=0 updated=0 ignored=1 errors=0
    2020-10-26T16:43:35.089+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=client duration=197.189µs
    2020-10-26T16:43:36.894+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=server duration=255.186µs
    2020-10-26T16:43:37.880+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48094
    2020-10-26T16:43:45.091+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=client duration=231.996µs
    2020-10-26T16:43:46.895+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=server duration=316.088µs
    2020-10-26T16:43:47.329+0100 [DEBUG] client: updated allocations: index=15 total=1 pulled=0 filtered=1
    2020-10-26T16:43:47.329+0100 [DEBUG] client: allocation updates: added=0 removed=0 updated=0 ignored=1
    2020-10-26T16:43:47.329+0100 [DEBUG] client: allocation updates applied: added=0 removed=0 updated=0 ignored=1 errors=0
    2020-10-26T16:43:47.880+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48120
    2020-10-26T16:43:48.580+0100 [DEBUG] worker: dequeued evaluation: eval_id=af7ccabb-bc2b-1269-1b01-5baff35bc9c6
    2020-10-26T16:43:48.580+0100 [DEBUG] worker.service_sched: reconciled current state with desired state: eval_id=af7ccabb-bc2b-1269-1b01-5baff35bc9c6 job_id=fail-service namespace=default results="Total changes: (place 0) (destructive 0) (inplace 0) (stop 0)
Deployment Update for ID "6b6552b2-1310-82eb-835d-15a91c467753": Status "successful"; Description "Deployment completed successfully"
Desired Changes for "api": (place 0) (inplace 0) (destructive 0) (stop 0) (migrate 0) (ignore 1) (canary 0)"
    2020-10-26T16:43:48.580+0100 [DEBUG] worker: submitted plan for evaluation: eval_id=af7ccabb-bc2b-1269-1b01-5baff35bc9c6
    2020-10-26T16:43:48.580+0100 [DEBUG] worker.service_sched: setting eval status: eval_id=af7ccabb-bc2b-1269-1b01-5baff35bc9c6 job_id=fail-service namespace=default status=complete
    2020-10-26T16:43:48.581+0100 [DEBUG] worker: updated evaluation: eval="<Eval "af7ccabb-bc2b-1269-1b01-5baff35bc9c6" JobID: "fail-service" Namespace: "default">"
    2020-10-26T16:43:48.581+0100 [DEBUG] worker: ack evaluation: eval_id=af7ccabb-bc2b-1269-1b01-5baff35bc9c6
    2020-10-26T16:43:55.092+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=client duration=179.771µs
    2020-10-26T16:43:55.281+0100 [DEBUG] http: request complete: method=GET path=/v1/allocations?prefix=2812a91f duration=283.269µs
    2020-10-26T16:43:55.282+0100 [DEBUG] http: request complete: method=GET path=/v1/allocation/2812a91f-c63f-b564-69f3-71a6d2dec8dc?namespace=default duration=249.454µs
    2020-10-26T16:43:55.285+0100 [DEBUG] http: request complete: method=GET path=/v1/client/allocation/2812a91f-c63f-b564-69f3-71a6d2dec8dc/stats duration=134.903µs
    2020-10-26T16:43:56.896+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=server duration=206.585µs
    2020-10-26T16:43:57.643+0100 [DEBUG] consul.health: check became unhealthy. Will restart if check doesn't become healthy: alloc_id=2812a91f-c63f-b564-69f3-71a6d2dec8dc check="service: "fail-service-nomad" check" task=group-api time_limit=30s
    2020-10-26T16:43:57.880+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48138
    2020-10-26T16:44:05.093+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=client duration=133.426µs
    2020-10-26T16:44:06.897+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=server duration=175.348µs
    2020-10-26T16:44:07.881+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48152
    2020-10-26T16:44:15.094+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=client duration=119.807µs
    2020-10-26T16:44:16.898+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=server duration=201.446µs
    2020-10-26T16:44:17.881+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48166
    2020-10-26T16:44:25.095+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=client duration=142.845µs
    2020-10-26T16:44:26.899+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=server duration=149.589µs
    2020-10-26T16:44:27.881+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48176
    2020-10-26T16:44:28.250+0100 [DEBUG] consul.health: restarting due to unhealthy check: alloc_id=2812a91f-c63f-b564-69f3-71a6d2dec8dc check="service: "fail-service-nomad" check" task=group-api
    2020-10-26T16:44:28.330+0100 [DEBUG] client: updated allocations: index=20 total=1 pulled=0 filtered=1
    2020-10-26T16:44:28.330+0100 [DEBUG] client: allocation updates: added=0 removed=0 updated=0 ignored=1
    2020-10-26T16:44:28.330+0100 [DEBUG] client: allocation updates applied: added=0 removed=0 updated=0 ignored=1 errors=0
    2020-10-26T16:44:28.430+0100 [DEBUG] client.alloc_runner.task_runner.task_hook.stats_hook: failed to start stats collection for task with unrecoverable error: alloc_id=2812a91f-c63f-b564-69f3-71a6d2dec8dc task=main error="container stopped"
    2020-10-26T16:44:28.431+0100 [DEBUG] client.driver_mgr.docker.docker_logger: plugin process exited: driver=docker path=/home/buecker/bin/nomad pid=8833
    2020-10-26T16:44:28.431+0100 [DEBUG] client.driver_mgr.docker.docker_logger: plugin exited: driver=docker
    2020-10-26T16:44:28.438+0100 [DEBUG] client.driver_mgr.docker: image id reference count decremented: driver=docker image_id=sha256:215edc8c886d2277f80848cc9909ce3ebafc25318ef4f65f6d29b6196b8d2964 references=0
    2020-10-26T16:44:28.438+0100 [INFO]  client.alloc_runner.task_runner: restarting task: alloc_id=2812a91f-c63f-b564-69f3-71a6d2dec8dc task=main reason="Restart within policy" delay=6.054541836s
    2020-10-26T16:44:28.529+0100 [DEBUG] client: updated allocations: index=21 total=1 pulled=0 filtered=1
    2020-10-26T16:44:28.529+0100 [DEBUG] client: allocation updates: added=0 removed=0 updated=0 ignored=1
    2020-10-26T16:44:28.529+0100 [DEBUG] client: allocation updates applied: added=0 removed=0 updated=0 ignored=1 errors=0
    2020-10-26T16:44:34.493+0100 [INFO]  client.alloc_runner.task_runner.task_hook.logmon.nomad: opening fifo: alloc_id=2812a91f-c63f-b564-69f3-71a6d2dec8dc task=main path=/tmp/NomadClient843835893/2812a91f-c63f-b564-69f3-71a6d2dec8dc/alloc/logs/.main.stdout.fifo @module=logmon timestamp=2020-10-26T16:44:34.493+0100
    2020-10-26T16:44:34.493+0100 [INFO]  client.alloc_runner.task_runner.task_hook.logmon.nomad: opening fifo: alloc_id=2812a91f-c63f-b564-69f3-71a6d2dec8dc task=main @module=logmon path=/tmp/NomadClient843835893/2812a91f-c63f-b564-69f3-71a6d2dec8dc/alloc/logs/.main.stderr.fifo timestamp=2020-10-26T16:44:34.493+0100
    2020-10-26T16:44:34.494+0100 [DEBUG] client.driver_mgr.docker: cancelling removal of container image: driver=docker image_name=thobe/fail_service:v0.1.0
    2020-10-26T16:44:34.495+0100 [DEBUG] client.driver_mgr.docker: image reference count incremented: driver=docker image_name=thobe/fail_service:v0.1.0 image_id=sha256:215edc8c886d2277f80848cc9909ce3ebafc25318ef4f65f6d29b6196b8d2964 references=1
    2020-10-26T16:44:34.495+0100 [DEBUG] client.driver_mgr.docker: configured resources: driver=docker task_name=main memory=134217728 memory_reservation=0 cpu_shares=100 cpu_quota=0 cpu_period=0
    2020-10-26T16:44:34.495+0100 [DEBUG] client.driver_mgr.docker: binding directories: driver=docker task_name=main binds="[]string{"/tmp/NomadClient843835893/2812a91f-c63f-b564-69f3-71a6d2dec8dc/alloc:/alloc", "/tmp/NomadClient843835893/2812a91f-c63f-b564-69f3-71a6d2dec8dc/main/local:/local", "/tmp/NomadClient843835893/2812a91f-c63f-b564-69f3-71a6d2dec8dc/main/secrets:/secrets"}"
    2020-10-26T16:44:34.495+0100 [DEBUG] client.driver_mgr.docker: configuring network mode for task group: driver=docker task_name=main network_mode=container:aa04244f7014b1285a70d669fbf8fe7b719ff1e2fec8d21fa9e9fa661f60d95f
    2020-10-26T16:44:34.495+0100 [DEBUG] client.driver_mgr.docker: applied labels on the container: driver=docker task_name=main labels=map[com.hashicorp.nomad.alloc_id:2812a91f-c63f-b564-69f3-71a6d2dec8dc]
    2020-10-26T16:44:34.495+0100 [DEBUG] client.driver_mgr.docker: setting container name: driver=docker task_name=main container_name=main-2812a91f-c63f-b564-69f3-71a6d2dec8dc
    2020-10-26T16:44:34.586+0100 [INFO]  client.driver_mgr.docker: created container: driver=docker container_id=aeb252b0d3a664881a9460ee54a05365afdf93e91c138412294ca91bb632a7eb
    2020-10-26T16:44:34.820+0100 [INFO]  client.driver_mgr.docker: started container: driver=docker container_id=aeb252b0d3a664881a9460ee54a05365afdf93e91c138412294ca91bb632a7eb
    2020-10-26T16:44:34.820+0100 [DEBUG] client.driver_mgr.docker.docker_logger: starting plugin: driver=docker path=/home/buecker/bin/nomad args=[/home/buecker/bin/nomad, docker_logger]
    2020-10-26T16:44:34.821+0100 [DEBUG] client.driver_mgr.docker.docker_logger: plugin started: driver=docker path=/home/buecker/bin/nomad pid=9120
    2020-10-26T16:44:34.821+0100 [DEBUG] client.driver_mgr.docker.docker_logger: waiting for RPC address: driver=docker path=/home/buecker/bin/nomad
    2020-10-26T16:44:34.825+0100 [DEBUG] client.driver_mgr.docker.docker_logger.nomad: plugin address: driver=docker @module=docker_logger address=/tmp/plugin226058614 network=unix timestamp=2020-10-26T16:44:34.825+0100
    2020-10-26T16:44:34.825+0100 [DEBUG] client.driver_mgr.docker.docker_logger: using plugin: driver=docker version=2
    2020-10-26T16:44:34.826+0100 [DEBUG] client.driver_mgr.docker.docker_logger.nomad: using client connection initialized from environment: driver=docker @module=docker_logger timestamp=2020-10-26T16:44:34.826+0100
    2020-10-26T16:44:34.928+0100 [DEBUG] client: updated allocations: index=22 total=1 pulled=0 filtered=1
    2020-10-26T16:44:34.928+0100 [DEBUG] client: allocation updates: added=0 removed=0 updated=0 ignored=1
    2020-10-26T16:44:34.929+0100 [DEBUG] client: allocation updates applied: added=0 removed=0 updated=0 ignored=1 errors=0
    2020-10-26T16:44:35.096+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=client duration=279.228µs
    2020-10-26T16:44:36.900+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=server duration=264.486µs
    2020-10-26T16:44:37.882+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48188
    2020-10-26T16:44:45.097+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=client duration=155.968µs
    2020-10-26T16:44:46.901+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=server duration=412.205µs
    2020-10-26T16:44:47.882+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48198
    2020-10-26T16:44:55.098+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=client duration=159.206µs
    2020-10-26T16:44:56.902+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=server duration=120.171µs
    2020-10-26T16:44:57.883+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48212
    2020-10-26T16:45:05.099+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=client duration=207.923µs
    2020-10-26T16:45:06.903+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=server duration=154.102µs
    2020-10-26T16:45:07.883+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48222
    2020-10-26T16:45:08.830+0100 [DEBUG] http: request complete: method=GET path=/v1/allocations?prefix=2812a91f duration=228.829µs
    2020-10-26T16:45:08.831+0100 [DEBUG] http: request complete: method=GET path=/v1/allocation/2812a91f-c63f-b564-69f3-71a6d2dec8dc?namespace=default duration=221.863µs
    2020-10-26T16:45:08.833+0100 [DEBUG] http: request complete: method=GET path=/v1/client/allocation/2812a91f-c63f-b564-69f3-71a6d2dec8dc/stats duration=130.252µs
    2020-10-26T16:45:15.101+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=client duration=382.516µs
    2020-10-26T16:45:16.904+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=server duration=136.724µs
    2020-10-26T16:45:17.884+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48238
    2020-10-26T16:45:25.102+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=client duration=95.353µs
    2020-10-26T16:45:26.905+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=server duration=187.473µs
    2020-10-26T16:45:27.884+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48248
    2020-10-26T16:45:35.103+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=client duration=281.566µs
    2020-10-26T16:45:36.906+0100 [DEBUG] http: request complete: method=GET path=/v1/agent/health?type=server duration=233.885µs
    2020-10-26T16:45:37.885+0100 [DEBUG] nomad: memberlist: Stream connection from=127.0.0.1:48258

Consul Server logs

==> Starting Consul agent...
           Version: '1.8.4'
           Node ID: '1d5f1bcf-e694-012e-7015-85162c6278dc'
         Node name: 'PC3776'
        Datacenter: 'dc1' (Segment: '<all>')
            Server: true (Bootstrap: false)
       Client Addr: [127.0.0.1] (HTTP: 8500, HTTPS: -1, gRPC: 8502, DNS: 8600)
      Cluster Addr: 127.0.0.1 (LAN: 8301, WAN: 8302)
           Encrypt: Gossip: false, TLS-Outgoing: false, TLS-Incoming: false, Auto-Encrypt-TLS: false

==> Log data will now stream in as it occurs:

    2020-10-26T16:42:48.600+0100 [INFO]  agent.server.raft: initial configuration: index=1 servers="[{Suffrage:Voter ID:1d5f1bcf-e694-012e-7015-85162c6278dc Address:127.0.0.1:8300}]"
    2020-10-26T16:42:48.600+0100 [INFO]  agent.server.raft: entering follower state: follower="Node at 127.0.0.1:8300 [Follower]" leader=
    2020-10-26T16:42:48.601+0100 [INFO]  agent.server.serf.wan: serf: EventMemberJoin: PC3776.dc1 127.0.0.1
    2020-10-26T16:42:48.601+0100 [INFO]  agent.server.serf.lan: serf: EventMemberJoin: PC3776 127.0.0.1
    2020-10-26T16:42:48.601+0100 [INFO]  agent.router: Initializing LAN area manager
    2020-10-26T16:42:48.601+0100 [INFO]  agent.server: Handled event for server in area: event=member-join server=PC3776.dc1 area=wan
    2020-10-26T16:42:48.601+0100 [INFO]  agent: Started DNS server: address=127.0.0.1:8600 network=udp
    2020-10-26T16:42:48.601+0100 [INFO]  agent.server: Adding LAN server: server="PC3776 (Addr: tcp/127.0.0.1:8300) (DC: dc1)"
    2020-10-26T16:42:48.601+0100 [INFO]  agent: Started DNS server: address=127.0.0.1:8600 network=tcp
    2020-10-26T16:42:48.601+0100 [INFO]  agent: Started HTTP server: address=127.0.0.1:8500 network=tcp
    2020-10-26T16:42:48.601+0100 [INFO]  agent: Started gRPC server: address=127.0.0.1:8502 network=tcp
    2020-10-26T16:42:48.601+0100 [INFO]  agent: started state syncer
==> Consul agent running!
    2020-10-26T16:42:48.655+0100 [WARN]  agent.server.raft: heartbeat timeout reached, starting election: last-leader=
    2020-10-26T16:42:48.655+0100 [INFO]  agent.server.raft: entering candidate state: node="Node at 127.0.0.1:8300 [Candidate]" term=2
    2020-10-26T16:42:48.655+0100 [DEBUG] agent.server.raft: votes: needed=1
    2020-10-26T16:42:48.655+0100 [DEBUG] agent.server.raft: vote granted: from=1d5f1bcf-e694-012e-7015-85162c6278dc term=2 tally=1
    2020-10-26T16:42:48.655+0100 [INFO]  agent.server.raft: election won: tally=1
    2020-10-26T16:42:48.655+0100 [INFO]  agent.server.raft: entering leader state: leader="Node at 127.0.0.1:8300 [Leader]"
    2020-10-26T16:42:48.656+0100 [INFO]  agent.server: cluster leadership acquired
    2020-10-26T16:42:48.656+0100 [DEBUG] agent.server: Cannot upgrade to new ACLs: leaderMode=0 mode=0 found=true leader=127.0.0.1:8300
    2020-10-26T16:42:48.656+0100 [INFO]  agent.server: New leader elected: payload=PC3776
    2020-10-26T16:42:48.656+0100 [DEBUG] connect.ca.consul: consul CA provider configured: id=07:80:c8:de:f6:41:86:29:8f:9c:b8:17:d6:48:c2:d5:c5:5c:7f:0c:03:f7:cf:97:5a:a7:c1:68:aa:23:ae:81 is_primary=true
    2020-10-26T16:42:48.663+0100 [INFO]  agent.server.connect: initialized primary datacenter CA with provider: provider=consul
    2020-10-26T16:42:48.663+0100 [INFO]  agent.leader: started routine: routine="federation state anti-entropy"
    2020-10-26T16:42:48.663+0100 [INFO]  agent.leader: started routine: routine="federation state pruning"
    2020-10-26T16:42:48.663+0100 [INFO]  agent.leader: started routine: routine="CA root pruning"
    2020-10-26T16:42:48.663+0100 [DEBUG] agent.server: Skipping self join check for node since the cluster is too small: node=PC3776
    2020-10-26T16:42:48.663+0100 [INFO]  agent.server: member joined, marking health alive: member=PC3776
    2020-10-26T16:42:48.664+0100 [INFO]  agent.server: federation state anti-entropy synced
    2020-10-26T16:42:48.880+0100 [DEBUG] agent: Skipping remote check since it is managed automatically: check=serfHealth
    2020-10-26T16:42:48.880+0100 [INFO]  agent: Synced node info
    2020-10-26T16:42:49.435+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43534 latency=329.647µs
    2020-10-26T16:42:49.435+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43534 latency=155.465µs
    2020-10-26T16:42:49.436+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:49.436+0100 [INFO]  agent: Synced service: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:49.436+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/register from=127.0.0.1:43534 latency=459.463µs
    2020-10-26T16:42:49.436+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:49.436+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:49.436+0100 [INFO]  agent: Synced service: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:49.436+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/register from=127.0.0.1:43534 latency=234.36µs
    2020-10-26T16:42:49.437+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:49.437+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:49.437+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:49.437+0100 [INFO]  agent: Synced service: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:49.437+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/register from=127.0.0.1:43534 latency=241.386µs
    2020-10-26T16:42:49.437+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:49.437+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:49.437+0100 [INFO]  agent: Synced service: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:42:49.437+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:49.437+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:49.437+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/register from=127.0.0.1:43534 latency=266.349µs
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:49.438+0100 [INFO]  agent: Synced check: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:49.438+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/register from=127.0.0.1:43534 latency=285.33µs
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:49.438+0100 [INFO]  agent: Synced check: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:49.438+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/register from=127.0.0.1:43534 latency=242.381µs
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:49.438+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:49.438+0100 [INFO]  agent: Synced check: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:42:49.438+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/register from=127.0.0.1:43534 latency=254.028µs
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:42:49.439+0100 [INFO]  agent: Synced check: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:42:49.439+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/register from=127.0.0.1:43534 latency=217.976µs
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: removed check: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: removed service: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:49.439+0100 [INFO]  agent: Deregistered service: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:42:49.439+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/deregister/_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r from=127.0.0.1:43534 latency=285.944µs
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: removed check: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: removed service: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:49.439+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:49.440+0100 [INFO]  agent: Deregistered service: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:42:49.440+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/deregister/_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r from=127.0.0.1:43534 latency=206.973µs
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: removed check: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: removed service: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:42:49.440+0100 [INFO]  agent: Deregistered service: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:42:49.440+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/deregister/_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp from=127.0.0.1:43534 latency=190.139µs
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: removed check: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: removed service: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:49.440+0100 [INFO]  agent: Deregistered service: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:42:49.440+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/deregister/_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp from=127.0.0.1:43534 latency=165.37µs
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: removed check: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:49.440+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/deregister/_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad from=127.0.0.1:43534 latency=27.18µs
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: removed check: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:49.440+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/deregister/_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 from=127.0.0.1:43534 latency=23.774µs
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: removed check: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:49.440+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:49.440+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/deregister/_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 from=127.0.0.1:43534 latency=21.785µs
    2020-10-26T16:42:49.441+0100 [DEBUG] agent: removed check: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:42:49.441+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:49.441+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/deregister/_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a from=127.0.0.1:43534 latency=22.316µs
    2020-10-26T16:42:50.132+0100 [DEBUG] agent: Skipping remote check since it is managed automatically: check=serfHealth
    2020-10-26T16:42:50.132+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:50.132+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:52.729+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/self from=127.0.0.1:43538 latency=1.222396ms
    2020-10-26T16:42:52.731+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=83.718µs
    2020-10-26T16:42:52.732+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=58.006µs
    2020-10-26T16:42:52.732+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:52.733+0100 [INFO]  agent: Synced service: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:52.733+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/register from=127.0.0.1:43538 latency=357.897µs
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:52.733+0100 [INFO]  agent: Synced service: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:52.733+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/register from=127.0.0.1:43538 latency=195.692µs
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:52.733+0100 [INFO]  agent: Synced service: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:52.733+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/register from=127.0.0.1:43538 latency=186.984µs
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:52.733+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:52.734+0100 [INFO]  agent: Synced check: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:52.734+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/register from=127.0.0.1:43538 latency=236.983µs
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:52.734+0100 [INFO]  agent: Synced check: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:52.734+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/register from=127.0.0.1:43538 latency=271.971µs
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:52.734+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:52.735+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:52.735+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:52.735+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:52.735+0100 [INFO]  agent: Synced check: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:42:52.735+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/register from=127.0.0.1:43538 latency=396.722µs
    2020-10-26T16:42:52.735+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:52.735+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:52.735+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:52.735+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:52.735+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:52.735+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:52.735+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:42:52.738+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/self from=127.0.0.1:43540 latency=587.104µs
    2020-10-26T16:42:54.500+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:42:54.501+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:54.501+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:54.501+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:54.501+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:54.501+0100 [INFO]  agent: Synced check: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:54.501+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:54.501+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:42:56.890+0100 [WARN]  agent: Check is now critical: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:42:57.878+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:42:57.878+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:57.878+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:57.878+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:57.878+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:57.878+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:57.878+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=195.616µs
    2020-10-26T16:42:57.879+0100 [INFO]  agent: Synced check: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:57.879+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:42:57.879+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=187.963µs
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:57.881+0100 [INFO]  agent: Synced service: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:42:57.881+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/register from=127.0.0.1:43538 latency=311.159µs
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:42:57.881+0100 [INFO]  agent: Synced check: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:42:57.881+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/register from=127.0.0.1:43538 latency=303.09µs
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:42:57.881+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:43:04.501+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:43:05.086+0100 [DEBUG] agent: Check status updated: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad status=passing
    2020-10-26T16:43:05.086+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:43:05.086+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:43:05.086+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:43:05.086+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:43:05.086+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:43:05.086+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:43:05.086+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:43:05.086+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:43:05.086+0100 [INFO]  agent: Synced check: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:43:06.891+0100 [DEBUG] agent: Check status updated: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a status=passing
    2020-10-26T16:43:06.891+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:43:06.891+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:43:06.891+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:43:06.891+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:43:06.891+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:43:06.891+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:43:06.891+0100 [INFO]  agent: Synced check: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:43:06.891+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:43:06.891+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:43:07.740+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/self from=127.0.0.1:43540 latency=623.075µs
    2020-10-26T16:43:07.879+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:43:14.501+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:43:15.087+0100 [DEBUG] agent: Check status updated: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad status=passing
    2020-10-26T16:43:16.892+0100 [DEBUG] agent: Check status updated: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a status=passing
    2020-10-26T16:43:17.879+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:43:22.741+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/self from=127.0.0.1:43540 latency=678.617µs
    2020-10-26T16:43:24.502+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:43:25.088+0100 [DEBUG] agent: Check status updated: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad status=passing
    2020-10-26T16:43:26.893+0100 [DEBUG] agent: Check status updated: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a status=passing
    2020-10-26T16:43:27.879+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:43:27.882+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=155.882µs
    2020-10-26T16:43:27.882+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=101.831µs
    2020-10-26T16:43:30.635+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=282.952µs
    2020-10-26T16:43:30.636+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=163.896µs
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:43:30.636+0100 [INFO]  agent: Synced service: service=_nomad-task-2812a91f-c63f-b564-69f3-71a6d2dec8dc-group-api-fail-service-nomad-http
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:43:30.636+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/register from=127.0.0.1:43538 latency=331.319µs
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Service in sync: service=_nomad-task-2812a91f-c63f-b564-69f3-71a6d2dec8dc-group-api-fail-service-nomad-http
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Service in sync: service=_nomad-task-2812a91f-c63f-b564-69f3-71a6d2dec8dc-group-api-fail-service-nomad-http
    2020-10-26T16:43:30.636+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Service in sync: service=_nomad-task-2812a91f-c63f-b564-69f3-71a6d2dec8dc-group-api-fail-service-nomad-http
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:43:30.637+0100 [INFO]  agent: Synced check: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:43:30.637+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/register from=127.0.0.1:43538 latency=479.916µs
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Service in sync: service=_nomad-task-2812a91f-c63f-b564-69f3-71a6d2dec8dc-group-api-fail-service-nomad-http
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Check in sync: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:43:30.637+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:43:30.762+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=307.556µs
    2020-10-26T16:43:30.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=216.336µs
    2020-10-26T16:43:31.262+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=152.225µs
    2020-10-26T16:43:31.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=281.027µs
    2020-10-26T16:43:31.535+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=404.478µs
    2020-10-26T16:43:31.762+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=178.958µs
    2020-10-26T16:43:31.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=114.519µs
    2020-10-26T16:43:32.262+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=384.625µs
    2020-10-26T16:43:32.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=207.688µs
    2020-10-26T16:43:32.435+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=203.276µs
    2020-10-26T16:43:32.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=246.108µs
    2020-10-26T16:43:32.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=161.793µs
    2020-10-26T16:43:33.262+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=207.128µs
    2020-10-26T16:43:33.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=171.354µs
    2020-10-26T16:43:33.336+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=255.088µs
    2020-10-26T16:43:33.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=162.131µs
    2020-10-26T16:43:33.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=178.896µs
    2020-10-26T16:43:34.236+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=218.553µs
    2020-10-26T16:43:34.262+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=182.348µs
    2020-10-26T16:43:34.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=137.717µs
    2020-10-26T16:43:34.502+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:43:34.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=230.35µs
    2020-10-26T16:43:34.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=149.936µs
    2020-10-26T16:43:35.090+0100 [DEBUG] agent: Check status updated: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad status=passing
    2020-10-26T16:43:35.136+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=204.822µs
    2020-10-26T16:43:35.262+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=135.413µs
    2020-10-26T16:43:35.264+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=162.426µs
    2020-10-26T16:43:35.762+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=211.745µs
    2020-10-26T16:43:35.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=161.656µs
    2020-10-26T16:43:36.037+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=190.781µs
    2020-10-26T16:43:36.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=183.27µs
    2020-10-26T16:43:36.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=105.767µs
    2020-10-26T16:43:36.762+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=225.029µs
    2020-10-26T16:43:36.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=207.558µs
    2020-10-26T16:43:36.894+0100 [DEBUG] agent: Check status updated: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a status=passing
    2020-10-26T16:43:36.896+0100 [DEBUG] agent: Check status updated: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c status=passing
    2020-10-26T16:43:36.896+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:43:36.896+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:43:36.896+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:43:36.896+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:43:36.896+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:43:36.896+0100 [DEBUG] agent: Service in sync: service=_nomad-task-2812a91f-c63f-b564-69f3-71a6d2dec8dc-group-api-fail-service-nomad-http
    2020-10-26T16:43:36.896+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:43:36.896+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:43:36.896+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:43:36.896+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:43:36.896+0100 [INFO]  agent: Synced check: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:43:36.937+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=227.91µs
    2020-10-26T16:43:37.262+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=129.054µs
    2020-10-26T16:43:37.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=156.568µs
    2020-10-26T16:43:37.741+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/self from=127.0.0.1:43540 latency=666.935µs
    2020-10-26T16:43:37.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=299.444µs
    2020-10-26T16:43:37.764+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=280.48µs
    2020-10-26T16:43:37.837+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=210.777µs
    2020-10-26T16:43:37.879+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:43:38.262+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=178.828µs
    2020-10-26T16:43:38.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=190.694µs
    2020-10-26T16:43:38.737+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=188.746µs
    2020-10-26T16:43:38.762+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=158.914µs
    2020-10-26T16:43:38.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=158.88µs
    2020-10-26T16:43:39.262+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=163.678µs
    2020-10-26T16:43:39.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=164.328µs
    2020-10-26T16:43:39.638+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=176.909µs
    2020-10-26T16:43:39.762+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=173.616µs
    2020-10-26T16:43:39.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=172.683µs
    2020-10-26T16:43:40.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=160.266µs
    2020-10-26T16:43:40.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=173.098µs
    2020-10-26T16:43:40.538+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=218.069µs
    2020-10-26T16:43:40.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=205.163µs
    2020-10-26T16:43:40.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=175.857µs
    2020-10-26T16:43:41.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=253.122µs
    2020-10-26T16:43:41.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=174.632µs
    2020-10-26T16:43:41.438+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=218.96µs
    2020-10-26T16:43:41.762+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=174.298µs
    2020-10-26T16:43:41.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=163.318µs
    2020-10-26T16:43:42.262+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=161.38µs
    2020-10-26T16:43:42.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=161.039µs
    2020-10-26T16:43:42.338+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=192.135µs
    2020-10-26T16:43:42.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=233.982µs
    2020-10-26T16:43:42.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=159.267µs
    2020-10-26T16:43:43.239+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=175.954µs
    2020-10-26T16:43:43.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=237.342µs
    2020-10-26T16:43:43.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=119.52µs
    2020-10-26T16:43:43.762+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=207.852µs
    2020-10-26T16:43:43.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=162.764µs
    2020-10-26T16:43:44.139+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=193.754µs
    2020-10-26T16:43:44.262+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=135.072µs
    2020-10-26T16:43:44.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=114.648µs
    2020-10-26T16:43:44.503+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:43:44.762+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=240.61µs
    2020-10-26T16:43:44.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=158.832µs
    2020-10-26T16:43:45.039+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=221.833µs
    2020-10-26T16:43:45.091+0100 [DEBUG] agent: Check status updated: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad status=passing
    2020-10-26T16:43:45.262+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=221.602µs
    2020-10-26T16:43:45.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=145.227µs
    2020-10-26T16:43:45.762+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=185.222µs
    2020-10-26T16:43:45.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=185.741µs
    2020-10-26T16:43:45.940+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=197.053µs
    2020-10-26T16:43:46.262+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=189.726µs
    2020-10-26T16:43:46.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=185.109µs
    2020-10-26T16:43:46.762+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=282.251µs
    2020-10-26T16:43:46.763+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=198.449µs
    2020-10-26T16:43:46.840+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=163.182µs
    2020-10-26T16:43:46.895+0100 [DEBUG] agent: Check status updated: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a status=passing
    2020-10-26T16:43:46.897+0100 [DEBUG] agent: Check status updated: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c status=passing
    2020-10-26T16:43:47.262+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=139.443µs
    2020-10-26T16:43:47.263+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=142.138µs
    2020-10-26T16:43:47.740+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=169.722µs
    2020-10-26T16:43:47.880+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:43:48.641+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=235.213µs
    2020-10-26T16:43:48.656+0100 [DEBUG] agent.server: Skipping self join check for node since the cluster is too small: node=PC3776
    2020-10-26T16:43:49.541+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=145.443µs
    2020-10-26T16:43:50.441+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=230.499µs
    2020-10-26T16:43:51.341+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=193.802µs
    2020-10-26T16:43:52.241+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=179.702µs
    2020-10-26T16:43:52.741+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/self from=127.0.0.1:43540 latency=637.58µs
    2020-10-26T16:43:53.142+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=631.332µs
    2020-10-26T16:43:54.042+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=242.232µs
    2020-10-26T16:43:54.504+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:43:54.942+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=179.153µs
    2020-10-26T16:43:55.092+0100 [DEBUG] agent: Check status updated: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad status=passing
    2020-10-26T16:43:55.842+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=128.197µs
    2020-10-26T16:43:56.000+0100 [DEBUG] agent: Skipping remote check since it is managed automatically: check=serfHealth
    2020-10-26T16:43:56.000+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:43:56.000+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:43:56.000+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:43:56.000+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:43:56.000+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:43:56.000+0100 [DEBUG] agent: Service in sync: service=_nomad-task-2812a91f-c63f-b564-69f3-71a6d2dec8dc-group-api-fail-service-nomad-http
    2020-10-26T16:43:56.000+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:43:56.000+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:43:56.000+0100 [DEBUG] agent: Check in sync: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:43:56.000+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:43:56.000+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:43:56.742+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=176.85µs
    2020-10-26T16:43:56.896+0100 [DEBUG] agent: Check status updated: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a status=passing
    2020-10-26T16:43:56.897+0100 [WARN]  agent: Check is now critical: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:43:56.897+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:43:56.897+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:43:56.897+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:43:56.897+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:43:56.897+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:43:56.897+0100 [DEBUG] agent: Service in sync: service=_nomad-task-2812a91f-c63f-b564-69f3-71a6d2dec8dc-group-api-fail-service-nomad-http
    2020-10-26T16:43:56.897+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:43:56.897+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:43:56.898+0100 [INFO]  agent: Synced check: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:43:56.898+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:43:56.898+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:43:57.643+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=229.148µs
    2020-10-26T16:43:57.880+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:43:58.543+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=220.265µs
    2020-10-26T16:43:59.443+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=190.625µs
    2020-10-26T16:44:00.343+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=211.65µs
    2020-10-26T16:44:00.638+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=168.04µs
    2020-10-26T16:44:00.639+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=187.136µs
    2020-10-26T16:44:01.244+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=186.738µs
    2020-10-26T16:44:02.144+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=231.371µs
    2020-10-26T16:44:03.044+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=164.93µs
    2020-10-26T16:44:03.944+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=201.431µs
    2020-10-26T16:44:04.504+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:44:04.844+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=206.601µs
    2020-10-26T16:44:05.093+0100 [DEBUG] agent: Check status updated: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad status=passing
    2020-10-26T16:44:05.745+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=193.416µs
    2020-10-26T16:44:06.645+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=219.832µs
    2020-10-26T16:44:06.897+0100 [DEBUG] agent: Check status updated: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a status=passing
    2020-10-26T16:44:06.898+0100 [WARN]  agent: Check is now critical: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:44:07.545+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=182.351µs
    2020-10-26T16:44:07.741+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/self from=127.0.0.1:43540 latency=691.036µs
    2020-10-26T16:44:07.881+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:44:08.445+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=199.427µs
    2020-10-26T16:44:09.345+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=177.474µs
    2020-10-26T16:44:10.245+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=262.929µs
    2020-10-26T16:44:11.145+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=211.601µs
    2020-10-26T16:44:12.046+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=241.822µs
    2020-10-26T16:44:12.946+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=160.112µs
    2020-10-26T16:44:13.846+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=175.818µs
    2020-10-26T16:44:14.505+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:44:14.746+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=171.634µs
    2020-10-26T16:44:15.094+0100 [DEBUG] agent: Check status updated: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad status=passing
    2020-10-26T16:44:15.646+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=194.241µs
    2020-10-26T16:44:16.546+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=220.854µs
    2020-10-26T16:44:16.898+0100 [DEBUG] agent: Check status updated: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a status=passing
    2020-10-26T16:44:16.898+0100 [WARN]  agent: Check is now critical: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:44:17.447+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=172.217µs
    2020-10-26T16:44:17.881+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:44:18.347+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=182.227µs
    2020-10-26T16:44:19.247+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=209.007µs
    2020-10-26T16:44:20.148+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=171.423µs
    2020-10-26T16:44:21.048+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=208.085µs
    2020-10-26T16:44:21.948+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=190.361µs
    2020-10-26T16:44:22.742+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/self from=127.0.0.1:43540 latency=749.256µs
    2020-10-26T16:44:22.848+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=222.527µs
    2020-10-26T16:44:23.749+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=183.415µs
    2020-10-26T16:44:24.505+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:44:24.649+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=185.305µs
    2020-10-26T16:44:25.095+0100 [DEBUG] agent: Check status updated: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad status=passing
    2020-10-26T16:44:25.549+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=196.052µs
    2020-10-26T16:44:26.450+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=158.408µs
    2020-10-26T16:44:26.899+0100 [WARN]  agent: Check is now critical: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:44:26.899+0100 [DEBUG] agent: Check status updated: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a status=passing
    2020-10-26T16:44:27.350+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=140.991µs
    2020-10-26T16:44:27.881+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:44:28.250+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=174.945µs
    2020-10-26T16:44:30.640+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=191.216µs
    2020-10-26T16:44:30.641+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=240.153µs
    2020-10-26T16:44:34.505+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:44:35.096+0100 [DEBUG] agent: Check status updated: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad status=passing
    2020-10-26T16:44:36.900+0100 [DEBUG] agent: Check status updated: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c status=passing
    2020-10-26T16:44:36.900+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:44:36.900+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:44:36.900+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:44:36.900+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:44:36.900+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:44:36.900+0100 [DEBUG] agent: Service in sync: service=_nomad-task-2812a91f-c63f-b564-69f3-71a6d2dec8dc-group-api-fail-service-nomad-http
    2020-10-26T16:44:36.900+0100 [DEBUG] agent: Check status updated: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a status=passing
    2020-10-26T16:44:36.901+0100 [INFO]  agent: Synced check: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:44:36.901+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:44:36.901+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:44:36.901+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:44:36.901+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:44:37.742+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/self from=127.0.0.1:43540 latency=689.24µs
    2020-10-26T16:44:37.882+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:44:44.506+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:44:45.097+0100 [DEBUG] agent: Check status updated: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad status=passing
    2020-10-26T16:44:46.901+0100 [DEBUG] agent: Check status updated: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c status=passing
    2020-10-26T16:44:46.902+0100 [DEBUG] agent: Check status updated: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a status=passing
    2020-10-26T16:44:47.882+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:44:48.601+0100 [DEBUG] agent.router.manager: Rebalanced servers, new active server: number_of_servers=1 active_server="PC3776.dc1 (Addr: tcp/127.0.0.1:8300) (DC: dc1)"
    2020-10-26T16:44:48.601+0100 [DEBUG] agent.router.manager: Rebalanced servers, new active server: number_of_servers=1 active_server="PC3776 (Addr: tcp/127.0.0.1:8300) (DC: dc1)"
    2020-10-26T16:44:48.656+0100 [DEBUG] agent.server: Skipping self join check for node since the cluster is too small: node=PC3776
    2020-10-26T16:44:52.742+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/self from=127.0.0.1:43540 latency=670.057µs
    2020-10-26T16:44:54.506+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:44:55.099+0100 [DEBUG] agent: Check status updated: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad status=passing
    2020-10-26T16:44:56.902+0100 [WARN]  agent: Check is now critical: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:44:56.902+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:44:56.902+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:44:56.902+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:44:56.902+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:44:56.902+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:44:56.902+0100 [DEBUG] agent: Service in sync: service=_nomad-task-2812a91f-c63f-b564-69f3-71a6d2dec8dc-group-api-fail-service-nomad-http
    2020-10-26T16:44:56.902+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:44:56.902+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:44:56.902+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:44:56.902+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:44:56.902+0100 [INFO]  agent: Synced check: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:44:56.902+0100 [DEBUG] agent: Check status updated: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a status=passing
    2020-10-26T16:44:57.883+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:45:00.642+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=196.437µs
    2020-10-26T16:45:00.643+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=175.073µs
    2020-10-26T16:45:04.507+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:45:05.100+0100 [DEBUG] agent: Check status updated: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad status=passing
    2020-10-26T16:45:06.903+0100 [WARN]  agent: Check is now critical: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:45:06.903+0100 [DEBUG] agent: Check status updated: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a status=passing
    2020-10-26T16:45:07.742+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/self from=127.0.0.1:43540 latency=585.106µs
    2020-10-26T16:45:07.883+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:45:14.507+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:45:15.101+0100 [DEBUG] agent: Check status updated: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad status=passing
    2020-10-26T16:45:15.596+0100 [DEBUG] agent: Skipping remote check since it is managed automatically: check=serfHealth
    2020-10-26T16:45:15.596+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:15.596+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:45:15.596+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:45:15.596+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:45:15.596+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:45:15.596+0100 [DEBUG] agent: Service in sync: service=_nomad-task-2812a91f-c63f-b564-69f3-71a6d2dec8dc-group-api-fail-service-nomad-http
    2020-10-26T16:45:15.596+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:45:15.596+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:45:15.596+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:45:15.596+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:45:15.596+0100 [DEBUG] agent: Check in sync: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:45:16.904+0100 [WARN]  agent: Check is now critical: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:45:16.904+0100 [DEBUG] agent: Check status updated: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a status=passing
    2020-10-26T16:45:17.884+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:45:22.742+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/self from=127.0.0.1:43540 latency=556.195µs
    2020-10-26T16:45:24.508+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:45:25.102+0100 [DEBUG] agent: Check status updated: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad status=passing
    2020-10-26T16:45:26.905+0100 [WARN]  agent: Check is now critical: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:45:26.905+0100 [DEBUG] agent: Check status updated: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a status=passing
    2020-10-26T16:45:27.884+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:45:30.644+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=196.261µs
    2020-10-26T16:45:30.645+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=210.576µs
    2020-10-26T16:45:34.508+0100 [DEBUG] agent: Check status updated: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 status=passing
    2020-10-26T16:45:35.103+0100 [DEBUG] agent: Check status updated: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad status=passing
    2020-10-26T16:45:36.906+0100 [WARN]  agent: Check is now critical: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:45:36.906+0100 [DEBUG] agent: Check status updated: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a status=passing
    2020-10-26T16:45:37.742+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/self from=127.0.0.1:43540 latency=657.927µs
    2020-10-26T16:45:37.885+0100 [DEBUG] agent: Check status updated: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651 status=passing
    2020-10-26T16:45:40.331+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=178.311µs
    2020-10-26T16:45:40.331+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=149.396µs
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: removed check: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: removed service: service=_nomad-task-2812a91f-c63f-b564-69f3-71a6d2dec8dc-group-api-fail-service-nomad-http
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.332+0100 [INFO]  agent: Deregistered service: service=_nomad-task-2812a91f-c63f-b564-69f3-71a6d2dec8dc-group-api-fail-service-nomad-http
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:45:40.332+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/deregister/_nomad-task-2812a91f-c63f-b564-69f3-71a6d2dec8dc-group-api-fail-service-nomad-http from=127.0.0.1:43538 latency=624.335µs
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: removed check: check=_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Service in sync: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:45:40.332+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:45:40.333+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:45:40.333+0100 [DEBUG] agent: Check in sync: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:45:40.333+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:45:40.333+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:45:40.333+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:45:40.333+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/deregister/_nomad-check-3a74e65a51da9ad84a79a62069da9097f0088e0c from=127.0.0.1:43538 latency=71.071µs
    2020-10-26T16:45:40.333+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=111.168µs
    2020-10-26T16:45:40.333+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=82.152µs
    2020-10-26T16:45:40.852+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/services from=127.0.0.1:43538 latency=160.997µs
    2020-10-26T16:45:40.853+0100 [DEBUG] agent.http: Request finished: method=GET url=/v1/agent/checks from=127.0.0.1:43538 latency=178.747µs
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: removed check: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: removed service: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:45:40.854+0100 [INFO]  agent: Deregistered service: service=_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Service in sync: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Check in sync: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:45:40.854+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/deregister/_nomad-server-el4cbcvnl5vhjq6cvok5weiasityir4r from=127.0.0.1:43538 latency=405.869µs
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.854+0100 [INFO]  agent: Deregistered service: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: removed check: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: removed service: service=_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Service in sync: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Check in sync: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:45:40.854+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:45:40.854+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/deregister/_nomad-server-5fpzvxurt4wharun5dh5alxqmbukh32r from=127.0.0.1:43538 latency=294.325µs
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: removed check: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: removed service: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.855+0100 [INFO]  agent: Deregistered service: service=_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: Service in sync: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: Check in sync: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:45:40.855+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/deregister/_nomad-server-k5zjlaqx5qv3sz7oksmrxafcd6wv45fp from=127.0.0.1:43538 latency=276.71µs
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: removed check: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: removed service: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.855+0100 [INFO]  agent: Deregistered service: service=_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp
    2020-10-26T16:45:40.855+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/service/deregister/_nomad-client-s6pxrvnmilluqd6pbwhivrfgoxn6y2bp from=127.0.0.1:43538 latency=188.833µs
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: removed check: check=_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.855+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/deregister/_nomad-check-feb7984a79b74b402ae6164c434be57e665acf5a from=127.0.0.1:43538 latency=66.999µs
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: removed check: check=_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad
    2020-10-26T16:45:40.855+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.855+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/deregister/_nomad-check-531739bb774721d47d2fdf2a5581f5e04a004dad from=127.0.0.1:43538 latency=27.419µs
    2020-10-26T16:45:40.856+0100 [DEBUG] agent: removed check: check=_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502
    2020-10-26T16:45:40.856+0100 [DEBUG] agent: Node info in sync
    2020-10-26T16:45:40.856+0100 [DEBUG] agent.http: Request finished: method=PUT url=/v1/agent/check/deregister/_nomad-check-cf2031031358830ba6bef4717afa7ec7e6b8b502 from=127.0.0.1:43538 latency=24.652µs
    2020-10-26T16:45:40.856+0100 [DEBUG] agent: removed check: check=_nomad-check-36a6d5207f8f687c20b1d3e994b77bd797b27651
    2020-10-26T16:45:40.856+0100 [DEBUG] agent: Node info in sync
@tgross
Copy link
Member

tgross commented Oct 26, 2020

Hi @naag! I was able to confirm this same behavior using that job file. I also was able to reproduce when moving the check_restart block directly under the service stanza. I can also confirm that Nomad's "check watcher" isn't getting updated after the restart, just as you suspected; we should be getting an update on this channel but aren't: check_watcher.go#L206

I'll mark this as a bug and I'll start digging in a bit more.

(Also, I've slightly edited your post to wrap the logs in a <details> block just to make this issue a bit easier to navigate, but thanks for providing them.)

@tgross tgross self-assigned this Oct 26, 2020
@tgross tgross added the stage/accepted Confirmed, and intend to work on. No timeline committment though. label Oct 26, 2020
@naag
Copy link
Author

naag commented Oct 26, 2020

Thanks @tgross! Please let me know if there's anything else I can do to assist.

@tgross
Copy link
Member

tgross commented Oct 27, 2020

In order to narrow down the behavior, I moved the service block under the task (see below) and this works on the current master (and also as far back as 0.10.7, because I tested a few versions back).

But now that's making me realize that the behavior of check_restart is ambiguous when at the group level; if the group has more than one task, which one is supposed to be restarted? If we look at the check_restart docs, we can see the "placement":

job -> group -> task -> service -> check_restart
job -> group -> task -> service -> check -> check_restart

Which suggests it's not intended to be used at the group level at all, and that you should be getting an error when the job is validated. (And restarting once is also a weird behavior, for sure.)


job "fail-service" {
  datacenters = ["dc1"]

  reschedule {
    delay          = "15s"
    delay_function = "constant"
    unlimited      = true
  }


  group "cache" {

    restart {
      attempts = 3
      interval = "30m"
      delay    = "5s"
      mode     = "fail"
    }

    task "main" {
      driver = "docker"

      config {
        image = "thobe/fail_service:v0.1.0"
        port_map {
          http = 8080
        }
      }

      env {
        HEALTHY_FOR   = 20
        UNHEALTHY_FOR = 120
      }


      service {
        name = "fail-service"
        port = "http"

        check_restart {
          limit           = 4
          grace           = "10s"
          ignore_warnings = false
        }

        check {
          type     = "http"
          port     = "http"
          path     = "/health"
          interval = "10s"
          timeout  = "2s"
        }
      }

      resources {
        cpu    = 500
        memory = 256

        network {
          mbits = 10
          port "http" {}
        }
      }
    }
  }
}

@tgross
Copy link
Member

tgross commented Oct 27, 2020

if the group has more than one task, which one is supposed to be restarted?

Oh, it appears someone smarter than me has thought of that already. We'll want a task field on the service as follows. Unfortunately, that doesn't fix the bug either.

service {
  name = "fail-service"
  port = "http"
  task = "main"
}

@tgross
Copy link
Member

tgross commented Oct 27, 2020

Ok, I've figured out why the behavior is different in the two cases. The problem isn't the check_watcher.go code at all, but in the client hooks; this is code that runs on the client when triggered by events in the lifecycle of an allocation or a task within an allocation. You can think of the allocation hooks as being the "outer" hooks while the task hooks run "inside" that timeline. Or in other words, if you had a simple job that started and ran to completion the order of the hooks would be something like:

  • alloc.PreRun
  • task.PreStart
  • task.PostStart
  • task.PreKilling
  • alloc.PreKill
  • task.Exited
  • alloc.PostRun
  • alloc.Destroy

Not every hook implements every event. In this case, we have the allocation's groupservice_hook and the task's service_hook.

In a job with a service at the task level, when the check fails and the task is restarted, we get hook events as follows:

  • task.PreKilling: calls RemoveWorkload to deregister the check
  • task.Exited: calls RemoveWorkload to deregister the check (we have to do this redundantly to handle unexpected task failure)
  • a moment passes while we restart the task...
  • task.PostStart: calls RegisterWorkload to register the check

But the hooks for group services fire only for allocation.PreRun, allocation.PreKill, and allocation.Postrun, none of which are triggered by a single task restarting, only by stopping the entire allocation (and it has to be a stop like nomad alloc stop :id, not a restart like nomad alloc restart :id).

So unfortunately there's a little architecture problem here with the group service hooks supporting check restarts. I think at this point I want to get a second opinion from a few of my colleagues on the Nomad team to figure out the best path forward.

@naag
Copy link
Author

naag commented Oct 27, 2020

That's a very thorough investigation, thanks for taking the time @tgross 👍!

However I didn't yet mention our intention to use Consul Connect, and it seems that this requires the service to be defined at the group level (https://www.nomadproject.io/docs/job-specification/connect) with network mode = "bridge".

As a workaround, we're currently considering to use check_restart with attempts = 0 and reschedule the whole allocation instead (using reschedule and restart). Do you think that's a good idea?

@tgross
Copy link
Member

tgross commented Oct 28, 2020

As a workaround, we're currently considering to use check_restart with attempts = 0 and reschedule the whole allocation instead (using reschedule and restart). Do you think that's a good idea?

I think you'll want to leave out the check_restart block entirely in order to lean on the reschedule behavior.

@tgross tgross removed their assignment Oct 30, 2020
@naag
Copy link
Author

naag commented Nov 18, 2020

Did you have the chance to discuss this with your colleagues? Is there any chance of a PR being accepted considering it's an architectural issue as you say?

@m1keil
Copy link

m1keil commented Dec 1, 2020

If I understand correctly, the reschedule stanza only will kick into effect if allocation completely fails (process crash as an example) but not if the process still running but not responding which is what healthchecks are trying to catch. A bit worrying.

schmichael added a commit that referenced this issue Dec 1, 2020
Add a warning about check_restart being limited to task networks and
link to the relevant issue: #9176.
@tgross tgross self-assigned this Jan 19, 2021
@tgross
Copy link
Member

tgross commented Jan 19, 2021

Did you have the chance to discuss this with your colleagues? Is there any chance of a PR being accepted considering it's an architectural issue as you say?

Apologies for the delay... I'm starting on the patch this week.

If I understand correctly, the reschedule stanza only will kick into effect if allocation completely fails (process crash as an example) but not if the process still running but not responding which is what healthchecks are trying to catch. A bit worrying.

Correct, which is why that's not a very good workaround and this isn't something we intended to leave unfixed.

@tgross
Copy link
Member

tgross commented Jan 21, 2021

Work in progress PR is #9869. Needs a good deal of testing and refactoring yet, but this should be on the right track now.

@tgross tgross added this to the 1.0.3 milestone Jan 22, 2021
@tgross
Copy link
Member

tgross commented Jan 22, 2021

#9869 has been merged and will go out with the next point release.

@github-actions
Copy link

I'm going to lock this issue because it has been closed for 120 days ⏳. This helps our maintainers find and focus on the active issues.
If you have found a problem that seems similar to this, please open a new issue and complete the issue template so we can capture all the details necessary to investigate further.

@github-actions github-actions bot locked as resolved and limited conversation to collaborators Oct 25, 2022
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Labels
stage/accepted Confirmed, and intend to work on. No timeline committment though. theme/restart/reschedule type/bug
Projects
None yet
Development

Successfully merging a pull request may close this issue.

3 participants