-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1


The Wednesday 2007-12-05 at 00:42 +0100, I wrote:

I'm investigating a problem I'm having with the clock getting very slow,
and I have traced the problem to something new in opensuse 10.3.

Some suggested that the CMOS clock could be running bad. While I'm sure the CMOS clock doesn't have anything to do, I wrote a cron job to make sure: it compares the system clock to the cmos clock every five minutes, and logs the possible error to syslog. It has been running the whole afternoon, and at most the difference is one second:

  Dec  7 16:25:02 nimrodel CLOCK: CMOS clock differs 1" at 16:25:02
  Dec  7 16:50:02 nimrodel CLOCK: CMOS clock differs 1" at 16:50:02
  Dec  7 17:50:02 nimrodel CLOCK: CMOS clock differs 1" at 17:50:02
  Dec  7 17:55:02 nimrodel CLOCK: CMOS clock differs 1" at 17:55:02
  Dec  7 18:10:02 nimrodel CLOCK: CMOS clock differs 1" at 18:10:02
  Dec  7 18:25:02 nimrodel CLOCK: CMOS clock differs 1" at 18:25:02
  Dec  7 18:40:02 nimrodel CLOCK: CMOS clock differs 1" at 18:40:02
  Dec  7 18:50:02 nimrodel CLOCK: CMOS clock differs 1" at 18:50:02
  Dec  7 19:00:02 nimrodel CLOCK: CMOS clock differs 1" at 19:00:02
  Dec  7 19:15:02 nimrodel CLOCK: CMOS clock differs 1" at 19:15:02
  Dec  7 19:20:02 nimrodel CLOCK: CMOS clock differs 1" at 19:20:02
  Dec  7 19:35:02 nimrodel CLOCK: CMOS clock differs 1" at 19:35:02
  Dec  7 20:00:02 nimrodel CLOCK: CMOS clock differs 1" at 20:00:02
  Dec  7 20:30:02 nimrodel CLOCK: CMOS clock differs 1" at 20:30:02
  Dec  7 21:05:02 nimrodel CLOCK: CMOS clock differs 1" at 21:05:02
  Dec  7 21:15:02 nimrodel CLOCK: CMOS clock differs 1" at 21:15:02
  Dec  7 22:20:02 nimrodel CLOCK: CMOS clock differs 1" at 22:20:02


And not always, the error gets corrected. Now, I should provoke a network error by powering down the adsl router and find out if either the system clock or cmos clock drifts.

[...]

One hour forty five minutes without network, and not a second of drift! Go figure... the clock is running fine now. Maybe the 'tsc' clock runs better than the 'acpi_pm' one on my machine... if it is not disabled.


  Dec  7 22:35:02 nimrodel CLOCK: CMOS clock differs 1" at 22:35:02
  Dec  7 22:45:02 nimrodel CLOCK: CMOS clock differs 1" at 22:45:02
  Dec  8 00:20:02 nimrodel CLOCK: CMOS clock differs 1" at 00:20:02
  Dec  8 00:25:02 nimrodel CLOCK: CMOS clock differs 1" at 00:25:02
  Dec  8 00:30:02 nimrodel CLOCK: CMOS clock differs 1" at 00:30:02
  Dec  8 00:35:02 nimrodel CLOCK: CMOS clock differs 1" at 00:35:02
  Dec  8 00:45:02 nimrodel CLOCK: CMOS clock differs 1" at 00:45:02
  Dec  8 00:55:02 nimrodel CLOCK: CMOS clock differs 1" at 00:55:02
  Dec  8 01:05:02 nimrodel CLOCK: CMOS clock differs 1" at 01:05:02

(ie, right now the diff is 0")


And, for those that are curious, these are the drift values, logged every
five minutes:

Dec  7 16:25:02  -40.550
Dec  7 16:30:02  -40.550
Dec  7 16:35:02  -40.550
Dec  7 16:40:02  -40.550
Dec  7 16:45:02  -40.550
Dec  7 16:50:02  -40.550
Dec  7 16:55:02  -40.550
Dec  7 17:00:02  -40.550
Dec  7 17:05:02  -40.550
Dec  7 17:10:02  -40.550
Dec  7 17:15:02  -53.899
Dec  7 17:20:02  -53.899
Dec  7 17:25:02  -53.899
Dec  7 17:30:02  -53.899
Dec  7 17:35:02  -53.899
Dec  7 17:40:02  -53.899
Dec  7 17:45:02  -53.899
Dec  7 17:50:02  -53.899
Dec  7 17:55:02  -53.899
Dec  7 18:00:02  -53.899
Dec  7 18:05:02  -53.899
Dec  7 18:10:02  -53.899
Dec  7 18:15:02  -53.899
Dec  7 18:20:02  -62.444
Dec  7 18:25:02  -62.444
Dec  7 18:30:03  -62.444
Dec  7 18:35:02  -62.444
Dec  7 18:40:02  -62.444
Dec  7 18:45:02  -62.444
Dec  7 18:50:02  -62.444
Dec  7 18:55:02  -62.444
Dec  7 19:00:02  -62.444
Dec  7 19:05:02  -62.444
Dec  7 19:10:02  -62.444
Dec  7 19:15:02  -62.444
Dec  7 19:20:02  -70.013
Dec  7 19:25:02  -70.013
Dec  7 19:30:02  -70.013
Dec  7 19:35:02  -70.013
Dec  7 19:40:02  -70.013
Dec  7 19:55:02  -70.013
Dec  7 20:00:02  -70.013
Dec  7 20:05:02  -70.013
Dec  7 20:10:02  -70.013
Dec  7 20:15:02  -70.013
Dec  7 20:20:02  -70.013
Dec  7 20:25:02  -75.492
Dec  7 20:30:02  -75.492
Dec  7 20:35:02  -75.492
Dec  7 20:40:02  -75.492
Dec  7 20:45:02  -75.492
Dec  7 20:50:02  -75.492
Dec  7 20:55:02  -75.492
Dec  7 21:00:02  -75.492
Dec  7 21:05:02  -75.492
Dec  7 21:10:02  -75.492
Dec  7 21:15:02  -75.492
Dec  7 21:20:02  -75.492
Dec  7 21:25:02  -75.492
Dec  7 21:30:02  -79.780
Dec  7 21:35:02  -79.780
Dec  7 21:40:02  -79.780
Dec  7 21:45:02  -79.780
Dec  7 21:50:02  -79.780
Dec  7 21:55:02  -79.780
Dec  7 22:00:02  -79.780
Dec  7 22:05:02  -79.780
Dec  7 22:10:02  -79.780
Dec  7 22:15:02  -79.780
Dec  7 22:20:02  -79.780
Dec  7 22:25:02  -79.780  -->  No network
Dec 7 22:30:02 -82.365 Dec 7 22:35:02 -82.365
Dec  7 22:40:02  -82.365
Dec  7 22:45:02  -82.365
Dec  7 22:50:02  -82.365
Dec  7 22:55:02  -82.365
Dec  7 23:00:02  -82.365
Dec  7 23:05:02  -82.365
Dec  7 23:10:02  -82.365
Dec  7 23:15:02  -82.365
Dec  7 23:20:02  -82.365
Dec  7 23:25:02  -82.365
Dec  7 23:30:02  -82.365
Dec  7 23:35:02  -82.365
Dec  7 23:40:02  -82.365
Dec  7 23:45:02  -82.365
Dec  7 23:51:40  -82.365
Dec  7 23:55:02  -82.365  -->  Network back here
Dec  8 00:00:02  -82.365
Dec  8 00:05:02  -82.365
Dec  8 00:10:02  -82.365
Dec  8 00:15:02  -82.365
Dec  8 00:20:02  -82.365
Dec  8 00:25:02  -82.365
Dec  8 00:30:02  -82.365
Dec  8 00:35:02  -108.031
Dec  8 00:40:02  -108.031
Dec  8 00:45:02  -108.031
Dec  8 00:50:02  -108.031
Dec  8 00:55:02  -108.031
Dec  8 01:00:02  -108.031
Dec  8 01:05:02  -108.031


And ntp didn't complain much:

 7 Dec 20:23:43 ntpd[26893]: offset -0.021580 sec freq -75.492 ppm error 
0.001413 poll 6
 7 Dec 21:25:31 ntpd[26893]: offset -0.013625 sec freq -79.780 ppm error 
0.002351 poll 6
 7 Dec 22:25:31 ntpd[26893]: offset -0.011646 sec freq -82.365 ppm error 
0.002677 poll 6
 7 Dec 22:32:16 ntpd[26893]: no servers reachable
 7 Dec 22:33:11 ntpd[26893]: peer 213.97... event 'event_unreach' (0x83) status 
'unreach, conf, 3 events, event_unreach' (0x8033)
 7 Dec 22:33:14 ntpd[26893]: peer 212.101... event 'event_unreach' (0x83) 
status 'unreach, conf, 3 events, event_unreach' (0x8033)
 7 Dec 22:33:16 ntpd[26893]: peer 193.48... event 'event_unreach' (0x83) status 
'unreach, conf, 5 events, event_unreach' (0x8053)
 7 Dec 22:33:19 ntpd[26893]: peer 195.10... event 'event_unreach' (0x83) status 
'unreach, conf, 3 events, event_unreach' (0x8033)
 7 Dec 22:33:39 ntpd[26893]: peer 213.96... event 'event_unreach' (0x83) status 
'unreach, conf, 7 events, event_unreach' (0x8073)
 7 Dec 22:33:52 ntpd[26893]: peer 130.206... event 'event_unreach' (0x83) 
status 'unreach, conf, 3 events, event_unreach' (0x8033)
 7 Dec 22:33:52 ntpd[26893]: peer 193.62... event 'event_unreach' (0x83) status 
'unreach, conf, 3 events, event_unreach' (0x8033)
 7 Dec 22:34:04 ntpd[26893]: peer 192.33... event 'event_unreach' (0x83) status 
'unreach, conf, 3 events, event_unreach' (0x8033)
 7 Dec 22:34:05 ntpd[26893]: peer 80.38... event 'event_unreach' (0x83) status 
'unreach, conf, 3 events, event_unreach' (0x8033)
 7 Dec 23:25:31 ntpd[26893]: offset -0.011646 sec freq -82.365 ppm error 
0.002533 poll 6
 7 Dec 23:54:39 ntpd[26893]: peer 130.206... event 'event_reach' (0x84) status 
'unreach, conf, 4 events, event_reach' (0x8044)
 7 Dec 23:54:55 ntpd[26893]: peer 193.62... event 'event_reach' (0x84) status 
'unreach, conf, 4 events, event_reach' (0x8044)
 7 Dec 23:54:55 ntpd[26893]: peer 192.33... event 'event_reach' (0x84) status 
'unreach, conf, 4 events, event_reach' (0x8044)
 7 Dec 23:54:56 ntpd[26893]: peer 213.97... event 'event_reach' (0x84) status 
'unreach, conf, 4 events, event_reach' (0x8044)
 7 Dec 23:55:05 ntpd[26893]: peer 80.38... event 'event_reach' (0x84) status 
'unreach, conf, 4 events, event_reach' (0x8044)
 7 Dec 23:55:09 ntpd[26893]: peer 212.101... event 'event_reach' (0x84) status 
'unreach, conf, 4 events, event_reach' (0x8044)
 7 Dec 23:55:11 ntpd[26893]: peer 193.48... event 'event_reach' (0x84) status 
'unreach, conf, 6 events, event_reach' (0x8064)
 7 Dec 23:55:25 ntpd[26893]: peer 195.10... event 'event_reach' (0x84) status 
'unreach, conf, 4 events, event_reach' (0x8044)
 7 Dec 23:55:35 ntpd[26893]: peer 213.96... event 'event_reach' (0x84) status 
'unreach, conf, 8 events, event_reach' (0x8084)
 8 Dec 00:14:35 ntpd[26893]: synchronized to 193.62..., stratum 1
 8 Dec 00:31:04 ntpd[26893]: offset -0.014240 sec freq -108.031 ppm error 
0.010117 poll 6




- -- Cheers,
       Carlos E. R.
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v2.0.4-svn0 (GNU/Linux)

iD8DBQFHWeiQtTMYHG2NR9URAkVIAJ9cJrsbhcgYWPoNtra4SIYY+nWNbACfU754
PjQ/2KZ/YWvBYbjjG+i2iWM=
=jqVv
-----END PGP SIGNATURE-----
--
To unsubscribe, e-mail: [EMAIL PROTECTED]
For additional commands, e-mail: [EMAIL PROTECTED]

Reply via email to