[ntp:questions] NMEA refclock on Windows and ntp 4.2.4 does not set the time but logs clk_fault

Lindholm Roger roger.lindholm at modul1.se
Mon Apr 16 15:46:55 UTC 2007


Hi,

I am running NTP 4.2.4 on Windows and am trying to get the NMEA refclock
to work with a Garmin GPSmap76. I am using a serial cable connected to
COM1 at 4800/8/1/N and no flow control. I am receiving what looks like
the correct messages and the debug output don't show anything strange
(as far as I can comprehend). It does however not set the time and it
logs event 'clk_fault' (0x03). The debug output shows the following for
each NMEA record received:

--------------------------

nmea: gpsread 122
$GPRMC,145808,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1E$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145808,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145808,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1E$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145808,5938.5864,N,0170

adj_systime: adj -0.000488213 -> remaining residual -0.000001377

adj_systime: adj -0.000488213 -> remaining residual -0.000003190

IoEvent occurred

refclock_gtraw: fd 1808 time 3385723811.949133 timecode 126
$GPRMC,145810,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*17

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145810,5938.5864,N,0170

--------------------------

See further details in the complete debug and config below. I have tried
to run with mode 7 as well as without, with fudged stratum and without,
with iburst and without etc.

 

Any ideas what may be the cause of this?

Best regards

Roger Lindholm

 

 

CONFIG:

tinker panic 0
# Set system clock that is way out of time, more than 15 minutes

#tinker stepout 60
# Step time if more than 1 minute wrong, intead of 15 minutes

#tos orphan 10
# Use stratum 10 if NTP network is in orphan mode, i.e. have no external
sync

#tos maxdist 4
# Allow a maximum delay of 4 seconds from root to this node

#keys %windir%\system32\drivers\etc\ntp.keys                # Key
repository

#enable auth
# Enable authentication subsystem

#enable stats
# Enable statistics subsystem

#statsdir %windir%\system32\drivers\etc\
# Directory for storing files

#filegen loopstats file ntp.loopstats type day link   # Create daily
statistics files

#filegen sysstats  file ntp.sysstats  type day link  # Create daily
statistics files

#statistics loopstats sysstats                                         #
Log statistics of this kind

#controlkey 1                                                     # Use
key number 1 for ntpq

#requestkey 1                                                    # Use
key number 1 for ntpdc

driftfile  %windir%\system32\drivers\etc\ntp.drift    # Local clock
frequency every hour

#trustedkey 1 2                                      # use key number 1
and 2

#server gateway iburst minpoll 4 maxpoll 6 key 2              # use
external NTP server

#enable bclient                                                  #
Enable broadcast client subsystem

#broadcastclient key 2                                       # listen
for broadcast from external clocks using key 2

#broadcast 255.255.255.255 key 1                                  #
broadcast time for other computers

#peer time.SISNODE7.net1.nbg08.mil.se iburst minpoll 4 maxpoll 6 key 1
# peer with all command posts within the unit

#peer time.SISNODE1.net1.nbg08.mil.se iburst minpoll 4 maxpoll 6 key 1
# peer with all command posts within the unit

#peer time.SISNODE2.net1.nbg08.mil.se iburst minpoll 4 maxpoll 6 key 1
# peer with all command posts within the unit

#peer time.SISNODE4.net1.nbg08.mil.se iburst minpoll 4 maxpoll 6 key 1
# peer with all command posts within the unit

#peer time.SISNODE5.net1.nbg08.mil.se iburst minpoll 4 maxpoll 6 key 1
# peer with all command posts within the unit

#peer time.SISNODE6.net1.nbg08.mil.se iburst minpoll 4 maxpoll 6 key 1
# peer with all command posts within the unit

server 127.127.20.1 mode 7 iburst minpoll 4 maxpoll 6

fudge 127.127.20.1 stratum 1

 

 

DEBUG output:

 

ntpd 4.2.4pNEW at 1.1437-o Jan 29 12:50:31 (UTC+01:00) 2007  (2)

addto_syslog: Initial Clock increment 15625.0 us

addto_syslog: Adjustment rate 6.400 ppm/s

adding new filegen

adding new filegen

adding new filegen

adding new filegen

adding new filegen

adding new filegen

addto_syslog: set_process_priority: Leave priority alone: priority_done
is <2>

addto_syslog: precision = 1.000 usec

create_sockets(123)

bind() fd 1848, family 2, port 123, addr 0.0.0.0, flags=0x89

Allocation 28 memory for io_completion_port_add_socket, ptr 9e29e0

Searching for addr 0.0.0.0 in list of addresses - NOT FOUND

Added addr 0.0.0.0 to list of addresses

addto_syslog: Listening on interface #0 wildcard, 0.0.0.0#123 Disabled

update_interfaces(123)

addto_syslog: no IPv6 interfaces found

addto_syslog: no IPv6 interfaces found

examining interface #0: fd=-1, bfd=-1, name=IP Interface 1, flags=0x19,
scope=0, ifindex=0, sin=10.39.142.1, bcast=255.255.255.255,,
mask=255.255.255.0, Enabled:

Dumping interface: 0012F8B0

fd = -1

bfd = -1

sin = 10.39.142.1,

0200007b 0a278e01 00000000 00000000 00000000 00000000 00000000 00000000 

bcast = 255.255.255.255,

0200007b ffffffff 00000000 00000000 00000000 00000000 00000000 00000000 

mask = 255.255.255.0,

0200007b ffffff00 00000000 00000000 00000000 00000000 00000000 00000000 

name = IP Interface 1

flags = 0x00000019

last_ttl = 0

addr_refid = 00000000

num_mcast = 0

received = 0

sent = 0

notsent = 0

ifindex = 0

scopeid = 0

peercnt = 0

phase = 1

Searching for addr 10.39.142.1 in list of addresses - NOT FOUND

create_interface(10.39.142.1#123)

bind() fd 1832, family 2, port 123, addr 10.39.142.1, flags=0x19

Allocation 28 memory for io_completion_port_add_socket, ptr 9e2a68

addto_syslog: Listening on interface #1 IP Interface 1, 10.39.142.1#123
Enabled

Searching for addr 10.39.142.1 in list of addresses - NOT FOUND

Added addr 10.39.142.1 to list of addresses

created interface #1: fd=1832, bfd=-1, name=IP Interface 1, flags=0x19,
scope=0, ifindex=0, sin=10.39.142.1, bcast=255.255.255.255,,
mask=255.255.255.0, Enabled:

Dumping interface: 009EA3E8

fd = 1832

bfd = -1

sin = 10.39.142.1,

0200007b 0a278e01 00000000 00000000 00000000 00000000 00000000 00000000 

bcast = 255.255.255.255,

0200007b ffffffff 00000000 00000000 00000000 00000000 00000000 00000000 

mask = 255.255.255.0,

0200007b ffffff00 00000000 00000000 00000000 00000000 00000000 00000000 

name = IP Interface 1

flags = 0x00000019

last_ttl = 0

addr_refid = 018e270a

num_mcast = 0

received = 0

sent = 0

notsent = 0

ifindex = 0

scopeid = 0

peercnt = 0

phase = 1

updating interface #1: fd=1832, bfd=-1, name=IP Interface 1, flags=0x19,
scope=0, ifindex=0, sin=10.39.142.1, bcast=255.255.255.255,,
mask=255.255.255.0, Enabled: new - created

Dumping interface: 009EA3E8

fd = 1832

bfd = -1

sin = 10.39.142.1,

0200007b 0a278e01 00000000 00000000 00000000 00000000 00000000 00000000 

bcast = 255.255.255.255,

0200007b ffffffff 00000000 00000000 00000000 00000000 00000000 00000000 

mask = 255.255.255.0,

0200007b ffffff00 00000000 00000000 00000000 00000000 00000000 00000000 

name = IP Interface 1

flags = 0x00000019

last_ttl = 0

addr_refid = 018e270a

num_mcast = 0

received = 0

sent = 0

notsent = 0

ifindex = 0

scopeid = 0

peercnt = 0

phase = 1

address_okay: listen Virtual: 1, IF name: IP Interface 1, Up Flag: 1

examining interface #0: fd=-1, bfd=-1, name=Loopback Interface 2,
flags=0x1d, scope=0, ifindex=0, sin=127.0.0.1, bcast=255.255.255.255,,
mask=255.0.0.0, Enabled:

Dumping interface: 0012F8B0

fd = -1

bfd = -1

sin = 127.0.0.1,

0200007b 7f000001 00000000 00000000 00000000 00000000 00000000 00000000 

bcast = 255.255.255.255,

0200007b ffffffff 00000000 00000000 00000000 00000000 00000000 00000000 

mask = 255.0.0.0,

0200007b ff000000 00000000 00000000 00000000 00000000 00000000 00000000 

name = Loopback Interface 2

flags = 0x0000001d

last_ttl = 0

addr_refid = 00000000

num_mcast = 0

received = 0

sent = 0

notsent = 0

ifindex = 0

scopeid = 0

peercnt = 0

phase = 1

Searching for addr 127.0.0.1 in list of addresses - NOT FOUND

create_interface(127.0.0.1#123)

bind() fd 1824, family 2, port 123, addr 127.0.0.1, flags=0x1d

Allocation 28 memory for io_completion_port_add_socket, ptr 9ea938

addto_syslog: Listening on interface #2 Loopback Interface 2,
127.0.0.1#123 Enabled

Searching for addr 127.0.0.1 in list of addresses - NOT FOUND

Added addr 127.0.0.1 to list of addresses

created interface #2: fd=1824, bfd=-1, name=Loopback Interface 2,
flags=0x1d, scope=0, ifindex=0, sin=127.0.0.1, bcast=255.255.255.255,,
mask=255.0.0.0, Enabled:

Dumping interface: 009EA6D0

fd = 1824

bfd = -1

sin = 127.0.0.1,

0200007b 7f000001 00000000 00000000 00000000 00000000 00000000 00000000 

bcast = 255.255.255.255,

0200007b ffffffff 00000000 00000000 00000000 00000000 00000000 00000000 

mask = 255.0.0.0,

0200007b ff000000 00000000 00000000 00000000 00000000 00000000 00000000 

name = Loopback Interface 2

flags = 0x0000001d

last_ttl = 0

addr_refid = 0100007f

num_mcast = 0

received = 0

sent = 0

notsent = 0

ifindex = 0

scopeid = 0

peercnt = 0

phase = 1

updating interface #2: fd=1824, bfd=-1, name=Loopback Interface 2,
flags=0x1d, scope=0, ifindex=0, sin=127.0.0.1, bcast=255.255.255.255,,
mask=255.0.0.0, Enabled: new - created

Dumping interface: 009EA6D0

fd = 1824

bfd = -1

sin = 127.0.0.1,

0200007b 7f000001 00000000 00000000 00000000 00000000 00000000 00000000 

bcast = 255.255.255.255,

0200007b ffffffff 00000000 00000000 00000000 00000000 00000000 00000000 

mask = 255.0.0.0,

0200007b ff000000 00000000 00000000 00000000 00000000 00000000 00000000 

name = Loopback Interface 2

flags = 0x0000001d

last_ttl = 0

addr_refid = 0100007f

num_mcast = 0

received = 0

sent = 0

notsent = 0

ifindex = 0

scopeid = 0

peercnt = 0

phase = 1

address_okay: listen Virtual: 1, IF name: Loopback Interface 2, Up Flag:
1

setting SO_REUSEADDR on IP Interface 1 at 10.39.142.1 to off

setting SO_REUSEADDR on Loopback Interfa at 127.0.0.1 to off

create_sockets: Total interfaces = 3

init_io: maxactivefd 0

local_clock: time 0 offset 0.000000 freq 0.000 state 0

addto_syslog: frequency initialized -488.213 PPM from
C:\WINDOWS\system32\drivers\etc\ntp.drift

getnetnum: calling getaddrinfo(127.127.20.1,...)

getnetnum given 127.127.20.1, got 127.127.20.1 (t_UNK/0)

newpeer: cast flags: 0x1 for address: 127.127.20.1

set_peerdstadr(127.127.20.1): change interface from <null> to 127.0.0.1

newpeer: using fd 1824 and our addr 127.0.0.1

peer_crypto_clear: at 0 next 0 assoc ID 54514

key_expire: at 0

peer_clear: at 0 next 1 assoc ID 54514 refid INIT

Allocation 28 memory for io_completion_port_add_clock_io, ptr 9ea358

addto_syslog: clock GPS_NMEA(1) event 'clk_fault' (0x03)

peer GPS_NMEA(1) event 'event_peer_clock' (0x85) status 'unreach, conf,
1 event, event_peer_clock' (0x8015)

newpeer: 127.0.0.1->127.127.20.1 mode 3 vers 4 poll 4 6 flags 0x1221 0x1
ttl 7 key 00000000

getnetnum: calling getaddrinfo(127.127.20.1,...)

getnetnum given 127.127.20.1, got 127.127.20.1 (t_REF/1)

authtrust: keyid 0000ffff life 1

local_clock: time 0 offset 0.000000 freq -488.213 state 1

report_event: system event 'event_restart' (0x01) status 'sync_alarm,
sync_unspec, 1 event, event_unspec' (0xc010)

SetSystemTimeAdjustment( -76) + (156250)

adj_systime: adj -0.000488213 -> remaining residual -0.000001813

refclock_transmit: at 1 127.127.20.1

poll_update: at 1 127.127.20.1 flags 1221 poll 4 burst 0 last 1 next 18

auth_agekeys: at 1 keys 1 expired 0

timer: refresh ts 0

timer: interface update

update_interfaces(123)

addto_syslog: no IPv6 interfaces found

addto_syslog: no IPv6 interfaces found

examining interface #0: fd=-1, bfd=-1, name=IP Interface 1, flags=0x19,
scope=0, ifindex=0, sin=10.39.142.1, bcast=255.255.255.255,,
mask=255.255.255.0, Enabled:

Dumping interface: 0012F69C

fd = -1

bfd = -1

sin = 10.39.142.1,

0200007b 0a278e01 00000000 00000000 00000000 00000000 00000000 00000000 

bcast = 255.255.255.255,

0200007b ffffffff 00000000 00000000 00000000 00000000 00000000 00000000 

mask = 255.255.255.0,

0200007b ffffff00 00000000 00000000 00000000 00000000 00000000 00000000 

name = IP Interface 1

flags = 0x00000019

last_ttl = 0

addr_refid = 00000000

num_mcast = 0

received = 0

sent = 0

notsent = 0

ifindex = 0

scopeid = 0

peercnt = 0

phase = 0

Searching for addr 10.39.142.1 in list of addresses - FOUND

updating interface #1: fd=1832, bfd=-1, name=IP Interface 1, flags=0x19,
scope=0, ifindex=0, sin=10.39.142.1, bcast=255.255.255.255,,
mask=255.255.255.0, Enabled: present

Dumping interface: 009EA3E8

fd = 1832

bfd = -1

sin = 10.39.142.1,

0200007b 0a278e01 00000000 00000000 00000000 00000000 00000000 00000000 

bcast = 255.255.255.255,

0200007b ffffffff 00000000 00000000 00000000 00000000 00000000 00000000 

mask = 255.255.255.0,

0200007b ffffff00 00000000 00000000 00000000 00000000 00000000 00000000 

name = IP Interface 1

flags = 0x00000019

last_ttl = 0

addr_refid = 018e270a

num_mcast = 0

received = 0

sent = 0

notsent = 0

ifindex = 0

scopeid = 0

peercnt = 0

phase = 0

address_okay: listen Virtual: 1, IF name: IP Interface 1, Up Flag: 1

examining interface #0: fd=-1, bfd=-1, name=Loopback Interface 2,
flags=0x1d, scope=0, ifindex=0, sin=127.0.0.1, bcast=255.255.255.255,,
mask=255.0.0.0, Enabled:

Dumping interface: 0012F69C

fd = -1

bfd = -1

sin = 127.0.0.1,

0200007b 7f000001 00000000 00000000 00000000 00000000 00000000 00000000 

bcast = 255.255.255.255,

0200007b ffffffff 00000000 00000000 00000000 00000000 00000000 00000000 

mask = 255.0.0.0,

0200007b ff000000 00000000 00000000 00000000 00000000 00000000 00000000 

name = Loopback Interface 2

flags = 0x0000001d

last_ttl = 0

addr_refid = 00000000

num_mcast = 0

received = 0

sent = 0

notsent = 0

ifindex = 0

scopeid = 0

peercnt = 0

phase = 0

Searching for addr 127.0.0.1 in list of addresses - FOUND

updating interface #2: fd=1824, bfd=-1, name=Loopback Interface 2,
flags=0x1d, scope=0, ifindex=0, sin=127.0.0.1, bcast=255.255.255.255,,
mask=255.0.0.0, Enabled: present

Dumping interface: 009EA6D0

fd = 1824

bfd = -1

sin = 127.0.0.1,

0200007b 7f000001 00000000 00000000 00000000 00000000 00000000 00000000 

bcast = 255.255.255.255,

0200007b ffffffff 00000000 00000000 00000000 00000000 00000000 00000000 

mask = 255.0.0.0,

0200007b ff000000 00000000 00000000 00000000 00000000 00000000 00000000 

name = Loopback Interface 2

flags = 0x0000001d

last_ttl = 0

addr_refid = 0100007f

num_mcast = 0

received = 0

sent = 0

notsent = 0

ifindex = 0

scopeid = 0

peercnt = 1

phase = 0

address_okay: listen Virtual: 1, IF name: Loopback Interface 2, Up Flag:
1

peer_refresh_interface: 127.0.0.1->127.127.20.1 mode 3 vers 4 poll 4 6
flags 0x1221 0x1 ttl 7 key 00000000: new interface: fd=1824, bfd=-1,
name=Loopback Interfa, flags=0x1d, scope=0, , sin=127.0.0.1,
bcast=255.255.255.255,, mask=255.0.0.0

Received 68 bytes from 10.39.142.13

SetSystemTimeAdjustment( -77) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000002774

receive: at 2 10.39.142.1<-10.39.142.13 flags 19 restrict 000

receive: at 2 10.39.142.1<-10.39.142.13 mode 3 code 3 keyid 00000001 len
48 mac 20 auth 2

sendpkt(fd=1832 dst=10.39.142.13, src=10.39.142.1, ttl=0, len=52)

Allocation 28 memory for io_completion_port_sendto, ptr 9ec890

WSASendTo - 52 bytes to 10.39.142.13 : 0

transmit: at 2 10.39.142.1->10.39.142.13 mode 4 keyid 00000000 len 48
mac 4

Freeing memory for OnWriteComplete, ptr 9ec890

IoEvent occurred

refclock_gtraw: fd 1808 time 3385723783.947174 timecode 126
$GPRMC,145742,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1F

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145742,5938.5864,N,0170

nmea: gpsread 122
$GPRMC,145742,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1F$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145742,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145742,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1F$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145742,5938.5864,N,0170

refclock_receive: at 2 127.127.20.1

peer GPS_NMEA(1) event 'event_peer_clock' (0x85) status 'unreach, conf,
2 events, event_peer_clock' (0x8025)

SetSystemTimeAdjustment( -76) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000000961

Received 68 bytes from 10.39.142.14

receive: at 3 10.39.142.1<-10.39.142.14 flags 19 restrict 000

receive: at 3 10.39.142.1<-10.39.142.14 mode 3 code 3 keyid 00000001 len
48 mac 20 auth 2

sendpkt(fd=1832 dst=10.39.142.14, src=10.39.142.1, ttl=0, len=52)

Allocation 28 memory for io_completion_port_sendto, ptr 9ec8e0

WSASendTo - 52 bytes to 10.39.142.14 : 0

transmit: at 3 10.39.142.1->10.39.142.14 mode 4 keyid 00000000 len 48
mac 4

Freeing memory for OnWriteComplete, ptr 9ec8e0

Received 68 bytes from 10.39.142.2

Received 68 bytes from 10.39.142.3

adj_systime: adj -0.000488213 -> remaining residual -0.000000852

receive: at 4 10.39.142.1<-10.39.142.2 flags 19 restrict 000

receive: at 4 10.39.142.1<-10.39.142.2 mode 3 code 3 keyid 00000001 len
48 mac 20 auth 2

sendpkt(fd=1832 dst=10.39.142.2, src=10.39.142.1, ttl=0, len=52)

Allocation 28 memory for io_completion_port_sendto, ptr 9ec8e0

WSASendTo - 52 bytes to 10.39.142.2 : 0

transmit: at 4 10.39.142.1->10.39.142.2 mode 4 keyid 00000000 len 48 mac
4

receive: at 4 10.39.142.1<-10.39.142.3 flags 19 restrict 000

receive: at 4 10.39.142.1<-10.39.142.3 mode 3 code 3 keyid 00000001 len
48 mac 20 auth 2

sendpkt(fd=1832 dst=10.39.142.3, src=10.39.142.1, ttl=0, len=52)

Allocation 28 memory for io_completion_port_sendto, ptr 9ecb68

Freeing memory for OnWriteComplete, ptr 9ec8e0

WSASendTo - 52 bytes to 10.39.142.3 : 0

transmit: at 4 10.39.142.1->10.39.142.3 mode 4 keyid 00000000 len 48 mac
4

Freeing memory for OnWriteComplete, ptr 9ecb68

IoEvent occurred

refclock_gtraw: fd 1808 time 3385723785.946194 timecode 126
$GPRMC,145744,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*19

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145744,5938.5864,N,0170

nmea: gpsread 122
$GPRMC,145744,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*19$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145744,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145744,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*19$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145744,5938.5864,N,0170

adj_systime: adj -0.000488213 -> remaining residual -0.000002665

SetSystemTimeAdjustment( -77) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000001922

IoEvent occurred

refclock_gtraw: fd 1808 time 3385723787.945264 timecode 126
$GPRMC,145746,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1B

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145746,5938.5864,N,0170

nmea: gpsread 122
$GPRMC,145746,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1B$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145746,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145746,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1B$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145746,5938.5864,N,0170

SetSystemTimeAdjustment( -76) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000000109

adj_systime: adj -0.000488213 -> remaining residual -0.000001704

IoEvent occurred

refclock_gtraw: fd 1808 time 3385723789.944260 timecode 126
$GPRMC,145748,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*15

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145748,5938.5864,N,0170

nmea: gpsread 122
$GPRMC,145748,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*15$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145748,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145748,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*15$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145748,5938.5864,N,0170

SetSystemTimeAdjustment( -77) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000002883

Received 68 bytes from 10.39.142.6

Received 68 bytes from 10.39.141.1

receive: at 9 10.39.142.1<-10.39.142.6 flags 19 restrict 000

receive: at 9 10.39.142.1<-10.39.142.6 mode 3 code 3 keyid 00000001 len
48 mac 20 auth 2

sendpkt(fd=1832 dst=10.39.142.6, src=10.39.142.1, ttl=0, len=52)

Allocation 28 memory for io_completion_port_sendto, ptr 9ec8e0

Freeing memory for OnWriteComplete, ptr 9ec8e0

WSASendTo - 52 bytes to 10.39.142.6 : 0

transmit: at 9 10.39.142.1->10.39.142.6 mode 4 keyid 00000000 len 48 mac
4

receive: at 9 10.39.142.1<-10.39.141.1 flags 19 restrict 000

receive: at 9 10.39.142.1<-10.39.141.1 mode 1 code 5 keyid 00000001 len
48 mac 20 auth 2

sendpkt(fd=1832 dst=10.39.141.1, src=10.39.142.1, ttl=0, len=52)

Allocation 28 memory for io_completion_port_sendto, ptr 9ec8e0

Freeing memory for OnWriteComplete, ptr 9ec8e0

WSASendTo - 52 bytes to 10.39.141.1 : 0

transmit: at 9 10.39.142.1->10.39.141.1 mode 1 keyid 00000000 len 48 mac
4

SetSystemTimeAdjustment( -76) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000001070

IoEvent occurred

refclock_gtraw: fd 1808 time 3385723791.943263 timecode 126
$GPRMC,145750,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1C

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145750,5938.5864,N,0170

nmea: gpsread 122
$GPRMC,145750,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1C$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145750,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145750,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1C$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145750,5938.5864,N,0170

adj_systime: adj -0.000488213 -> remaining residual -0.000000743

adj_systime: adj -0.000488213 -> remaining residual -0.000002556

IoEvent occurred

refclock_gtraw: fd 1808 time 3385723793.942285 timecode 126
$GPRMC,145752,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1E

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145752,5938.5864,N,0170

nmea: gpsread 122
$GPRMC,145752,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1E$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145752,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145752,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1E$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145752,5938.5864,N,0170

SetSystemTimeAdjustment( -77) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000002031

Received 68 bytes from 10.39.142.6

receive: at 13 10.39.142.1<-10.39.142.6 flags 19 restrict 000

receive: at 13 10.39.142.1<-10.39.142.6 mode 3 code 3 keyid 00000001 len
48 mac 20 auth 2

sendpkt(fd=1832 dst=10.39.142.6, src=10.39.142.1, ttl=0, len=52)

Allocation 28 memory for io_completion_port_sendto, ptr 9ec8e0

WSASendTo - 52 bytes to 10.39.142.6 : 0

transmit: at 13 10.39.142.1->10.39.142.6 mode 4 keyid 00000000 len 48
mac 4

Freeing memory for OnWriteComplete, ptr 9ec8e0

SetSystemTimeAdjustment( -76) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000000218

IoEvent occurred

refclock_gtraw: fd 1808 time 3385723795.941313 timecode 126
$GPRMC,145754,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*18

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145754,5938.5864,N,0170

nmea: gpsread 122
$GPRMC,145754,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*18$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145754,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145754,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*18$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145754,5938.5864,N,0170

adj_systime: adj -0.000488213 -> remaining residual -0.000001595

SetSystemTimeAdjustment( -77) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000002992

IoEvent occurred

refclock_gtraw: fd 1808 time 3385723797.940352 timecode 126
$GPRMC,145756,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1A

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145756,5938.5864,N,0170

nmea: gpsread 122
$GPRMC,145756,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1A$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145756,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145756,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1A$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145756,5938.5864,N,0170

SetSystemTimeAdjustment( -76) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000001179

adj_systime: adj -0.000488213 -> remaining residual -0.000000634

refclock_transmit: at 18 127.127.20.1

addto_syslog: clock GPS_NMEA(1) event 'clk_fault' (0x03)

peer GPS_NMEA(1) event 'event_peer_clock' (0x85) status 'unreach, conf,
3 events, event_peer_clock' (0x8035)

poll_update: at 18 127.127.20.1 flags 1221 poll 4 burst 0 last 18 next
36

Received 68 bytes from 10.39.142.12

IoEvent occurred

receive: at 18 10.39.142.1<-10.39.142.12 flags 19 restrict 000

receive: at 18 10.39.142.1<-10.39.142.12 mode 3 code 3 keyid 00000001
len 48 mac 20 auth 2

sendpkt(fd=1832 dst=10.39.142.12, src=10.39.142.1, ttl=0, len=52)

Allocation 28 memory for io_completion_port_sendto, ptr 9ec8e0

WSASendTo - 52 bytes to 10.39.142.12 : 0

Freeing memory for OnWriteComplete, ptr 9ec8e0

transmit: at 18 10.39.142.1->10.39.142.12 mode 4 keyid 00000000 len 48
mac 4

refclock_gtraw: fd 1808 time 3385723799.939365 timecode 126
$GPRMC,145758,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*14

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145758,5938.5864,N,0170

nmea: gpsread 122
$GPRMC,145758,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*14$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145758,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145758,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*14$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145758,5938.5864,N,0170

refclock_receive: at 18 127.127.20.1

peer GPS_NMEA(1) event 'event_peer_clock' (0x85) status 'unreach, conf,
4 events, event_peer_clock' (0x8045)

adj_systime: adj -0.000488213 -> remaining residual -0.000002447

SetSystemTimeAdjustment( -77) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000002140

IoEvent occurred

refclock_gtraw: fd 1808 time 3385723801.938389 timecode 126
$GPRMC,145800,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*16

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145800,5938.5864,N,0170

nmea: gpsread 122
$GPRMC,145800,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*16$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145800,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145800,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*16$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145800,5938.5864,N,0170

SetSystemTimeAdjustment( -76) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000000327

adj_systime: adj -0.000488213 -> remaining residual -0.000001486

Received 68 bytes from 10.39.142.14

receive: at 22 10.39.142.1<-10.39.142.14 flags 19 restrict 000

receive: at 22 10.39.142.1<-10.39.142.14 mode 3 code 3 keyid 00000001
len 48 mac 20 auth 2

sendpkt(fd=1832 dst=10.39.142.14, src=10.39.142.1, ttl=0, len=52)

Allocation 28 memory for io_completion_port_sendto, ptr 9ec8e0

WSASendTo - 52 bytes to 10.39.142.14 : 0

transmit: at 22 10.39.142.1->10.39.142.14 mode 4 keyid 00000000 len 48
mac 4

Freeing memory for OnWriteComplete, ptr 9ec8e0

IoEvent occurred

refclock_gtraw: fd 1808 time 3385723803.953019 timecode 126
$GPRMC,145802,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*14

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145802,5938.5864,N,0170

nmea: gpsread 122
$GPRMC,145802,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*14$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145802,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145802,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*14$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145802,5938.5864,N,0170

SetSystemTimeAdjustment( -77) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000003101

SetSystemTimeAdjustment( -76) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000001288

IoEvent occurred

refclock_gtraw: fd 1808 time 3385723805.952044 timecode 126
$GPRMC,145804,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*12

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145804,5938.5864,N,0170

nmea: gpsread 122
$GPRMC,145804,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*12$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145804,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145804,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*12$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145804,5938.5864,N,0170

adj_systime: adj -0.000488213 -> remaining residual -0.000000525

adj_systime: adj -0.000488213 -> remaining residual -0.000002338

IoEvent occurred

refclock_gtraw: fd 1808 time 3385723807.951069 timecode 126
$GPRMC,145806,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*10

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145806,5938.5864,N,0170

nmea: gpsread 122
$GPRMC,145806,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*10$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145806,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145806,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*10$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145806,5938.5864,N,0170

SetSystemTimeAdjustment( -77) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000002249

Received 68 bytes from 10.39.142.6

receive: at 27 10.39.142.1<-10.39.142.6 flags 19 restrict 000

receive: at 27 10.39.142.1<-10.39.142.6 mode 3 code 3 keyid 00000001 len
48 mac 20 auth 2

sendpkt(fd=1832 dst=10.39.142.6, src=10.39.142.1, ttl=0, len=52)

Allocation 28 memory for io_completion_port_sendto, ptr 9ec8e0

WSASendTo - 52 bytes to 10.39.142.6 : 0

transmit: at 27 10.39.142.1->10.39.142.6 mode 4 keyid 00000000 len 48
mac 4

Freeing memory for OnWriteComplete, ptr 9ec8e0

SetSystemTimeAdjustment( -76) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000000436

IoEvent occurred

refclock_gtraw: fd 1808 time 3385723809.950109 timecode 126
$GPRMC,145808,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1E

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145808,5938.5864,N,0170

nmea: gpsread 122
$GPRMC,145808,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1E$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145808,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145808,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*1E$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145808,5938.5864,N,0170

adj_systime: adj -0.000488213 -> remaining residual -0.000001377

adj_systime: adj -0.000488213 -> remaining residual -0.000003190

IoEvent occurred

refclock_gtraw: fd 1808 time 3385723811.949133 timecode 126
$GPRMC,145810,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*17

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145810,5938.5864,N,0170

nmea: gpsread 122
$GPRMC,145810,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*17$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145810,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145810,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*17$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145810,5938.5864,N,0170

SetSystemTimeAdjustment( -77) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000001397

SetSystemTimeAdjustment( -76) + (156250)

adj_systime: adj -0.000488213 -> remaining residual -0.000000416

IoEvent occurred

refclock_gtraw: fd 1808 time 3385723813.932521 timecode 126
$GPRMC,145812,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*15

$GPRMB,V,,,,,,,,,,,,A,S*0E

$GPGGA,145812,5938.5864,N,0170

nmea: gpsread 122
$GPRMC,145812,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*15$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145812,5938.5864,N,0170

nmea: timecode 122
$GPRMC,145812,V,5938.5864,N,01706.8010,E,0.0,0.0,160407,3.6,E,S*15$GPRMB
,V,,,,,,,,,,,,A,S*0E$GPGGA,145812,5938.5864,N,0170

adj_systime: adj -0.000488213 -> remaining residual -0.000002229

SetSystemTimeAdjustment( -77) + (156250)

adj_systime: adj -0.000488213 -> remaining residual 0.000002358

Overlapped IO Thread Exits: 

addto_syslog: ntservice: The Network Time Protocol Service has stopped.




More information about the questions mailing list