-g broken

Thu Mar 22 20:37:37 UTC 2018

Yo Hal!

On Thu, 22 Mar 2018 12:54:44 -0700
Hal Murray <hmurray at megapathdsl.net> wrote:

> >> There should be something in the log file.  
> > Yes, I already attached to log file bits.  But, once again:  
> 
> The subject is "-g broken".  Your log sample shows ntpd exiting when
> it gets a big jump.  Was there previous big jump that worked in the
> log file?  You only get one.

See below for entire log of one failure cycle.  So, yes, two steps.
You can say -g is broken, or you can say the first choice of chimer
is broken:  pick one, or both.

> devel at ntpsec.org said:
> > Ah, but did you also do the gpsd part?  That is ESSENTIAL to
> > duplicate this bug.   
> 
> I'm not trying to reproduce the bug.  I'm trying to get you to focus
> on the real bug.  As far as I can tell, -g works as expected.

Tell me that after looking at the log.  Feel free to call the bug
anything you want if you can fix it.

> Was there a successful jump in the log file?

You tell me, below is all I have for one test cycle.  I have many similar
test cycles logs.

RGDS
GARY
---------------------------------------------------------------------------
Gary E. Miller Rellim 109 NW Wilmington Ave., Suite E, Bend, OR 97703
	gem at rellim.com  Tel:+1 541 382 8588

	    Veritas liberabit vos. -- Quid est veritas?
    "If you can’t measure it, you can’t improve it." - Lord Kelvin

12-31T16:00:21 ntpd[1931]: CONFIG: restrict default: notrap keyword is ignored.
12-31T16:00:21 ntpd[1931]: CONFIG: restrict ::: notrap keyword is ignored.
12-31T16:00:21 ntpd[1931]: CLOCK: leapsecond file ('/etc/leapfile'): stat failed: No such file or directory
12-31T16:00:21 ntpd[1931]: INIT: Using SO_TIMESTAMPNS
12-31T16:00:21 ntpd[1931]: IO: Listen and drop on 0 v6wildcard [::]:123
12-31T16:00:21 ntpd[1931]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123
12-31T16:00:21 ntpd[1931]: IO: Listen normally on 2 lo 127.0.0.1:123
12-31T16:00:21 ntpd[1931]: IO: Listen normally on 3 lo [::1]:123
12-31T16:00:21 ntpd[1931]: IO: Listen normally on 4 wlan0 [fe80::ba27:ebff:fec9:af4e%6]:123
12-31T16:00:21 ntpd[1931]: IO: Listening on routing socket on fd #21 for interface updates
12-31T16:00:21 ntpd[1931]: PROTO: 127.127.28.1 8011 81 mobilize assoc 19432
12-31T16:00:21 ntpd[1931]: PROTO: 127.127.28.3 8011 81 mobilize assoc 19433
12-31T16:00:21 ntpd[1931]: PROTO: 127.127.28.2 8011 81 mobilize assoc 19434
12-31T16:00:21 ntpd[1931]: CONFIG: peer deprecated, treated as server: 2001:470:e815::8
12-31T16:00:21 ntpd[1931]: PROTO: 2001:470:e815::8 8011 81 mobilize assoc 19435
12-31T16:00:21 ntpd[1931]: CONFIG: peer deprecated, treated as server: 204.17.205.16
12-31T16:00:21 ntpd[1931]: PROTO: 204.17.205.16 8011 81 mobilize assoc 19436
12-31T16:00:21 ntpd[1931]: CONFIG: peer deprecated, treated as server: 204.17.205.17
12-31T16:00:21 ntpd[1931]: PROTO: 204.17.205.17 8011 81 mobilize assoc 19437
12-31T16:00:21 ntpd[1931]: CONFIG: peer deprecated, treated as server: 204.17.205.23
12-31T16:00:21 ntpd[1931]: PROTO: 204.17.205.23 8011 81 mobilize assoc 19438
12-31T16:00:21 ntpd[1931]: CONFIG: peer deprecated, treated as server: 204.17.205.24
12-31T16:00:21 ntpd[1931]: PROTO: 204.17.205.24 8011 81 mobilize assoc 19439
12-31T16:00:21 ntpd[1931]: CONFIG: peer deprecated, treated as server: 204.17.205.1
12-31T16:00:21 ntpd[1931]: PROTO: 204.17.205.1 8011 81 mobilize assoc 19440
12-31T16:00:21 ntpd[1931]: CONFIG: peer deprecated, treated as server: 204.17.205.27
12-31T16:00:21 ntpd[1931]: PROTO: 204.17.205.27 8011 81 mobilize assoc 19441
12-31T16:00:21 ntpd[1931]: CONFIG: peer deprecated, treated as server: 204.17.205.30
12-31T16:00:21 ntpd[1931]: PROTO: 204.17.205.30 8011 81 mobilize assoc 19442
12-31T16:00:21 ntpd[1931]: PROTO: 127.127.28.0 8011 81 mobilize assoc 19443
12-31T16:00:21 ntpd[1931]: SYNC: Found 12 servers, suggest minsane at least 3
12-31T16:00:21 ntpd[1931]: PROTO: 0.0.0.0 c01d 0d kern kernel time sync enabled
12-31T16:00:21 ntpd[1931]: PROTO: 0.0.0.0 c012 02 freq_set kernel -5.932983 PPM
12-31T16:00:21 ntpd[1931]: PROTO: 0.0.0.0 c016 06 restart
12-31T16:00:22 ntpd[1931]: PROTO: SHM(1) 801b 8b clock_event clk_no_reply
12-31T16:00:23 ntpd[1931]: PROTO: SHM(3) 801b 8b clock_event clk_no_reply
12-31T16:00:24 ntpd[1931]: PROTO: SHM(2) 801b 8b clock_event clk_no_reply
12-31T16:00:24 ntpd[1931]: PROTO: SHM(1) 8014 84 reachable
12-31T16:00:24 ntpd[1931]: PROTO: SHM(1) 901a 8a sys_peer
12-31T16:00:24 ntpd[1931]: PROTO: 0.0.0.0 c41c 0c clock_step +902266367.623732 s
09-10T21:01:27 ntpd[1931]: CLOCK: time stepped by 902266367.623732
09-10T21:01:27 ntpd[1931]: PROTO: 0.0.0.0 c415 05 clock_sync
09-10T21:01:27 ntpd[1931]: IO: Listen normally on 5 wlan0 204.17.205.38:123
09-10T21:01:27 ntpd[1931]: IO: Listen normally on 6 wlan0 [2001:470:e815::38]:123
09-10T21:01:28 ntpd[1931]: PROTO: 0.0.0.0 c418 08 no_sys_peer
09-10T21:01:28 ntpd[1931]: PROTO: 204.17.205.1 8014 84 reachable
09-10T21:01:29 ntpd[1931]: PROTO: SHM(1) 8014 84 reachable
09-10T21:01:29 ntpd[1931]: PROTO: SHM(1) 901a 8a sys_peer
09-10T21:01:29 ntpd[1931]: IO: bind(24) AF_INET6 2001:470:e815:0:ba27:ebff:fec9:af4e#123 flags 0x1 failed: Cannot assign requested address
09-10T21:01:29 ntpd[1931]: IO: unable to create socket on wlan0 (7) for 2001:470:e815:0:ba27:ebff:fec9:af4e#123
09-10T21:01:29 ntpd[1931]: IO: failed to init interface for address 2001:470:e815:0:ba27:ebff:fec9:af4e
09-10T21:01:30 ntpd[1931]: PROTO: SHM(0) 8014 84 reachable
09-10T21:01:31 ntpd[1931]: IO: Listen normally on 8 wlan0 [2001:470:e815:0:ba27:ebff:fec9:af4e]:123
09-10T21:01:31 ntpd[1931]: PROTO: 2001:470:e815::8 unlink local addr 2001:470:e815::38 -> 2001:470:e815:0:ba27:ebff:fec9:af4e
09-10T21:01:34 ntpd[1931]: PROTO: 204.17.205.24 8014 84 reachable
09-10T21:01:36 ntpd[1931]: PROTO: 204.17.205.23 8014 84 reachable
09-10T21:01:39 ntpd[1931]: PROTO: 204.17.205.16 8014 84 reachable
09-10T21:01:46 ntpd[1931]: PROTO: 0.0.0.0 0428 08 no_sys_peer
09-10T21:01:50 ntpd[1931]: PROTO: 2001:470:e815::8 8014 84 reachable
09-10T21:01:53 ntpd[1931]: PROTO: 204.17.205.27 8014 84 reachable
09-10T21:01:55 ntpd[1931]: PROTO: 204.17.205.30 8014 84 reachable
09-10T21:03:15 ntpd[1931]: PROTO: 204.17.205.1 911a 8a sys_peer
09-10T21:03:15 ntpd[1931]: PROTO: 0.0.0.0 0017 07 panic_stop +619315200 s; set clock manually within 1000 s.
09-10T21:03:15 ntpd[1931]: CLOCK: Panic: offset too big: 619315200.001
09-10T21:03:15 ntpd[1931]: PROTO: 0.0.0.0 001d 0d kern kernel time sync disabled
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 488 bytes
Desc: OpenPGP digital signature
URL: <https://lists.ntpsec.org/pipermail/devel/attachments/20180322/bd0ac426/attachment.bin>