From owner-freebsd-bugs@freebsd.org Thu Nov 21 11:54:46 2019 Return-Path: Delivered-To: freebsd-bugs@mailman.nyi.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2610:1c1:1:606c::19:1]) by mailman.nyi.freebsd.org (Postfix) with ESMTP id EBA421BB4D4 for ; Thu, 21 Nov 2019 11:54:46 +0000 (UTC) (envelope-from bugzilla-noreply@freebsd.org) Received: from mailman.nyi.freebsd.org (unknown [127.0.1.3]) by mx1.freebsd.org (Postfix) with ESMTP id 47JdK260Phz4HVd for ; Thu, 21 Nov 2019 11:54:46 +0000 (UTC) (envelope-from bugzilla-noreply@freebsd.org) Received: by mailman.nyi.freebsd.org (Postfix) id CDD901BB4D2; Thu, 21 Nov 2019 11:54:46 +0000 (UTC) Delivered-To: bugs@mailman.nyi.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2610:1c1:1:606c::19:1]) by mailman.nyi.freebsd.org (Postfix) with ESMTP id CDA101BB4D1 for ; Thu, 21 Nov 2019 11:54:46 +0000 (UTC) (envelope-from bugzilla-noreply@freebsd.org) Received: from mxrelay.nyi.freebsd.org (mxrelay.nyi.freebsd.org [IPv6:2610:1c1:1:606c::19:3]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) server-signature RSA-PSS (4096 bits) client-signature RSA-PSS (4096 bits) client-digest SHA256) (Client CN "mxrelay.nyi.freebsd.org", Issuer "Let's Encrypt Authority X3" (verified OK)) by mx1.freebsd.org (Postfix) with ESMTPS id 47JdK25BTZz4HVc for ; Thu, 21 Nov 2019 11:54:46 +0000 (UTC) (envelope-from bugzilla-noreply@freebsd.org) Received: from kenobi.freebsd.org (kenobi.freebsd.org [IPv6:2610:1c1:1:606c::50:1d]) (using TLSv1.2 with cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)) (Client did not present a certificate) by mxrelay.nyi.freebsd.org (Postfix) with ESMTPS id 96672AD0A for ; Thu, 21 Nov 2019 11:54:46 +0000 (UTC) (envelope-from bugzilla-noreply@freebsd.org) Received: from kenobi.freebsd.org ([127.0.1.5]) by kenobi.freebsd.org (8.15.2/8.15.2) with ESMTP id xALBskLg099028 for ; Thu, 21 Nov 2019 11:54:46 GMT (envelope-from bugzilla-noreply@freebsd.org) Received: (from www@localhost) by kenobi.freebsd.org (8.15.2/8.15.2/Submit) id xALBsk11099027 for bugs@FreeBSD.org; Thu, 21 Nov 2019 11:54:46 GMT (envelope-from bugzilla-noreply@freebsd.org) X-Authentication-Warning: kenobi.freebsd.org: www set sender to bugzilla-noreply@freebsd.org using -f From: bugzilla-noreply@freebsd.org To: bugs@FreeBSD.org Subject: [Bug 242137] [NTP] "Unexpected origin timestamp 0xe180e455.d15bf3fb does not match aorg 0000000000.00000000 from server" (kernel reports TIME_ERROR: 0x41: Clock Unsynchronized) Date: Thu, 21 Nov 2019 11:54:46 +0000 X-Bugzilla-Reason: AssignedTo X-Bugzilla-Type: new X-Bugzilla-Watch-Reason: None X-Bugzilla-Product: Base System X-Bugzilla-Component: bin X-Bugzilla-Version: 12.0-STABLE X-Bugzilla-Keywords: X-Bugzilla-Severity: Affects Some People X-Bugzilla-Who: egypcio@FreeBSD.org X-Bugzilla-Status: New X-Bugzilla-Resolution: X-Bugzilla-Priority: --- X-Bugzilla-Assigned-To: bugs@FreeBSD.org X-Bugzilla-Flags: X-Bugzilla-Changed-Fields: bug_id short_desc product version rep_platform op_sys bug_status bug_severity priority component assigned_to reporter Message-ID: Content-Type: text/plain; charset="UTF-8" Content-Transfer-Encoding: quoted-printable X-Bugzilla-URL: https://bugs.freebsd.org/bugzilla/ Auto-Submitted: auto-generated MIME-Version: 1.0 X-BeenThere: freebsd-bugs@freebsd.org X-Mailman-Version: 2.1.29 Precedence: list List-Id: Bug reports List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Thu, 21 Nov 2019 11:54:47 -0000 https://bugs.freebsd.org/bugzilla/show_bug.cgi?id=3D242137 Bug ID: 242137 Summary: [NTP] "Unexpected origin timestamp 0xe180e455.d15bf3fb does not match aorg 0000000000.00000000 from server" (kernel reports TIME_ERROR: 0x41: Clock Unsynchronized) Product: Base System Version: 12.0-STABLE Hardware: amd64 OS: Any Status: New Severity: Affects Some People Priority: --- Component: bin Assignee: bugs@FreeBSD.org Reporter: egypcio@FreeBSD.org *** this issue was first noticed on FreeBSD stable/12@r354367; amd64. after updating the machine to stable/12@r354893 it still persists. bare metal, no= VM. OSVERSION and KERNVERSION are both 1201502. *** it was tested in 2 different machines (same hardware with same BIOS version, drivers and so on; all up to date. dell poweredge r440). % grep -i ntp /etc/rc.conf ntpdate_enable=3D"YES" ntpd_enable=3D"YES" ntpd_flags=3D"${ntpd_flags} -4 --logfile=3D/var/log/ntpd.log --statsdir=3D/etc/ntp/stats -g -g -g -G" % grep -v ^# /etc/ntp.conf=20 restrict default limited kod nomodify notrap noquery nopeer restrict 127.0.0.1 mask 255.255.255.255 restrict 10.64.35.0 mask 255.255.255.248 nomodify notrap server ntp1 iburst prefer server ntp2 iburst prefer server ntp3 iburst server ntp4 iburst tos orphan 3 statistics clockstats cryptostats loopstats peerstats rawstats sysstats % uptime 12:39PM up 19:28, 4 users, load averages: 0.33, 0.26, 0.21 % date ; ntpq -4 -n -c peers localhost Thu Nov 21 12:39:57 CET 2019 remote refid st t when poll reach delay offset jit= ter =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D 10.0.5.3 134.130.4.17 2 u 14 64 1 0.172 1295.19 145.= 506 10.0.5.4 192.87.106.2 2 u 12 64 1 0.199 1151.60 163.= 782 10.0.32.248 134.130.4.17 2 u 15 64 1 1.039 1093.62 252.= 553 10.0.32.249 192.87.106.2 2 u 14 64 1 1.064 1084.35 263.= 641 % tail -n5 /var/log/ntpd.log 21 Nov 11:54:19 ntpd[62796]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized 21 Nov 11:54:19 ntpd[62796]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized 21 Nov 11:54:27 ntpd[62796]: Doing intital time step 21 Nov 12:07:40 ntpd[62796]: receive: Unexpected origin timestamp 0xe180ee7b.1b07155c does not match aorg 0000000000.00000000 from server@10.0.32.249 xmt 0xe180ee7c.0e6de70f 21 Nov 12:12:48 ntpd[62796]: receive: Unexpected origin timestamp 0xe180efb0.0f7b03f3 does not match aorg 0000000000.00000000 from server@10.0.32.249 xmt 0xe180efb0.c5f625d7 *** the server reported above by ntpd.log is NOT always the same; also used servers from the public pool offered by ntp.org, and others. *** stratum 1 and 2 were used to try synchonizing the clock. the machine ran with a slightly nodified 'ntp.conf' with +4 servers for a couple minutes. s= ame behavior. *** a reboot was conducted just in case.=20 # tail /etc/ntp/stats/*.20191121 =3D=3D> /etc/ntp/stats/loopstats.20191121 <=3D=3D 58808 39269.319 0.000000000 41.447 0.000000238 0.000000 6 58808 39276.266 0.016292991 41.447 0.005760442 0.000000 6 58808 39280.272 0.024378028 41.447 0.006099659 0.000000 6 58808 39283.292 0.028349141 41.447 0.005875910 0.000000 6 58808 39284.325 0.031962872 41.447 0.005642953 0.000000 6 58808 39648.103 0.000000000 41.447 0.000000238 0.000000 6 58808 40060.057 0.000000000 41.447 0.000000238 0.000000 6 58808 40368.773 0.000000000 41.447 0.000000238 0.000003 6 58808 41071.263 0.000000000 41.447 0.000000238 0.000003 6 58808 41831.078 0.000000000 41.447 0.000000238 0.000003 6 =3D=3D> /etc/ntp/stats/peerstats.20191121 <=3D=3D 58808 41832.116 10.0.5.4 9014 1.084662017 0.000208704 7.937500151 0.0000002= 38 58808 41832.116 10.0.5.3 9014 1.084808389 0.000198845 7.937500151 0.0000002= 38 58808 41832.117 10.0.32.249 9074 1.084358544 0.001063677 7.937500157 0.000000238 58808 41836.090 10.0.32.248 9064 1.093624959 0.001039041 7.937500157 0.000000238 58808 41861.061 10.0.5.4 9014 1.151605000 0.000199260 3.937608976 0.0669429= 83 58808 41921.087 10.0.5.3 9014 1.290578800 0.000184648 3.937833976 0.2057704= 11 58808 41923.097 10.0.5.3 9014 1.295191429 0.000171885 1.937678388 0.1487990= 25 58808 42044.123 10.0.32.248 9064 1.574754317 0.000975819 0.438625303 0.321708459 58808 42045.071 10.0.5.3 961a 1.577294399 0.000142329 0.438419041 0.3259213= 91 58808 42107.065 10.0.32.249 9374 1.720286304 0.000934281 0.188952496 0.417919861 =3D=3D> /etc/ntp/stats/rawstats.20191121 <=3D=3D 58808 42045.071 10.0.5.3 10.0.5.7 3783325245.070321137 3783325246.647686701 3783325246.647714587 3783325245.070491352 0 4 4 2 6 -24 0.002029 0.038559 134.130.4.17 58808 42045.071 10.0.32.249 10.0.248.26 3783325245.070242874 3783325246.647650840 3783325246.647691908 3783325245.071362245 0 4 4 2 6 -24 0.006226 0.043961 192.87.106.2 58808 42047.107 10.0.5.7 10.0.5.4 49709281.717738837 32932065.437737468 1073119457.879780001 0.000000000 3 4 3 3 6 -22 2382364672.000000 1449198080.000000 10.0.5.3 58808 42047.107 10.0.5.4 10.0.5.7 3783325247.106494539 3783325248.688494213 3783325248.688520585 3783325247.106853646 0 4 4 2 6 -24 0.005630 0.042023 192.87.106.2 58808 42106.109 10.0.248.26 10.0.32.248 1039565025.590554219 1022787809.035393484 2062975201.462277836 0.000000000 3 4 3 3 6 -22 2382364672.000000 2422276608.000000 10.0.5.3 58808 42106.110 10.0.32.248 10.0.248.26 3783325306.108754603 3783325307.827359874 3783325307.827429942 3783325306.109918638 0 4 4 2 6 -24 0.002396 0.036011 134.130.4.17 58808 42107.064 10.0.248.26 10.0.32.249 1056342241.047421434 1039565025.799976591 2079752417.367572907 0.000000000 3 4 3 3 6 -22 2382364672.000000 2439053824.000000 10.0.5.3 58808 42107.064 10.0.5.7 10.0.5.3 1056342241.541501084 1039565025.647339527 2079752417.948276881 0.000000000 3 4 3 3 6 -22 2382364672.000000 2439053824.000000 10.0.5.3 58808 42107.064 10.0.5.3 10.0.5.7 3783325307.063579735 3783325308.784316616 3783325308.784336750 3783325307.063787127 0 4 4 2 6 -24 0.002029 0.039490 134.130.4.17 58808 42107.065 10.0.32.249 10.0.248.26 3783325307.063508592 3783325308.784262036 3783325308.784329157 3783325307.064509994 0 4 4 2 6 -24 0.006226 0.044891 192.87.106.2 =3D=3D> /etc/ntp/stats/sysstats.20191121 <=3D=3D 58808 1051.535 3600 288 236 288 0 0 0 0 0 0 0 58808 4660.020 3600 287 231 287 0 0 0 0 0 0 0 58808 8266.163 3600 281 227 281 0 0 0 0 0 0 0 58808 11875.331 3600 284 231 284 0 0 0 0 0 0 0 58808 15484.618 3600 288 235 288 0 0 0 0 0 0 0 58808 19091.952 3600 284 227 284 0 0 0 0 0 0 0 58808 22701.535 3600 284 238 284 0 0 0 0 0 0 0 58808 26308.724 3600 282 228 282 0 0 0 0 0 0 0 58808 29917.975 3600 286 233 286 0 0 0 0 0 0 0 58808 33526.016 3600 272 227 272 0 0 0 0 0 0 0 % date ; ntpq -4 -n -c peers localhost Thu Nov 21 12:53:22 CET 2019 remote refid st t when poll reach delay offset jit= ter =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D *10.0.5.3 134.130.4.17 2 u 11 64 1 0.168 973.772 232.= 305 +10.0.5.4 192.87.106.2 2 u 9 64 1 0.195 978.173 278.= 795 +10.0.32.248 134.130.4.17 2 u 12 64 1 1.071 684.348 162.= 969 +10.0.32.249 192.87.106.2 2 u 11 64 1 1.061 624.044 207.= 852 *** other machines running either older revisions of stable/12 or releng/12= .0 and releng/12.1 are not affected. --=20 You are receiving this mail because: You are the assignee for the bug.=