Received-SPF: pass (google.com: domain of linux-kernel+bounces-146346-linux.lists.archive=gmail.com@vger.kernel.org designates 147.75.199.223 as permitted sender) client-ip=147.75.199.223;
Date: Tue, 16 Apr 2024 14:27:26 +0800
From: kernel test robot <oliver.sang@intel.com>
To: Adrian Hunter <adrian.hunter@intel.com>
CC: <oe-lkp@lists.linux.dev>, <lkp@intel.com>, <linux-kernel@vger.kernel.org>,
	<x86@kernel.org>, Thomas Gleixner <tglx@linutronix.de>,
	<oliver.sang@intel.com>
Subject: [tip:timers/core] [timekeeping]  e84f43e34f:
 BUG:KCSAN:data-race_in_timekeeping_advance/timekeeping_debug_get_ns
Message-ID: <202404161050.38f1c92e-lkp@intel.com>
Content-Type: text/plain; charset="us-ascii"
Content-Disposition: inline
Precedence: bulk
MIME-Version: 1.0
X-MS-Exchange-AntiSpam-MessageData-ChunkCount: 1
X-MS-Exchange-AntiSpam-MessageData-0: =?us-ascii?Q?cS9YRWSX+qe9ZAyvpV/SlE9nw7DAmbykI21lj1QyBtcSo8VZi5Kv6cvT+ku9?=
 =?us-ascii?Q?T+leXFX+4O7KdM5/kajrVj1y/v1A5G5vrvN2N9TyD5QFw9oLPyVhgImKKISd?=
 =?us-ascii?Q?V41S1EhegJl7An2reT4r3rf5+h3WcRIN+LxtrHgyOvvTNDymg4AVPtJm53bD?=
 =?us-ascii?Q?xmj3vL0FwoSWb4KAPpTIi9DDMix8p+ZR9b+AiQs7AO2rcZr+PfQuE4vZD2DY?=
 =?us-ascii?Q?BSCMvi9txrznhG9/ua8Y95i0wXOjO71ta85M2WEulVMI4fkqq225cpXZEzBB?=
 =?us-ascii?Q?I9/6L7jC5vrv3MUbA/Ig+MviTBsLo68YMmLcaAohpMnIAPZSyfldRDbMl51r?=
 =?us-ascii?Q?FisI58IgiY3d9Gwuo+EB+enZQDDFOd2G816QNXluYFyXmvz+jV9jruoAZ/Mp?=
 =?us-ascii?Q?4KlyyDsqMYSQyPA2pHW+nS90WTo35354XXl8aBnFJsYaWlqwIT3/MeENFrMX?=
 =?us-ascii?Q?K4fgXbQmyqnxdr9lG27Hd+RYqhmdyKkXkP0wt8iiuxTchDJsVmGUY6p3YQQh?=
 =?us-ascii?Q?LXpHq3/5oNNaRAgQ3ETp+roDqTP/savjddSKSSuDXc/dD7ffj3syzJtsgTY2?=
 =?us-ascii?Q?A+2rH6m5PBx76eRFzhhif6HCe7+s8y8WM/l4yhkt5Q3TOHyIQkM21iJBWCrx?=
 =?us-ascii?Q?G777ksmd1vsvp+4h+J/I7QoT02Tfa555oldGKJboQBSUxFdWZN2pgmJ/HYXa?=
 =?us-ascii?Q?C9GfeVx8CCxxm320cJJkiuApkIV1vG5VIMOVIrHsjIKErLPASKQot54tXZqp?=
 =?us-ascii?Q?kXpQwe6v7D9AmObWHXT73wjHS6Q5f9vVu5ckEt8akQPBGEQBX40oNnFeLsmH?=
 =?us-ascii?Q?azT0zMhtS5vuTMYBWpx6/maiqHWzs7EDerz8/5UMxIopRfKGiCp6uHi8HamV?=
 =?us-ascii?Q?CBpLOqk0LSA2jWpc8hQ+Ih1zM1yzagVzaxmarzvKArlDlkcNoEQparg0dNSW?=
 =?us-ascii?Q?HjzeEbAdwzBkFBFr6pJsypZizfBipkmkBE5lCNgybTiHZS/HIeEmJ8E3H+RQ?=
 =?us-ascii?Q?GphqcjzP1JqAQyx51fLZgf7VXL1SNoFxakLLJiHDZA1kpt+G+TfyBrboHKSP?=
 =?us-ascii?Q?m3eY1nIGlVrJh104SxftUr/mYJSgOQqpmWxcXjpkqumnauEHbJuUO3KLIfwi?=
 =?us-ascii?Q?XutDyWt1huc9JWUNB9KP+o+E7R0lZ11qAG+ntAhQnQLzCRynDFPfuS3al4Yt?=
 =?us-ascii?Q?gSsNSUlDlwUm6cOoWJfw60+7GhxWB299mkglGprmq7Yimy3nqqGwuxXSB/rT?=
 =?us-ascii?Q?ZCfKrRQLAMAJFBofdgM/008UVxhe0e3Imx5rq2/XcyhsOL977m8ucTsC8KKt?=
 =?us-ascii?Q?BjQgiL36brsGvD3Qi8sV4uRGoiilLCgqNKLy2vtxQKGRrlqTLIF6gp4gLS4q?=
 =?us-ascii?Q?/asDRmytqE54Qudk8nhVwhSU5f1cd1yaSz8TPNGXgRYVpQ1LaNnzt6dYlRTM?=
 =?us-ascii?Q?THbN7khtJc6NJAJGU7h/AZVHtT+jbqyqMiGXXy64v1N87RYAL0PmntesSTwG?=
 =?us-ascii?Q?N5djJqplIcG8kXUc4YKlvFJhOLm06qidBg8tIIW+koo85NSwy4Kdu8mKBwPC?=
 =?us-ascii?Q?6KtDOngjlNonWL1b1l/dmKr30pm4u0TjeuiMefCMU5YaXTWkRRKpkfkaZzl8?=
 =?us-ascii?Q?aA=3D=3D?=
X-MS-Exchange-CrossTenant-Network-Message-Id: 97f2151e-7364-4383-ae7e-08dc5dde4d19
X-MS-Exchange-CrossTenant-AuthSource: LV3PR11MB8603.namprd11.prod.outlook.com
X-MS-Exchange-CrossTenant-AuthAs: Internal
X-MS-Exchange-CrossTenant-OriginalArrivalTime: 16 Apr 2024 06:27:34.0346
 (UTC)
X-MS-Exchange-CrossTenant-FromEntityHeader: Hosted
X-MS-Exchange-CrossTenant-Id: 46c98d88-e344-4ed4-8496-4ed7712e255d
X-MS-Exchange-CrossTenant-MailboxType: HOSTED
X-MS-Exchange-CrossTenant-UserPrincipalName: b1v6heYSG5GJmuvaYO2WeP0JkukBGyVGnglWuQNPHdbjsluC0GuQGWNJhl+oyTSGek+OhjrKPYv0E1jtvZqXOw==
X-MS-Exchange-Transport-CrossTenantHeadersStamped: DS0PR11MB7801
X-OriginatorOrg: intel.com


Hello,

kernel test robot noticed "BUG:KCSAN:data-race_in_timekeeping_advance/timekeeping_debug_get_ns" on:

commit: e84f43e34faf85816587f80594541ec978449d6e ("timekeeping: Consolidate timekeeping helpers")
https://git.kernel.org/cgit/linux/kernel/git/tip/tip.git timers/core

[test failed on linux-next/master 9ed46da14b9b9b2ad4edb3b0c545b6dbe5c00d39]

in testcase: boot

compiler: gcc-13
test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 16G

(please refer to attached dmesg/kmsg for entire log/backtrace)


we noticed this issue doesn't always happen on this commit (63 times out of
111 runs as below), however, parent keeps clean for this issue, but has other
KCSAN:data-race issues which does not happen on e84f43e34f.

e8e9d21a5df655a6 e84f43e34faf85816587f805945
---------------- ---------------------------
       fail:runs  %reproduction    fail:runs
           |             |             |
         16:60         -27%            :111   dmesg.BUG:KCSAN:data-race_in_ktime_get/timekeeping_advance
          7:60         -12%            :111   dmesg.BUG:KCSAN:data-race_in_ktime_get_update_offsets_now/timekeeping_advance
           :60         105%          63:111   dmesg.BUG:KCSAN:data-race_in_timekeeping_advance/timekeeping_debug_get_ns


If you fix the issue in a separate patch/commit (i.e. not just a new version of
the same patch/commit), kindly add following tags
| Reported-by: kernel test robot <oliver.sang@intel.com>
| Closes: https://lore.kernel.org/oe-lkp/202404161050.38f1c92e-lkp@intel.com


[  108.068027][    C1] BUG: KCSAN: data-race in timekeeping_advance / timekeeping_debug_get_ns
[  108.069188][    C1]
[  108.069511][    C1] write to 0xffffffff95672dc0 of 296 bytes by interrupt on cpu 0:
[ 108.070559][ C1] timekeeping_advance (kernel/time/timekeeping.c:2207 (discriminator 1)) 
[ 108.071242][ C1] update_wall_time (kernel/time/timekeeping.c:2222 (discriminator 1)) 
[ 108.071883][ C1] tick_do_update_jiffies64 (kernel/time/tick-sched.c:149) 
[ 108.072638][ C1] tick_nohz_handler (kernel/time/tick-sched.c:229 kernel/time/tick-sched.c:287) 
[ 108.073235][ C1] __hrtimer_run_queues (kernel/time/hrtimer.c:1692 kernel/time/hrtimer.c:1756) 
[ 108.073840][ C1] hrtimer_interrupt (kernel/time/hrtimer.c:1821) 
[ 108.074430][ C1] __sysvec_apic_timer_interrupt (arch/x86/include/asm/jump_label.h:27 include/linux/jump_label.h:207 arch/x86/include/asm/trace/irq_vectors.h:41 arch/x86/kernel/apic/apic.c:1050) 
[ 108.075132][ C1] sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1043 (discriminator 2) arch/x86/kernel/apic/apic.c:1043 (discriminator 2)) 
[ 108.075821][ C1] asm_sysvec_apic_timer_interrupt (arch/x86/include/asm/idtentry.h:702) 
[  108.076657][    C1]
[  108.076982][    C1] read to 0xffffffff95672de0 of 8 bytes by interrupt on cpu 1:
[ 108.077994][ C1] timekeeping_debug_get_ns (kernel/time/timekeeping.c:373 kernel/time/timekeeping.c:383 kernel/time/timekeeping.c:280) 
[ 108.078766][ C1] ktime_get (kernel/time/timekeeping.c:394 kernel/time/timekeeping.c:838) 
[ 108.079325][ C1] tick_nohz_handler (kernel/time/tick-sched.c:220 kernel/time/tick-sched.c:287) 
[ 108.079995][ C1] __hrtimer_run_queues (kernel/time/hrtimer.c:1692 kernel/time/hrtimer.c:1756) 
[ 108.080740][ C1] hrtimer_interrupt (kernel/time/hrtimer.c:1821) 
[ 108.081423][ C1] __sysvec_apic_timer_interrupt (arch/x86/include/asm/jump_label.h:27 include/linux/jump_label.h:207 arch/x86/include/asm/trace/irq_vectors.h:41 arch/x86/kernel/apic/apic.c:1050) 
[ 108.082241][ C1] sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1043 (discriminator 2) arch/x86/kernel/apic/apic.c:1043 (discriminator 2)) 
[ 108.083014][ C1] asm_sysvec_apic_timer_interrupt (arch/x86/include/asm/idtentry.h:702) 
[  108.083849][    C1]
[  108.084183][    C1] value changed: 0x000e771a64000000 -> 0x000e959ee4000000
[  108.085518][    C1]
[  108.085775][    C1] Reported by Kernel Concurrency Sanitizer on:
[  108.086563][    C1] CPU: 1 PID: 265 Comm: sed Tainted: G        W   E    N 6.9.0-rc3-00015-ge84f43e34faf #1 ddd7212d5d239f10e5f20ca1605d3d23d4ce80eb
[  108.088374][    C1] ==================================================================


The kernel config and materials to reproduce are available at:
https://download.01.org/0day-ci/archive/20240416/202404161050.38f1c92e-lkp@intel.com


-- 
0-DAY CI Kernel Test Service
https://github.com/intel/lkp-tests/wiki