logging

Udo van den Heuvel udovdh at xs4all.nl
Fri Apr 12 15:20:45 UTC 2019


Hello,

What to think about logging like this:

Apr 11 19:12:25 vr ntpd[3428]: JUNK: M3 V4 0/23 1 4ef 48/ 0 0 550 from
[2600:1700:6731:6c0:f2de:f1ff:fe20:1bbe]:38764, lng=1408
Apr 11 19:14:34 vr ntpd[3428]: JUNK: M3 V4 0/23 1 4ef 48/ 0 0 560 from
[2600:1700:6731:6c0:f2de:f1ff:fe20:1bbe]:38764, lng=1424
Apr 11 19:16:44 vr ntpd[3428]: JUNK: M3 V4 0/23 1 4ef 48/ 0 0 570 from
[2600:1700:6731:6c0:f2de:f1ff:fe20:1bbe]:38764, lng=1440
Apr 11 19:18:28 vr ntpd[3428]: JUNK: M3 V4 0/23 1 4ef 48/ 0 0 020 from
68.75.8.147:38764, lng=80
Apr 11 19:20:34 vr ntpd[3428]: JUNK: M3 V4 0/23 1 4ef 48/ 0 0 030 from
68.75.8.147:38764, lng=96
[many of these]

Should I worry? Change config?


On another box:

Apr 12 14:15:23 box.local ntpd[2093]: 2019-04-12T14:15:23 ntpd[2093]:
INIT: ntpd ntpsec-1.1.3 2019-04-07T11:49:43Z: Starting
Apr 12 14:15:23 box.local ntpd[2093]: 2019-04-12T14:15:23 ntpd[2093]:
INIT: Command line: /usr/sbin/ntpd -u ntp:ntp -g -N -p /var/run/ntpd.pid
-i /chroot/ntpd
Apr 12 14:15:28 box.local ntpd[2109]: PROTO: fd01:c0a8:a10:1::1 unlink
local addr ::1 -> <null>
Apr 12 14:15:33 box.local ntpd[2109]: IO: Listen normally on 4 eth0
192.168.110.60:123
Apr 12 14:15:33 box.local ntpd[2109]: IO: Listen normally on 5 eth0
[fd01:c0a8:a10:1::60]:123
Apr 12 14:15:33 box.local ntpd[2109]: IO: Listen normally on 6 eth0
[2001:981:a812:0:e2d5:5eef:fee3:6b35]:123
Apr 12 14:15:33 box.local ntpd[2109]: IO: Listen normally on 7 eth0
[fe80::e2d5:5eef:fee3:6b35%2]:123
Apr 12 14:32:16 box.local ntpd[2109]: CLOCK: ts_prev 1555072336 s +
595028760 ns, ts_min 1555072336 s + 595027293 ns
Apr 12 14:32:16 box.local ntpd[2109]: CLOCK: ts 1555072336 s + 595028760 ns
Apr 12 14:32:16 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001663
Apr 12 14:32:16 box.local ntpd[2109]: CLOCK: this fuzz -0.000001626
Apr 12 14:32:16 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b03d0.9853b2dc is 0.000000006 later than 0xe05b03d0.9853b2c2
Apr 12 14:34:42 box.local ntpd[2109]: CLOCK: ts_prev 1555072482 s +
752318448 ns, ts_min 1555072482 s + 752317400 ns
Apr 12 14:34:42 box.local ntpd[2109]: CLOCK: ts 1555072482 s + 752318448 ns
Apr 12 14:34:42 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001758
Apr 12 14:34:42 box.local ntpd[2109]: CLOCK: this fuzz -0.000001783
Apr 12 14:34:42 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b0462.c097de8e is 0.000000677 later than 0xe05b0462.c097d332
Apr 12 14:34:57 box.local ntpd[2109]: CLOCK: ts_prev 1555072497 s +
745400752 ns, ts_min 1555072497 s + 745399215 ns
Apr 12 14:34:57 box.local ntpd[2109]: CLOCK: ts 1555072497 s + 745400752 ns
Apr 12 14:34:57 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001745
Apr 12 14:34:57 box.local ntpd[2109]: CLOCK: this fuzz -0.000001718
Apr 12 14:34:57 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b0471.bed27a70 is 0.000000109 later than 0xe05b0471.bed2789a
Apr 12 14:37:35 box.local ntpd[2109]: CLOCK: ts_prev 1555072655 s +
712154322 ns, ts_min 1555072655 s + 712153764 ns
Apr 12 14:37:35 box.local ntpd[2109]: CLOCK: ts 1555072655 s + 712154322 ns
Apr 12 14:37:35 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001594
Apr 12 14:37:35 box.local ntpd[2109]: CLOCK: this fuzz -0.000001722
Apr 12 14:37:35 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b050f.b64fb1ce is 0.000000942 later than 0xe05b050f.b64fa201
Apr 12 14:42:48 box.local ntpd[2109]: CLOCK: ts_prev 1555072968 s +
724550807 ns, ts_min 1555072968 s + 724549760 ns
Apr 12 14:42:48 box.local ntpd[2109]: CLOCK: ts 1555072968 s + 724550807 ns
Apr 12 14:42:48 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001230
Apr 12 14:42:48 box.local ntpd[2109]: CLOCK: this fuzz -0.000001707
Apr 12 14:42:48 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b0648.b97c0dfd is 0.000000073 later than 0xe05b0648.b97c0cc3
Apr 12 14:48:26 box.local ntpd[2109]: CLOCK: ts_prev 1555073306 s +
699738523 ns, ts_min 1555073306 s + 699737476 ns
Apr 12 14:48:26 box.local ntpd[2109]: CLOCK: ts 1555073306 s + 699738523 ns
Apr 12 14:48:26 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001798
Apr 12 14:48:26 box.local ntpd[2109]: CLOCK: this fuzz -0.000001401
Apr 12 14:48:26 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b079a.b321fe7a is 0.000000336 later than 0xe05b079a.b321f8d8
Apr 12 14:51:14 box.local ntpd[2109]: CLOCK: ts_prev 1555073474 s +
735160940 ns, ts_min 1555073474 s + 735160382 ns
Apr 12 14:51:14 box.local ntpd[2109]: CLOCK: ts 1555073474 s + 735160940 ns
Apr 12 14:51:14 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001322
Apr 12 14:51:14 box.local ntpd[2109]: CLOCK: this fuzz -0.000001126
Apr 12 14:51:14 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b0842.bc33703a is 0.000000074 later than 0xe05b0842.bc336efe
Apr 12 15:35:02 box.local ntpd[2109]: CLOCK: ts_prev 1555076102 s +
595269459 ns, ts_min 1555076102 s + 595268412 ns
Apr 12 15:35:02 box.local ntpd[2109]: CLOCK: ts 1555076102 s + 595269459 ns
Apr 12 15:35:02 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001620
Apr 12 15:35:02 box.local ntpd[2109]: CLOCK: this fuzz -0.000001776
Apr 12 15:35:02 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b1286.98637f71 is 0.000000533 later than 0xe05b1286.9863767f
Apr 12 15:42:33 box.local ntpd[2109]: CLOCK: ts_prev 1555076553 s +
574646140 ns, ts_min 1555076553 s + 574645092 ns
Apr 12 15:42:33 box.local ntpd[2109]: CLOCK: ts 1555076553 s + 574646140 ns
Apr 12 15:42:33 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001798
Apr 12 15:42:33 box.local ntpd[2109]: CLOCK: this fuzz -0.000001483
Apr 12 15:42:33 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b1449.931bf086 is 0.000000416 later than 0xe05b1449.931be98b
Apr 12 15:49:31 box.local ntpd[2109]: CLOCK: ts_prev 1555076971 s +
605157689 ns, ts_min 1555076971 s + 605156711 ns
Apr 12 15:49:31 box.local ntpd[2109]: CLOCK: ts 1555076971 s + 605157689 ns
Apr 12 15:49:31 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001228
Apr 12 15:49:31 box.local ntpd[2109]: CLOCK: this fuzz -0.000001615
Apr 12 15:49:31 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b15eb.9aeb82fc is 0.000000048 later than 0xe05b15eb.9aeb822c
Apr 12 16:01:49 box.local ntpd[2109]: CLOCK: ts_prev 1555077709 s +
757582308 ns, ts_min 1555077709 s + 757581330 ns
Apr 12 16:01:49 box.local ntpd[2109]: CLOCK: ts 1555077709 s + 757582308 ns
Apr 12 16:01:49 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001083
Apr 12 16:01:49 box.local ntpd[2109]: CLOCK: this fuzz -0.000001801
Apr 12 16:01:49 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b18cd.c1f0cd51 is 0.000000090 later than 0xe05b18cd.c1f0cbcf
Apr 12 16:07:59 box.local ntpd[2109]: CLOCK: ts_prev 1555078079 s +
755252677 ns, ts_min 1555078079 s + 755251700 ns
Apr 12 16:07:59 box.local ntpd[2109]: CLOCK: ts 1555078079 s + 755252677 ns
Apr 12 16:07:59 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001437
Apr 12 16:07:59 box.local ntpd[2109]: CLOCK: this fuzz -0.000001810
Apr 12 16:07:59 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b1a3f.c158268b is 0.000000454 later than 0xe05b1a3f.c1581eed
Apr 12 16:10:02 box.local ntpd[2109]: CLOCK: ts_prev 1555078202 s +
598108144 ns, ts_min 1555078202 s + 598107655 ns
Apr 12 16:10:02 box.local ntpd[2109]: CLOCK: ts 1555078202 s + 598108144 ns
Apr 12 16:10:02 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000000968
Apr 12 16:10:02 box.local ntpd[2109]: CLOCK: this fuzz -0.000001576
Apr 12 16:10:02 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b1aba.991d8717 is 0.000000239 later than 0xe05b1aba.991d8314
Apr 12 16:11:05 box.local ntpd[2109]: CLOCK: ts_prev 1555078265 s +
618182236 ns, ts_min 1555078265 s + 618181189 ns
Apr 12 16:11:05 box.local ntpd[2109]: CLOCK: ts 1555078265 s + 618182236 ns
Apr 12 16:11:05 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001648
Apr 12 16:11:05 box.local ntpd[2109]: CLOCK: this fuzz -0.000001236
Apr 12 16:11:05 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b1af9.9e411c84 is 0.000000020 later than 0xe05b1af9.9e411c2c
Apr 12 16:20:02 box.local ntpd[2109]: CLOCK: ts_prev 1555078802 s +
623788898 ns, ts_min 1555078802 s + 623788339 ns
Apr 12 16:20:02 box.local ntpd[2109]: CLOCK: ts 1555078802 s + 623788898 ns
Apr 12 16:20:02 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001707
Apr 12 16:20:02 box.local ntpd[2109]: CLOCK: this fuzz -0.000001120
Apr 12 16:20:02 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b1d12.9fb095dd is 0.000000451 later than 0xe05b1d12.9fb08e4d
Apr 12 16:20:02 box.local ntpd[2109]: CLOCK: ts_prev 1555078802 s +
624140411 ns, ts_min 1555078802 s + 624139434 ns
Apr 12 16:20:02 box.local ntpd[2109]: CLOCK: ts 1555078802 s + 624140411 ns
Apr 12 16:20:02 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001057
Apr 12 16:20:02 box.local ntpd[2109]: CLOCK: this fuzz -0.000001772
Apr 12 16:20:02 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b1d12.9fc78d5d is 0.000000036 later than 0xe05b1d12.9fc78cc2
Apr 12 16:47:58 box.local ntpd[2109]: CLOCK: ts_prev 1555080478 s +
647205446 ns, ts_min 1555080478 s + 647204817 ns
Apr 12 16:47:58 box.local ntpd[2109]: CLOCK: ts 1555080478 s + 647205446 ns
Apr 12 16:47:58 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001243
Apr 12 16:47:58 box.local ntpd[2109]: CLOCK: this fuzz -0.000001312
Apr 12 16:47:58 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b239e.a5af2d67 is 0.000000110 later than 0xe05b239e.a5af2b8d
Apr 12 16:54:39 box.local ntpd[2109]: CLOCK: ts_prev 1555080879 s +
828769172 ns, ts_min 1555080879 s + 828768194 ns
Apr 12 16:54:39 box.local ntpd[2109]: CLOCK: ts 1555080879 s + 828769172 ns
Apr 12 16:54:39 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001709
Apr 12 16:54:39 box.local ntpd[2109]: CLOCK: this fuzz -0.000001231
Apr 12 16:54:39 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b252f.d42a2536 is 0.000000146 later than 0xe05b252f.d42a22c4
Apr 12 17:02:36 box.local ntpd[2109]: CLOCK: ts_prev 1555081356 s +
766935445 ns, ts_min 1555081356 s + 766934817 ns
Apr 12 17:02:36 box.local ntpd[2109]: CLOCK: ts 1555081356 s + 766935445 ns
Apr 12 17:02:36 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001620
Apr 12 17:02:36 box.local ntpd[2109]: CLOCK: this fuzz -0.000000848
Apr 12 17:02:36 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b270c.c455d3cb is 0.000000024 later than 0xe05b270c.c455d365
Apr 12 17:13:29 box.local ntpd[2109]: CLOCK: ts_prev 1555082009 s +
599233278 ns, ts_min 1555082009 s + 599231881 ns
Apr 12 17:13:29 box.local ntpd[2109]: CLOCK: ts 1555082009 s + 599233278 ns
Apr 12 17:13:29 box.local ntpd[2109]: CLOCK: sys_fuzz 1816 nsec, prior
fuzz 0.000001527
Apr 12 17:13:29 box.local ntpd[2109]: CLOCK: this fuzz -0.000001733
Apr 12 17:13:29 box.local ntpd[2109]: CLOCK: prev get_systime
0xe05b2999.99673dd8 is 0.000000046 later than 0xe05b2999.99673d13

Should I worry? Change config if this is normal and I do not want to
much noise?

Please advise...

Kind regards,
Udo


More information about the devel mailing list