[gpsd-users] time setting chicken and egg

[Yan Seiner]

I'm working on a platform that is mobile it sometimes has access to the 
internet and sometimes not.   The hardware does not have a RTC so at 
power-up it sets the clock to something like Dec 31, 1969.

I'm trying to set up chrony + gpsd to set the time from the gps directly.

The behavior I'm seeing from gpsd is that it won't report a time to 
chrony until the system clock is close to what gpsd is seeing from the 
gps.  I have not found a way to override this.

I'm attaching a log of what happens.  On wifi connect the system gets a 
system time via rdate, at which point gpsd and chrony start to function 
normally.  You can see that gpsd reports a discrepancy and chrony 
doesn't set the clock at all.....

Does anyone have any ideas on where to look?

--
Few people are capable of expressing with equanimity opinions which differ from 
the prejudices of their social environment. Most people are even incapable of 
forming such opinions.
   Albert Einstein

Dec 31 16:11:10 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:12 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:13 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:14 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:15 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:16 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:17 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:18 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:19 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:20 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:20 anchor user.info sysinit: essid: EluOnHea2
Dec 31 16:11:20 anchor user.info sysinit: EluOnHea2
Dec 31 16:11:22 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:22 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:23 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:23 anchor user.info kernel: b43-phy0: Loading firmware version 
410.2160 (2007-05-26 15:32:10)
Dec 31 16:11:25 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:26 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:27 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:28 anchor user.info firewall: removing wifi (wlan0) from zone wifi
Dec 31 16:11:28 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:29 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:29 anchor user.debug kernel: wlan0: authenticate with 
ce:76:19:a3:11:a1 (try 1)
Dec 31 16:11:29 anchor user.debug kernel: wlan0: authenticated
Dec 31 16:11:29 anchor user.debug kernel: wlan0: associate with 
ce:76:19:a3:11:a1 (try 1)
Dec 31 16:11:29 anchor user.debug kernel: wlan0: RX AssocResp from 
ce:76:19:a3:11:a1 (capab=0x411 status=0 aid=2)
Dec 31 16:11:29 anchor user.debug kernel: wlan0: associated
Dec 31 16:11:30 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:30 anchor user.info sysinit: udhcpc (v1.15.3) started
Dec 31 16:11:31 anchor user.info sysinit: Sending discover...
Dec 31 16:11:31 anchor user.info sysinit: Sending select for 192.168.128.10...
Dec 31 16:11:31 anchor user.info sysinit: Lease of 192.168.128.10 obtained, 
lease time 14400
Dec 31 16:11:32 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:32 anchor user.info sysinit: udhcpc: ifconfig wlan0 192.168.128.10 
netmask 255.255.255.0 broadcast +
Dec 31 16:11:32 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:32 anchor user.info sysinit: udhcpc: setting default routers: 
192.168.128.19
Dec 31 16:11:32 anchor user.info sysinit: udhcpc: setting dns servers: 
192.168.128.6
Dec 31 16:11:33 anchor user.info sysinit: udhcpc: setting dns domain: seiner.lan
Dec 31 16:11:33 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:33 anchor user.notice ifup: Allowing Router Advertisements on wifi 
(wlan0)
Dec 31 16:11:35 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:35 anchor user.notice rdate: No usable time server for wifi found
Dec 31 16:11:35 anchor user.info firewall: adding wifi (wlan0) to zone wifi
Dec 31 16:11:35 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:36 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:38 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:39 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:40 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:41 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:42 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:43 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:45 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:45 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:46 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:48 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:48 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:49 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:50 anchor daemon.info dnsmasq[1301]: reading /tmp/resolv.conf.auto
Dec 31 16:11:50 anchor daemon.info dnsmasq[1301]: using nameserver 
192.168.128.6#53
Dec 31 16:11:50 anchor daemon.info dnsmasq[1301]: using local addresses only 
for domain roadtrek.lan
Dec 31 16:11:50 anchor daemon.info dnsmasq[1301]: using local addresses only 
for domain lan
Dec 31 16:11:50 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:17:50 anchor user.info sysinit: Sun Oct 23 18:17:50 2011
Oct 23 18:18:00 anchor cron.err crond[1259]: time disparity of 21990306 minutes 
detected
Oct 23 18:18:00 anchor daemon.warn chronyd[1335]: System clock wrong by 
-598527509.572429 seconds, adjustment started
Oct 23 18:18:00 anchor daemon.warn chronyd[1335]: Required tick 5083 outside 
allowed range (9005 .. 10995)
Oct 23 18:18:10 anchor daemon.warn dnsmasq[1301]: possible DNS-rebind attack 
detected: www.seiner.com
Oct 23 18:18:10 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:18:09 anchor user.info sysinit: Sun Oct 23 18:18:09 2011
Oct 23 18:18:17 anchor daemon.warn chronyd[1335]: System clock wrong by 
-3.318620 seconds, adjustment started
Oct 23 18:18:17 anchor daemon.warn chronyd[1335]: Required tick 4219 outside 
allowed range (9005 .. 10995)
Oct 23 18:18:29 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:18:30 anchor user.info sysinit: Sun Oct 23 18:18:30 2011
Oct 23 18:18:34 anchor daemon.warn chronyd[1335]: Required tick 4218 outside 
allowed range (9005 .. 10995)
Oct 23 18:18:50 anchor daemon.warn dnsmasq[1301]: possible DNS-rebind attack 
detected: www.seiner.com
Oct 23 18:18:50 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:18:52 anchor user.info sysinit: Sun Oct 23 18:18:52 2011
Oct 23 18:18:52 anchor daemon.warn chronyd[1335]: System clock wrong by 
-1.769501 seconds, adjustment started
Oct 23 18:18:52 anchor daemon.warn chronyd[1335]: Required tick 4218 outside 
allowed range (9005 .. 10995)
Oct 23 18:18:53 anchor user.info kernel: saa7115 0-0025: saa7113 found 
(1f7113d0e100000) @ 0x4a (usbvision #0)
Oct 23 18:19:08 anchor daemon.warn chronyd[1335]: System clock wrong by 
12.940229 seconds, adjustment started
Oct 23 18:19:12 anchor daemon.warn dnsmasq[1301]: possible DNS-rebind attack 
detected: www.seiner.com
Oct 23 18:19:12 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:19:14 anchor user.info sysinit: Sun Oct 23 18:19:14 2011
Oct 23 18:19:31 anchor auth.info sshd[2113]: WARNING: /etc/ssh/moduli does not 
exist, using fixed modulus
Oct 23 18:19:34 anchor daemon.warn dnsmasq[1301]: possible DNS-rebind attack 
detected: www.seiner.com
Oct 23 18:19:34 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:19:37 anchor user.info sysinit: Sun Oct 23 18:19:37 2011
Oct 23 18:19:37 anchor auth.err sshd[2113]: error: Could not get shadow 
information for root
Oct 23 18:19:37 anchor auth.info sshd[2113]: Accepted password for root from 
10.10.0.1 port 60427 ssh2
Oct 23 18:19:44 anchor daemon.warn chronyd[1335]: System clock wrong by 
-0.727348 seconds, adjustment started
Oct 23 18:19:54 anchor user.info kernel: saa7115 0-0025: saa7113 found 
(1f7113d0e100000) @ 0x4a (usbvision #0)
Oct 23 18:19:57 anchor daemon.warn dnsmasq[1301]: possible DNS-rebind attack 
detected: www.seiner.com
Oct 23 18:19:57 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:19:59 anchor user.info sysinit: Sun Oct 23 18:19:59 2011
Oct 23 18:20:06 anchor cron.err crond[1259]: USER root pid 2140 cmd 
/usr/sbin/movepics
Oct 23 18:20:06 anchor cron.err crond[1259]: USER root pid 2141 cmd 
/usr/sbin/kfs
Oct 23 18:20:19 anchor daemon.warn dnsmasq[1301]: possible DNS-rebind attack 
detected: www.seiner.com
Oct 23 18:20:19 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:20:22 anchor user.info sysinit: Sun Oct 23 18:20:22 2011
Oct 23 18:20:37 anchor daemon.warn chronyd[1335]: System clock wrong by 
-0.715995 seconds, adjustment started
Oct 23 18:20:42 anchor daemon.warn dnsmasq[1301]: possible DNS-rebind attack 
detected: www.seiner.com
Oct 23 18:20:42 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:20:44 anchor user.info sysinit: Sun Oct 23 18:20:44 2011

Dec 31 16:11:26 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:27 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:28 anchor user.info firewall: removing wifi (wlan0) from zone wifi
Dec 31 16:11:28 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:29 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:29 anchor user.debug kernel: wlan0: authenticate with 
ce:76:19:a3:11:a1 (try 1)
Dec 31 16:11:29 anchor user.debug kernel: wlan0: authenticated
Dec 31 16:11:29 anchor user.debug kernel: wlan0: associate with 
ce:76:19:a3:11:a1 (try 1)
Dec 31 16:11:29 anchor user.debug kernel: wlan0: RX AssocResp from 
ce:76:19:a3:11:a1 (capab=0x411 status=0 aid=2)
Dec 31 16:11:29 anchor user.debug kernel: wlan0: associated
Dec 31 16:11:30 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:30 anchor user.info sysinit: udhcpc (v1.15.3) started
Dec 31 16:11:31 anchor user.info sysinit: Sending discover...
Dec 31 16:11:31 anchor user.info sysinit: Sending select for 192.168.128.10...
Dec 31 16:11:31 anchor user.info sysinit: Lease of 192.168.128.10 obtained, 
lease time 14400
Dec 31 16:11:32 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:32 anchor user.info sysinit: udhcpc: ifconfig wlan0 192.168.128.10 
netmask 255.255.255.0 broadcast +
Dec 31 16:11:32 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:32 anchor user.info sysinit: udhcpc: setting default routers: 
192.168.128.19
Dec 31 16:11:32 anchor user.info sysinit: udhcpc: setting dns servers: 
192.168.128.6
Dec 31 16:11:33 anchor user.info sysinit: udhcpc: setting dns domain: seiner.lan
Dec 31 16:11:33 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:33 anchor user.notice ifup: Allowing Router Advertisements on wifi 
(wlan0)
Dec 31 16:11:35 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:35 anchor user.notice rdate: No usable time server for wifi found
Dec 31 16:11:35 anchor user.info firewall: adding wifi (wlan0) to zone wifi
Dec 31 16:11:35 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:36 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:38 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:39 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:40 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:41 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:42 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:43 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:45 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:45 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:46 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:48 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:48 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:49 anchor user.err gpsd[1307]: gpsd: date more than a year in the 
future!
Dec 31 16:11:50 anchor daemon.info dnsmasq[1301]: reading /tmp/resolv.conf.auto
Dec 31 16:11:50 anchor daemon.info dnsmasq[1301]: using nameserver 
192.168.128.6#53
Dec 31 16:11:50 anchor daemon.info dnsmasq[1301]: using local addresses only 
for domain roadtrek.lan
Dec 31 16:11:50 anchor daemon.info dnsmasq[1301]: using local addresses only 
for domain lan
Dec 31 16:11:50 anchor daemon.warn dnsmasq[1301]: possible DNS-rebind attack 
detected: www.seiner.com
Dec 31 16:11:50 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:17:50 anchor user.info sysinit: Sun Oct 23 18:17:50 2011
Oct 23 18:18:00 anchor cron.err crond[1259]: time disparity of 21990306 minutes 
detected
Oct 23 18:18:00 anchor daemon.warn chronyd[1335]: System clock wrong by 
-598527509.572429 seconds, adjustment started
Oct 23 18:18:00 anchor daemon.warn chronyd[1335]: Required tick 5083 outside 
allowed range (9005 .. 10995)
Oct 23 18:18:10 anchor daemon.warn dnsmasq[1301]: possible DNS-rebind attack 
detected: www.seiner.com
Oct 23 18:18:10 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:18:09 anchor user.info sysinit: Sun Oct 23 18:18:09 2011
Oct 23 18:18:17 anchor daemon.warn chronyd[1335]: System clock wrong by 
-3.318620 seconds, adjustment started
Oct 23 18:18:17 anchor daemon.warn chronyd[1335]: Required tick 4219 outside 
allowed range (9005 .. 10995)
Oct 23 18:18:29 anchor daemon.warn dnsmasq[1301]: possible DNS-rebind attack 
detected: www.seiner.com
Oct 23 18:18:29 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:18:30 anchor user.info sysinit: Sun Oct 23 18:18:30 2011
Oct 23 18:18:34 anchor daemon.warn chronyd[1335]: Required tick 4218 outside 
allowed range (9005 .. 10995)
Oct 23 18:18:50 anchor daemon.warn dnsmasq[1301]: possible DNS-rebind attack 
detected: www.seiner.com
Oct 23 18:18:50 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:18:52 anchor user.info sysinit: Sun Oct 23 18:18:52 2011
Oct 23 18:18:52 anchor daemon.warn chronyd[1335]: System clock wrong by 
-1.769501 seconds, adjustment started
Oct 23 18:18:52 anchor daemon.warn chronyd[1335]: Required tick 4218 outside 
allowed range (9005 .. 10995)
Oct 23 18:18:53 anchor user.info kernel: saa7115 0-0025: saa7113 found 
(1f7113d0e100000) @ 0x4a (usbvision #0)
Oct 23 18:19:02 anchor cron.err crond[1259]: USER root pid 2045 cmd 
/usr/sbin/movepics
Oct 23 18:19:02 anchor cron.err crond[1259]: USER root pid 2046 cmd 
/usr/sbin/kfs
Oct 23 18:19:08 anchor daemon.warn chronyd[1335]: System clock wrong by 
12.940229 seconds, adjustment started
Oct 23 18:19:12 anchor daemon.warn dnsmasq[1301]: possible DNS-rebind attack 
detected: www.seiner.com
Oct 23 18:19:12 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:19:14 anchor user.info sysinit: Sun Oct 23 18:19:14 2011
Oct 23 18:19:31 anchor auth.info sshd[2113]: WARNING: /etc/ssh/moduli does not 
exist, using fixed modulus
Oct 23 18:19:34 anchor daemon.warn dnsmasq[1301]: possible DNS-rebind attack 
detected: www.seiner.com
Oct 23 18:19:34 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:19:37 anchor user.info sysinit: Sun Oct 23 18:19:37 2011
Oct 23 18:19:37 anchor auth.err sshd[2113]: error: Could not get shadow 
information for root
Oct 23 18:19:37 anchor auth.info sshd[2113]: Accepted password for root from 
10.10.0.1 port 60427 ssh2
Oct 23 18:19:44 anchor daemon.warn chronyd[1335]: System clock wrong by 
-0.727348 seconds, adjustment started
Oct 23 18:19:54 anchor user.info kernel: saa7115 0-0025: saa7113 found 
(1f7113d0e100000) @ 0x4a (usbvision #0)
Oct 23 18:19:57 anchor daemon.warn dnsmasq[1301]: possible DNS-rebind attack 
detected: www.seiner.com
Oct 23 18:19:57 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:19:59 anchor user.info sysinit: Sun Oct 23 18:19:59 2011
Oct 23 18:20:06 anchor cron.err crond[1259]: USER root pid 2140 cmd 
/usr/sbin/movepics
Oct 23 18:20:06 anchor cron.err crond[1259]: USER root pid 2141 cmd 
/usr/sbin/kfs
Oct 23 18:20:19 anchor daemon.warn dnsmasq[1301]: possible DNS-rebind attack 
detected: www.seiner.com
Oct 23 18:20:19 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:20:22 anchor user.info sysinit: Sun Oct 23 18:20:22 2011
Oct 23 18:20:37 anchor daemon.warn chronyd[1335]: System clock wrong by 
-0.715995 seconds, adjustment started
Oct 23 18:20:42 anchor daemon.warn dnsmasq[1301]: possible DNS-rebind attack 
detected: www.seiner.com
Oct 23 18:20:42 anchor user.info sysinit: rdate: bad address 'www.seiner.com'
Oct 23 18:20:44 anchor user.info sysinit: Sun Oct 23 18:20:44 2011