lkml.org 
[lkml]   [2023]   [Aug]   [18]   [last100]   RSS Feed
Views: [wrap][no wrap]   [headers]  [forward] 
 
Messages in this thread
/
Date
SubjectRe: [BUG] KCSAN: data-race in process_one_work / process_one_work
From

On 8/17/23 23:59, Tejun Heo wrote:
> On Thu, Aug 17, 2023 at 09:24:28PM +0200, Mirsad Todorovac wrote:
>> Hi,
>>
>> This is your friendly bug reporter.
>>
>> The environment is vanilla torvalds tree kernel on Ubuntu 22.04 LTS and on a Ryzen 7950X
>> assembled box.
>>
>> The kernel reports G taint "properietary module loaded", but I know of no module since
>> the boot state when the status was "Not tainted".
>>
>> Please find the complete dmesg output (or what's left in the ring buffer) and lshw output
>> attached.
>>
>> Here is the dmesg output excerpt:
>>
>> [ 6849.221584] ==================================================================
>> [ 6849.221607] BUG: KCSAN: data-race in process_one_work / process_one_work
>>
>> [ 6849.221635] write to 0xffff9b7440151398 of 8 bytes by task 6364 on cpu 7:
>> [ 6849.221647] process_one_work+0x504/0x930
>> [ 6849.221660] worker_thread+0x311/0x7e0
>> [ 6849.221673] kthread+0x18b/0x1d0
>> [ 6849.221683] ret_from_fork+0x43/0x70
>> [ 6849.221695] ret_from_fork_asm+0x1b/0x30
>>
>> [ 6849.221712] read to 0xffff9b7440151398 of 8 bytes by task 5586 on cpu 28:
>> [ 6849.221724] process_one_work+0x4e8/0x930
>> [ 6849.221737] worker_thread+0x519/0x7e0
>> [ 6849.221749] kthread+0x18b/0x1d0
>> [ 6849.221759] ret_from_fork+0x43/0x70
>> [ 6849.221770] ret_from_fork_asm+0x1b/0x30
>>
>> [ 6849.221786] value changed: 0x00000000000154d0 -> 0x00000000000154d1
>>
>> [ 6849.221802] Reported by Kernel Concurrency Sanitizer on:
>> [ 6849.221811] CPU: 28 PID: 5586 Comm: kworker/u64:0 Tainted: G L 6.5.0-rc6-net-cfg-kcsan-00038-g16931859a650 #35
>> [ 6849.221825] Hardware name: ASRock X670E PG Lightning/X670E PG Lightning, BIOS 1.21 04/26/2023
>> [ 6849.221833] Workqueue: events_unbound wq_barrier_func
>> [ 6849.221846] ==================================================================
>
> That looks like the PENDING bit. The setting is atomic. Maybe there's a raw
> read? Can you map the addresses to lines?
>
> Thanks.

Sadly not: I had to rebuild the kernel with debug on, so I am attempting to catch the issue again.

Are you interested in any of these:

# sudo dmesg --color=always | grep KCSAN | grep -v acpi | less -R
[ 101.395134] BUG: KCSAN: data-race in xas_clear_mark / xas_find_marked
[ 133.628459] BUG: KCSAN: data-race in vprintk_emit+0x343/0x400
[ 154.742056] BUG: KCSAN: data-race in rcu_implicit_dynticks_qs / rcu_sched_clock_irq
[ 171.626135] BUG: KCSAN: data-race in rcu_implicit_dynticks_qs / rcu_sched_clock_irq
[ 209.239022] BUG: KCSAN: data-race in vprintk_emit+0x343/0x400
[ 227.654170] BUG: KCSAN: data-race in rcu_implicit_dynticks_qs / rcu_sched_clock_irq
[ 240.411681] BUG: KCSAN: data-race in _find_next_bit+0x42/0xf0
[ 244.273756] BUG: KCSAN: data-race in mas_wr_bnode / mtree_range_walk
[ 259.304431] BUG: KCSAN: data-race in mas_wr_bnode / mtree_range_walk
[ 261.572037] BUG: KCSAN: data-race in poll_schedule_timeout.constprop.0 / pollwake
[ 261.639803] BUG: KCSAN: data-race in mas_spanning_rebalance.isra.0 / mtree_range_walk
[ 261.792478] BUG: KCSAN: data-race in mas_wr_bnode / mtree_range_walk
[ 262.690859] BUG: KCSAN: data-race in mas_wr_bnode / mtree_range_walk
[ 262.866290] BUG: KCSAN: data-race in __mod_lruvec_page_state / shmem_get_folio_gfp
[ 262.873251] BUG: KCSAN: data-race in shmem_add_to_page_cache / shmem_recalc_inode
[ 262.878189] BUG: KCSAN: data-race in _find_next_and_bit+0x43/0x120
[ 268.948899] BUG: KCSAN: data-race in do_epoll_ctl / ep_poll_callback
[ 268.990769] BUG: KCSAN: data-race in poll_schedule_timeout.constprop.0 / pollwake
[ 269.231597] BUG: KCSAN: data-race in mas_wr_node_store / mtree_range_walk
[ 271.133684] BUG: KCSAN: data-race in _find_next_bit+0x42/0xf0
[ 271.329759] BUG: KCSAN: data-race in mas_wr_node_store / mtree_range_walk
[ 273.815884] BUG: KCSAN: data-race in cgroup_freezer_migrate_task / handle_mm_fault
[ 274.821427] BUG: KCSAN: data-race in _find_next_bit+0x42/0xf0
[ 275.007630] BUG: KCSAN: data-race in poll_schedule_timeout.constprop.0 / pollwake
[ 275.129286] BUG: KCSAN: data-race in _find_next_and_bit+0x43/0x120
[ 275.602701] BUG: KCSAN: data-race in __mod_lruvec_page_state / folio_wait_bit_common
[ 275.605323] BUG: KCSAN: data-race in __mod_lruvec_page_state / shmem_get_folio_gfp
[ 275.634562] BUG: KCSAN: data-race in shmem_add_to_page_cache / shmem_recalc_inode
[ 276.397103] BUG: KCSAN: data-race in do_epoll_ctl / ep_poll_callback
[ 279.557086] BUG: KCSAN: data-race in _find_next_and_bit+0x43/0x120
[ 282.374662] BUG: KCSAN: data-race in mas_descend_adopt / mtree_range_walk
[ 282.640776] BUG: KCSAN: data-race in mas_spanning_rebalance.isra.0 / mtree_range_walk
[ 285.180973] BUG: KCSAN: data-race in _find_next_bit+0x42/0xf0
[ 289.606686] BUG: KCSAN: data-race in kernfs_refresh_inode / kernfs_refresh_inode
[ 289.619587] BUG: KCSAN: data-race in kernfs_refresh_inode / kernfs_refresh_inode
[ 289.625210] BUG: KCSAN: data-race in generic_fillattr / kernfs_refresh_inode
[ 290.000921] BUG: KCSAN: data-race in kernfs_refresh_inode / kernfs_refresh_inode
[ 297.491913] BUG: KCSAN: data-race in _find_next_bit+0x42/0xf0
[ 303.411512] BUG: KCSAN: data-race in _find_next_bit+0x42/0xf0
[ 317.191778] BUG: KCSAN: data-race in __read_end_io / folio_batch_move_lru
[ 321.395561] BUG: KCSAN: data-race in n_tty_poll / n_tty_receive_buf_common
[ 321.938843] BUG: KCSAN: data-race in n_tty_poll / n_tty_receive_buf_common
[ 325.702280] BUG: KCSAN: data-race in tick_nohz_idle_stop_tick / tick_nohz_idle_stop_tick
[ 326.930194] BUG: KCSAN: data-race in tick_sched_do_timer / tick_sched_do_timer
[ 327.230053] BUG: KCSAN: data-race in tick_nohz_next_event+0xe7/0x1e0
[ 329.501788] BUG: KCSAN: data-race in _find_next_and_bit+0x43/0x120
[ 359.973752] BUG: KCSAN: data-race in tick_nohz_idle_stop_tick / tick_nohz_next_event
#

However, I naively set CONFIG_LOG_BUF_SHIFT to 22 and it rolled over, so the ring buffer
ran out of space ...

I will get them the next time ...

Best regards,
Mirsad Todorovac

\
 
 \ /
  Last update: 2023-08-18 08:29    [W:0.124 / U:1.032 seconds]
©2003-2020 Jasper Spaans|hosted at Digital Ocean and TransIP|Read the blog|Advertise on this site