change in ntpd behavior

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

change in ntpd behavior

Paul de Weerd
Hi all,

While scanning my logs for something unrelated, I came across this
message from ntpd (note that I have ntpd_flags set to '-v' in
/etc/rc.conf.local):

2019-10-21T02:03:50.143Z despair ntpd[57871]: adjusting local clock by 5.579152s

I found it weird, because the machine had been up for almost three
weeks by then - normally, there's some clock adjustment after boot and
once it's OK, ntpd only adjusts the clock frequency.

I searched all logs and found the following:

# zgrep -he '/bsd: OpenBSD' -e 'ntpd.*adjusting' /var/log/all* | sort | cut -f2- -d' ' | sed 's/ by.*//' | uniq -c
   1 /bsd: OpenBSD 6.4-current (GENERIC.MP) #395: Fri Oct 26 19:59:35 MDT 2018
   1 ntpd[83741]: adjusting local clock
  47 ntpd[83741]: adjusting clock frequency
   1 /bsd: OpenBSD 6.5-beta (GENERIC.MP) #761: Fri Mar  1 21:34:04 MST 2019
   2 ntpd[79657]: adjusting local clock
   1 /bsd: OpenBSD 6.5-beta (GENERIC.MP) #761: Fri Mar  1 21:34:04 MST 2019
   1 ntpd[30514]: adjusting local clock
  16 ntpd[30514]: adjusting clock frequency
   1 /bsd: OpenBSD 6.5-beta (GENERIC.MP) #761: Fri Mar  1 21:34:04 MST 2019
   2 ntpd[38581]: adjusting local clock
   5 ntpd[38581]: adjusting clock frequency
   1 /bsd: OpenBSD 6.5 (GENERIC.MP) #847: Tue Apr  9 09:12:46 MDT 2019
   1 ntpd[94340]: adjusting local clock
  30 ntpd[94340]: adjusting clock frequency
   1 /bsd: OpenBSD 6.5-current (GENERIC.MP) #6: Tue Jun  4 15:05:10 MDT 2019
   1 ntpd[4423]: adjusting local clock
  66 ntpd[4423]: adjusting clock frequency
   1 /bsd: OpenBSD 6.5-current (GENERIC.MP) #6: Tue Jun  4 15:05:10 MDT 2019
   1 ntpd[94384]: adjusting local clock
  16 ntpd[94384]: adjusting clock frequency
   1 /bsd: OpenBSD 6.5-current (GENERIC.MP) #6: Tue Jun  4 15:05:10 MDT 2019
   1 ntpd[43359]: adjusting local clock
   5 ntpd[43359]: adjusting clock frequency
   1 /bsd: OpenBSD 6.6-beta (GENERIC.MP) #335: Sun Sep 29 21:47:20 MDT 2019
   1 ntpd[57871]: adjusting local clock
   2 ntpd[57871]: adjusting clock frequency
   1 ntpd[57871]: adjusting local clock
   1 ntpd[57871]: adjusting clock frequency
   4 ntpd[57871]: adjusting local clock
   2 ntpd[57871]: adjusting clock frequency
   1 ntpd[57871]: adjusting local clock
   1 ntpd[57871]: adjusting clock frequency
   4 ntpd[57871]: adjusting local clock
   2 ntpd[57871]: adjusting clock frequency
   1 ntpd[57871]: adjusting local clock
   1 ntpd[57871]: adjusting clock frequency
  11 ntpd[57871]: adjusting local clock
   2 ntpd[57871]: adjusting clock frequency
   1 ntpd[57871]: adjusting local clock
   1 ntpd[57871]: adjusting clock frequency
   4 ntpd[57871]: adjusting local clock
   1 ntpd[57871]: adjusting clock frequency

(here things alternate between adjusting clock and adjusting frequency
40 times in 22 days).

So from 6.4-current, back in October 2018, there was indeed one or two
'adjusting local clock' after boot, followed by a bunch of 'adjusting
clock frequency'.  However, since the update to 6.6-beta end of
September, ntpd is regularly adjusting the local clock.

Is anyone else seeing this?  Could it be a sign of broken hardware or
is the battery of my RTC dead?

Thanks,

Paul

--
>++++++++[<++++++++++>-]<+++++++.>+++[<------>-]<.>+++[<+
+++++++++++>-]<.>++[<------------>-]<+.--------------.[-]
                 http://www.weirdnet.nl/                 

Reply | Threaded
Open this post in threaded view
|

Re: change in ntpd behavior

Otto Moerbeek
On Wed, Oct 23, 2019 at 09:57:49AM +0200, Paul de Weerd wrote:

> Hi all,
>
> While scanning my logs for something unrelated, I came across this
> message from ntpd (note that I have ntpd_flags set to '-v' in
> /etc/rc.conf.local):
>
> 2019-10-21T02:03:50.143Z despair ntpd[57871]: adjusting local clock by 5.579152s
>
> I found it weird, because the machine had been up for almost three
> weeks by then - normally, there's some clock adjustment after boot and
> once it's OK, ntpd only adjusts the clock frequency.
>
> I searched all logs and found the following:
>
> # zgrep -he '/bsd: OpenBSD' -e 'ntpd.*adjusting' /var/log/all* | sort | cut -f2- -d' ' | sed 's/ by.*//' | uniq -c
>    1 /bsd: OpenBSD 6.4-current (GENERIC.MP) #395: Fri Oct 26 19:59:35 MDT 2018
>    1 ntpd[83741]: adjusting local clock
>   47 ntpd[83741]: adjusting clock frequency
>    1 /bsd: OpenBSD 6.5-beta (GENERIC.MP) #761: Fri Mar  1 21:34:04 MST 2019
>    2 ntpd[79657]: adjusting local clock
>    1 /bsd: OpenBSD 6.5-beta (GENERIC.MP) #761: Fri Mar  1 21:34:04 MST 2019
>    1 ntpd[30514]: adjusting local clock
>   16 ntpd[30514]: adjusting clock frequency
>    1 /bsd: OpenBSD 6.5-beta (GENERIC.MP) #761: Fri Mar  1 21:34:04 MST 2019
>    2 ntpd[38581]: adjusting local clock
>    5 ntpd[38581]: adjusting clock frequency
>    1 /bsd: OpenBSD 6.5 (GENERIC.MP) #847: Tue Apr  9 09:12:46 MDT 2019
>    1 ntpd[94340]: adjusting local clock
>   30 ntpd[94340]: adjusting clock frequency
>    1 /bsd: OpenBSD 6.5-current (GENERIC.MP) #6: Tue Jun  4 15:05:10 MDT 2019
>    1 ntpd[4423]: adjusting local clock
>   66 ntpd[4423]: adjusting clock frequency
>    1 /bsd: OpenBSD 6.5-current (GENERIC.MP) #6: Tue Jun  4 15:05:10 MDT 2019
>    1 ntpd[94384]: adjusting local clock
>   16 ntpd[94384]: adjusting clock frequency
>    1 /bsd: OpenBSD 6.5-current (GENERIC.MP) #6: Tue Jun  4 15:05:10 MDT 2019
>    1 ntpd[43359]: adjusting local clock
>    5 ntpd[43359]: adjusting clock frequency
>    1 /bsd: OpenBSD 6.6-beta (GENERIC.MP) #335: Sun Sep 29 21:47:20 MDT 2019
>    1 ntpd[57871]: adjusting local clock
>    2 ntpd[57871]: adjusting clock frequency
>    1 ntpd[57871]: adjusting local clock
>    1 ntpd[57871]: adjusting clock frequency
>    4 ntpd[57871]: adjusting local clock
>    2 ntpd[57871]: adjusting clock frequency
>    1 ntpd[57871]: adjusting local clock
>    1 ntpd[57871]: adjusting clock frequency
>    4 ntpd[57871]: adjusting local clock
>    2 ntpd[57871]: adjusting clock frequency
>    1 ntpd[57871]: adjusting local clock
>    1 ntpd[57871]: adjusting clock frequency
>   11 ntpd[57871]: adjusting local clock
>    2 ntpd[57871]: adjusting clock frequency
>    1 ntpd[57871]: adjusting local clock
>    1 ntpd[57871]: adjusting clock frequency
>    4 ntpd[57871]: adjusting local clock
>    1 ntpd[57871]: adjusting clock frequency
>
> (here things alternate between adjusting clock and adjusting frequency
> 40 times in 22 days).
>
> So from 6.4-current, back in October 2018, there was indeed one or two
> 'adjusting local clock' after boot, followed by a bunch of 'adjusting
> clock frequency'.  However, since the update to 6.6-beta end of
> September, ntpd is regularly adjusting the local clock.
>
> Is anyone else seeing this?  Could it be a sign of broken hardware or
> is the battery of my RTC dead?
>
> Thanks,
>
> Paul
>
> --
> >++++++++[<++++++++++>-]<+++++++.>+++[<------>-]<.>+++[<+
> +++++++++++>-]<.>++[<------------>-]<+.--------------.[-]
>                  http://www.weirdnet.nl/                 
>

I could * this has to do with the logging changes. Previously, debug
messages could disappear because of wrong logging setup. In non -v
mode, only big adjustments are logged. Sadly you stripped those
numbers away so i cannot tell how big the adjustments are.

        -Otto

Reply | Threaded
Open this post in threaded view
|

Re: change in ntpd behavior

Paul de Weerd
Hi Otto,

On Wed, Oct 23, 2019 at 10:58:24AM +0200, Otto Moerbeek wrote:
| I could * this has to do with the logging changes. Previously, debug
| messages could disappear because of wrong logging setup. In non -v
| mode, only big adjustments are logged. Sadly you stripped those
| numbers away so i cannot tell how big the adjustments are.

The recent adjustments that happen during runtime are bigger than what
was logged previously at boot:

2019-06-07T13:15:53.341Z despair ntpd[4423]: adjusting local clock by -0.068174s
2019-09-04T05:38:00.448Z despair ntpd[94384]: adjusting local clock by 0.205606s
2019-09-25T04:30:45.225Z despair ntpd[43359]: adjusting local clock by 0.352666s
2019-09-30T20:13:34.739Z despair ntpd[57871]: adjusting local clock by 1.172165s
2019-10-02T02:55:02.851Z despair ntpd[57871]: adjusting local clock by 2.788555s
2019-10-02T03:21:39.721Z despair ntpd[57871]: adjusting local clock by -0.043674s
2019-10-02T06:23:22.228Z despair ntpd[57871]: adjusting local clock by -0.343597s
2019-10-02T06:51:22.766Z despair ntpd[57871]: adjusting local clock by -0.112257s
2019-10-02T07:42:06.567Z despair ntpd[57871]: adjusting local clock by -0.108361s
2019-10-03T02:18:06.172Z despair ntpd[57871]: adjusting local clock by 2.792710s
2019-10-03T02:46:26.658Z despair ntpd[57871]: adjusting local clock by -0.056351s
2019-10-03T03:37:45.273Z despair ntpd[57871]: adjusting local clock by -0.135864s
2019-10-03T04:49:06.489Z despair ntpd[57871]: adjusting local clock by -0.092314s
2019-10-03T04:59:25.046Z despair ntpd[57871]: adjusting local clock by -0.119596s
2019-10-04T03:14:01.960Z despair ntpd[57871]: adjusting local clock by 2.789228s

Below is a bit more history (from early January this year).

The wildly varying clock frequency is the reason I'm suspecting
hardware failure...

Thanks,

Paul

--
>++++++++[<++++++++++>-]<+++++++.>+++[<------>-]<.>+++[<+
+++++++++++>-]<.>++[<------------>-]<+.--------------.[-]
                 http://www.weirdnet.nl/                 



2019-01-08T06:45:31.782Z despair ntpd[83741]: adjusting clock frequency by 0.113893 to 22.810188ppm
2019-01-08T20:50:38.833Z despair ntpd[83741]: adjusting clock frequency by -0.052936 to 22.757252ppm
2019-01-11T18:16:43.213Z despair ntpd[83741]: adjusting clock frequency by 0.070955 to 22.765158ppm
2019-01-14T16:32:01.570Z despair ntpd[83741]: adjusting clock frequency by -0.079218 to 22.772022ppm
2019-01-16T07:25:40.735Z despair ntpd[83741]: adjusting clock frequency by 0.061495 to 22.784900ppm
2019-01-18T00:46:17.217Z despair ntpd[83741]: adjusting clock frequency by -0.067692 to 22.715564ppm
2019-01-18T22:48:55.795Z despair ntpd[83741]: adjusting clock frequency by -0.087077 to 22.628487ppm
2019-01-26T13:11:05.776Z despair ntpd[83741]: adjusting clock frequency by 0.111245 to 22.680715ppm
2019-01-28T22:59:57.151Z despair ntpd[83741]: adjusting clock frequency by -0.054828 to 22.649700ppm
2019-01-29T16:24:24.050Z despair ntpd[83741]: adjusting clock frequency by -0.061384 to 22.588317ppm
2019-02-07T06:37:27.499Z despair ntpd[83741]: adjusting clock frequency by 0.096997 to 22.657004ppm
2019-02-09T13:37:37.168Z despair ntpd[83741]: adjusting clock frequency by 0.051171 to 22.704285ppm
2019-02-11T10:11:48.668Z despair ntpd[83741]: adjusting clock frequency by -0.061547 to 22.624355ppm
2019-02-12T02:40:36.043Z despair ntpd[83741]: adjusting clock frequency by -0.074976 to 22.549378ppm
2019-02-19T07:57:12.596Z despair ntpd[83741]: adjusting clock frequency by 0.076256 to 22.614877ppm
2019-02-20T20:33:45.286Z despair ntpd[83741]: adjusting clock frequency by -0.072584 to 22.540745ppm
2019-02-22T04:58:58.612Z despair ntpd[83741]: adjusting clock frequency by 0.075267 to 22.620051ppm
2019-02-24T12:53:13.053Z despair ntpd[83741]: adjusting clock frequency by -0.071152 to 22.482398ppm
2019-02-27T06:50:35.783Z despair ntpd[83741]: adjusting clock frequency by 0.060008 to 22.504098ppm
2019-02-28T23:36:38.467Z despair ntpd[83741]: adjusting clock frequency by 0.064345 to 22.579627ppm
2019-03-02T12:59:39.951Z despair ntpd[79657]: adjusting local clock by 0.239351s
2019-03-02T13:00:10.725Z despair ntpd[79657]: adjusting local clock by 0.084351s
2019-03-02T13:11:56.028Z despair ntpd[30514]: adjusting local clock by 0.757522s
2019-03-02T13:36:39.058Z despair ntpd[30514]: adjusting clock frequency by -1.011747 to 21.548253ppm
2019-03-02T13:59:40.575Z despair ntpd[30514]: adjusting clock frequency by 0.076373 to 21.624625ppm
2019-03-02T14:19:51.514Z despair ntpd[30514]: adjusting clock frequency by -0.096260 to 21.528365ppm
2019-03-03T05:30:40.004Z despair ntpd[30514]: adjusting clock frequency by 0.119092 to 21.647457ppm
2019-03-03T23:04:41.902Z despair ntpd[30514]: adjusting clock frequency by 0.103132 to 21.750589ppm
2019-03-05T06:03:11.928Z despair ntpd[30514]: adjusting clock frequency by -0.133629 to 21.595439ppm
2019-03-07T01:36:00.175Z despair ntpd[30514]: adjusting clock frequency by 0.051264 to 21.662972ppm
2019-03-08T12:34:41.956Z despair ntpd[30514]: adjusting clock frequency by -0.104027 to 21.598958ppm
2019-03-11T09:21:43.495Z despair ntpd[30514]: adjusting clock frequency by -0.050271 to 21.513281ppm
2019-03-12T21:59:25.194Z despair ntpd[30514]: adjusting clock frequency by 0.082820 to 21.563817ppm
2019-03-17T17:52:36.896Z despair ntpd[30514]: adjusting clock frequency by -0.060276 to 21.581184ppm
2019-03-18T10:41:28.898Z despair ntpd[30514]: adjusting clock frequency by -0.090451 to 21.490733ppm
2019-03-19T01:08:50.013Z despair ntpd[30514]: adjusting clock frequency by -0.060271 to 21.430461ppm
2019-03-24T05:25:08.011Z despair ntpd[30514]: adjusting clock frequency by -0.078510 to 21.473110ppm
2019-03-27T08:01:14.108Z despair ntpd[30514]: adjusting clock frequency by 0.050375 to 21.480257ppm
2019-04-01T06:42:56.506Z despair ntpd[30514]: adjusting clock frequency by -0.094575 to 21.424325ppm
2019-04-02T08:22:06.438Z despair ntpd[38581]: adjusting local clock by -0.793199s
2019-04-02T08:22:38.907Z despair ntpd[38581]: adjusting local clock by -0.635013s
2019-04-02T08:46:06.102Z despair ntpd[38581]: adjusting clock frequency by -0.122341 to 21.268659ppm
2019-04-02T09:12:12.744Z despair ntpd[38581]: adjusting clock frequency by -1.829745 to 19.438913ppm
2019-04-02T23:01:13.727Z despair ntpd[38581]: adjusting clock frequency by 0.123445 to 19.586482ppm
2019-04-05T01:53:47.618Z despair ntpd[38581]: adjusting clock frequency by -0.052237 to 19.516544ppm
2019-04-07T13:41:15.176Z despair ntpd[38581]: adjusting clock frequency by 0.087925 to 19.619287ppm
2019-04-09T18:48:24.471Z despair ntpd[94340]: adjusting local clock by 0.457236s
2019-04-09T19:07:40.027Z despair ntpd[94340]: adjusting clock frequency by -0.722604 to 18.955396ppm
2019-04-09T19:28:27.899Z despair ntpd[94340]: adjusting clock frequency by -0.524858 to 18.430538ppm
2019-04-10T10:42:47.177Z despair ntpd[94340]: adjusting clock frequency by 0.208084 to 18.623874ppm
2019-04-11T03:04:08.785Z despair ntpd[94340]: adjusting clock frequency by -0.064775 to 18.559099ppm
2019-04-12T20:27:39.173Z despair ntpd[94340]: adjusting clock frequency by 0.055953 to 18.602639ppm
2019-04-13T11:17:25.710Z despair ntpd[94340]: adjusting clock frequency by -0.050935 to 18.551704ppm
2019-04-16T08:44:28.425Z despair ntpd[94340]: adjusting clock frequency by 0.076776 to 18.697320ppm
2019-04-18T12:25:30.676Z despair ntpd[94340]: adjusting clock frequency by -0.079013 to 18.696495ppm
2019-04-19T09:10:21.240Z despair ntpd[94340]: adjusting clock frequency by 0.075817 to 18.772313ppm
2019-04-24T07:03:27.155Z despair ntpd[94340]: adjusting clock frequency by 0.096280 to 18.925407ppm
2019-04-25T19:16:22.124Z despair ntpd[94340]: adjusting clock frequency by -0.084848 to 18.878067ppm
2019-04-26T13:11:26.502Z despair ntpd[94340]: adjusting clock frequency by -0.056613 to 18.821455ppm
2019-04-27T23:50:39.299Z despair ntpd[94340]: adjusting clock frequency by -0.064278 to 18.760748ppm
2019-05-04T00:20:31.343Z despair ntpd[94340]: adjusting clock frequency by -0.111168 to 18.605360ppm
2019-05-09T01:22:56.808Z despair ntpd[94340]: adjusting clock frequency by 0.084542 to 18.634222ppm
2019-05-11T09:04:10.285Z despair ntpd[94340]: adjusting clock frequency by -0.064043 to 18.572246ppm
2019-05-12T08:44:59.048Z despair ntpd[94340]: adjusting clock frequency by -0.097073 to 18.475173ppm
2019-05-17T09:58:01.436Z despair ntpd[94340]: adjusting clock frequency by 0.056900 to 18.578899ppm
2019-05-18T22:04:18.509Z despair ntpd[94340]: adjusting clock frequency by 0.104781 to 18.703822ppm
2019-05-22T05:52:09.440Z despair ntpd[94340]: adjusting clock frequency by -0.065212 to 18.688099ppm
2019-05-26T23:02:35.939Z despair ntpd[94340]: adjusting clock frequency by 0.084034 to 18.767830ppm
2019-05-29T00:13:01.803Z despair ntpd[94340]: adjusting clock frequency by -0.102652 to 18.625510ppm
2019-05-30T05:17:10.440Z despair ntpd[94340]: adjusting clock frequency by 0.104181 to 18.688675ppm
2019-05-30T21:29:48.730Z despair ntpd[94340]: adjusting clock frequency by 0.068342 to 18.757017ppm
2019-06-01T21:40:02.441Z despair ntpd[94340]: adjusting clock frequency by 0.051014 to 18.846626ppm
2019-06-02T14:38:41.341Z despair ntpd[94340]: adjusting clock frequency by 0.093861 to 18.940487ppm
2019-06-03T23:32:31.042Z despair ntpd[94340]: adjusting clock frequency by -0.057903 to 18.869288ppm
2019-06-04T18:24:19.540Z despair ntpd[94340]: adjusting clock frequency by -0.073277 to 18.796011ppm
2019-06-05T11:48:57.184Z despair ntpd[94340]: adjusting clock frequency by 0.142221 to 18.938232ppm
2019-06-06T20:38:17.627Z despair ntpd[94340]: adjusting clock frequency by -0.093133 to 18.796535ppm
2019-06-07T13:15:53.341Z despair ntpd[4423]: adjusting local clock by -0.068174s
2019-06-07T13:31:00.310Z despair ntpd[4423]: adjusting clock frequency by 4.502033 to 23.253033ppm
2019-06-07T13:51:07.915Z despair ntpd[4423]: adjusting clock frequency by 0.481906 to 23.734939ppm
2019-06-07T14:08:52.308Z despair ntpd[4423]: adjusting clock frequency by -0.395832 to 23.339107ppm
2019-06-08T06:21:55.284Z despair ntpd[4423]: adjusting clock frequency by 0.125060 to 23.464167ppm
2019-06-08T22:43:36.320Z despair ntpd[4423]: adjusting clock frequency by -0.083238 to 23.380928ppm
2019-06-10T15:29:18.521Z despair ntpd[4423]: adjusting clock frequency by 0.087945 to 23.459801ppm
2019-06-11T06:54:13.578Z despair ntpd[4423]: adjusting clock frequency by 0.099642 to 23.559443ppm
2019-06-11T22:06:51.660Z despair ntpd[4423]: adjusting clock frequency by -0.074874 to 23.484569ppm
2019-06-12T17:04:27.307Z despair ntpd[4423]: adjusting clock frequency by -0.069184 to 23.415385ppm
2019-06-19T10:55:38.025Z despair ntpd[4423]: adjusting clock frequency by 0.117077 to 23.610477ppm
2019-06-20T22:02:53.300Z despair ntpd[4423]: adjusting clock frequency by -0.071049 to 23.536990ppm
2019-06-22T10:44:40.165Z despair ntpd[4423]: adjusting clock frequency by -0.116509 to 23.387096ppm
2019-06-22T22:07:35.286Z despair ntpd[4423]: adjusting clock frequency by 0.055410 to 23.442507ppm
2019-06-23T15:33:17.850Z despair ntpd[4423]: adjusting clock frequency by 0.112113 to 23.554620ppm
2019-06-24T07:54:29.859Z despair ntpd[4423]: adjusting clock frequency by 0.088857 to 23.643476ppm
2019-06-24T21:33:02.301Z despair ntpd[4423]: adjusting clock frequency by 0.136506 to 23.779983ppm
2019-06-25T21:31:52.276Z despair ntpd[4423]: adjusting clock frequency by 0.417633 to 24.148130ppm
2019-06-26T08:44:56.996Z despair ntpd[4423]: adjusting clock frequency by -0.464036 to 23.684094ppm
2019-06-27T00:44:14.652Z despair ntpd[4423]: adjusting clock frequency by -0.092340 to 23.591754ppm
2019-06-27T13:35:55.606Z despair ntpd[4423]: adjusting clock frequency by -0.150729 to 23.441026ppm
2019-06-30T10:17:13.638Z despair ntpd[4423]: adjusting clock frequency by 0.068768 to 23.625579ppm
2019-07-01T19:20:24.091Z despair ntpd[4423]: adjusting clock frequency by -0.089585 to 23.523883ppm
2019-07-02T10:02:12.764Z despair ntpd[4423]: adjusting clock frequency by -0.071570 to 23.452312ppm
2019-07-03T17:59:03.910Z despair ntpd[4423]: adjusting clock frequency by -0.055021 to 23.362962ppm
2019-07-04T08:20:51.532Z despair ntpd[4423]: adjusting clock frequency by -0.055255 to 23.307707ppm
2019-07-05T22:36:13.246Z despair ntpd[4423]: adjusting clock frequency by 0.091018 to 23.437977ppm
2019-07-08T03:01:52.591Z despair ntpd[4423]: adjusting clock frequency by -0.088208 to 23.318931ppm
2019-07-08T20:48:55.856Z despair ntpd[4423]: adjusting clock frequency by -0.060261 to 23.258669ppm
2019-07-10T05:22:18.085Z despair ntpd[4423]: adjusting clock frequency by 0.058767 to 23.283927ppm
2019-07-11T17:55:18.349Z despair ntpd[4423]: adjusting clock frequency by 0.140533 to 23.446317ppm
2019-07-13T22:37:21.408Z despair ntpd[4423]: adjusting clock frequency by -0.051781 to 23.431555ppm
2019-07-14T15:35:49.005Z despair ntpd[4423]: adjusting clock frequency by -0.085285 to 23.346270ppm
2019-07-17T16:31:46.191Z despair ntpd[4423]: adjusting clock frequency by 0.056869 to 23.387482ppm
2019-07-18T03:52:31.720Z despair ntpd[4423]: adjusting clock frequency by 0.054937 to 23.442418ppm
2019-07-21T00:43:58.919Z despair ntpd[4423]: adjusting clock frequency by 0.077154 to 23.583708ppm
2019-07-21T16:58:25.689Z despair ntpd[4423]: adjusting clock frequency by -0.133399 to 23.450309ppm
2019-07-23T05:30:32.645Z despair ntpd[4423]: adjusting clock frequency by 0.101089 to 23.506079ppm
2019-07-23T22:50:13.596Z despair ntpd[4423]: adjusting clock frequency by 0.089860 to 23.595938ppm
2019-07-24T13:40:54.562Z despair ntpd[4423]: adjusting clock frequency by 0.095916 to 23.691855ppm
2019-07-25T17:18:21.817Z despair ntpd[4423]: adjusting clock frequency by 0.166593 to 23.873008ppm
2019-07-27T13:10:51.631Z despair ntpd[4423]: adjusting clock frequency by -0.286794 to 23.645483ppm
2019-07-29T18:41:40.661Z despair ntpd[4423]: adjusting clock frequency by -0.119201 to 23.538875ppm
2019-07-31T08:24:55.193Z despair ntpd[4423]: adjusting clock frequency by 0.076883 to 23.613537ppm
2019-08-03T21:49:00.887Z despair ntpd[4423]: adjusting clock frequency by -0.087799 to 23.482575ppm
2019-08-05T07:00:30.876Z despair ntpd[4423]: adjusting clock frequency by 0.112613 to 23.555395ppm
2019-08-06T18:38:26.608Z despair ntpd[4423]: adjusting clock frequency by -0.050417 to 23.502452ppm
2019-08-09T20:36:46.196Z despair ntpd[4423]: adjusting clock frequency by 0.127462 to 23.563129ppm
2019-08-11T03:28:06.672Z despair ntpd[4423]: adjusting clock frequency by -0.077703 to 23.463375ppm
2019-08-11T19:31:23.913Z despair ntpd[4423]: adjusting clock frequency by -0.085988 to 23.377387ppm
2019-08-13T04:19:42.156Z despair ntpd[4423]: adjusting clock frequency by -0.059446 to 23.354720ppm
2019-08-13T22:10:22.652Z despair ntpd[4423]: adjusting clock frequency by -0.064262 to 23.290459ppm
2019-08-14T10:58:21.963Z despair ntpd[4423]: adjusting clock frequency by -0.060770 to 23.229689ppm
2019-08-15T02:44:29.379Z despair ntpd[4423]: adjusting clock frequency by 0.071201 to 23.300890ppm
2019-08-15T15:43:56.059Z despair ntpd[4423]: adjusting clock frequency by 0.116211 to 23.417102ppm
2019-08-16T20:30:26.591Z despair ntpd[4423]: adjusting clock frequency by -0.053176 to 23.316990ppm
2019-08-17T14:27:43.171Z despair ntpd[4423]: adjusting clock frequency by 0.118529 to 23.435519ppm
2019-08-21T18:18:41.158Z despair ntpd[4423]: adjusting clock frequency by -0.089037 to 23.248734ppm
2019-08-23T23:31:43.436Z despair ntpd[4423]: adjusting clock frequency by 0.126937 to 23.353953ppm
2019-08-27T14:05:06.045Z despair ntpd[4423]: adjusting clock frequency by 0.093292 to 23.568652ppm
2019-08-28T03:43:32.514Z despair ntpd[4423]: adjusting clock frequency by 0.151584 to 23.720236ppm
2019-08-28T22:11:22.579Z despair ntpd[4423]: adjusting clock frequency by -0.095816 to 23.624420ppm
2019-08-29T15:59:05.130Z despair ntpd[4423]: adjusting clock frequency by -0.101126 to 23.523293ppm
2019-08-30T10:46:57.624Z despair ntpd[4423]: adjusting clock frequency by -0.117308 to 23.405985ppm
2019-09-01T17:09:01.659Z despair ntpd[4423]: adjusting clock frequency by -0.093759 to 23.344053ppm
2019-09-02T09:26:33.080Z despair ntpd[4423]: adjusting clock frequency by -0.112101 to 23.231952ppm
2019-09-03T18:46:42.450Z despair ntpd[4423]: adjusting clock frequency by 0.059225 to 23.298971ppm
2019-09-04T05:38:00.448Z despair ntpd[94384]: adjusting local clock by 0.205606s
2019-09-04T06:01:07.241Z despair ntpd[94384]: adjusting clock frequency by -5.152448 to 18.146552ppm
2019-09-04T06:29:07.910Z despair ntpd[94384]: adjusting clock frequency by 0.102389 to 18.248942ppm
2019-09-05T14:59:32.221Z despair ntpd[94384]: adjusting clock frequency by -0.091441 to 18.128836ppm
2019-09-06T04:23:54.007Z despair ntpd[94384]: adjusting clock frequency by -0.083599 to 18.045237ppm
2019-09-07T12:05:28.785Z despair ntpd[94384]: adjusting clock frequency by 0.105433 to 18.127731ppm
2019-09-08T09:11:28.055Z despair ntpd[94384]: adjusting clock frequency by -0.053777 to 18.073954ppm
2019-09-11T22:30:15.419Z despair ntpd[94384]: adjusting clock frequency by 0.075566 to 18.133313ppm
2019-09-13T06:40:55.682Z despair ntpd[94384]: adjusting clock frequency by 0.068719 to 18.225415ppm
2019-09-13T23:24:40.426Z despair ntpd[94384]: adjusting clock frequency by -0.082615 to 18.142801ppm
2019-09-14T18:54:14.544Z despair ntpd[94384]: adjusting clock frequency by -0.115367 to 18.027434ppm
2019-09-16T02:42:15.613Z despair ntpd[94384]: adjusting clock frequency by 0.098754 to 18.136523ppm
2019-09-17T11:08:03.213Z despair ntpd[94384]: adjusting clock frequency by -0.083464 to 18.024047ppm
2019-09-18T09:01:25.975Z despair ntpd[94384]: adjusting clock frequency by -0.115676 to 17.908371ppm
2019-09-19T20:20:32.346Z despair ntpd[94384]: adjusting clock frequency by 0.084223 to 18.000185ppm
2019-09-23T04:43:42.777Z despair ntpd[94384]: adjusting clock frequency by 0.188180 to 18.173361ppm
2019-09-24T13:30:46.774Z despair ntpd[94384]: adjusting clock frequency by -0.066054 to 18.138848ppm
2019-09-25T04:30:45.225Z despair ntpd[43359]: adjusting local clock by 0.352666s
2019-09-25T04:56:15.078Z despair ntpd[43359]: adjusting clock frequency by 1.813773 to 19.952773ppm
2019-09-25T05:34:04.449Z despair ntpd[43359]: adjusting clock frequency by -0.085726 to 19.845940ppm
2019-09-25T21:01:04.213Z despair ntpd[43359]: adjusting clock frequency by 0.088658 to 19.934599ppm
2019-09-30T01:19:51.791Z despair ntpd[43359]: adjusting clock frequency by 0.071707 to 20.022707ppm
2019-09-30T16:41:03.701Z despair ntpd[43359]: adjusting clock frequency by -0.092157 to 19.930550ppm
2019-09-30T20:13:34.739Z despair ntpd[57871]: adjusting local clock by 1.172165s
2019-09-30T20:41:01.365Z despair ntpd[57871]: adjusting clock frequency by -0.876624 to 19.054376ppm
2019-09-30T21:22:33.036Z despair ntpd[57871]: adjusting clock frequency by -0.100501 to 18.980951ppm
2019-10-02T02:55:02.851Z despair ntpd[57871]: adjusting local clock by 2.788555s
2019-10-02T02:55:02.856Z despair ntpd[57871]: adjusting clock frequency by 36.583542 to 55.590682ppm
2019-10-02T03:21:39.721Z despair ntpd[57871]: adjusting local clock by -0.043674s
2019-10-02T06:23:22.228Z despair ntpd[57871]: adjusting local clock by -0.343597s
2019-10-02T06:51:22.766Z despair ntpd[57871]: adjusting local clock by -0.112257s
2019-10-02T07:42:06.567Z despair ntpd[57871]: adjusting local clock by -0.108361s
2019-10-02T07:57:25.794Z despair ntpd[57871]: adjusting clock frequency by -38.265676 to 17.325006ppm
2019-10-02T12:45:02.143Z despair ntpd[57871]: adjusting clock frequency by 1.183919 to 18.508925ppm
2019-10-03T02:18:06.172Z despair ntpd[57871]: adjusting local clock by 2.792710s
2019-10-03T02:18:06.347Z despair ntpd[57871]: adjusting clock frequency by 48.286290 to 66.795215ppm
2019-10-03T02:46:26.658Z despair ntpd[57871]: adjusting local clock by -0.056351s
2019-10-03T03:37:45.273Z despair ntpd[57871]: adjusting local clock by -0.135864s
2019-10-03T04:49:06.489Z despair ntpd[57871]: adjusting local clock by -0.092314s
2019-10-03T04:59:25.046Z despair ntpd[57871]: adjusting local clock by -0.119596s
2019-10-03T04:59:25.214Z despair ntpd[57871]: adjusting clock frequency by -44.208503 to 22.586712ppm
2019-10-03T08:24:03.399Z despair ntpd[57871]: adjusting clock frequency by -3.795810 to 18.790902ppm
2019-10-04T03:14:01.960Z despair ntpd[57871]: adjusting local clock by 2.789228s
2019-10-04T04:45:04.116Z despair ntpd[57871]: adjusting clock frequency by 102.377293 to 121.183234ppm
2019-10-04T07:52:33.373Z despair ntpd[57871]: adjusting local clock by -0.499062s
2019-10-04T08:20:57.285Z despair ntpd[57871]: adjusting local clock by -0.808530s
2019-10-04T08:23:02.175Z despair ntpd[57871]: adjusting local clock by -0.205615s
2019-10-04T08:41:40.943Z despair ntpd[57871]: adjusting local clock by -0.035396s
2019-10-04T08:46:30.420Z despair ntpd[57871]: adjusting local clock by -0.073398s
2019-10-04T09:09:59.893Z despair ntpd[57871]: adjusting local clock by -0.071734s
2019-10-04T09:14:17.342Z despair ntpd[57871]: adjusting local clock by -0.049199s
2019-10-04T09:37:59.650Z despair ntpd[57871]: adjusting local clock by -0.080466s
2019-10-04T09:42:53.759Z despair ntpd[57871]: adjusting local clock by -0.092445s
2019-10-04T10:02:56.474Z despair ntpd[57871]: adjusting local clock by -0.050431s
2019-10-04T10:05:34.493Z despair ntpd[57871]: adjusting local clock by -0.036164s
2019-10-04T10:05:34.494Z despair ntpd[57871]: adjusting clock frequency by -103.872057 to 17.310819ppm
2019-10-04T16:50:38.042Z despair ntpd[57871]: adjusting clock frequency by 1.497635 to 18.808454ppm
2019-10-05T03:54:15.316Z despair ntpd[57871]: adjusting local clock by 3.788213s
2019-10-05T04:21:03.169Z despair ntpd[57871]: adjusting clock frequency by 94.503664 to 113.312118ppm
2019-10-05T06:07:11.146Z despair ntpd[57871]: adjusting local clock by -0.182783s
2019-10-05T06:35:44.844Z despair ntpd[57871]: adjusting local clock by -0.565035s
2019-10-05T06:58:52.486Z despair ntpd[57871]: adjusting local clock by -0.058713s
2019-10-05T07:53:34.424Z despair ntpd[57871]: adjusting local clock by -0.307457s
2019-10-05T07:53:34.429Z despair ntpd[57871]: adjusting clock frequency by -138.365051 to -25.052934ppm
2019-10-05T09:50:45.414Z despair ntpd[57871]: adjusting local clock by 0.056762s
2019-10-05T10:09:41.959Z despair ntpd[57871]: adjusting local clock by 0.286678s
2019-10-05T11:11:20.479Z despair ntpd[57871]: adjusting local clock by 0.118117s
2019-10-05T11:11:20.480Z despair ntpd[57871]: adjusting clock frequency by 40.033737 to 14.980803ppm
2019-10-05T11:20:40.812Z despair ntpd[57871]: adjusting local clock by 0.039287s
2019-10-05T14:53:27.414Z despair ntpd[57871]: adjusting local clock by 0.039958s
2019-10-05T19:58:04.385Z despair ntpd[57871]: adjusting local clock by 0.050255s
2019-10-05T19:58:04.387Z despair ntpd[57871]: adjusting clock frequency by 3.255988 to 18.236791ppm
2019-10-06T01:30:47.572Z despair ntpd[57871]: adjusting local clock by 2.794070s
2019-10-06T04:39:33.633Z despair ntpd[57871]: adjusting clock frequency by 119.060064 to 137.296855ppm
2019-10-06T05:58:46.269Z despair ntpd[57871]: adjusting local clock by -0.467724s
2019-10-06T06:10:42.387Z despair ntpd[57871]: adjusting local clock by -0.154828s
2019-10-06T06:44:52.099Z despair ntpd[57871]: adjusting local clock by -0.096887s
2019-10-06T06:51:19.663Z despair ntpd[57871]: adjusting local clock by -0.170391s
2019-10-06T06:57:14.834Z despair ntpd[57871]: adjusting clock frequency by -123.595863 to 13.700992ppm
2019-10-06T10:57:35.226Z despair ntpd[57871]: adjusting local clock by 0.034371s
2019-10-06T13:15:47.886Z despair ntpd[57871]: adjusting clock frequency by 5.098349 to 18.799341ppm
2019-10-07T02:46:01.914Z despair ntpd[57871]: adjusting local clock by 0.999016s
2019-10-07T03:12:18.907Z despair ntpd[57871]: adjusting clock frequency by 17.748534 to 36.547875ppm
2019-10-07T04:14:25.198Z despair ntpd[57871]: adjusting local clock by -0.040082s
2019-10-07T04:52:53.256Z despair ntpd[57871]: adjusting local clock by -0.032568s
2019-10-07T05:31:48.597Z despair ntpd[57871]: adjusting clock frequency by -18.958049 to 17.589826ppm
2019-10-07T13:52:36.177Z despair ntpd[57871]: adjusting clock frequency by 0.935717 to 18.525543ppm
2019-10-08T01:58:43.306Z despair ntpd[57871]: adjusting clock frequency by 0.175944 to 18.701487ppm
2019-10-08T14:32:44.207Z despair ntpd[57871]: adjusting clock frequency by 0.119754 to 18.821241ppm
2019-10-09T02:02:36.600Z despair ntpd[57871]: adjusting local clock by 3.789589s
2019-10-09T02:36:55.508Z despair ntpd[57871]: adjusting clock frequency by 94.744753 to 113.565994ppm
2019-10-09T03:59:17.500Z despair ntpd[57871]: adjusting local clock by -0.149111s
2019-10-09T05:48:41.247Z despair ntpd[57871]: adjusting local clock by -0.686201s
2019-10-09T06:14:51.915Z despair ntpd[57871]: adjusting local clock by -0.387029s
2019-10-09T07:12:14.922Z despair ntpd[57871]: adjusting clock frequency by -114.822091 to -1.256097ppm
2019-10-09T07:53:09.381Z despair ntpd[57871]: adjusting local clock by -0.277260s
2019-10-09T09:25:22.569Z despair ntpd[57871]: adjusting clock frequency by 20.188146 to 18.932049ppm
2019-10-09T22:55:34.460Z despair ntpd[57871]: adjusting clock frequency by -0.131469 to 18.800580ppm
2019-10-11T05:47:46.703Z despair ntpd[57871]: adjusting local clock by 0.999576s
2019-10-11T06:47:57.798Z despair ntpd[57871]: adjusting clock frequency by 20.812371 to 39.596874ppm
2019-10-11T09:53:35.732Z despair ntpd[57871]: adjusting local clock by -0.072182s
2019-10-11T10:24:21.421Z despair ntpd[57871]: adjusting local clock by -0.195521s
2019-10-11T11:18:08.855Z despair ntpd[57871]: adjusting local clock by -0.043196s
2019-10-11T11:29:34.448Z despair ntpd[57871]: adjusting local clock by -0.032144s
2019-10-11T12:57:34.281Z despair ntpd[57871]: adjusting clock frequency by -19.010743 to 20.586131ppm
2019-10-11T13:46:34.099Z despair ntpd[57871]: adjusting local clock by -0.110462s
2019-10-11T17:56:50.100Z despair ntpd[57871]: adjusting clock frequency by -1.560165 to 19.025967ppm
2019-10-12T02:41:40.055Z despair ntpd[57871]: adjusting local clock by 2.788821s
2019-10-12T06:21:25.786Z despair ntpd[57871]: adjusting clock frequency by 83.894350 to 102.920317ppm
2019-10-12T08:21:59.739Z despair ntpd[57871]: adjusting local clock by -0.329220s
2019-10-12T08:38:07.627Z despair ntpd[57871]: adjusting local clock by -0.349950s
2019-10-12T08:56:11.533Z despair ntpd[57871]: adjusting local clock by -0.052764s
2019-10-12T09:01:21.042Z despair ntpd[57871]: adjusting local clock by -0.049049s
2019-10-12T09:55:20.274Z despair ntpd[57871]: adjusting local clock by -0.268308s
2019-10-12T10:49:20.629Z despair ntpd[57871]: adjusting local clock by -0.270500s
2019-10-12T10:50:25.114Z despair ntpd[57871]: adjusting clock frequency by -94.613470 to 8.306847ppm
2019-10-12T14:24:29.285Z despair ntpd[57871]: adjusting local clock by 0.067002s
2019-10-12T14:43:53.543Z despair ntpd[57871]: adjusting local clock by 0.045560s
2019-10-12T14:46:05.271Z despair ntpd[57871]: adjusting clock frequency by 10.309420 to 18.616266ppm
2019-10-13T02:22:31.650Z despair ntpd[57871]: adjusting local clock by 4.580235s
2019-10-13T02:22:31.655Z despair ntpd[57871]: adjusting clock frequency by 69.049537 to 87.665804ppm
2019-10-13T02:50:01.208Z despair ntpd[57871]: adjusting local clock by -0.083349s
2019-10-13T03:20:09.347Z despair ntpd[57871]: adjusting local clock by -0.034135s
2019-10-13T03:25:28.761Z despair ntpd[57871]: adjusting local clock by -0.068644s
2019-10-13T03:33:48.650Z despair ntpd[57871]: adjusting clock frequency by -62.999290 to 24.666513ppm
2019-10-13T03:55:14.969Z despair ntpd[57871]: adjusting local clock by -0.034093s
2019-10-13T06:49:12.093Z despair ntpd[57871]: adjusting local clock by -0.046964s
2019-10-13T08:17:01.107Z despair ntpd[57871]: adjusting clock frequency by -5.181217 to 19.485297ppm
2019-10-13T14:28:08.731Z despair ntpd[57871]: adjusting clock frequency by -0.496189 to 18.989107ppm
2019-10-14T04:28:23.058Z despair ntpd[57871]: adjusting local clock by 3.787977s
2019-10-14T04:57:20.328Z despair ntpd[57871]: adjusting clock frequency by 72.400713 to 91.389821ppm
2019-10-14T07:12:50.239Z despair ntpd[57871]: adjusting local clock by -0.098134s
2019-10-14T07:41:09.385Z despair ntpd[57871]: adjusting local clock by -0.588457s
2019-10-14T08:33:45.309Z despair ntpd[57871]: adjusting local clock by -0.217582s
2019-10-14T08:38:58.934Z despair ntpd[57871]: adjusting clock frequency by -75.344468 to 16.045353ppm
2019-10-14T12:36:41.001Z despair ntpd[57871]: adjusting clock frequency by 1.835204 to 17.880557ppm
2019-10-15T00:01:24.837Z despair ntpd[57871]: adjusting clock frequency by 1.053149 to 18.933706ppm
2019-10-15T00:45:41.522Z despair ntpd[57871]: adjusting local clock by 5.583228s
2019-10-15T00:50:33.245Z despair ntpd[57871]: adjusting local clock by 4.130414s
2019-10-15T00:53:40.823Z despair ntpd[57871]: adjusting local clock by 3.199848s
2019-10-15T00:54:44.551Z despair ntpd[57871]: adjusting local clock by 2.879703s
2019-10-15T00:57:28.851Z despair ntpd[57871]: adjusting local clock by 2.065175s
2019-10-15T01:00:10.759Z despair ntpd[57871]: adjusting local clock by 1.259297s
2019-10-15T01:02:52.858Z despair ntpd[57871]: adjusting local clock by 0.454847s
2019-10-16T03:30:04.113Z despair ntpd[57871]: adjusting local clock by 2.789336s
2019-10-16T10:09:45.896Z despair ntpd[57871]: adjusting clock frequency by 76.823298 to 95.758483ppm
2019-10-16T15:27:16.716Z despair ntpd[57871]: adjusting local clock by -0.999114s
2019-10-16T15:55:26.407Z despair ntpd[57871]: adjusting local clock by -0.582795s
2019-10-16T16:18:03.518Z despair ntpd[57871]: adjusting local clock by -0.041548s
2019-10-16T16:42:12.546Z despair ntpd[57871]: adjusting local clock by -0.089354s
2019-10-16T16:42:12.550Z despair ntpd[57871]: adjusting clock frequency by -105.147770 to -9.389287ppm
2019-10-16T18:24:34.666Z despair ntpd[57871]: adjusting local clock by 0.052193s
2019-10-16T18:57:13.898Z despair ntpd[57871]: adjusting clock frequency by 27.915958 to 18.526671ppm
2019-10-17T01:36:03.785Z despair ntpd[57871]: adjusting local clock by 2.790805s
2019-10-17T01:41:19.834Z despair ntpd[57871]: adjusting local clock by 1.216983s
2019-10-17T01:43:32.234Z despair ntpd[57871]: adjusting local clock by 0.558525s
2019-10-17T22:25:55.671Z despair ntpd[57871]: adjusting clock frequency by 0.314919 to 18.842122ppm
2019-10-18T04:22:05.812Z despair ntpd[57871]: adjusting local clock by 2.789475s
2019-10-18T10:44:51.237Z despair ntpd[57871]: adjusting clock frequency by 82.025367 to 100.867489ppm
2019-10-18T13:28:08.359Z despair ntpd[57871]: adjusting local clock by -0.132360s
2019-10-18T13:53:44.959Z despair ntpd[57871]: adjusting local clock by -0.785914s
2019-10-18T15:30:23.307Z despair ntpd[57871]: adjusting local clock by -0.091146s
2019-10-18T15:46:23.551Z despair ntpd[57871]: adjusting local clock by -0.436075s
2019-10-18T15:58:01.816Z despair ntpd[57871]: adjusting clock frequency by -102.407866 to -1.540377ppm
2019-10-18T17:54:52.535Z despair ntpd[57871]: adjusting local clock by 0.099486s
2019-10-18T18:08:43.867Z despair ntpd[57871]: adjusting local clock by 0.052751s
2019-10-18T19:36:56.576Z despair ntpd[57871]: adjusting clock frequency by 15.916700 to 14.376323ppm
2019-10-18T22:08:38.521Z despair ntpd[57871]: adjusting local clock by 0.122039s
2019-10-19T01:46:59.030Z despair ntpd[57871]: adjusting local clock by 2.828176s
2019-10-19T01:52:52.306Z despair ntpd[57871]: adjusting local clock by 1.079976s
2019-10-19T01:53:57.631Z despair ntpd[57871]: adjusting local clock by 0.756297s
2019-10-19T05:57:52.832Z despair ntpd[57871]: adjusting local clock by 0.046475s
2019-10-19T14:58:42.925Z despair ntpd[57871]: adjusting clock frequency by 4.611179 to 18.988087ppm
2019-10-20T04:42:16.121Z despair ntpd[57871]: adjusting clock frequency by -0.193726 to 18.794361ppm
2019-10-21T02:03:50.143Z despair ntpd[57871]: adjusting local clock by 5.579152s
2019-10-21T10:32:34.747Z despair ntpd[57871]: adjusting clock frequency by 117.968629 to 136.718342ppm
2019-10-21T14:02:35.440Z despair ntpd[57871]: adjusting local clock by -0.757493s
2019-10-21T14:33:22.662Z despair ntpd[57871]: adjusting local clock by -0.932076s
2019-10-21T14:35:29.696Z despair ntpd[57871]: adjusting local clock by -0.300248s
2019-10-21T17:25:23.011Z despair ntpd[57871]: adjusting local clock by -0.805754s
2019-10-21T17:56:13.662Z despair ntpd[57871]: adjusting local clock by -0.582839s
2019-10-21T17:56:47.878Z despair ntpd[57871]: adjusting local clock by -0.423582s
2019-10-21T18:14:55.767Z despair ntpd[57871]: adjusting local clock by -0.049582s
2019-10-21T18:18:31.799Z despair ntpd[57871]: adjusting local clock by -0.074613s
2019-10-21T18:32:15.401Z despair ntpd[57871]: adjusting local clock by -0.053776s
2019-10-21T18:36:59.076Z despair ntpd[57871]: adjusting local clock by -0.041973s
2019-10-21T18:58:34.313Z despair ntpd[57871]: adjusting local clock by -0.075649s
2019-10-21T19:04:26.838Z despair ntpd[57871]: adjusting local clock by -0.080840s
2019-10-21T19:16:56.656Z despair ntpd[57871]: adjusting local clock by -0.032014s
2019-10-21T20:11:31.348Z despair ntpd[57871]: adjusting local clock by -0.404448s
2019-10-21T20:12:04.540Z despair ntpd[57871]: adjusting local clock by -0.239447s
2019-10-21T20:12:04.544Z despair ntpd[57871]: adjusting clock frequency by -121.277487 to 15.440156ppm
2019-10-22T03:36:49.195Z despair ntpd[57871]: adjusting clock frequency by 3.314512 to 18.754668ppm

Reply | Threaded
Open this post in threaded view
|

Re: change in ntpd behavior

Otto Moerbeek
On Wed, Oct 23, 2019 at 11:17:02AM +0200, Paul de Weerd wrote:

> Hi Otto,
>
> On Wed, Oct 23, 2019 at 10:58:24AM +0200, Otto Moerbeek wrote:
> | I could * this has to do with the logging changes. Previously, debug
> | messages could disappear because of wrong logging setup. In non -v
> | mode, only big adjustments are logged. Sadly you stripped those
> | numbers away so i cannot tell how big the adjustments are.
>
> The recent adjustments that happen during runtime are bigger than what
> was logged previously at boot:
>
> 2019-06-07T13:15:53.341Z despair ntpd[4423]: adjusting local clock by -0.068174s
> 2019-09-04T05:38:00.448Z despair ntpd[94384]: adjusting local clock by 0.205606s
> 2019-09-25T04:30:45.225Z despair ntpd[43359]: adjusting local clock by 0.352666s
> 2019-09-30T20:13:34.739Z despair ntpd[57871]: adjusting local clock by 1.172165s
> 2019-10-02T02:55:02.851Z despair ntpd[57871]: adjusting local clock by 2.788555s
> 2019-10-02T03:21:39.721Z despair ntpd[57871]: adjusting local clock by -0.043674s
> 2019-10-02T06:23:22.228Z despair ntpd[57871]: adjusting local clock by -0.343597s
> 2019-10-02T06:51:22.766Z despair ntpd[57871]: adjusting local clock by -0.112257s
> 2019-10-02T07:42:06.567Z despair ntpd[57871]: adjusting local clock by -0.108361s
> 2019-10-03T02:18:06.172Z despair ntpd[57871]: adjusting local clock by 2.792710s
> 2019-10-03T02:46:26.658Z despair ntpd[57871]: adjusting local clock by -0.056351s
> 2019-10-03T03:37:45.273Z despair ntpd[57871]: adjusting local clock by -0.135864s
> 2019-10-03T04:49:06.489Z despair ntpd[57871]: adjusting local clock by -0.092314s
> 2019-10-03T04:59:25.046Z despair ntpd[57871]: adjusting local clock by -0.119596s
> 2019-10-04T03:14:01.960Z despair ntpd[57871]: adjusting local clock by 2.789228s
>
> Below is a bit more history (from early January this year).
>
> The wildly varying clock frequency is the reason I'm suspecting
> hardware failure...

Yeah pretty wild. This is a machine that is permanently on?

        -Otto

>
> Thanks,
>
> Paul
>
> --
> >++++++++[<++++++++++>-]<+++++++.>+++[<------>-]<.>+++[<+
> +++++++++++>-]<.>++[<------------>-]<+.--------------.[-]
>                  http://www.weirdnet.nl/                 
>
>
>
> 2019-01-08T06:45:31.782Z despair ntpd[83741]: adjusting clock frequency by 0.113893 to 22.810188ppm
> 2019-01-08T20:50:38.833Z despair ntpd[83741]: adjusting clock frequency by -0.052936 to 22.757252ppm
> 2019-01-11T18:16:43.213Z despair ntpd[83741]: adjusting clock frequency by 0.070955 to 22.765158ppm
> 2019-01-14T16:32:01.570Z despair ntpd[83741]: adjusting clock frequency by -0.079218 to 22.772022ppm
> 2019-01-16T07:25:40.735Z despair ntpd[83741]: adjusting clock frequency by 0.061495 to 22.784900ppm
> 2019-01-18T00:46:17.217Z despair ntpd[83741]: adjusting clock frequency by -0.067692 to 22.715564ppm
> 2019-01-18T22:48:55.795Z despair ntpd[83741]: adjusting clock frequency by -0.087077 to 22.628487ppm
> 2019-01-26T13:11:05.776Z despair ntpd[83741]: adjusting clock frequency by 0.111245 to 22.680715ppm
> 2019-01-28T22:59:57.151Z despair ntpd[83741]: adjusting clock frequency by -0.054828 to 22.649700ppm
> 2019-01-29T16:24:24.050Z despair ntpd[83741]: adjusting clock frequency by -0.061384 to 22.588317ppm
> 2019-02-07T06:37:27.499Z despair ntpd[83741]: adjusting clock frequency by 0.096997 to 22.657004ppm
> 2019-02-09T13:37:37.168Z despair ntpd[83741]: adjusting clock frequency by 0.051171 to 22.704285ppm
> 2019-02-11T10:11:48.668Z despair ntpd[83741]: adjusting clock frequency by -0.061547 to 22.624355ppm
> 2019-02-12T02:40:36.043Z despair ntpd[83741]: adjusting clock frequency by -0.074976 to 22.549378ppm
> 2019-02-19T07:57:12.596Z despair ntpd[83741]: adjusting clock frequency by 0.076256 to 22.614877ppm
> 2019-02-20T20:33:45.286Z despair ntpd[83741]: adjusting clock frequency by -0.072584 to 22.540745ppm
> 2019-02-22T04:58:58.612Z despair ntpd[83741]: adjusting clock frequency by 0.075267 to 22.620051ppm
> 2019-02-24T12:53:13.053Z despair ntpd[83741]: adjusting clock frequency by -0.071152 to 22.482398ppm
> 2019-02-27T06:50:35.783Z despair ntpd[83741]: adjusting clock frequency by 0.060008 to 22.504098ppm
> 2019-02-28T23:36:38.467Z despair ntpd[83741]: adjusting clock frequency by 0.064345 to 22.579627ppm
> 2019-03-02T12:59:39.951Z despair ntpd[79657]: adjusting local clock by 0.239351s
> 2019-03-02T13:00:10.725Z despair ntpd[79657]: adjusting local clock by 0.084351s
> 2019-03-02T13:11:56.028Z despair ntpd[30514]: adjusting local clock by 0.757522s
> 2019-03-02T13:36:39.058Z despair ntpd[30514]: adjusting clock frequency by -1.011747 to 21.548253ppm
> 2019-03-02T13:59:40.575Z despair ntpd[30514]: adjusting clock frequency by 0.076373 to 21.624625ppm
> 2019-03-02T14:19:51.514Z despair ntpd[30514]: adjusting clock frequency by -0.096260 to 21.528365ppm
> 2019-03-03T05:30:40.004Z despair ntpd[30514]: adjusting clock frequency by 0.119092 to 21.647457ppm
> 2019-03-03T23:04:41.902Z despair ntpd[30514]: adjusting clock frequency by 0.103132 to 21.750589ppm
> 2019-03-05T06:03:11.928Z despair ntpd[30514]: adjusting clock frequency by -0.133629 to 21.595439ppm
> 2019-03-07T01:36:00.175Z despair ntpd[30514]: adjusting clock frequency by 0.051264 to 21.662972ppm
> 2019-03-08T12:34:41.956Z despair ntpd[30514]: adjusting clock frequency by -0.104027 to 21.598958ppm
> 2019-03-11T09:21:43.495Z despair ntpd[30514]: adjusting clock frequency by -0.050271 to 21.513281ppm
> 2019-03-12T21:59:25.194Z despair ntpd[30514]: adjusting clock frequency by 0.082820 to 21.563817ppm
> 2019-03-17T17:52:36.896Z despair ntpd[30514]: adjusting clock frequency by -0.060276 to 21.581184ppm
> 2019-03-18T10:41:28.898Z despair ntpd[30514]: adjusting clock frequency by -0.090451 to 21.490733ppm
> 2019-03-19T01:08:50.013Z despair ntpd[30514]: adjusting clock frequency by -0.060271 to 21.430461ppm
> 2019-03-24T05:25:08.011Z despair ntpd[30514]: adjusting clock frequency by -0.078510 to 21.473110ppm
> 2019-03-27T08:01:14.108Z despair ntpd[30514]: adjusting clock frequency by 0.050375 to 21.480257ppm
> 2019-04-01T06:42:56.506Z despair ntpd[30514]: adjusting clock frequency by -0.094575 to 21.424325ppm
> 2019-04-02T08:22:06.438Z despair ntpd[38581]: adjusting local clock by -0.793199s
> 2019-04-02T08:22:38.907Z despair ntpd[38581]: adjusting local clock by -0.635013s
> 2019-04-02T08:46:06.102Z despair ntpd[38581]: adjusting clock frequency by -0.122341 to 21.268659ppm
> 2019-04-02T09:12:12.744Z despair ntpd[38581]: adjusting clock frequency by -1.829745 to 19.438913ppm
> 2019-04-02T23:01:13.727Z despair ntpd[38581]: adjusting clock frequency by 0.123445 to 19.586482ppm
> 2019-04-05T01:53:47.618Z despair ntpd[38581]: adjusting clock frequency by -0.052237 to 19.516544ppm
> 2019-04-07T13:41:15.176Z despair ntpd[38581]: adjusting clock frequency by 0.087925 to 19.619287ppm
> 2019-04-09T18:48:24.471Z despair ntpd[94340]: adjusting local clock by 0.457236s
> 2019-04-09T19:07:40.027Z despair ntpd[94340]: adjusting clock frequency by -0.722604 to 18.955396ppm
> 2019-04-09T19:28:27.899Z despair ntpd[94340]: adjusting clock frequency by -0.524858 to 18.430538ppm
> 2019-04-10T10:42:47.177Z despair ntpd[94340]: adjusting clock frequency by 0.208084 to 18.623874ppm
> 2019-04-11T03:04:08.785Z despair ntpd[94340]: adjusting clock frequency by -0.064775 to 18.559099ppm
> 2019-04-12T20:27:39.173Z despair ntpd[94340]: adjusting clock frequency by 0.055953 to 18.602639ppm
> 2019-04-13T11:17:25.710Z despair ntpd[94340]: adjusting clock frequency by -0.050935 to 18.551704ppm
> 2019-04-16T08:44:28.425Z despair ntpd[94340]: adjusting clock frequency by 0.076776 to 18.697320ppm
> 2019-04-18T12:25:30.676Z despair ntpd[94340]: adjusting clock frequency by -0.079013 to 18.696495ppm
> 2019-04-19T09:10:21.240Z despair ntpd[94340]: adjusting clock frequency by 0.075817 to 18.772313ppm
> 2019-04-24T07:03:27.155Z despair ntpd[94340]: adjusting clock frequency by 0.096280 to 18.925407ppm
> 2019-04-25T19:16:22.124Z despair ntpd[94340]: adjusting clock frequency by -0.084848 to 18.878067ppm
> 2019-04-26T13:11:26.502Z despair ntpd[94340]: adjusting clock frequency by -0.056613 to 18.821455ppm
> 2019-04-27T23:50:39.299Z despair ntpd[94340]: adjusting clock frequency by -0.064278 to 18.760748ppm
> 2019-05-04T00:20:31.343Z despair ntpd[94340]: adjusting clock frequency by -0.111168 to 18.605360ppm
> 2019-05-09T01:22:56.808Z despair ntpd[94340]: adjusting clock frequency by 0.084542 to 18.634222ppm
> 2019-05-11T09:04:10.285Z despair ntpd[94340]: adjusting clock frequency by -0.064043 to 18.572246ppm
> 2019-05-12T08:44:59.048Z despair ntpd[94340]: adjusting clock frequency by -0.097073 to 18.475173ppm
> 2019-05-17T09:58:01.436Z despair ntpd[94340]: adjusting clock frequency by 0.056900 to 18.578899ppm
> 2019-05-18T22:04:18.509Z despair ntpd[94340]: adjusting clock frequency by 0.104781 to 18.703822ppm
> 2019-05-22T05:52:09.440Z despair ntpd[94340]: adjusting clock frequency by -0.065212 to 18.688099ppm
> 2019-05-26T23:02:35.939Z despair ntpd[94340]: adjusting clock frequency by 0.084034 to 18.767830ppm
> 2019-05-29T00:13:01.803Z despair ntpd[94340]: adjusting clock frequency by -0.102652 to 18.625510ppm
> 2019-05-30T05:17:10.440Z despair ntpd[94340]: adjusting clock frequency by 0.104181 to 18.688675ppm
> 2019-05-30T21:29:48.730Z despair ntpd[94340]: adjusting clock frequency by 0.068342 to 18.757017ppm
> 2019-06-01T21:40:02.441Z despair ntpd[94340]: adjusting clock frequency by 0.051014 to 18.846626ppm
> 2019-06-02T14:38:41.341Z despair ntpd[94340]: adjusting clock frequency by 0.093861 to 18.940487ppm
> 2019-06-03T23:32:31.042Z despair ntpd[94340]: adjusting clock frequency by -0.057903 to 18.869288ppm
> 2019-06-04T18:24:19.540Z despair ntpd[94340]: adjusting clock frequency by -0.073277 to 18.796011ppm
> 2019-06-05T11:48:57.184Z despair ntpd[94340]: adjusting clock frequency by 0.142221 to 18.938232ppm
> 2019-06-06T20:38:17.627Z despair ntpd[94340]: adjusting clock frequency by -0.093133 to 18.796535ppm
> 2019-06-07T13:15:53.341Z despair ntpd[4423]: adjusting local clock by -0.068174s
> 2019-06-07T13:31:00.310Z despair ntpd[4423]: adjusting clock frequency by 4.502033 to 23.253033ppm
> 2019-06-07T13:51:07.915Z despair ntpd[4423]: adjusting clock frequency by 0.481906 to 23.734939ppm
> 2019-06-07T14:08:52.308Z despair ntpd[4423]: adjusting clock frequency by -0.395832 to 23.339107ppm
> 2019-06-08T06:21:55.284Z despair ntpd[4423]: adjusting clock frequency by 0.125060 to 23.464167ppm
> 2019-06-08T22:43:36.320Z despair ntpd[4423]: adjusting clock frequency by -0.083238 to 23.380928ppm
> 2019-06-10T15:29:18.521Z despair ntpd[4423]: adjusting clock frequency by 0.087945 to 23.459801ppm
> 2019-06-11T06:54:13.578Z despair ntpd[4423]: adjusting clock frequency by 0.099642 to 23.559443ppm
> 2019-06-11T22:06:51.660Z despair ntpd[4423]: adjusting clock frequency by -0.074874 to 23.484569ppm
> 2019-06-12T17:04:27.307Z despair ntpd[4423]: adjusting clock frequency by -0.069184 to 23.415385ppm
> 2019-06-19T10:55:38.025Z despair ntpd[4423]: adjusting clock frequency by 0.117077 to 23.610477ppm
> 2019-06-20T22:02:53.300Z despair ntpd[4423]: adjusting clock frequency by -0.071049 to 23.536990ppm
> 2019-06-22T10:44:40.165Z despair ntpd[4423]: adjusting clock frequency by -0.116509 to 23.387096ppm
> 2019-06-22T22:07:35.286Z despair ntpd[4423]: adjusting clock frequency by 0.055410 to 23.442507ppm
> 2019-06-23T15:33:17.850Z despair ntpd[4423]: adjusting clock frequency by 0.112113 to 23.554620ppm
> 2019-06-24T07:54:29.859Z despair ntpd[4423]: adjusting clock frequency by 0.088857 to 23.643476ppm
> 2019-06-24T21:33:02.301Z despair ntpd[4423]: adjusting clock frequency by 0.136506 to 23.779983ppm
> 2019-06-25T21:31:52.276Z despair ntpd[4423]: adjusting clock frequency by 0.417633 to 24.148130ppm
> 2019-06-26T08:44:56.996Z despair ntpd[4423]: adjusting clock frequency by -0.464036 to 23.684094ppm
> 2019-06-27T00:44:14.652Z despair ntpd[4423]: adjusting clock frequency by -0.092340 to 23.591754ppm
> 2019-06-27T13:35:55.606Z despair ntpd[4423]: adjusting clock frequency by -0.150729 to 23.441026ppm
> 2019-06-30T10:17:13.638Z despair ntpd[4423]: adjusting clock frequency by 0.068768 to 23.625579ppm
> 2019-07-01T19:20:24.091Z despair ntpd[4423]: adjusting clock frequency by -0.089585 to 23.523883ppm
> 2019-07-02T10:02:12.764Z despair ntpd[4423]: adjusting clock frequency by -0.071570 to 23.452312ppm
> 2019-07-03T17:59:03.910Z despair ntpd[4423]: adjusting clock frequency by -0.055021 to 23.362962ppm
> 2019-07-04T08:20:51.532Z despair ntpd[4423]: adjusting clock frequency by -0.055255 to 23.307707ppm
> 2019-07-05T22:36:13.246Z despair ntpd[4423]: adjusting clock frequency by 0.091018 to 23.437977ppm
> 2019-07-08T03:01:52.591Z despair ntpd[4423]: adjusting clock frequency by -0.088208 to 23.318931ppm
> 2019-07-08T20:48:55.856Z despair ntpd[4423]: adjusting clock frequency by -0.060261 to 23.258669ppm
> 2019-07-10T05:22:18.085Z despair ntpd[4423]: adjusting clock frequency by 0.058767 to 23.283927ppm
> 2019-07-11T17:55:18.349Z despair ntpd[4423]: adjusting clock frequency by 0.140533 to 23.446317ppm
> 2019-07-13T22:37:21.408Z despair ntpd[4423]: adjusting clock frequency by -0.051781 to 23.431555ppm
> 2019-07-14T15:35:49.005Z despair ntpd[4423]: adjusting clock frequency by -0.085285 to 23.346270ppm
> 2019-07-17T16:31:46.191Z despair ntpd[4423]: adjusting clock frequency by 0.056869 to 23.387482ppm
> 2019-07-18T03:52:31.720Z despair ntpd[4423]: adjusting clock frequency by 0.054937 to 23.442418ppm
> 2019-07-21T00:43:58.919Z despair ntpd[4423]: adjusting clock frequency by 0.077154 to 23.583708ppm
> 2019-07-21T16:58:25.689Z despair ntpd[4423]: adjusting clock frequency by -0.133399 to 23.450309ppm
> 2019-07-23T05:30:32.645Z despair ntpd[4423]: adjusting clock frequency by 0.101089 to 23.506079ppm
> 2019-07-23T22:50:13.596Z despair ntpd[4423]: adjusting clock frequency by 0.089860 to 23.595938ppm
> 2019-07-24T13:40:54.562Z despair ntpd[4423]: adjusting clock frequency by 0.095916 to 23.691855ppm
> 2019-07-25T17:18:21.817Z despair ntpd[4423]: adjusting clock frequency by 0.166593 to 23.873008ppm
> 2019-07-27T13:10:51.631Z despair ntpd[4423]: adjusting clock frequency by -0.286794 to 23.645483ppm
> 2019-07-29T18:41:40.661Z despair ntpd[4423]: adjusting clock frequency by -0.119201 to 23.538875ppm
> 2019-07-31T08:24:55.193Z despair ntpd[4423]: adjusting clock frequency by 0.076883 to 23.613537ppm
> 2019-08-03T21:49:00.887Z despair ntpd[4423]: adjusting clock frequency by -0.087799 to 23.482575ppm
> 2019-08-05T07:00:30.876Z despair ntpd[4423]: adjusting clock frequency by 0.112613 to 23.555395ppm
> 2019-08-06T18:38:26.608Z despair ntpd[4423]: adjusting clock frequency by -0.050417 to 23.502452ppm
> 2019-08-09T20:36:46.196Z despair ntpd[4423]: adjusting clock frequency by 0.127462 to 23.563129ppm
> 2019-08-11T03:28:06.672Z despair ntpd[4423]: adjusting clock frequency by -0.077703 to 23.463375ppm
> 2019-08-11T19:31:23.913Z despair ntpd[4423]: adjusting clock frequency by -0.085988 to 23.377387ppm
> 2019-08-13T04:19:42.156Z despair ntpd[4423]: adjusting clock frequency by -0.059446 to 23.354720ppm
> 2019-08-13T22:10:22.652Z despair ntpd[4423]: adjusting clock frequency by -0.064262 to 23.290459ppm
> 2019-08-14T10:58:21.963Z despair ntpd[4423]: adjusting clock frequency by -0.060770 to 23.229689ppm
> 2019-08-15T02:44:29.379Z despair ntpd[4423]: adjusting clock frequency by 0.071201 to 23.300890ppm
> 2019-08-15T15:43:56.059Z despair ntpd[4423]: adjusting clock frequency by 0.116211 to 23.417102ppm
> 2019-08-16T20:30:26.591Z despair ntpd[4423]: adjusting clock frequency by -0.053176 to 23.316990ppm
> 2019-08-17T14:27:43.171Z despair ntpd[4423]: adjusting clock frequency by 0.118529 to 23.435519ppm
> 2019-08-21T18:18:41.158Z despair ntpd[4423]: adjusting clock frequency by -0.089037 to 23.248734ppm
> 2019-08-23T23:31:43.436Z despair ntpd[4423]: adjusting clock frequency by 0.126937 to 23.353953ppm
> 2019-08-27T14:05:06.045Z despair ntpd[4423]: adjusting clock frequency by 0.093292 to 23.568652ppm
> 2019-08-28T03:43:32.514Z despair ntpd[4423]: adjusting clock frequency by 0.151584 to 23.720236ppm
> 2019-08-28T22:11:22.579Z despair ntpd[4423]: adjusting clock frequency by -0.095816 to 23.624420ppm
> 2019-08-29T15:59:05.130Z despair ntpd[4423]: adjusting clock frequency by -0.101126 to 23.523293ppm
> 2019-08-30T10:46:57.624Z despair ntpd[4423]: adjusting clock frequency by -0.117308 to 23.405985ppm
> 2019-09-01T17:09:01.659Z despair ntpd[4423]: adjusting clock frequency by -0.093759 to 23.344053ppm
> 2019-09-02T09:26:33.080Z despair ntpd[4423]: adjusting clock frequency by -0.112101 to 23.231952ppm
> 2019-09-03T18:46:42.450Z despair ntpd[4423]: adjusting clock frequency by 0.059225 to 23.298971ppm
> 2019-09-04T05:38:00.448Z despair ntpd[94384]: adjusting local clock by 0.205606s
> 2019-09-04T06:01:07.241Z despair ntpd[94384]: adjusting clock frequency by -5.152448 to 18.146552ppm
> 2019-09-04T06:29:07.910Z despair ntpd[94384]: adjusting clock frequency by 0.102389 to 18.248942ppm
> 2019-09-05T14:59:32.221Z despair ntpd[94384]: adjusting clock frequency by -0.091441 to 18.128836ppm
> 2019-09-06T04:23:54.007Z despair ntpd[94384]: adjusting clock frequency by -0.083599 to 18.045237ppm
> 2019-09-07T12:05:28.785Z despair ntpd[94384]: adjusting clock frequency by 0.105433 to 18.127731ppm
> 2019-09-08T09:11:28.055Z despair ntpd[94384]: adjusting clock frequency by -0.053777 to 18.073954ppm
> 2019-09-11T22:30:15.419Z despair ntpd[94384]: adjusting clock frequency by 0.075566 to 18.133313ppm
> 2019-09-13T06:40:55.682Z despair ntpd[94384]: adjusting clock frequency by 0.068719 to 18.225415ppm
> 2019-09-13T23:24:40.426Z despair ntpd[94384]: adjusting clock frequency by -0.082615 to 18.142801ppm
> 2019-09-14T18:54:14.544Z despair ntpd[94384]: adjusting clock frequency by -0.115367 to 18.027434ppm
> 2019-09-16T02:42:15.613Z despair ntpd[94384]: adjusting clock frequency by 0.098754 to 18.136523ppm
> 2019-09-17T11:08:03.213Z despair ntpd[94384]: adjusting clock frequency by -0.083464 to 18.024047ppm
> 2019-09-18T09:01:25.975Z despair ntpd[94384]: adjusting clock frequency by -0.115676 to 17.908371ppm
> 2019-09-19T20:20:32.346Z despair ntpd[94384]: adjusting clock frequency by 0.084223 to 18.000185ppm
> 2019-09-23T04:43:42.777Z despair ntpd[94384]: adjusting clock frequency by 0.188180 to 18.173361ppm
> 2019-09-24T13:30:46.774Z despair ntpd[94384]: adjusting clock frequency by -0.066054 to 18.138848ppm
> 2019-09-25T04:30:45.225Z despair ntpd[43359]: adjusting local clock by 0.352666s
> 2019-09-25T04:56:15.078Z despair ntpd[43359]: adjusting clock frequency by 1.813773 to 19.952773ppm
> 2019-09-25T05:34:04.449Z despair ntpd[43359]: adjusting clock frequency by -0.085726 to 19.845940ppm
> 2019-09-25T21:01:04.213Z despair ntpd[43359]: adjusting clock frequency by 0.088658 to 19.934599ppm
> 2019-09-30T01:19:51.791Z despair ntpd[43359]: adjusting clock frequency by 0.071707 to 20.022707ppm
> 2019-09-30T16:41:03.701Z despair ntpd[43359]: adjusting clock frequency by -0.092157 to 19.930550ppm
> 2019-09-30T20:13:34.739Z despair ntpd[57871]: adjusting local clock by 1.172165s
> 2019-09-30T20:41:01.365Z despair ntpd[57871]: adjusting clock frequency by -0.876624 to 19.054376ppm
> 2019-09-30T21:22:33.036Z despair ntpd[57871]: adjusting clock frequency by -0.100501 to 18.980951ppm
> 2019-10-02T02:55:02.851Z despair ntpd[57871]: adjusting local clock by 2.788555s
> 2019-10-02T02:55:02.856Z despair ntpd[57871]: adjusting clock frequency by 36.583542 to 55.590682ppm
> 2019-10-02T03:21:39.721Z despair ntpd[57871]: adjusting local clock by -0.043674s
> 2019-10-02T06:23:22.228Z despair ntpd[57871]: adjusting local clock by -0.343597s
> 2019-10-02T06:51:22.766Z despair ntpd[57871]: adjusting local clock by -0.112257s
> 2019-10-02T07:42:06.567Z despair ntpd[57871]: adjusting local clock by -0.108361s
> 2019-10-02T07:57:25.794Z despair ntpd[57871]: adjusting clock frequency by -38.265676 to 17.325006ppm
> 2019-10-02T12:45:02.143Z despair ntpd[57871]: adjusting clock frequency by 1.183919 to 18.508925ppm
> 2019-10-03T02:18:06.172Z despair ntpd[57871]: adjusting local clock by 2.792710s
> 2019-10-03T02:18:06.347Z despair ntpd[57871]: adjusting clock frequency by 48.286290 to 66.795215ppm
> 2019-10-03T02:46:26.658Z despair ntpd[57871]: adjusting local clock by -0.056351s
> 2019-10-03T03:37:45.273Z despair ntpd[57871]: adjusting local clock by -0.135864s
> 2019-10-03T04:49:06.489Z despair ntpd[57871]: adjusting local clock by -0.092314s
> 2019-10-03T04:59:25.046Z despair ntpd[57871]: adjusting local clock by -0.119596s
> 2019-10-03T04:59:25.214Z despair ntpd[57871]: adjusting clock frequency by -44.208503 to 22.586712ppm
> 2019-10-03T08:24:03.399Z despair ntpd[57871]: adjusting clock frequency by -3.795810 to 18.790902ppm
> 2019-10-04T03:14:01.960Z despair ntpd[57871]: adjusting local clock by 2.789228s
> 2019-10-04T04:45:04.116Z despair ntpd[57871]: adjusting clock frequency by 102.377293 to 121.183234ppm
> 2019-10-04T07:52:33.373Z despair ntpd[57871]: adjusting local clock by -0.499062s
> 2019-10-04T08:20:57.285Z despair ntpd[57871]: adjusting local clock by -0.808530s
> 2019-10-04T08:23:02.175Z despair ntpd[57871]: adjusting local clock by -0.205615s
> 2019-10-04T08:41:40.943Z despair ntpd[57871]: adjusting local clock by -0.035396s
> 2019-10-04T08:46:30.420Z despair ntpd[57871]: adjusting local clock by -0.073398s
> 2019-10-04T09:09:59.893Z despair ntpd[57871]: adjusting local clock by -0.071734s
> 2019-10-04T09:14:17.342Z despair ntpd[57871]: adjusting local clock by -0.049199s
> 2019-10-04T09:37:59.650Z despair ntpd[57871]: adjusting local clock by -0.080466s
> 2019-10-04T09:42:53.759Z despair ntpd[57871]: adjusting local clock by -0.092445s
> 2019-10-04T10:02:56.474Z despair ntpd[57871]: adjusting local clock by -0.050431s
> 2019-10-04T10:05:34.493Z despair ntpd[57871]: adjusting local clock by -0.036164s
> 2019-10-04T10:05:34.494Z despair ntpd[57871]: adjusting clock frequency by -103.872057 to 17.310819ppm
> 2019-10-04T16:50:38.042Z despair ntpd[57871]: adjusting clock frequency by 1.497635 to 18.808454ppm
> 2019-10-05T03:54:15.316Z despair ntpd[57871]: adjusting local clock by 3.788213s
> 2019-10-05T04:21:03.169Z despair ntpd[57871]: adjusting clock frequency by 94.503664 to 113.312118ppm
> 2019-10-05T06:07:11.146Z despair ntpd[57871]: adjusting local clock by -0.182783s
> 2019-10-05T06:35:44.844Z despair ntpd[57871]: adjusting local clock by -0.565035s
> 2019-10-05T06:58:52.486Z despair ntpd[57871]: adjusting local clock by -0.058713s
> 2019-10-05T07:53:34.424Z despair ntpd[57871]: adjusting local clock by -0.307457s
> 2019-10-05T07:53:34.429Z despair ntpd[57871]: adjusting clock frequency by -138.365051 to -25.052934ppm
> 2019-10-05T09:50:45.414Z despair ntpd[57871]: adjusting local clock by 0.056762s
> 2019-10-05T10:09:41.959Z despair ntpd[57871]: adjusting local clock by 0.286678s
> 2019-10-05T11:11:20.479Z despair ntpd[57871]: adjusting local clock by 0.118117s
> 2019-10-05T11:11:20.480Z despair ntpd[57871]: adjusting clock frequency by 40.033737 to 14.980803ppm
> 2019-10-05T11:20:40.812Z despair ntpd[57871]: adjusting local clock by 0.039287s
> 2019-10-05T14:53:27.414Z despair ntpd[57871]: adjusting local clock by 0.039958s
> 2019-10-05T19:58:04.385Z despair ntpd[57871]: adjusting local clock by 0.050255s
> 2019-10-05T19:58:04.387Z despair ntpd[57871]: adjusting clock frequency by 3.255988 to 18.236791ppm
> 2019-10-06T01:30:47.572Z despair ntpd[57871]: adjusting local clock by 2.794070s
> 2019-10-06T04:39:33.633Z despair ntpd[57871]: adjusting clock frequency by 119.060064 to 137.296855ppm
> 2019-10-06T05:58:46.269Z despair ntpd[57871]: adjusting local clock by -0.467724s
> 2019-10-06T06:10:42.387Z despair ntpd[57871]: adjusting local clock by -0.154828s
> 2019-10-06T06:44:52.099Z despair ntpd[57871]: adjusting local clock by -0.096887s
> 2019-10-06T06:51:19.663Z despair ntpd[57871]: adjusting local clock by -0.170391s
> 2019-10-06T06:57:14.834Z despair ntpd[57871]: adjusting clock frequency by -123.595863 to 13.700992ppm
> 2019-10-06T10:57:35.226Z despair ntpd[57871]: adjusting local clock by 0.034371s
> 2019-10-06T13:15:47.886Z despair ntpd[57871]: adjusting clock frequency by 5.098349 to 18.799341ppm
> 2019-10-07T02:46:01.914Z despair ntpd[57871]: adjusting local clock by 0.999016s
> 2019-10-07T03:12:18.907Z despair ntpd[57871]: adjusting clock frequency by 17.748534 to 36.547875ppm
> 2019-10-07T04:14:25.198Z despair ntpd[57871]: adjusting local clock by -0.040082s
> 2019-10-07T04:52:53.256Z despair ntpd[57871]: adjusting local clock by -0.032568s
> 2019-10-07T05:31:48.597Z despair ntpd[57871]: adjusting clock frequency by -18.958049 to 17.589826ppm
> 2019-10-07T13:52:36.177Z despair ntpd[57871]: adjusting clock frequency by 0.935717 to 18.525543ppm
> 2019-10-08T01:58:43.306Z despair ntpd[57871]: adjusting clock frequency by 0.175944 to 18.701487ppm
> 2019-10-08T14:32:44.207Z despair ntpd[57871]: adjusting clock frequency by 0.119754 to 18.821241ppm
> 2019-10-09T02:02:36.600Z despair ntpd[57871]: adjusting local clock by 3.789589s
> 2019-10-09T02:36:55.508Z despair ntpd[57871]: adjusting clock frequency by 94.744753 to 113.565994ppm
> 2019-10-09T03:59:17.500Z despair ntpd[57871]: adjusting local clock by -0.149111s
> 2019-10-09T05:48:41.247Z despair ntpd[57871]: adjusting local clock by -0.686201s
> 2019-10-09T06:14:51.915Z despair ntpd[57871]: adjusting local clock by -0.387029s
> 2019-10-09T07:12:14.922Z despair ntpd[57871]: adjusting clock frequency by -114.822091 to -1.256097ppm
> 2019-10-09T07:53:09.381Z despair ntpd[57871]: adjusting local clock by -0.277260s
> 2019-10-09T09:25:22.569Z despair ntpd[57871]: adjusting clock frequency by 20.188146 to 18.932049ppm
> 2019-10-09T22:55:34.460Z despair ntpd[57871]: adjusting clock frequency by -0.131469 to 18.800580ppm
> 2019-10-11T05:47:46.703Z despair ntpd[57871]: adjusting local clock by 0.999576s
> 2019-10-11T06:47:57.798Z despair ntpd[57871]: adjusting clock frequency by 20.812371 to 39.596874ppm
> 2019-10-11T09:53:35.732Z despair ntpd[57871]: adjusting local clock by -0.072182s
> 2019-10-11T10:24:21.421Z despair ntpd[57871]: adjusting local clock by -0.195521s
> 2019-10-11T11:18:08.855Z despair ntpd[57871]: adjusting local clock by -0.043196s
> 2019-10-11T11:29:34.448Z despair ntpd[57871]: adjusting local clock by -0.032144s
> 2019-10-11T12:57:34.281Z despair ntpd[57871]: adjusting clock frequency by -19.010743 to 20.586131ppm
> 2019-10-11T13:46:34.099Z despair ntpd[57871]: adjusting local clock by -0.110462s
> 2019-10-11T17:56:50.100Z despair ntpd[57871]: adjusting clock frequency by -1.560165 to 19.025967ppm
> 2019-10-12T02:41:40.055Z despair ntpd[57871]: adjusting local clock by 2.788821s
> 2019-10-12T06:21:25.786Z despair ntpd[57871]: adjusting clock frequency by 83.894350 to 102.920317ppm
> 2019-10-12T08:21:59.739Z despair ntpd[57871]: adjusting local clock by -0.329220s
> 2019-10-12T08:38:07.627Z despair ntpd[57871]: adjusting local clock by -0.349950s
> 2019-10-12T08:56:11.533Z despair ntpd[57871]: adjusting local clock by -0.052764s
> 2019-10-12T09:01:21.042Z despair ntpd[57871]: adjusting local clock by -0.049049s
> 2019-10-12T09:55:20.274Z despair ntpd[57871]: adjusting local clock by -0.268308s
> 2019-10-12T10:49:20.629Z despair ntpd[57871]: adjusting local clock by -0.270500s
> 2019-10-12T10:50:25.114Z despair ntpd[57871]: adjusting clock frequency by -94.613470 to 8.306847ppm
> 2019-10-12T14:24:29.285Z despair ntpd[57871]: adjusting local clock by 0.067002s
> 2019-10-12T14:43:53.543Z despair ntpd[57871]: adjusting local clock by 0.045560s
> 2019-10-12T14:46:05.271Z despair ntpd[57871]: adjusting clock frequency by 10.309420 to 18.616266ppm
> 2019-10-13T02:22:31.650Z despair ntpd[57871]: adjusting local clock by 4.580235s
> 2019-10-13T02:22:31.655Z despair ntpd[57871]: adjusting clock frequency by 69.049537 to 87.665804ppm
> 2019-10-13T02:50:01.208Z despair ntpd[57871]: adjusting local clock by -0.083349s
> 2019-10-13T03:20:09.347Z despair ntpd[57871]: adjusting local clock by -0.034135s
> 2019-10-13T03:25:28.761Z despair ntpd[57871]: adjusting local clock by -0.068644s
> 2019-10-13T03:33:48.650Z despair ntpd[57871]: adjusting clock frequency by -62.999290 to 24.666513ppm
> 2019-10-13T03:55:14.969Z despair ntpd[57871]: adjusting local clock by -0.034093s
> 2019-10-13T06:49:12.093Z despair ntpd[57871]: adjusting local clock by -0.046964s
> 2019-10-13T08:17:01.107Z despair ntpd[57871]: adjusting clock frequency by -5.181217 to 19.485297ppm
> 2019-10-13T14:28:08.731Z despair ntpd[57871]: adjusting clock frequency by -0.496189 to 18.989107ppm
> 2019-10-14T04:28:23.058Z despair ntpd[57871]: adjusting local clock by 3.787977s
> 2019-10-14T04:57:20.328Z despair ntpd[57871]: adjusting clock frequency by 72.400713 to 91.389821ppm
> 2019-10-14T07:12:50.239Z despair ntpd[57871]: adjusting local clock by -0.098134s
> 2019-10-14T07:41:09.385Z despair ntpd[57871]: adjusting local clock by -0.588457s
> 2019-10-14T08:33:45.309Z despair ntpd[57871]: adjusting local clock by -0.217582s
> 2019-10-14T08:38:58.934Z despair ntpd[57871]: adjusting clock frequency by -75.344468 to 16.045353ppm
> 2019-10-14T12:36:41.001Z despair ntpd[57871]: adjusting clock frequency by 1.835204 to 17.880557ppm
> 2019-10-15T00:01:24.837Z despair ntpd[57871]: adjusting clock frequency by 1.053149 to 18.933706ppm
> 2019-10-15T00:45:41.522Z despair ntpd[57871]: adjusting local clock by 5.583228s
> 2019-10-15T00:50:33.245Z despair ntpd[57871]: adjusting local clock by 4.130414s
> 2019-10-15T00:53:40.823Z despair ntpd[57871]: adjusting local clock by 3.199848s
> 2019-10-15T00:54:44.551Z despair ntpd[57871]: adjusting local clock by 2.879703s
> 2019-10-15T00:57:28.851Z despair ntpd[57871]: adjusting local clock by 2.065175s
> 2019-10-15T01:00:10.759Z despair ntpd[57871]: adjusting local clock by 1.259297s
> 2019-10-15T01:02:52.858Z despair ntpd[57871]: adjusting local clock by 0.454847s
> 2019-10-16T03:30:04.113Z despair ntpd[57871]: adjusting local clock by 2.789336s
> 2019-10-16T10:09:45.896Z despair ntpd[57871]: adjusting clock frequency by 76.823298 to 95.758483ppm
> 2019-10-16T15:27:16.716Z despair ntpd[57871]: adjusting local clock by -0.999114s
> 2019-10-16T15:55:26.407Z despair ntpd[57871]: adjusting local clock by -0.582795s
> 2019-10-16T16:18:03.518Z despair ntpd[57871]: adjusting local clock by -0.041548s
> 2019-10-16T16:42:12.546Z despair ntpd[57871]: adjusting local clock by -0.089354s
> 2019-10-16T16:42:12.550Z despair ntpd[57871]: adjusting clock frequency by -105.147770 to -9.389287ppm
> 2019-10-16T18:24:34.666Z despair ntpd[57871]: adjusting local clock by 0.052193s
> 2019-10-16T18:57:13.898Z despair ntpd[57871]: adjusting clock frequency by 27.915958 to 18.526671ppm
> 2019-10-17T01:36:03.785Z despair ntpd[57871]: adjusting local clock by 2.790805s
> 2019-10-17T01:41:19.834Z despair ntpd[57871]: adjusting local clock by 1.216983s
> 2019-10-17T01:43:32.234Z despair ntpd[57871]: adjusting local clock by 0.558525s
> 2019-10-17T22:25:55.671Z despair ntpd[57871]: adjusting clock frequency by 0.314919 to 18.842122ppm
> 2019-10-18T04:22:05.812Z despair ntpd[57871]: adjusting local clock by 2.789475s
> 2019-10-18T10:44:51.237Z despair ntpd[57871]: adjusting clock frequency by 82.025367 to 100.867489ppm
> 2019-10-18T13:28:08.359Z despair ntpd[57871]: adjusting local clock by -0.132360s
> 2019-10-18T13:53:44.959Z despair ntpd[57871]: adjusting local clock by -0.785914s
> 2019-10-18T15:30:23.307Z despair ntpd[57871]: adjusting local clock by -0.091146s
> 2019-10-18T15:46:23.551Z despair ntpd[57871]: adjusting local clock by -0.436075s
> 2019-10-18T15:58:01.816Z despair ntpd[57871]: adjusting clock frequency by -102.407866 to -1.540377ppm
> 2019-10-18T17:54:52.535Z despair ntpd[57871]: adjusting local clock by 0.099486s
> 2019-10-18T18:08:43.867Z despair ntpd[57871]: adjusting local clock by 0.052751s
> 2019-10-18T19:36:56.576Z despair ntpd[57871]: adjusting clock frequency by 15.916700 to 14.376323ppm
> 2019-10-18T22:08:38.521Z despair ntpd[57871]: adjusting local clock by 0.122039s
> 2019-10-19T01:46:59.030Z despair ntpd[57871]: adjusting local clock by 2.828176s
> 2019-10-19T01:52:52.306Z despair ntpd[57871]: adjusting local clock by 1.079976s
> 2019-10-19T01:53:57.631Z despair ntpd[57871]: adjusting local clock by 0.756297s
> 2019-10-19T05:57:52.832Z despair ntpd[57871]: adjusting local clock by 0.046475s
> 2019-10-19T14:58:42.925Z despair ntpd[57871]: adjusting clock frequency by 4.611179 to 18.988087ppm
> 2019-10-20T04:42:16.121Z despair ntpd[57871]: adjusting clock frequency by -0.193726 to 18.794361ppm
> 2019-10-21T02:03:50.143Z despair ntpd[57871]: adjusting local clock by 5.579152s
> 2019-10-21T10:32:34.747Z despair ntpd[57871]: adjusting clock frequency by 117.968629 to 136.718342ppm
> 2019-10-21T14:02:35.440Z despair ntpd[57871]: adjusting local clock by -0.757493s
> 2019-10-21T14:33:22.662Z despair ntpd[57871]: adjusting local clock by -0.932076s
> 2019-10-21T14:35:29.696Z despair ntpd[57871]: adjusting local clock by -0.300248s
> 2019-10-21T17:25:23.011Z despair ntpd[57871]: adjusting local clock by -0.805754s
> 2019-10-21T17:56:13.662Z despair ntpd[57871]: adjusting local clock by -0.582839s
> 2019-10-21T17:56:47.878Z despair ntpd[57871]: adjusting local clock by -0.423582s
> 2019-10-21T18:14:55.767Z despair ntpd[57871]: adjusting local clock by -0.049582s
> 2019-10-21T18:18:31.799Z despair ntpd[57871]: adjusting local clock by -0.074613s
> 2019-10-21T18:32:15.401Z despair ntpd[57871]: adjusting local clock by -0.053776s
> 2019-10-21T18:36:59.076Z despair ntpd[57871]: adjusting local clock by -0.041973s
> 2019-10-21T18:58:34.313Z despair ntpd[57871]: adjusting local clock by -0.075649s
> 2019-10-21T19:04:26.838Z despair ntpd[57871]: adjusting local clock by -0.080840s
> 2019-10-21T19:16:56.656Z despair ntpd[57871]: adjusting local clock by -0.032014s
> 2019-10-21T20:11:31.348Z despair ntpd[57871]: adjusting local clock by -0.404448s
> 2019-10-21T20:12:04.540Z despair ntpd[57871]: adjusting local clock by -0.239447s
> 2019-10-21T20:12:04.544Z despair ntpd[57871]: adjusting clock frequency by -121.277487 to 15.440156ppm
> 2019-10-22T03:36:49.195Z despair ntpd[57871]: adjusting clock frequency by 3.314512 to 18.754668ppm
>

Reply | Threaded
Open this post in threaded view
|

Re: change in ntpd behavior

Paul de Weerd
On Wed, Oct 23, 2019 at 11:43:25AM +0200, Otto Moerbeek wrote:
| On Wed, Oct 23, 2019 at 11:17:02AM +0200, Paul de Weerd wrote:
|
| > Hi Otto,
| >
| > On Wed, Oct 23, 2019 at 10:58:24AM +0200, Otto Moerbeek wrote:
| > | I could * this has to do with the logging changes. Previously, debug
| > | messages could disappear because of wrong logging setup. In non -v
| > | mode, only big adjustments are logged. Sadly you stripped those
| > | numbers away so i cannot tell how big the adjustments are.
| >
| > The recent adjustments that happen during runtime are bigger than what
| > was logged previously at boot:
| >
| > 2019-06-07T13:15:53.341Z despair ntpd[4423]: adjusting local clock by -0.068174s
| > 2019-09-04T05:38:00.448Z despair ntpd[94384]: adjusting local clock by 0.205606s
| > 2019-09-25T04:30:45.225Z despair ntpd[43359]: adjusting local clock by 0.352666s
| > 2019-09-30T20:13:34.739Z despair ntpd[57871]: adjusting local clock by 1.172165s
| > 2019-10-02T02:55:02.851Z despair ntpd[57871]: adjusting local clock by 2.788555s
| > 2019-10-02T03:21:39.721Z despair ntpd[57871]: adjusting local clock by -0.043674s
| > 2019-10-02T06:23:22.228Z despair ntpd[57871]: adjusting local clock by -0.343597s
| > 2019-10-02T06:51:22.766Z despair ntpd[57871]: adjusting local clock by -0.112257s
| > 2019-10-02T07:42:06.567Z despair ntpd[57871]: adjusting local clock by -0.108361s
| > 2019-10-03T02:18:06.172Z despair ntpd[57871]: adjusting local clock by 2.792710s
| > 2019-10-03T02:46:26.658Z despair ntpd[57871]: adjusting local clock by -0.056351s
| > 2019-10-03T03:37:45.273Z despair ntpd[57871]: adjusting local clock by -0.135864s
| > 2019-10-03T04:49:06.489Z despair ntpd[57871]: adjusting local clock by -0.092314s
| > 2019-10-03T04:59:25.046Z despair ntpd[57871]: adjusting local clock by -0.119596s
| > 2019-10-04T03:14:01.960Z despair ntpd[57871]: adjusting local clock by 2.789228s
| >
| > Below is a bit more history (from early January this year).
| >
| > The wildly varying clock frequency is the reason I'm suspecting
| > hardware failure...
|
| Yeah pretty wild. This is a machine that is permanently on?

Yes, it's hosted in a data center and runs 24/7.

Cheers,

Paul

--
>++++++++[<++++++++++>-]<+++++++.>+++[<------>-]<.>+++[<+
+++++++++++>-]<.>++[<------------>-]<+.--------------.[-]
                 http://www.weirdnet.nl/                 

Reply | Threaded
Open this post in threaded view
|

Re: change in ntpd behavior

Mark Kettenis
In reply to this post by Otto Moerbeek
> Date: Wed, 23 Oct 2019 11:43:25 +0200
> From: Otto Moerbeek <[hidden email]>
>
> On Wed, Oct 23, 2019 at 11:17:02AM +0200, Paul de Weerd wrote:
>
> > Hi Otto,
> >
> > On Wed, Oct 23, 2019 at 10:58:24AM +0200, Otto Moerbeek wrote:
> > | I could * this has to do with the logging changes. Previously, debug
> > | messages could disappear because of wrong logging setup. In non -v
> > | mode, only big adjustments are logged. Sadly you stripped those
> > | numbers away so i cannot tell how big the adjustments are.
> >
> > The recent adjustments that happen during runtime are bigger than what
> > was logged previously at boot:
> >
> > 2019-06-07T13:15:53.341Z despair ntpd[4423]: adjusting local clock by -0.068174s
> > 2019-09-04T05:38:00.448Z despair ntpd[94384]: adjusting local clock by 0.205606s
> > 2019-09-25T04:30:45.225Z despair ntpd[43359]: adjusting local clock by 0.352666s
> > 2019-09-30T20:13:34.739Z despair ntpd[57871]: adjusting local clock by 1.172165s
> > 2019-10-02T02:55:02.851Z despair ntpd[57871]: adjusting local clock by 2.788555s
> > 2019-10-02T03:21:39.721Z despair ntpd[57871]: adjusting local clock by -0.043674s
> > 2019-10-02T06:23:22.228Z despair ntpd[57871]: adjusting local clock by -0.343597s
> > 2019-10-02T06:51:22.766Z despair ntpd[57871]: adjusting local clock by -0.112257s
> > 2019-10-02T07:42:06.567Z despair ntpd[57871]: adjusting local clock by -0.108361s
> > 2019-10-03T02:18:06.172Z despair ntpd[57871]: adjusting local clock by 2.792710s
> > 2019-10-03T02:46:26.658Z despair ntpd[57871]: adjusting local clock by -0.056351s
> > 2019-10-03T03:37:45.273Z despair ntpd[57871]: adjusting local clock by -0.135864s
> > 2019-10-03T04:49:06.489Z despair ntpd[57871]: adjusting local clock by -0.092314s
> > 2019-10-03T04:59:25.046Z despair ntpd[57871]: adjusting local clock by -0.119596s
> > 2019-10-04T03:14:01.960Z despair ntpd[57871]: adjusting local clock by 2.789228s
> >
> > Below is a bit more history (from early January this year).
> >
> > The wildly varying clock frequency is the reason I'm suspecting
> > hardware failure...
>
> Yeah pretty wild. This is a machine that is permanently on?

This may be a consequence of the timecounter choice.  Did the output
of kern.timecounter change on this machine?

What is the output of sysctl kern.timecounter in this state?

Reply | Threaded
Open this post in threaded view
|

Re: change in ntpd behavior

Claudio Jeker-3
In reply to this post by Paul de Weerd
On Wed, Oct 23, 2019 at 11:17:02AM +0200, Paul de Weerd wrote:

> Hi Otto,
>
> On Wed, Oct 23, 2019 at 10:58:24AM +0200, Otto Moerbeek wrote:
> | I could * this has to do with the logging changes. Previously, debug
> | messages could disappear because of wrong logging setup. In non -v
> | mode, only big adjustments are logged. Sadly you stripped those
> | numbers away so i cannot tell how big the adjustments are.
>
> The recent adjustments that happen during runtime are bigger than what
> was logged previously at boot:
>
> 2019-06-07T13:15:53.341Z despair ntpd[4423]: adjusting local clock by -0.068174s
> 2019-09-04T05:38:00.448Z despair ntpd[94384]: adjusting local clock by 0.205606s
> 2019-09-25T04:30:45.225Z despair ntpd[43359]: adjusting local clock by 0.352666s
> 2019-09-30T20:13:34.739Z despair ntpd[57871]: adjusting local clock by 1.172165s
> 2019-10-02T02:55:02.851Z despair ntpd[57871]: adjusting local clock by 2.788555s
> 2019-10-02T03:21:39.721Z despair ntpd[57871]: adjusting local clock by -0.043674s
> 2019-10-02T06:23:22.228Z despair ntpd[57871]: adjusting local clock by -0.343597s
> 2019-10-02T06:51:22.766Z despair ntpd[57871]: adjusting local clock by -0.112257s
> 2019-10-02T07:42:06.567Z despair ntpd[57871]: adjusting local clock by -0.108361s
> 2019-10-03T02:18:06.172Z despair ntpd[57871]: adjusting local clock by 2.792710s
> 2019-10-03T02:46:26.658Z despair ntpd[57871]: adjusting local clock by -0.056351s
> 2019-10-03T03:37:45.273Z despair ntpd[57871]: adjusting local clock by -0.135864s
> 2019-10-03T04:49:06.489Z despair ntpd[57871]: adjusting local clock by -0.092314s
> 2019-10-03T04:59:25.046Z despair ntpd[57871]: adjusting local clock by -0.119596s
> 2019-10-04T03:14:01.960Z despair ntpd[57871]: adjusting local clock by 2.789228s
>
> Below is a bit more history (from early January this year).
>
> The wildly varying clock frequency is the reason I'm suspecting
> hardware failure...
>

Maybe check the timecounter (sysctl kern.timecounter). The difference
between 6.5 and 6.6 is that TSC is used more often. Maybe try acpihpet0
which I guess was the one used in 6.5 and see if that is better.

--
:wq Claudio

Reply | Threaded
Open this post in threaded view
|

Re: change in ntpd behavior

Paul de Weerd
Hi Mark, Claudio,

Thanks for your replies.

On Wed, Oct 23, 2019 at 12:07:22PM +0200, Mark Kettenis wrote:
| This may be a consequence of the timecounter choice.  Did the output
| of kern.timecounter change on this machine?
|
| What is the output of sysctl kern.timecounter in this state?

On Wed, Oct 23, 2019 at 12:08:03PM +0200, Claudio Jeker wrote:
| Maybe check the timecounter (sysctl kern.timecounter). The difference
| between 6.5 and 6.6 is that TSC is used more often. Maybe try acpihpet0
| which I guess was the one used in 6.5 and see if that is better.

As this machine runs a couple of VMs, I've forced it to use tsc quite
some time ago already.

[weerd@despair] $ sysctl kern.timecounter
kern.timecounter.tick=1
kern.timecounter.timestepwarnings=0
kern.timecounter.hardware=tsc
kern.timecounter.choice=i8254(0) tsc(2000) acpihpet0(1000) acpitimer0(1000)

This is also set through /etc/sysctl.conf, which hasn't been touched
since February:

[weerd@despair] $ ls -l /etc/sysctl.conf
-rw-r--r--  1 root  wheel  157 Feb 22  2019 /etc/sysctl.conf

So, before I was also using tsc.  I've now changed to acpihpet0 and
will see if that helps at all.

Thanks,

Paul

(Adding dmesg now, which I somehow managed to not add in the original
e-mail)

--
>++++++++[<++++++++++>-]<+++++++.>+++[<------>-]<.>+++[<+
+++++++++++>-]<.>++[<------------>-]<+.--------------.[-]
                 http://www.weirdnet.nl/                 


OpenBSD 6.6-beta (GENERIC.MP) #335: Sun Sep 29 21:47:20 MDT 2019
    [hidden email]:/usr/src/sys/arch/amd64/compile/GENERIC.MP
real mem = 34332733440 (32742MB)
avail mem = 33279508480 (31737MB)
mpath0 at root
scsibus0 at mpath0: 256 targets
mainbus0 at root
bios0 at mainbus0: SMBIOS rev. 2.7 @ 0xe66d0 (57 entries)
bios0: vendor Dell Inc. version "1.2.3" date 07/21/2011
bios0: Dell Inc. PowerEdge R210 II
acpi0 at bios0: ACPI 4.0
acpi0: sleep states S0 S4 S5
acpi0: tables DSDT FACP SPMI ASF! HPET APIC MCFG BOOT SSDT ASPT SSDT SSDT SPCR DMAR HEST ERST BERT EINJ
acpi0: wakeup devices P0P1(S4) GLAN(S0) EHC1(S4) EHC2(S4) RP01(S5) PXSX(S4) RP02(S5) PXSX(S4) RP03(S5) PXSX(S4) RP04(S5) PXSX(S4) RP05(S5) PXSX(S4) RP06(S5) PXSX(S4) [...]
acpitimer0 at acpi0: 3579545 Hz, 24 bits
acpihpet0 at acpi0: 14318179 Hz
acpimadt0 at acpi0 addr 0xfee00000: PC-AT compat
cpu0 at mainbus0: apid 0 (boot processor)
cpu0: Intel(R) Xeon(R) CPU E31260L @ 2.40GHz, 2400.41 MHz, 06-2a-07
cpu0: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,POPCNT,DEADLINE,AES,XSAVE,AVX,NXE,RDTSCP,LONG,LAHF,PERF,ITSC,MD_CLEAR,IBRS,IBPB,STIBP,L1DF,SSBD,SENSOR,ARAT,XSAVEOPT,MELTDOWN
cpu0: 256KB 64b/line 8-way L2 cache
tsc_timecounter_init: TSC skew=0 observed drift=0
cpu0: smt 0, core 0, package 0
mtrr: Pentium Pro MTRR support, 10 var ranges, 88 fixed ranges
cpu0: apic clock running at 100MHz
cpu0: mwait min=64, max=64, C-substates=0.2.1.1, IBE
cpu1 at mainbus0: apid 1 (application processor)
TSC skew=2
cpu1: Intel(R) Xeon(R) CPU E31260L @ 2.40GHz, 2400.02 MHz, 06-2a-07
cpu1: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,POPCNT,DEADLINE,AES,XSAVE,AVX,NXE,RDTSCP,LONG,LAHF,PERF,ITSC,MD_CLEAR,IBRS,IBPB,STIBP,L1DF,SSBD,SENSOR,ARAT,XSAVEOPT,MELTDOWN
cpu1: 256KB 64b/line 8-way L2 cache
tsc_timecounter_init: TSC skew=2 observed drift=0
cpu1: smt 1, core 0, package 0
cpu2 at mainbus0: apid 2 (application processor)
TSC skew=-38
cpu2: Intel(R) Xeon(R) CPU E31260L @ 2.40GHz, 2400.02 MHz, 06-2a-07
cpu2: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,POPCNT,DEADLINE,AES,XSAVE,AVX,NXE,RDTSCP,LONG,LAHF,PERF,ITSC,MD_CLEAR,IBRS,IBPB,STIBP,L1DF,SSBD,SENSOR,ARAT,XSAVEOPT,MELTDOWN
cpu2: 256KB 64b/line 8-way L2 cache
tsc_timecounter_init: TSC skew=-38 observed drift=0
cpu2: smt 0, core 1, package 0
cpu3 at mainbus0: apid 3 (application processor)
TSC skew=-42
cpu3: Intel(R) Xeon(R) CPU E31260L @ 2.40GHz, 2400.02 MHz, 06-2a-07
cpu3: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,POPCNT,DEADLINE,AES,XSAVE,AVX,NXE,RDTSCP,LONG,LAHF,PERF,ITSC,MD_CLEAR,IBRS,IBPB,STIBP,L1DF,SSBD,SENSOR,ARAT,XSAVEOPT,MELTDOWN
cpu3: 256KB 64b/line 8-way L2 cache
tsc_timecounter_init: TSC skew=-42 observed drift=0
cpu3: smt 1, core 1, package 0
cpu4 at mainbus0: apid 4 (application processor)
TSC skew=-38
cpu4: Intel(R) Xeon(R) CPU E31260L @ 2.40GHz, 2400.02 MHz, 06-2a-07
cpu4: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,POPCNT,DEADLINE,AES,XSAVE,AVX,NXE,RDTSCP,LONG,LAHF,PERF,ITSC,MD_CLEAR,IBRS,IBPB,STIBP,L1DF,SSBD,SENSOR,ARAT,XSAVEOPT,MELTDOWN
cpu4: 256KB 64b/line 8-way L2 cache
tsc_timecounter_init: TSC skew=-38 observed drift=0
cpu4: smt 0, core 2, package 0
cpu5 at mainbus0: apid 5 (application processor)
TSC skew=-42
cpu5: Intel(R) Xeon(R) CPU E31260L @ 2.40GHz, 2400.02 MHz, 06-2a-07
cpu5: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,POPCNT,DEADLINE,AES,XSAVE,AVX,NXE,RDTSCP,LONG,LAHF,PERF,ITSC,MD_CLEAR,IBRS,IBPB,STIBP,L1DF,SSBD,SENSOR,ARAT,XSAVEOPT,MELTDOWN
cpu5: 256KB 64b/line 8-way L2 cache
tsc_timecounter_init: TSC skew=-42 observed drift=0
cpu5: smt 1, core 2, package 0
cpu6 at mainbus0: apid 6 (application processor)
TSC skew=-40
cpu6: Intel(R) Xeon(R) CPU E31260L @ 2.40GHz, 2400.02 MHz, 06-2a-07
cpu6: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,POPCNT,DEADLINE,AES,XSAVE,AVX,NXE,RDTSCP,LONG,LAHF,PERF,ITSC,MD_CLEAR,IBRS,IBPB,STIBP,L1DF,SSBD,SENSOR,ARAT,XSAVEOPT,MELTDOWN
cpu6: 256KB 64b/line 8-way L2 cache
tsc_timecounter_init: TSC skew=-40 observed drift=0
cpu6: smt 0, core 3, package 0
cpu7 at mainbus0: apid 7 (application processor)
TSC skew=0
cpu7: Intel(R) Xeon(R) CPU E31260L @ 2.40GHz, 2400.02 MHz, 06-2a-07
cpu7: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,POPCNT,DEADLINE,AES,XSAVE,AVX,NXE,RDTSCP,LONG,LAHF,PERF,ITSC,MD_CLEAR,IBRS,IBPB,STIBP,L1DF,SSBD,SENSOR,ARAT,XSAVEOPT,MELTDOWN
cpu7: 256KB 64b/line 8-way L2 cache
tsc_timecounter_init: TSC skew=0 observed drift=0
cpu7: smt 1, core 3, package 0
ioapic0 at mainbus0: apid 0 pa 0xfec00000, version 20, 24 pins
acpimcfg0 at acpi0
acpimcfg0: addr 0xe0000000, bus 0-255
acpiprt0 at acpi0: bus 0 (PCI0)
acpiprt1 at acpi0: bus 2 (P0P1)
acpiprt2 at acpi0: bus 1 (RP01)
acpiprt3 at acpi0: bus -1 (RP02)
acpiprt4 at acpi0: bus -1 (RP03)
acpiprt5 at acpi0: bus -1 (RP04)
acpiprt6 at acpi0: bus -1 (RP05)
acpiprt7 at acpi0: bus -1 (RP06)
acpiprt8 at acpi0: bus -1 (RP07)
acpiprt9 at acpi0: bus -1 (RP08)
acpiprt10 at acpi0: bus -1 (PEG0)
acpiprt11 at acpi0: bus -1 (PEG1)
acpiprt12 at acpi0: bus -1 (PEG2)
acpiprt13 at acpi0: bus -1 (PEG3)
acpicpu0 at acpi0: C3(350@104 mwait.1@0x20), C2(500@80 mwait.1@0x10), C1(1000@1 mwait.1), PSS
acpicpu1 at acpi0: C3(350@104 mwait.1@0x20), C2(500@80 mwait.1@0x10), C1(1000@1 mwait.1), PSS
acpicpu2 at acpi0: C3(350@104 mwait.1@0x20), C2(500@80 mwait.1@0x10), C1(1000@1 mwait.1), PSS
acpicpu3 at acpi0: C3(350@104 mwait.1@0x20), C2(500@80 mwait.1@0x10), C1(1000@1 mwait.1), PSS
acpicpu4 at acpi0: C3(350@104 mwait.1@0x20), C2(500@80 mwait.1@0x10), C1(1000@1 mwait.1), PSS
acpicpu5 at acpi0: C3(350@104 mwait.1@0x20), C2(500@80 mwait.1@0x10), C1(1000@1 mwait.1), PSS
acpicpu6 at acpi0: C3(350@104 mwait.1@0x20), C2(500@80 mwait.1@0x10), C1(1000@1 mwait.1), PSS
acpicpu7 at acpi0: C3(350@104 mwait.1@0x20), C2(500@80 mwait.1@0x10), C1(1000@1 mwait.1), PSS
acpipwrres0 at acpi0: FN00, resource for FAN0
acpipwrres1 at acpi0: FN01, resource for FAN1
acpipwrres2 at acpi0: FN02, resource for FAN2
acpipwrres3 at acpi0: FN03, resource for FAN3
acpipwrres4 at acpi0: FN04, resource for FAN4
acpitz0 at acpi0: critical temperature is 100 degC
acpipci0 at acpi0 PCI0: 0x00000010 0x00000011 0x00000000
"IPI0001" at acpi0 not configured
acpicmos0 at acpi0
"PNP0A05" at acpi0 not configured
"PNP0A05" at acpi0 not configured
"PNP0C0B" at acpi0 not configured
"PNP0C0B" at acpi0 not configured
"PNP0C0B" at acpi0 not configured
"PNP0C0B" at acpi0 not configured
"PNP0C0B" at acpi0 not configured
ipmi at mainbus0 not configured
cpu0: using VERW MDS workaround (except on vmm entry)
cpu0: Enhanced SpeedStep 2400 MHz: speeds: 2401, 2400, 2300, 2200, 2100, 2000, 1900, 1800, 1700, 1600 MHz
pci0 at mainbus0 bus 0
pchb0 at pci0 dev 0 function 0 "Intel Xeon E3-1200 Host" rev 0x09
ehci0 at pci0 dev 26 function 0 "Intel 6 Series USB" rev 0x04: apic 0 int 16
usb0 at ehci0: USB revision 2.0
uhub0 at usb0 configuration 1 interface 0 "Intel EHCI root hub" rev 2.00/1.00 addr 1
ppb0 at pci0 dev 28 function 0 "Intel 6 Series PCIE" rev 0xb4: msi
pci1 at ppb0 bus 1
bnx0 at pci1 dev 0 function 0 "Broadcom BCM5716" rev 0x20: apic 0 int 16
bnx1 at pci1 dev 0 function 1 "Broadcom BCM5716" rev 0x20: apic 0 int 17
ehci1 at pci0 dev 29 function 0 "Intel 6 Series USB" rev 0x04: apic 0 int 23
usb1 at ehci1: USB revision 2.0
uhub1 at usb1 configuration 1 interface 0 "Intel EHCI root hub" rev 2.00/1.00 addr 1
ppb1 at pci0 dev 30 function 0 "Intel 82801BA Hub-to-PCI" rev 0xa4
pci2 at ppb1 bus 2
2:3:0: rom address conflict 0xffff0000/0x10000
vga1 at pci2 dev 3 function 0 "Matrox MGA G200eW" rev 0x0a
wsdisplay0 at vga1 mux 1: console (80x25, vt100 emulation)
wsdisplay0: screen 1-5 added (80x25, vt100 emulation)
pcib0 at pci0 dev 31 function 0 "Intel C202 LPC" rev 0x04
ahci0 at pci0 dev 31 function 2 "Intel 6 Series AHCI" rev 0x04: msi, AHCI 1.3
ahci0: port 0: 3.0Gb/s
ahci0: port 1: 3.0Gb/s
ahci0: port 4: 1.5Gb/s
scsibus1 at ahci0: 32 targets
sd0 at scsibus1 targ 0 lun 0: <ATA, INTEL SSDSC2BW48, DC32> naa.55cd2e414c807f33
sd0: 457862MB, 512 bytes/sector, 937703088 sectors, thin
sd1 at scsibus1 targ 1 lun 0: <ATA, WDC WD60EFRX-68L, 82.0> naa.50014ee20dd29e68
sd1: 5723166MB, 512 bytes/sector, 11721045168 sectors
cd0 at scsibus1 targ 4 lun 0: <TSSTcorp, DVD+-RW TS-L633J, D150> removable
ichiic0 at pci0 dev 31 function 3 "Intel 6 Series SMBus" rev 0x04: apic 0 int 19
iic0 at ichiic0
sdtemp0 at iic0 addr 0x18: mcp98243
sdtemp1 at iic0 addr 0x19: mcp98243
sdtemp2 at iic0 addr 0x1a: mcp98243
sdtemp3 at iic0 addr 0x1b: mcp98243
spdmem0 at iic0 addr 0x50: 8GB DDR3 SDRAM ECC PC3-12800 with thermal sensor
spdmem1 at iic0 addr 0x51: 8GB DDR3 SDRAM ECC PC3-12800 with thermal sensor
spdmem2 at iic0 addr 0x52: 8GB DDR3 SDRAM ECC PC3-12800 with thermal sensor
spdmem3 at iic0 addr 0x53: 8GB DDR3 SDRAM ECC PC3-12800 with thermal sensor
isa0 at pcib0
isadma0 at isa0
com0 at isa0 port 0x3f8/8 irq 4: ns16550a, 16 byte fifo
com0: console
com1 at isa0 port 0x2f8/8 irq 3: ns16550a, 16 byte fifo
pckbc0 at isa0 port 0x60/5 irq 1 irq 12
pcppi0 at isa0 port 0x61
spkr0 at pcppi0
vmm0 at mainbus0: VMX/EPT
uhub2 at uhub0 port 1 configuration 1 interface 0 "Intel Rate Matching Hub" rev 2.00/0.00 addr 2
uhub3 at uhub1 port 1 configuration 1 interface 0 "Intel Rate Matching Hub" rev 2.00/0.00 addr 2
uhidev0 at uhub3 port 1 configuration 1 interface 0 "RDing TEMPERHUM1V1.2" rev 2.00/0.01 addr 3
uhidev0: iclass 3/1, 1 report id
ukbd0 at uhidev0 reportid 1: 8 variable keys, 5 key codes
wskbd0 at ukbd0: console keyboard, using wsdisplay0
uhidev1 at uhub3 port 1 configuration 1 interface 1 "RDing TEMPERHUM1V1.2" rev 2.00/0.01 addr 3
uhidev1: iclass 3/1
ugold0 at uhidev1
uhub4 at uhub3 port 5 configuration 1 interface 0 "Standard Microsystems product 0x2514" rev 2.00/0.00 addr 4
vscsi0 at root
scsibus2 at vscsi0: 256 targets
softraid0 at root
scsibus3 at softraid0: 256 targets
sd2 at scsibus3 targ 1 lun 0: <OPENBSD, SR CRYPTO, 006>
sd2: 409602MB, 512 bytes/sector, 838865577 sectors
root on sd2a (5a5905f3a7206f17.a) swap on sd2b dump on sd2b
bnx0: address bc:30:5b:e8:d6:48
brgphy0 at bnx0 phy 1: BCM5709 10/100/1000baseT PHY, rev. 8
bnx1: address bc:30:5b:e8:d6:49
brgphy1 at bnx1 phy 1: BCM5709 10/100/1000baseT PHY, rev. 8
ugold0: 2 sensors type si7006 (temperature and humidity)
sd3 at scsibus3 targ 2 lun 0: <OPENBSD, SR CRYPTO, 006>
sd3: 5231654MB, 512 bytes/sector, 10714427745 sectors

Reply | Threaded
Open this post in threaded view
|

Re: change in ntpd behavior

Paul de Weerd
On Wed, Oct 23, 2019 at 01:00:59PM +0200, Paul de Weerd wrote:
| So, before I was also using tsc.  I've now changed to acpihpet0 and
| will see if that helps at all.

I still see a lot of clock adjustments after having switched to
acpihpet0:

   1 despair ntpd[57871]: adjusting local clock
   1 despair ntpd[57871]: adjusting clock frequency
   2 despair ntpd[57871]: adjusting local clock
   1 despair ntpd[57871]: adjusting clock frequency
   1 despair ntpd[57871]: adjusting local clock
   1 despair ntpd[57871]: adjusting clock frequency
   1 despair ntpd[57871]: adjusting local clock
   1 despair ntpd[57871]: adjusting clock frequency
  12 despair ntpd[57871]: adjusting local clock
   1 despair ntpd[57871]: adjusting clock frequency
   1 despair ntpd[57871]: adjusting local clock
   4 despair ntpd[57871]: adjusting clock frequency
   1 despair ntpd[57871]: adjusting local clock
   1 despair ntpd[57871]: adjusting clock frequency
   4 despair ntpd[57871]: adjusting local clock
   1 despair ntpd[57871]: adjusting clock frequency
   5 despair ntpd[57871]: adjusting local clock
   1 despair ntpd[57871]: adjusting clock frequency
   1 despair ntpd[57871]: adjusting local clock
   1 despair ntpd[57871]: adjusting clock frequency
   1 despair ntpd[57871]: adjusting local clock
   1 despair ntpd[57871]: adjusting clock frequency
  20 despair ntpd[57871]: adjusting local clock
   1 despair ntpd[57871]: adjusting clock frequency
   1 despair ntpd[57871]: adjusting local clock

Five adjustments larger than 1 second, and again a wildly moving
clock frequency.  All logs where ntpd adjusts something since changing
to acpihpet0 below.

Paul

--
>++++++++[<++++++++++>-]<+++++++.>+++[<------>-]<.>+++[<+
+++++++++++>-]<.>++[<------------>-]<+.--------------.[-]
                 http://www.weirdnet.nl/                 


2019-10-23T13:53:56.317Z despair ntpd[57871]: adjusting local clock by 0.172197s
2019-10-23T13:53:56.490Z despair ntpd[57871]: adjusting clock frequency by 1.569433 to 1.569433ppm
2019-10-23T15:57:25.413Z despair ntpd[57871]: adjusting local clock by 0.082550s
2019-10-23T18:10:13.156Z despair ntpd[57871]: adjusting local clock by 0.093516s
2019-10-23T18:10:13.161Z despair ntpd[57871]: adjusting clock frequency by 15.369173 to 16.938606ppm
2019-10-23T18:29:52.027Z despair ntpd[57871]: adjusting local clock by 0.038953s
2019-10-24T00:21:07.301Z despair ntpd[57871]: adjusting clock frequency by 1.379106 to 18.317712ppm
2019-10-24T01:48:03.556Z despair ntpd[57871]: adjusting local clock by 3.000333s
2019-10-24T07:12:17.440Z despair ntpd[57871]: adjusting clock frequency by 99.116190 to 117.433902ppm
2019-10-24T09:22:21.850Z despair ntpd[57871]: adjusting local clock by -0.485882s
2019-10-24T09:49:00.223Z despair ntpd[57871]: adjusting local clock by -0.431387s
2019-10-24T09:50:04.074Z despair ntpd[57871]: adjusting local clock by -0.114916s
2019-10-24T10:04:34.626Z despair ntpd[57871]: adjusting local clock by -0.033257s
2019-10-24T10:08:13.629Z despair ntpd[57871]: adjusting local clock by -0.040840s
2019-10-24T10:19:50.907Z despair ntpd[57871]: adjusting local clock by -0.047784s
2019-10-24T10:25:09.847Z despair ntpd[57871]: adjusting local clock by -0.032271s
2019-10-24T10:53:56.983Z despair ntpd[57871]: adjusting local clock by -0.107483s
2019-10-24T11:00:44.903Z despair ntpd[57871]: adjusting local clock by -0.074635s
2019-10-24T11:55:19.412Z despair ntpd[57871]: adjusting local clock by -0.303542s
2019-10-24T12:55:43.749Z despair ntpd[57871]: adjusting local clock by -0.150739s
2019-10-24T13:02:26.315Z despair ntpd[57871]: adjusting local clock by -0.217944s
2019-10-24T13:02:26.320Z despair ntpd[57871]: adjusting clock frequency by -90.344579 to 27.089066ppm
2019-10-24T18:05:39.272Z despair ntpd[57871]: adjusting local clock by -0.095032s
2019-10-24T18:05:39.278Z despair ntpd[57871]: adjusting clock frequency by -6.035271 to 21.053795ppm
2019-10-24T23:30:35.406Z despair ntpd[57871]: adjusting clock frequency by -1.934449 to 19.119345ppm
2019-10-25T05:56:46.192Z despair ntpd[57871]: adjusting clock frequency by -0.681514 to 18.437831ppm
2019-10-25T20:50:52.058Z despair ntpd[57871]: adjusting clock frequency by -0.051466 to 18.386365ppm
2019-10-26T03:38:59.807Z despair ntpd[57871]: adjusting local clock by 3.999886s
2019-10-26T11:32:06.378Z despair ntpd[57871]: adjusting clock frequency by 88.136333 to 106.522698ppm
2019-10-26T14:38:22.765Z despair ntpd[57871]: adjusting local clock by -0.869362s
2019-10-26T14:45:11.105Z despair ntpd[57871]: adjusting local clock by -0.128839s
2019-10-26T15:04:29.280Z despair ntpd[57871]: adjusting local clock by -0.054317s
2019-10-26T15:09:46.838Z despair ntpd[57871]: adjusting local clock by -0.048844s
2019-10-26T15:14:06.219Z despair ntpd[57871]: adjusting clock frequency by -263.273239 to -156.750541ppm
2019-10-26T15:32:20.140Z despair ntpd[57871]: adjusting local clock by 0.056161s
2019-10-26T15:35:25.175Z despair ntpd[57871]: adjusting local clock by 0.088634s
2019-10-26T15:38:04.874Z despair ntpd[57871]: adjusting local clock by 0.034238s
2019-10-26T15:39:06.962Z despair ntpd[57871]: adjusting local clock by 0.034589s
2019-10-26T15:43:28.485Z despair ntpd[57871]: adjusting local clock by 0.047081s
2019-10-26T15:47:10.912Z despair ntpd[57871]: adjusting clock frequency by 183.487556 to 26.737015ppm
2019-10-26T18:48:11.870Z despair ntpd[57871]: adjusting local clock by -0.057785s
2019-10-26T19:37:18.808Z despair ntpd[57871]: adjusting clock frequency by -7.223416 to 19.513600ppm
2019-10-27T01:57:08.187Z despair ntpd[57871]: adjusting local clock by 2.980592s
2019-10-27T02:28:26.102Z despair ntpd[57871]: adjusting clock frequency by 144.818110 to 164.331710ppm
2019-10-27T05:34:30.565Z despair ntpd[57871]: adjusting local clock by -0.235385s
2019-10-27T06:01:53.218Z despair ntpd[57871]: adjusting local clock by -1.604514s
2019-10-27T06:02:54.930Z despair ntpd[57871]: adjusting local clock by -1.323809s
2019-10-27T06:07:17.425Z despair ntpd[57871]: adjusting local clock by -0.035310s
2019-10-27T06:11:34.818Z despair ntpd[57871]: adjusting local clock by -0.037899s
2019-10-27T07:03:45.582Z despair ntpd[57871]: adjusting local clock by -0.345970s
2019-10-27T07:06:29.421Z despair ntpd[57871]: adjusting local clock by -0.044009s
2019-10-27T07:09:41.539Z despair ntpd[57871]: adjusting local clock by -0.095194s
2019-10-27T07:12:20.099Z despair ntpd[57871]: adjusting local clock by -0.042239s
2019-10-27T07:45:46.594Z despair ntpd[57871]: adjusting local clock by -0.223866s
2019-10-27T08:16:06.047Z despair ntpd[57871]: adjusting local clock by -0.052372s
2019-10-27T08:46:33.504Z despair ntpd[57871]: adjusting local clock by -0.510968s
2019-10-27T08:48:09.200Z despair ntpd[57871]: adjusting local clock by -0.047166s
2019-10-27T08:55:02.676Z despair ntpd[57871]: adjusting local clock by -0.038429s
2019-10-27T09:18:15.081Z despair ntpd[57871]: adjusting local clock by -0.072713s
2019-10-27T09:26:15.951Z despair ntpd[57871]: adjusting local clock by -0.145935s
2019-10-27T10:24:52.037Z despair ntpd[57871]: adjusting local clock by -0.493024s
2019-10-27T10:37:29.498Z despair ntpd[57871]: adjusting local clock by -0.033438s
2019-10-27T10:41:11.110Z despair ntpd[57871]: adjusting local clock by -0.056150s
2019-10-27T10:45:26.416Z despair ntpd[57871]: adjusting local clock by -0.035944s
2019-10-27T10:51:48.311Z despair ntpd[57871]: adjusting clock frequency by -140.969616 to 23.361354ppm
2019-10-27T13:23:40.700Z despair ntpd[57871]: adjusting local clock by -0.032606s