https://bugzilla.novell.com/show_bug.cgi?id=344356#c5
--- Comment #5 from Carlos Robinson 2007-11-28 16:48:27 MST ---
It is happening right now.
The system local time is 00:19, and the real time is 00:23.
nimrodel:~ # ntpdc -c pe
remote local st poll reach delay offset disp
=======================================================================
=192.33.214.57 192.168.1.12 2 1024 377 0.13571 290.59757 0.12036
=LOCAL(0) 127.0.0.1 10 64 377 0.00000 0.000000 0.03049
=punk.kls.es 192.168.1.12 2 1024 377 0.08014 290.59311 0.12131
=96.Red-213-96-1 192.168.1.12 2 1024 377 0.22598 290.54091 0.12125
=srv1.m.faelix.n 192.168.1.12 2 1024 377 0.10805 290.62112 0.12129
=endor.ordrejedi 192.168.1.12 3 1024 377 0.10883 290.58992 0.12135
*time3.ethz.ch 192.168.1.12 2 1024 377 0.10649 290.60127 0.12126
nimrodel:~ # date ; ntpdc -c pe
Thu Nov 29 00:21:34 CET 2007
remote local st poll reach delay offset disp
=======================================================================
=192.33.214.57 192.168.1.12 2 1024 377 0.13612 295.28592 0.12125
=LOCAL(0) 127.0.0.1 10 64 377 0.00000 0.000000 0.03038
=punk.kls.es 192.168.1.12 2 1024 377 0.08014 290.59311 0.12131
=96.Red-213-96-1 192.168.1.12 2 1024 377 0.22598 290.54091 0.12125
=srv1.m.faelix.n 192.168.1.12 2 1024 377 0.10805 290.62112 0.12129
=endor.ordrejedi 192.168.1.12 3 1024 377 0.10883 290.58992 0.12135
*time3.ethz.ch 192.168.1.12 2 1024 377 0.10649 290.60127 0.12126
nimrodel:~ # date ; ntpdc -c pe
Thu Nov 29 00:28:04 CET 2007
remote local st poll reach delay offset disp
=======================================================================
=192.33.214.57 192.168.1.12 2 1024 377 0.13612 295.28592 0.12125
=LOCAL(0) 127.0.0.1 10 64 377 0.00000 0.000000 0.03075
=punk.kls.es 192.168.1.12 2 1024 377 0.08014 290.59311 0.12131
=96.Red-213-96-1 192.168.1.12 2 1024 377 0.22598 290.54091 0.12125
=srv1.m.faelix.n 192.168.1.12 2 1024 377 0.10805 290.62112 0.12129
=endor.ordrejedi 192.168.1.12 3 1024 377 0.10883 290.58992 0.12135
*time3.ethz.ch 192.168.1.12 2 1024 377 0.10649 290.60127 0.12126
nimrodel:~ # date ; ntpdc -c pe
Thu Nov 29 00:32:08 CET 2007
remote local st poll reach delay offset disp
=======================================================================
=192.33.214.57 192.168.1.12 2 1024 377 0.13612 295.28592 0.12125
*LOCAL(0) 127.0.0.1 10 64 377 0.00000 0.000000 0.03075
=punk.kls.es 192.168.1.12 2 1024 377 0.08012 295.27988 0.12161
=96.Red-213-96-1 192.168.1.12 2 1024 377 0.11411 295.29405 0.12167
=srv1.m.faelix.n 192.168.1.12 2 1024 377 0.10986 295.30046 0.12164
=endor.ordrejedi 192.168.1.12 3 1024 377 0.10886 295.27848 0.12166
=time3.ethz.ch 192.168.1.12 2 1024 377 0.11043 295.28876 0.12169
(the error is 5 minutes now)
nimrodel:~ # date ; ntpdc -c pe
Thu Nov 29 00:37:07 CET 2007
remote local st poll reach delay offset disp
=======================================================================
=192.33.214.57 192.168.1.12 2 1024 377 0.13612 295.28592 0.13649
*LOCAL(0) 127.0.0.1 10 64 377 0.00000 0.000000 0.03065
=punk.kls.es 192.168.1.12 2 1024 377 0.08012 295.27988 0.12161
=96.Red-213-96-1 192.168.1.12 2 1024 377 0.11411 295.29405 0.12167
=srv1.m.faelix.n 192.168.1.12 2 1024 377 0.10986 295.30046 0.12164
=endor.ordrejedi 192.168.1.12 3 1024 377 0.10886 295.27848 0.12166
=time3.ethz.ch 192.168.1.12 2 1024 377 0.11043 295.28876 0.12169
nimrodel:~ # date ; ntpdc -c pe
Thu Nov 29 00:41:30 CET 2007
remote local st poll reach delay offset disp
=======================================================================
*192.33.214.57 192.168.1.12 2 1024 377 0.13612 295.28592 0.13649
=LOCAL(0) 127.0.0.1 10 64 377 0.00000 0.000000 0.03084
=punk.kls.es 192.168.1.12 2 1024 377 0.08012 295.27988 0.12161
=96.Red-213-96-1 192.168.1.12 2 1024 377 0.11411 295.29405 0.12167
=ntp1.faelix.net 192.168.1.12 2 1024 377 0.10986 295.30046 0.12164
=endor.ordrejedi 192.168.1.12 3 1024 377 0.10886 295.27848 0.12166
=time3.ethz.ch 192.168.1.12 2 1024 377 0.11043 295.28876 0.12169
/var/log/ntp since last ntp restart (I enabled a hook to restart it every time
I suspend to disk, thinking that might be the problem, but no):
28 Nov 16:30:24 ntpd[6148]: ntpd exiting on signal 15
28 Nov 16:30:24 ntpd[6148]: offset 0.000000 sec freq 78.416 ppm error 0.000001
poll 10
28 Nov 19:50:19 ntpd[19587]: system event 'event_restart' (0x01) status
'sync_alarm, sync_unspec, 1 event, event_unspec' (0xc010)
28 Nov 19:50:19 ntpd[19587]: peer LOCAL(0) event 'event_reach' (0x84) status
'unreach, conf, 1 event, event_reach' (0x8014)
28 Nov 19:50:19 ntpd[19587]: Deleting interface #2 lo, ::1#123, interface
stats: received=0, sent=0, dropped=0, active_time=1 secs
28 Nov 19:50:19 ntpd[19587]: Deleting interface #3 eth0,
fe80::240:f4ff:fe2e:b121#123, interface stats: received=0, sent=0, dropped=0,
active_time=1 secs
28 Nov 19:50:25 ntpd[19587]: peer 212.9.75.245 event 'event_reach' (0x84)
status 'unreach, conf, 1 event, event_reach' (0x8014)
28 Nov 19:50:25 ntpd[19587]: peer 193.142.245.196 event 'event_reach' (0x84)
status 'unreach, conf, 1 event, event_reach' (0x8014)
28 Nov 19:50:25 ntpd[19587]: peer 213.161.194.93 event 'event_reach' (0x84)
status 'unreach, conf, 1 event, event_reach' (0x8014)
28 Nov 19:50:25 ntpd[19587]: peer 192.33.98.103 event 'event_reach' (0x84)
status 'unreach, conf, 1 event, event_reach' (0x8014)
28 Nov 19:50:26 ntpd[19587]: peer 192.33.214.57 event 'event_reach' (0x84)
status 'unreach, conf, 1 event, event_reach' (0x8014)
28 Nov 19:53:32 ntpd[19587]: system event 'event_peer/strat_chg' (0x04) status
'sync_alarm, sync_local_proto, 2 events, event_restart' (0xc521)
28 Nov 19:53:32 ntpd[19587]: synchronized to LOCAL(0), stratum 10
28 Nov 19:53:32 ntpd[19587]: kernel time sync status change 0001
28 Nov 19:53:32 ntpd[19587]: system event 'event_sync_chg' (0x03) status
'leap_none, sync_local_proto, 3 events, event_peer/strat_chg' (0x534)
28 Nov 19:53:32 ntpd[19587]: system event 'event_peer/strat_chg' (0x04) status
'leap_none, sync_local_proto, 4 events, event_sync_chg' (0x543)
28 Nov 19:55:43 ntpd[19587]: synchronized to 192.33.214.57, stratum 2
28 Nov 20:03:19 ntpd[19587]: peer 213.96.197.96 event 'event_reach' (0x84)
status 'unreach, conf, 1 event, event_reach' (0x8014)
28 Nov 20:06:24 ntpd[19587]: synchronized to 192.33.98.103, stratum 2
28 Nov 20:50:18 ntpd[19587]: offset -0.001408 sec freq 78.770 ppm error
0.001970 poll 7
28 Nov 21:01:16 ntpd[19587]: synchronized to 192.33.214.57, stratum 2
28 Nov 21:50:18 ntpd[19587]: offset -0.001597 sec freq 78.736 ppm error
0.004094 poll 9
28 Nov 22:38:00 ntpd[19587]: synchronized to 192.33.98.103, stratum 2
28 Nov 22:50:18 ntpd[19587]: offset 0.000773 sec freq 78.729 ppm error 0.005932
poll 10
28 Nov 23:50:18 ntpd[19587]: offset 0.002848 sec freq 78.946 ppm error 0.002366
poll 10
28 Nov 23:56:26 ntpd[19587]: synchronized to 192.33.214.57, stratum 2
29 Nov 00:02:53 ntpd[19587]: synchronized to LOCAL(0), stratum 10
29 Nov 00:13:29 ntpd[19587]: synchronized to 192.33.98.103, stratum 2
while I write this report, three more entries:
29 Nov 00:30:35 ntpd[19587]: synchronized to 212.9.75.245, stratum 2
29 Nov 00:31:30 ntpd[19587]: synchronized to LOCAL(0), stratum 10
29 Nov 00:37:39 ntpd[19587]: synchronized to 192.33.214.57, stratum 2
/var/log/kernel:
Nov 28 19:49:49 nimrodel kernel: Restarting tasks ... done.
Nov 28 19:49:53 nimrodel kernel: swsusp: Basic memory bitmaps freed
Nov 28 19:49:56 nimrodel kernel: input: Power Button (FF) as
/class/input/input9
Nov 28 19:49:56 nimrodel kernel: ACPI: Power Button (FF) [PWRF]
Nov 28 19:49:56 nimrodel kernel: input: Power Button (CM) as
/class/input/input10
Nov 28 19:49:56 nimrodel kernel: ACPI: Power Button (CM) [PWRB]
Nov 28 19:49:56 nimrodel kernel: input: Sleep Button (CM) as
/class/input/input11
Nov 28 19:49:56 nimrodel kernel: ACPI: Sleep Button (CM) [SLPB]
Nov 28 23:55:29 nimrodel kernel: set_rtc_mmss: can't update from 0 to 55
Nov 28 23:55:29 nimrodel kernel: klogd 1.4.1, ---------- state change
----------
/var/log/messages:
Nov 28 16:30:21 nimrodel gnome-power-manager: (cer) Hibernating computer
because clicked tray
Nov 28 19:49:49 nimrodel syslog-ng[3823]: STATS: dropped 0
Nov 28 19:49:54 nimrodel gnome-power-manager: (cer) Resuming computer
Nov 28 19:49:56 nimrodel gnome-power-manager: (cer) hibernate failed
Nov 28 19:50:18 nimrodel ntpdate[19502]: step time server 212.9.75.245 offset
4.583790 sec
Nov 28 19:50:18 nimrodel ntpd[19586]: ntpd 4.2.4p3@1.1502-o Sat Sep 22 01:01:25
UTC 2007 (1)
Nov 28 19:50:18 nimrodel ntpd[19587]: precision = 1.000 usec
Nov 28 19:50:18 nimrodel ntpd[19587]: ntp_io: estimated max descriptors: 1024,
initial socket boundary: 16
Nov 28 19:50:18 nimrodel ntpd[19587]: Listening on interface #0 wildcard,
0.0.0.0#123 Disabled
Nov 28 19:50:18 nimrodel ntpd[19587]: Listening on interface #1 wildcard,
::#123 Disabled
Nov 28 19:50:18 nimrodel ntpd[19587]: Listening on interface #2 lo, ::1#123
Enabled
Nov 28 19:50:18 nimrodel ntpd[19587]: Listening on interface #3 eth0,
fe80::240:f4ff:fe2e:b121#123 Enabled
Nov 28 19:50:18 nimrodel ntpd[19587]: Listening on interface #4 lo,
127.0.0.1#123 Enabled
Nov 28 19:50:18 nimrodel ntpd[19587]: Listening on interface #5 eth0,
192.168.1.12#123 Enabled
Nov 28 19:50:18 nimrodel ntpd[19587]: kernel time sync status 0040
Nov 28 19:50:19 nimrodel ntpd[19587]: frequency initialized 78.416 PPM from
/var/lib/ntp/drift/ntp.drift
Nov 28 20:00:01 nimrodel /usr/sbin/cron[21174]: (cer) CMD
(/home/cer/bin/avisar_hablando time > /dev/null)
Nov 28 20:19:29 nimrodel smartd[5218]: Device: /dev/hda, SMART Usage Attribute:
190 Temperature_Celsius changed from 73 to 74
Nov 28 20:19:29 nimrodel smartd[5218]: Device: /dev/hda, SMART Usage Attribute:
194 Temperature_Celsius changed from 27 to 26
Nov 28 20:19:29 nimrodel smartd[5218]: Device: /dev/hda, SMART Usage Attribute:
195 Hardware_ECC_Recovered changed from 71 to 74
Nov 28 20:19:29 nimrodel smartd[5218]: Device: /dev/hdb, SMART Usage Attribute:
194 Temperature_Celsius changed from 26 to 24
Nov 28 20:19:29 nimrodel smartd[5218]: Device: /dev/hdd, SMART Usage Attribute:
194 Temperature_Celsius changed from 33 to 31
Nov 28 20:30:01 nimrodel /usr/sbin/cron[23322]: (cer) CMD
(/home/cer/bin/avisar_hablando time > /dev/null)
Nov 28 20:30:16 nimrodel smartd[5218]: Device: /dev/hda, SMART Usage Attribute:
190 Temperature_Celsius changed from 74 to 73
Nov 28 20:30:16 nimrodel smartd[5218]: Device: /dev/hda, SMART Usage Attribute:
194 Temperature_Celsius changed from 26 to 27
Nov 28 20:30:17 nimrodel smartd[5218]: Device: /dev/hdb, SMART Usage Attribute:
194 Temperature_Celsius changed from 24 to 25
Nov 28 20:30:17 nimrodel smartd[5218]: Device: /dev/hdd, SMART Usage Attribute:
194 Temperature_Celsius changed from 31 to 32
Nov 28 20:49:50 nimrodel syslog-ng[3823]: STATS: dropped 0
Nov 28 21:00:01 nimrodel /usr/sbin/cron[25777]: (cer) CMD
(/home/cer/bin/avisar_hablando time > /dev/null)
Nov 28 21:00:16 nimrodel smartd[5218]: Device: /dev/hda, SMART Usage Attribute:
195 Hardware_ECC_Recovered changed from 74 to 73
Nov 28 21:00:16 nimrodel smartd[5218]: Device: /dev/hdb, SMART Prefailure
Attribute: 1 Raw_Read_Error_Rate changed from 59 to 60
Nov 28 21:00:16 nimrodel smartd[5218]: Device: /dev/hdb, SMART Usage Attribute:
194 Temperature_Celsius changed from 25 to 26
Nov 28 21:00:16 nimrodel smartd[5218]: Device: /dev/hdd, SMART Usage Attribute:
194 Temperature_Celsius changed from 32 to 33
Nov 28 21:24:21 nimrodel gconfd (root-27608): starting (version 2.20.0), pid
27608 user 'root'
Nov 28 21:24:21 nimrodel gconfd (root-27608): Resolved address
"xml:readonly:/etc/gconf/gconf.xml.mandatory" to a read-only configuration
source at position 0
Nov 28 21:24:21 nimrodel gconfd (root-27608): Resolved address
"xml:readwrite:/root/.gconf" to a writable configuration source at position 1
Nov 28 21:24:21 nimrodel gconfd (root-27608): Resolved address
"xml:readonly:/etc/gconf/gconf.xml.defaults" to a read-only configuration
source at position 2
Nov 28 21:24:21 nimrodel gconfd (root-27608): Resolved address
"xml:readonly:/etc/gconf/gconf.xml.schemas" to a read-only configuration source
at position 3
Nov 28 21:30:01 nimrodel /usr/sbin/cron[28110]: (cer) CMD
(/home/cer/bin/avisar_hablando time > /dev/null)
Nov 28 21:30:17 nimrodel smartd[5218]: Device: /dev/hda, SMART Usage Attribute:
195 Hardware_ECC_Recovered changed from 73 to 72
Nov 28 21:30:17 nimrodel smartd[5218]: Device: /dev/hdb, SMART Prefailure
Attribute: 1 Raw_Read_Error_Rate changed from 60 to 58
Nov 28 21:30:17 nimrodel smartd[5218]: Device: /dev/hdd, SMART Prefailure
Attribute: 1 Raw_Read_Error_Rate changed from 59 to 60
Nov 28 21:30:17 nimrodel smartd[5218]: Device: /dev/hdd, SMART Usage Attribute:
195 Hardware_ECC_Recovered changed from 59 to 60
Nov 28 21:49:50 nimrodel syslog-ng[3823]: STATS: dropped 0
Nov 28 22:00:01 nimrodel /usr/sbin/cron[30265]: (cer) CMD
(/home/cer/bin/avisar_hablando time > /dev/null)
Nov 28 22:00:16 nimrodel smartd[5218]: Device: /dev/hdb, SMART Prefailure
Attribute: 1 Raw_Read_Error_Rate changed from 58 to 57
Nov 28 22:30:01 nimrodel /usr/sbin/cron[32324]: (cer) CMD
(/home/cer/bin/avisar_hablando time > /dev/null)
Nov 28 22:30:16 nimrodel smartd[5218]: Device: /dev/hdb, SMART Prefailure
Attribute: 1 Raw_Read_Error_Rate changed from 57 to 59
Nov 28 22:49:50 nimrodel syslog-ng[3823]: STATS: dropped 0
Nov 28 23:00:01 nimrodel /usr/sbin/cron[2188]: (cer) CMD
(/home/cer/bin/avisar_hablando time > /dev/null)
Nov 28 23:00:16 nimrodel smartd[5218]: Device: /dev/hda, SMART Usage Attribute:
195 Hardware_ECC_Recovered changed from 72 to 71
Nov 28 23:00:17 nimrodel smartd[5218]: Device: /dev/hdb, SMART Prefailure
Attribute: 1 Raw_Read_Error_Rate changed from 59 to 60
Nov 28 23:30:01 nimrodel /usr/sbin/cron[4300]: (cer) CMD
(/home/cer/bin/avisar_hablando time > /dev/null)
Nov 28 23:45:02 nimrodel su: (to beagleindex) root on none
Nov 28 23:45:02 nimrodel su: (to beagleindex) root on none
Nov 28 23:45:18 nimrodel run-crons[5359]: beagle-crawl-system: OK
Nov 28 23:45:32 nimrodel syslog-ng[3823]: SIGHUP received, restarting syslog-ng
Nov 28 23:45:33 nimrodel syslog-ng[3823]: new configuration initialized
Nov 28 23:45:42 nimrodel logrotate: ALERT exited abnormally with [1]
Nov 28 23:45:42 nimrodel logrotate: /usr/bin/mysqladmin: connect to server at
'localhost' failed
Nov 28 23:45:42 nimrodel logrotate: error: 'Access denied for user
'root'@'localhost' (using password: NO)'
Nov 28 23:45:42 nimrodel logrotate: /etc/logrotate.d/mysql failed, probably
because
Nov 28 23:45:42 nimrodel logrotate: the root acount is protected by password.
Nov 28 23:45:42 nimrodel logrotate: See comments in /etc/logrotate.d/mysql on
how to fix this
Nov 28 23:45:42 nimrodel logrotate: error: error running non-shared postrotate
script for /var/lib/mysql/mysqld.log of '/var/lib/mysql/mysqld.log '
Nov 28 23:45:42 nimrodel logrotate: Reload syslog service..done
Nov 28 23:45:42 nimrodel run-crons[5359]: logrotate: OK
Nov 28 23:45:44 nimrodel run-crons[5359]: suse-clean_catman: OK
Nov 28 23:47:36 nimrodel run-crons[5359]: suse-do_mandb: OK
Nov 28 23:47:37 nimrodel su: (to nobody) root on none
Nov 28 23:47:43 nimrodel syslog-ng[3823]: last message repeated 3 times
Nov 28 23:47:43 nimrodel run-crons[5359]: suse-texlive: OK
Nov 28 23:47:44 nimrodel run-crons[5359]: suse.de-backup-rc.config: OK
Nov 28 23:47:47 nimrodel run-crons[5359]: suse.de-backup-rpmdb: OK
Nov 28 23:47:47 nimrodel run-crons[5359]: suse.de-check-battery: OK
Nov 28 23:47:49 nimrodel run-crons[5359]: suse.de-clean-tmp: OK
Nov 28 23:47:49 nimrodel run-crons[5359]: suse.de-cron-local: OK
Nov 28 23:47:49 nimrodel run-crons[5359]: suse.de-faxcron: OK
Nov 28 23:47:49 nimrodel run-crons[5359]: suse.de-update-preload: OK
Nov 28 23:47:49 nimrodel su: (to nobody) root on none
Nov 28 23:48:34 nimrodel syslog-ng[3823]: last message repeated 2 times
Nov 28 23:55:29 nimrodel syslog-ng[3823]: Cannot open file /dev/tty10 for
writing (Permission denied)
Nov 28 23:55:32 nimrodel run-crons[5359]: suse.de-updatedb: OK
Nov 29 00:00:01 nimrodel /usr/sbin/cron[6804]: (root) CMD ( test -x
/usr/lib/secchk/security-control.sh && /usr/lib/secchk/security-control.sh
daily &)
Nov 29 00:00:01 nimrodel /usr/sbin/cron[6806]: (cer) CMD
(/home/cer/bin/avisar_hablando time > /dev/null)
Nov 29 00:00:17 nimrodel smartd[5218]: Device: /dev/hdb, SMART Prefailure
Attribute: 1 Raw_Read_Error_Rate changed from 60 to 59
Nov 29 00:00:17 nimrodel smartd[5218]: Device: /dev/hdd, SMART Prefailure
Attribute: 1 Raw_Read_Error_Rate changed from 60 to 59
Nov 29 00:00:17 nimrodel smartd[5218]: Device: /dev/hdd, SMART Usage Attribute:
195 Hardware_ECC_Recovered changed from 60 to 59
While I write this
Nov 29 00:30:01 nimrodel /usr/sbin/cron[9518]: (cer) CMD
(/home/cer/bin/avisar_hablando time > /dev/null)
Nov 29 00:30:16 nimrodel smartd[5218]: Device: /dev/hda, SMART Usage Attribute:
190 Temperature_Celsius changed from 73 to 72
Nov 29 00:30:16 nimrodel smartd[5218]: Device: /dev/hda, SMART Usage Attribute:
194 Temperature_Celsius changed from 27 to 28
Nov 29 00:30:16 nimrodel smartd[5218]: Device: /dev/hdb, SMART Usage Attribute:
194 Temperature_Celsius changed from 26 to 27
Nov 29 00:30:17 nimrodel smartd[5218]: Device: /dev/hdd, SMART Usage Attribute:
194 Temperature_Celsius changed from 33 to 34
I'll restart ntp in a while.
--
Configure bugmail: https://bugzilla.novell.com/userprefs.cgi?tab=email
------- You are receiving this mail because: -------
You are on the CC list for the bug.