[Kea-users] Different logging time in dhcp4 log and ctrl-agent log

classic Classic list List threaded Threaded
3 messages Options
Reply | Threaded
Open this post in threaded view
|

[Kea-users] Different logging time in dhcp4 log and ctrl-agent log

Marcin Romanowski
I have different time log on agnets:

2020-06-03 14:00:05.755 INFO  [kea-ctrl-agent.ctrl-agent/10744] CTRL_AGENT_COMMAND_FORWARDED command config-write successfully forwarded to the service dhcp4

20.06.03 12:00:05.611 INFO  [kea-dhcp4.dhcpsrv/10736] DHCPSRV_PGSQL_HOST_DB_READONLY PostgreSQL host database opened for read access only
20.06.03 12:00:05.629 INFO  [kea-dhcp4.commands/10736] COMMAND_RECEIVED Received command 'config-write'

These logs are from the same time but dhcp4 shows 2 hours less. kea-ctrl-agnet show system time.
I didn't make any changes in configuration about time 

Where to find solution?

{
  "Dhcp4": {
    "authoritative": false,
    "boot-file-name": "",
    "calculate-tee-times": false,
    "control-socket": {
      "socket-name": "/opt/kea/var/run/kea/kea-dhcp4-ctrl.sock",
      "socket-type": "unix"
    },
    "decline-probation-period": 86400,
    "dhcp-ddns": {
      "enable-updates": false,
      "generated-prefix": "myhost",
      "max-queue-size": 1024,
      "ncr-format": "JSON",
      "ncr-protocol": "UDP",
      "override-client-update": false,
      "override-no-update": false,
      "qualifying-suffix": "",
      "replace-client-name": "never",
      "sender-ip": "0.0.0.0",
      "sender-port": 0,
      "server-ip": "127.0.0.1",
      "server-port": 53001
    },
    "dhcp-queue-control": {
      "capacity": 500,
      "enable-queue": false,
      "queue-type": "kea-ring4"
    },
    "dhcp4o6-port": 0,
    "echo-client-id": false,
    "expired-leases-processing": {
      "flush-reclaimed-timer-wait-time": 25,
      "hold-reclaimed-time": 3600,
      "max-reclaim-leases": 100,
      "max-reclaim-time": 250,
      "reclaim-timer-wait-time": 10,
      "unwarned-reclaim-cycles": 5
    },
    "hooks-libraries": [ ],
    "host-reservation-identifiers": [ "hw-address", "duid" ],
    "hostname-char-replacement": "",
    "hostname-char-set": "[^A-Za-z0-9.-]",
    "hosts-databases": [
      {
        "host": "10.1.1.1.",
        "max-reconnect-tries": 100000,
        "name": "kea",
        "password": "kea",
        "port": 5432,
        "readonly": true,
        "reconnect-wait-time": 30000,
        "type": "postgresql",
        "user": "kea"
      }
    ],
    "interfaces-config": {
      "dhcp-socket-type": "udp",
      "interfaces": [ "vlan4049" ],
      "re-detect": true
    },
    "lease-database": {
      "lfc-interval": 3600,
      "type": "memfile"
    },
    "loggers": [
      {
        "name": "kea-dhcp4",
        "output_options": [
          {
            "output": "/opt/kea/var/log/kea-dhcp4.log",
            "pattern": "%d{%y.%m.%d %H:%M:%S.%q} %-5p [%c/%i] %m\n"
          }
        ],
        "severity": "INFO"
      }
    ],
    "match-client-id": false,
    "next-server": "0.0.0.0",
    "option-data": [
      {
        "always-send": true,
        "code": 6,
        "csv-format": true,
        "data": "8.8.8.8,1.1.1.1",
        "name": "domain-name-servers",
        "space": "dhcp4"
      }
    ],
    "option-def": [ ],
    "rebind-timer": 2000,
    "renew-timer": 1000,
    "reservation-mode": "all",
    "sanity-checks": {
      "lease-checks": "fix-del"
    },
    "server-hostname": "",
    "server-tag": "",
    "shared-networks": [    ],
    "subnet4": [ ],
    "t1-percent": 0.5,
    "t2-percent": 0.875,
    "valid-lifetime": 86400
  }
}


_______________________________________________
ISC funds the development of this software with paid support subscriptions. Contact us at https://www.isc.org/contact/ for more information.

To unsubscribe visit https://lists.isc.org/mailman/listinfo/kea-users.

Kea-users mailing list
[hidden email]
https://lists.isc.org/mailman/listinfo/kea-users
Reply | Threaded
Open this post in threaded view
|

Re: [Kea-users] Different logging time in dhcp4 log and ctrl-agent log

Marcin Romanowski
Hello,

Any idea why this happens? 
I notice that when I enabled ha in hooks logger I have also decreased 2h time. Only kea-ctrl-agent presents system's time.
This issue I have on 3 different systems (debian 9, debian 10 and ubuntu 18) and tried on kea 1.6.2 and 1.7.8 with the same issue.






śr., 3 cze 2020 o 14:08 Marcin Romanowski <[hidden email]> napisał(a):
I have different time log on agnets:

2020-06-03 14:00:05.755 INFO  [kea-ctrl-agent.ctrl-agent/10744] CTRL_AGENT_COMMAND_FORWARDED command config-write successfully forwarded to the service dhcp4

20.06.03 12:00:05.611 INFO  [kea-dhcp4.dhcpsrv/10736] DHCPSRV_PGSQL_HOST_DB_READONLY PostgreSQL host database opened for read access only
20.06.03 12:00:05.629 INFO  [kea-dhcp4.commands/10736] COMMAND_RECEIVED Received command 'config-write'

These logs are from the same time but dhcp4 shows 2 hours less. kea-ctrl-agnet show system time.
I didn't make any changes in configuration about time 

Where to find solution?

{
  "Dhcp4": {
    "authoritative": false,
    "boot-file-name": "",
    "calculate-tee-times": false,
    "control-socket": {
      "socket-name": "/opt/kea/var/run/kea/kea-dhcp4-ctrl.sock",
      "socket-type": "unix"
    },
    "decline-probation-period": 86400,
    "dhcp-ddns": {
      "enable-updates": false,
      "generated-prefix": "myhost",
      "max-queue-size": 1024,
      "ncr-format": "JSON",
      "ncr-protocol": "UDP",
      "override-client-update": false,
      "override-no-update": false,
      "qualifying-suffix": "",
      "replace-client-name": "never",
      "sender-ip": "0.0.0.0",
      "sender-port": 0,
      "server-ip": "127.0.0.1",
      "server-port": 53001
    },
    "dhcp-queue-control": {
      "capacity": 500,
      "enable-queue": false,
      "queue-type": "kea-ring4"
    },
    "dhcp4o6-port": 0,
    "echo-client-id": false,
    "expired-leases-processing": {
      "flush-reclaimed-timer-wait-time": 25,
      "hold-reclaimed-time": 3600,
      "max-reclaim-leases": 100,
      "max-reclaim-time": 250,
      "reclaim-timer-wait-time": 10,
      "unwarned-reclaim-cycles": 5
    },
    "hooks-libraries": [ ],
    "host-reservation-identifiers": [ "hw-address", "duid" ],
    "hostname-char-replacement": "",
    "hostname-char-set": "[^A-Za-z0-9.-]",
    "hosts-databases": [
      {
        "host": "10.1.1.1.",
        "max-reconnect-tries": 100000,
        "name": "kea",
        "password": "kea",
        "port": 5432,
        "readonly": true,
        "reconnect-wait-time": 30000,
        "type": "postgresql",
        "user": "kea"
      }
    ],
    "interfaces-config": {
      "dhcp-socket-type": "udp",
      "interfaces": [ "vlan4049" ],
      "re-detect": true
    },
    "lease-database": {
      "lfc-interval": 3600,
      "type": "memfile"
    },
    "loggers": [
      {
        "name": "kea-dhcp4",
        "output_options": [
          {
            "output": "/opt/kea/var/log/kea-dhcp4.log",
            "pattern": "%d{%y.%m.%d %H:%M:%S.%q} %-5p [%c/%i] %m\n"
          }
        ],
        "severity": "INFO"
      }
    ],
    "match-client-id": false,
    "next-server": "0.0.0.0",
    "option-data": [
      {
        "always-send": true,
        "code": 6,
        "csv-format": true,
        "data": "8.8.8.8,1.1.1.1",
        "name": "domain-name-servers",
        "space": "dhcp4"
      }
    ],
    "option-def": [ ],
    "rebind-timer": 2000,
    "renew-timer": 1000,
    "reservation-mode": "all",
    "sanity-checks": {
      "lease-checks": "fix-del"
    },
    "server-hostname": "",
    "server-tag": "",
    "shared-networks": [    ],
    "subnet4": [ ],
    "t1-percent": 0.5,
    "t2-percent": 0.875,
    "valid-lifetime": 86400
  }
}



--
Marcin Romanowski / nicraM



_______________________________________________
ISC funds the development of this software with paid support subscriptions. Contact us at https://www.isc.org/contact/ for more information.

To unsubscribe visit https://lists.isc.org/mailman/listinfo/kea-users.

Kea-users mailing list
[hidden email]
https://lists.isc.org/mailman/listinfo/kea-users
Reply | Threaded
Open this post in threaded view
|

Re: [Kea-users] Different logging time in dhcp4 log and ctrl-agent log

Stephen Morris
On 04/06/2020 07:58, Marcin Romanowski wrote:
> Hello,
>
> Any idea why this happens?
> I notice that when I enabled ha in hooks logger I have also decreased 2h
> time. Only kea-ctrl-agent presents system's time.
> This issue I have on 3 different systems (debian 9, debian 10 and ubuntu
> 18) and tried on kea 1.6.2 and 1.7.8 with the same issue.

In your previous post, you gave the pattern used for logging:

> "pattern": "%d{%y.%m.%d %H:%M:%S.%q} %-5p [%c/%i] %m\n"

Kea logging is built on log4cplus, and the pattern specification is the
one used by that package.  The log4cplus manual at:

https://log4cplus.sourceforge.io/docs/html/classlog4cplus_1_1PatternLayout.html

says that "%d{...}" outputs the time in UTC.

The web site associated with your email domain (nicram.net) is in
Polish. Poland is currently on CEST, two hours ahead of UTC.  This would
account for the discrepancy you are seeing.

Try changing your pattern from "%d{...}" to "%D{...}", which will output
the time in local time.

Stephen
_______________________________________________
ISC funds the development of this software with paid support subscriptions. Contact us at https://www.isc.org/contact/ for more information.

To unsubscribe visit https://lists.isc.org/mailman/listinfo/kea-users.

Kea-users mailing list
[hidden email]
https://lists.isc.org/mailman/listinfo/kea-users