debug of good run

ntp.ok.debug (text/plain), 5.50 KB, created by Alexander Zubkov on 2015-01-27 12:57:56 UTC

repo ~ # /usr/sbin/ntpd -g -u ntp:ntp -n -d
27 Jan 15:31:55 ntpd[21942]: ntpd 4.2.8@1.3265-o Tue Jan 27 12:20:04 UTC 2015 (1): Starting
27 Jan 15:31:55 ntpd[21942]: Command line: /usr/sbin/ntpd -g -u ntp:ntp -n -d
27 Jan 15:31:55 ntpd[21942]: proto: precision = 0.240 usec (-22)
Finished Parsing!!
move_fd: estimated max descriptors: 1024, initial socket boundary: 16
27 Jan 15:31:55 ntpd[21942]: Listen and drop on 0 v6wildcard [::]:123
27 Jan 15:31:55 ntpd[21942]: Listen and drop on 1 v4wildcard 0.0.0.0:123
27 Jan 15:31:55 ntpd[21942]: Listen normally on 2 lo 127.0.0.1:123
restrict: op 1 addr 127.0.0.1 mask 255.255.255.255 mflags 00003000 flags 00000001
27 Jan 15:31:55 ntpd[21942]: Listen normally on 3 eth0 93.180.19.230:123
restrict: op 1 addr 93.180.19.230 mask 255.255.255.255 mflags 00003000 flags 00000001
27 Jan 15:31:55 ntpd[21942]: Listen normally on 4 lo [::1]:123
restrict: op 1 addr ::1 mask ffff:ffff:ffff:ffff:ffff:ffff:ffff:ffff mflags 00003000 flags 00000001
27 Jan 15:31:55 ntpd[21942]: Listen normally on 5 eth0 [2a00:f480:0:104:407e:fdff:fed9:25ce]:123
restrict: op 1 addr 2a00:f480:0:104:407e:fdff:fed9:25ce mask ffff:ffff:ffff:ffff:ffff:ffff:ffff:ffff mflags 00003000 flags 00000001
27 Jan 15:31:55 ntpd[21942]: Listen normally on 6 eth0 [2a00:f480:0:104::3]:123
restrict: op 1 addr 2a00:f480:0:104::3 mask ffff:ffff:ffff:ffff:ffff:ffff:ffff:ffff mflags 00003000 flags 00000001
27 Jan 15:31:55 ntpd[21942]: Listen normally on 7 eth0 [fe80::407e:fdff:fed9:25ce%2]:123
restrict: op 1 addr fe80::407e:fdff:fed9:25ce%2 mask ffff:ffff:ffff:ffff:ffff:ffff:ffff:ffff mflags 00003000 flags 00000001
27 Jan 15:31:55 ntpd[21942]: Listening on routing socket on fd #24 for interface updates
restrict: op 1 addr 0.0.0.0 mask 0.0.0.0 mflags 00000000 flags 000004f0
restrict: op 1 addr :: mask :: mflags 00000000 flags 000004f0
restrict: op 1 addr 127.0.0.1 mask 255.255.255.255 mflags 00000000 flags 00000000
restrict: op 1 addr ::1 mask ffff:ffff:ffff:ffff:ffff:ffff:ffff:ffff mflags 00000000 flags 00000000
event at 0 0.0.0.0 c01d 0d kern kernel time sync enabled
event at 0 0.0.0.0 c012 02 freq_set kernel 500.000 PPM
event at 0 0.0.0.0 c016 06 restart
peer_name_resolved(mirror.msu.net) rescode 0
key_expire: at 0 associd 19757
peer_clear: at 0 next 4 associd 19757 refid INIT
event at 0 2a00:f480:0:102::1d 8011 81 mobilize assoc 19757
newpeer: 2a00:f480:0:104::3->2a00:f480:0:102::1d mode 3 vers 4 poll 6 10 flags 0x1 0x1 ttl 0 key 00000000
peer_name_resolved(ns.msu.ru) rescode 0
key_expire: at 0 associd 19758
peer_clear: at 0 next 5 associd 19758 refid INIT
event at 0 93.180.0.1 8011 81 mobilize assoc 19758
newpeer: 93.180.19.230->93.180.0.1 mode 3 vers 4 poll 6 10 flags 0x1 0x1 ttl 0 key 00000000
peer_name_resolved(gw2.rector.msu.ru) rescode 0
key_expire: at 0 associd 19759
peer_clear: at 0 next 4 associd 19759 refid INIT
event at 0 93.180.5.99 8011 81 mobilize assoc 19759
newpeer: 93.180.19.230->93.180.5.99 mode 3 vers 4 poll 6 10 flags 0x1 0x1 ttl 0 key 00000000
select() returned -1: Interrupted system call
auth_agekeys: at 1 keys 0 expired 0
select() returned -1: Interrupted system call
select() returned -1: Interrupted system call
select() returned -1: Interrupted system call
transmit: at 4 93.180.19.230->93.180.5.99 mode 3 len 48
transmit: at 4 2a00:f480:0:104::3->2a00:f480:0:102::1d mode 3 len 48
processing that packet
receive: at 4 93.180.19.230<-93.180.5.99 mode 4 len 48
event at 4 93.180.5.99 8024 84 reachable
clock_filter: n 1 off 0.145476 del 0.001827 dsp 7.937500 jit 0.000000
select: combine offset 0.145475610 jitter 0.000000000
event at 4 93.180.5.99 903a 8a sys_peer
clock_update: at 4 sample 4 associd 19759
event at 4 0.0.0.0 c61c 0c clock_step +0.145476 s
In ntp_set_tod
ntp_set_tod: clock_settime: 0 Success
ntp_set_tod: Final result: clock_settime: 0 Success
event at 4 0.0.0.0 c615 05 clock_sync
local_clock: offset 0.000000000 jit 0.000000238 freq 500.000 stab 0.000 poll 6
key_expire: at 4 associd 19759
peer_clear: at 4 next 4 associd 19759 refid STEP
key_expire: at 4 associd 19758
peer_clear: at 4 next 5 associd 19758 refid STEP
key_expire: at 4 associd 19757
peer_clear: at 4 next 8 associd 19757 refid STEP
clear_all: at 4
Got an IPv6 packet, from <2a00:f480:0:102::1d> (0) to <2a00:f480:0:104::3> (1)
processing that packet
receive: at 4 2a00:f480:0:104::3<-2a00:f480:0:102::1d mode 4 len 48
packet: flash header 1202
select() returned -1: Interrupted system call
event at 5 0.0.0.0 c618 08 no_sys_peer
transmit: at 5 93.180.19.230->93.180.5.99 mode 3 len 48
transmit: at 5 93.180.19.230->93.180.0.1 mode 3 len 48
processing that packet
processing that packet
receive: at 5 93.180.19.230<-93.180.5.99 mode 4 len 48
event at 5 93.180.5.99 8044 84 reachable
clock_filter: n 1 off -0.000243 del 0.001092 dsp 7.937500 jit 0.000000
select: combine offset -0.000242976 jitter 0.000000000
event at 5 93.180.5.99 905a 8a sys_peer
clock_update: at 5 sample 5 associd 19759
local_clock: offset -0.000242975 jit 0.000085905 freq 499.999 stab 0.000 poll 6
receive: at 5 93.180.19.230<-93.180.0.1 mode 4 len 48
event at 5 93.180.0.1 8024 84 reachable
clock_filter: n 1 off 0.000209 del 0.001045 dsp 7.937504 jit 0.000000
select: clockhop 2 0.000452 0.001000
select: combine offset 0.000053198 jitter 0.000524783
select() returned -1: Interrupted system call
select() returned -1: Interrupted system call
select() returned -1: Interrupted system call
select() returned -1: Interrupted system call
select() returned -1: Interrupted system call
^C27 Jan 15:32:06 ntpd[21942]: ntpd exiting on signal 2 (Interrupt)
event at 10 0.0.0.0 061d 0d kern kernel time sync disabled