Message-ID: <CA+G9fYtOP-spGS7gYyBB-8wpUDiKxd5e_Nk05J9gzWR_DLBeFA@mail.gmail.com>
Date:   Thu, 20 Oct 2022 12:24:25 +0530
From:   Naresh Kamboju <naresh.kamboju@...aro.org>
To:     linux-perf-users@...r.kernel.org,
        open list <linux-kernel@...r.kernel.org>,
        regressions@...ts.linux.dev, lkft-triage@...ts.linaro.org
Cc:     Arnaldo Carvalho de Melo <acme@...nel.org>,
        Borislav Petkov <bp@...en8.de>,
        Peter Zijlstra <peterz@...radead.org>,
        Ingo Molnar <mingo@...hat.com>
Subject: Perf: BUG: kernel NULL pointer dereference, address: 0000000000000198

Following kernel BUG noticed while running perf testing on qemu-x86-64.

Reported-by: Linux Kernel Functional Testing <lkft@...aro.org>

--- start ---
test child forked, pid 528
Using CPUID AuthenticAMD-23-1-2
/usr/libexec/perf-core/tests/attr.py:142: DeprecationWarning: The
SafeConfigParser class has been renamed to ConfigParser in Python 3.2.
This alias will be removed in Python 3.12. Use ConfigParser directly
instead.
  parser = configparser.SafeConfigParser()
running '/usr/libexec/perf-core/tests/attr/test-record-graph-default-aarch64'
test limitation 'aarch64'
/usr/libexec/perf-core/tests/attr.py:201: DeprecationWarning: The
SafeConfigParser class has been renamed to ConfigParser in Python 3.2.
This alias will be removed in Python 3.12. Use ConfigParser directly
instead.
  parser_event = configparser.SafeConfigParser()
/usr/libexec/perf-core/tests/attr.py:215: DeprecationWarning: The
SafeConfigParser class has been renamed to ConfigParser in Python 3.2.
This alias will be removed in Python 3.12. Use ConfigParser directly
instead.
  parser_base = configparser.SafeConfigParser()
skipped [x86_64]
'/usr/libexec/perf-core/tests/attr/test-record-graph-default-aarch64'
running '/usr/libexec/perf-core/tests/attr/test-record-group2'
running '/usr/libexec/perf-core/tests/attr/test-record-C0'
running '/usr/libexec/perf-core/tests/attr/test-record-spe-period'
test limitation 'aarch64'
skipped [x86_64] '/usr/libexec/perf-core/tests/attr/test-record-spe-period'
running '/usr/libexec/perf-core/tests/attr/test-record-period'
[  198.864607] perf: interrupt took too long (11296 > 11195), lowering
kernel.perf_event_max_sample_rate to 17000
[  198.867588] perf: interrupt took too long (14200 > 14120), lowering
kernel.perf_event_max_sample_rate to 14000
[  198.871230] perf: interrupt took too long (17803 > 17750), lowering
kernel.perf_event_max_sample_rate to 11000
[  198.878141] perf: interrupt took too long (22268 > 22253), lowering
kernel.perf_event_max_sample_rate to 8000
running '/usr/libexec/perf-core/tests/attr/test-record-branch-any'
unsupp  '/usr/libexec/perf-core/tests/attr/test-record-branch-any'
running '/usr/libexec/perf-core/tests/attr/test-record-branch-filter-any_call'
unsupp  '/usr/libexec/perf-core/tests/attr/test-record-branch-filter-any_call'
running '/usr/libexec/perf-core/tests/attr/test-stat-C0'
running '/usr/libexec/perf-core/tests/attr/test-record-no-samples'
running '/usr/libexec/perf-core/tests/attr/test-record-spe-period-term'
test limitation 'aarch64'
skipped [x86_64] '/usr/libexec/perf-core/tests/attr/test-record-spe-period-term'
running '/usr/libexec/perf-core/tests/attr/test-stat-no-inherit'
running '/usr/libexec/perf-core/tests/attr/test-stat-detailed-1'
running '/usr/libexec/perf-core/tests/attr/test-record-count'
running '/usr/libexec/perf-core/tests/attr/test-record-data'
running '/usr/libexec/perf-core/tests/attr/test-record-raw'
running '/usr/libexec/perf-core/tests/attr/test-stat-default'
running '/usr/libexec/perf-core/tests/attr/test-record-branch-filter-ind_call'
unsupp  '/usr/libexec/perf-core/tests/attr/test-record-branch-filter-ind_call'
running '/usr/libexec/perf-core/tests/attr/test-record-group'
[  204.124863] BUG: kernel NULL pointer dereference, address: 0000000000000198
[  204.125878] #PF: supervisor read access in kernel mode
[  204.126482] #PF: error_code(0x0000) - not-present page
[  204.127127] PGD 0 P4D 0
[  204.127435] Oops: 0000 [#1] PREEMPT SMP NOPTI
[  204.127939] CPU: 3 PID: 574 Comm: perf-exec Not tainted 6.0.3-rc1 #1
[  204.128711] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996),
BIOS 1.12.0-1 04/01/2014
[  204.129619] RIP: 0010:x86_pmu_enable_event+0x43/0x130
[  204.130246] Code: af e8 51 78 0e 01 65 8b 05 6a fe 40 52 85 c0 75
0d 5b 41 5c 41 5d 41 5e 5d e9 69 dc 3f 01 48 c7 c7 6b b4 76 af e8 2d
78 0e 01 <8b> 8b 98 01 00 00 65 4c 8b 25 47 0f 41 52 85 c9 0f 85 ab 00
00 00
[  204.132695] RSP: 0018:ffff9cc040857be8 EFLAGS: 00010092
[  204.133418] RAX: 0000000000000003 RBX: 0000000000000000 RCX: 00000000c0010200
[  204.134401] RDX: 0000000000000000 RSI: ffffffffaf76b46b RDI: ffffffffaf6bbc6f
[  204.135398] RBP: ffff9cc040857c08 R08: 0000002f86b5dc60 R09: ffff9cc040857a38
[  204.136413] R10: 0000000000000002 R11: ffffffffaff970a0 R12: ffff8f663bd95c20
[  204.137385] R13: ffff8f663bd95e20 R14: 0000000000000002 R15: 00000000c0010201
[  204.138235] FS:  00007fe3226d77c0(0000) GS:ffff8f663bd80000(0000)
knlGS:0000000000000000
[  204.139133] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  204.139842] CR2: 0000000000000198 CR3: 00000001056a2000 CR4: 00000000003506e0
[  204.140801] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[  204.141744] DR3: 0000000000000000 DR6: 00000000ffff4ff0 DR7: 0000000000000400
[  204.142731] Call Trace:
[  204.143082]  <TASK>
[  204.143406]  amd_pmu_enable_all+0x4b/0x70
[  204.143974]  x86_pmu_enable+0x17c/0x320
[  204.144530]  ctx_resched+0xcb/0x110
[  204.145016]  perf_event_exec+0x384/0x460
[  204.145575]  begin_new_exec+0x5f7/0xb90
[  204.146471]  load_elf_binary+0xa82/0x17a0
[  204.147042]  ? __kernel_read+0x15a/0x250
[  204.147661]  ? __kernel_read+0x15a/0x250
[  204.148196]  ? trace_preempt_off+0x29/0xc0
[  204.148764]  ? trace_preempt_on+0x29/0xc0
[  204.149206]  ? load_misc_binary+0x215/0x290
[  204.149656]  bprm_execve+0x283/0x680
[  204.150054]  do_execveat_common.isra.0+0x19f/0x220
[  204.150671]  __x64_sys_execve+0x37/0x50
[  204.151204]  do_syscall_64+0x3b/0x90
[  204.151703]  entry_SYSCALL_64_after_hwframe+0x63/0xcd
[  204.152450] RIP: 0033:0x7fe321cd925b
[  204.152988] Code: Unable to access opcode bytes at RIP 0x7fe321cd9231.
[  204.153870] RSP: 002b:00007ffc5a199828 EFLAGS: 00000202 ORIG_RAX:
000000000000003b
[  204.154865] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007fe321cd925b
[  204.155828] RDX: 0000000002809980 RSI: 00007ffc5a19f100 RDI: 00007ffc5a199830
[  204.156765] RBP: 00007ffc5a199940 R08: 0000000000000fff R09: 00000000027c9bff
[  204.157768] R10: 00007fe321c14680 R11: 0000000000000202 R12: 00007ffc5a19f100
[  204.158671] R13: 0000000002809980 R14: 00007ffc5a1a0d59 R15: 00000000027c9bfb
[  204.159451]  </TASK>
[  204.159749] Modules linked in:
[  204.160204] CR2: 0000000000000198
[  204.160696] ---[ end trace 0000000000000000 ]---
[  204.161347] RIP: 0010:x86_pmu_enable_event+0x43/0x130
[  204.162056] Code: af e8 51 78 0e 01 65 8b 05 6a fe 40 52 85 c0 75
0d 5b 41 5c 41 5d 41 5e 5d e9 69 dc 3f 01 48 c7 c7 6b b4 76 af e8 2d
78 0e 01 <8b> 8b 98 01 00 00 65 4c 8b 25 47 0f 41 52 85 c9 0f 85 ab 00
00 00
[  204.164521] RSP: 0018:ffff9cc040857be8 EFLAGS: 00010092
[  204.165287] RAX: 0000000000000003 RBX: 0000000000000000 RCX: 00000000c0010200
[  204.166284] RDX: 0000000000000000 RSI: ffffffffaf76b46b RDI: ffffffffaf6bbc6f
[  204.167167] RBP: ffff9cc040857c08 R08: 0000002f86b5dc60 R09: ffff9cc040857a38
[  204.167958] R10: 0000000000000002 R11: ffffffffaff970a0 R12: ffff8f663bd95c20
[  204.168957] R13: ffff8f663bd95e20 R14: 0000000000000002 R15: 00000000c0010201
[  204.170005] FS:  00007fe3226d77c0(0000) GS:ffff8f663bd80000(0000)
knlGS:0000000000000000
[  204.171106] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  204.171924] CR2: 00007fe321cd9231 CR3: 00000001056a2000 CR4: 00000000003506e0
[  204.172942] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[  204.173924] DR3: 0000000000000000 DR6: 00000000ffff4ff0 DR7: 0000000000000400
[  204.174986] note: perf-exec[574] exited with preempt_count 2
[  225.122265] rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
[  225.123795] rcu: 3-...0: (2 ticks this GP)
idle=0cfc/1/0x4000000000000000 softirq=4348/4348 fqs=5211
[  225.125479] (detected by 2, t=21004 jiffies, g=9861, q=182 ncpus=4)
[  225.126647] Sending NMI from CPU 2 to CPUs 3:
[  225.127690] NMI backtrace for cpu 3
[  225.127728] CPU: 3 PID: 574 Comm: perf-exec Tainted: G      D
     6.0.3-rc1 #1
[  225.127748] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996),
BIOS 1.12.0-1 04/01/2014
[  225.127769] RIP: 0010:queued_spin_lock_slowpath+0x3b/0x2f0
[  225.127883] Code: fc 53 66 90 ba 01 00 00 00 41 8b 04 24 85 c0 75
17 f0 41 0f b1 14 24 85 c0 75 ee 5b 41 5c 41 5d 41 5e 5d e9 c7 7f 1c
01 f3 90 <eb> dd 81 fe 00 01 00 00 74 57 40 30 f6 85 f6 75 7b f0 41 0f
ba 2c
[  225.127887] RSP: 0018:ffff9cc040130dd8 EFLAGS: 00000002
[  225.127901] RAX: 0000000000000001 RBX: ffff8f660089b400 RCX: ffff8f663bda6ac0
[  225.127905] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff8f660089b408
[  225.127908] RBP: ffff9cc040130df8 R08: ffff8f66027f1900 R09: 000000000000010b
[  225.127910] R10: 0000000000000033 R11: 0000000000000227 R12: ffff8f660089b408
[  225.127913] R13: ffff8f663bda6a90 R14: 0000000000026ac0 R15: ffff8f660089b408
[  225.127920] FS:  00007fe3226d77c0(0000) GS:ffff8f663bd80000(0000)
knlGS:0000000000000000
[  225.127927] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  225.127931] CR2: 00007fe321cd9231 CR3: 00000001056a2000 CR4: 00000000003506e0
[  225.127933] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[  225.127935] DR3: 0000000000000000 DR6: 00000000ffff4ff0 DR7: 0000000000000400
[  225.127947] Call Trace:
[  225.127952]  <IRQ>
[  225.127969]  _raw_spin_lock+0x3b/0x50
[  225.128018]  perf_event_task_tick+0xa2/0x3d0
[  225.128059]  ? task_tick_fair+0x76/0x2d0
[  225.128073]  scheduler_tick+0xda/0x280
[  225.128082]  update_process_times+0x95/0xb0
[  225.128116]  tick_sched_handle+0x38/0x50
[  225.128138]  tick_sched_timer+0x7b/0xa0
[  225.128144]  ? tick_sched_do_timer+0xa0/0xa0
[  225.128149]  __hrtimer_run_queues+0xa7/0x300
[  225.128157]  hrtimer_interrupt+0x110/0x230
[  225.128163]  __sysvec_apic_timer_interrupt+0x84/0x170
[  225.128187]  sysvec_apic_timer_interrupt+0xab/0xd0
[  225.128210]  </IRQ>
[  225.128213]  <TASK>
[  225.128216]  asm_sysvec_apic_timer_interrupt+0x1b/0x20
[  225.128222] RIP: 0010:_raw_spin_unlock_irq+0x17/0x40
[  225.128230] Code: e9 be b8 30 00 66 66 2e 0f 1f 84 00 00 00 00 00
0f 1f 00 0f 1f 44 00 00 55 48 89 e5 c6 07 00 e8 df 9d 1f ff fb bf 01
00 00 00 <e8> 34 45 11 ff 65 8b 05 4d 29 32 51 85 c0 74 06 5d e9 83 b8
30 00
[  225.128235] RSP: 0018:ffff9cc040857ec8 EFLAGS: 00000246
[  225.128239] RAX: 0000000000000000 RBX: ffff8f66021c2080 RCX: 0000000080000000
[  225.128254] RDX: 0000000000000001 RSI: ffffffffaddd9a56 RDI: 0000000000000001
[  225.128257] RBP: ffff9cc040857ec8 R08: ffffffffafb60cc8 R09: 00000000ffffdfff
[  225.128270] R10: ffffffffafa80ce0 R11: ffffffffafb30ce0 R12: 0000000000000000
[  225.128275] R13: 0000000000000009 R14: ffff9cc040857b38 R15: 0000000000000046
[  225.128283]  ? do_exit+0x66/0xae0
[  225.128331]  ? _raw_spin_unlock_irq+0x11/0x40
[  225.128338]  do_exit+0x66/0xae0
[  225.128347]  make_task_dead+0x5a/0x60
[  225.128355]  rewind_stack_and_make_dead+0x17/0x20
[  225.128384] RIP: 0033:0x7fe321cd925b
[  225.128461] Code: Unable to access opcode bytes at RIP 0x7fe321cd9231.
[  225.128463] RSP: 002b:00007ffc5a199828 EFLAGS: 00000202 ORIG_RAX:
000000000000003b
[  225.128469] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007fe321cd925b
[  225.128472] RDX: 0000000002809980 RSI: 00007ffc5a19f100 RDI: 00007ffc5a199830
[  225.128475] RBP: 00007ffc5a199940 R08: 0000000000000fff R09: 00000000027c9bff
[  225.128477] R10: 00007fe321c14680 R11: 0000000000000202 R12: 00007ffc5a19f100
[  225.128479] R13: 0000000002809980 R14: 00007ffc5a1a0d59 R15: 00000000027c9bfb
[  225.128487]  </TASK>
[  288.133586] rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
[  288.137745] rcu: 3-...0: (2 ticks this GP)
idle=0cfc/1/0x4000000000000000 softirq=4348/4348 fqs=20510
[  288.138774] (detected by 1, t=84019 jiffies, g=9861, q=349 ncpus=4)
[  288.139441] Sending NMI from CPU 1 to CPUs 3:
[  288.140110] NMI backtrace for cpu 3
[  288.140118] CPU: 3 PID: 574 Comm: perf-exec Tainted: G      D
     6.0.3-rc1 #1
[  288.140124] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996),
BIOS 1.12.0-1 04/01/2014
[  288.140129] RIP: 0010:queued_spin_lock_slowpath+0x3b/0x2f0
[  288.140140] Code: fc 53 66 90 ba 01 00 00 00 41 8b 04 24 85 c0 75
17 f0 41 0f b1 14 24 85 c0 75 ee 5b 41 5c 41 5d 41 5e 5d e9 c7 7f 1c
01 f3 90 <eb> dd 81 fe 00 01 00 00 74 57 40 30 f6 85 f6 75 7b f0 41 0f
ba 2c
[  288.140143] RSP: 0018:ffff9cc040130dd8 EFLAGS: 00000002
[  288.140146] RAX: 0000000000000001 RBX: ffff8f660089b400 RCX: ffff8f663bda6ac0
[  288.140148] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff8f660089b408
[  288.140148] RBP: ffff9cc040130df8 R08: ffff8f66027f1900 R09: 000000000000010b
[  288.140149] R10: 0000000000000033 R11: 0000000000000227 R12: ffff8f660089b408
[  288.140150] R13: ffff8f663bda6a90 R14: 0000000000026ac0 R15: ffff8f660089b408
[  288.140154] FS:  00007fe3226d77c0(0000) GS:ffff8f663bd80000(0000)
knlGS:0000000000000000
[  288.140157] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  288.140158] CR2: 00007fe321cd9231 CR3: 00000001056a2000 CR4: 00000000003506e0
[  288.140159] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[  288.140160] DR3: 0000000000000000 DR6: 00000000ffff4ff0 DR7: 0000000000000400
[  288.140161] Call Trace:
[  288.140165]  <IRQ>
[  288.140170]  _raw_spin_lock+0x3b/0x50
[  288.140174]  perf_event_task_tick+0xa2/0x3d0
[  288.140179]  ? task_tick_fair+0x76/0x2d0
[  288.140182]  scheduler_tick+0xda/0x280
[  288.140185]  update_process_times+0x95/0xb0
[  288.140189]  tick_sched_handle+0x38/0x50
[  288.140197]  tick_sched_timer+0x7b/0xa0
[  288.140199]  ? tick_sched_do_timer+0xa0/0xa0
[  288.140202]  __hrtimer_run_queues+0xa7/0x300
[  288.140204]  hrtimer_interrupt+0x110/0x230
[  288.140206]  __sysvec_apic_timer_interrupt+0x84/0x170
[  288.140211]  sysvec_apic_timer_interrupt+0xab/0xd0
[  288.140224]  </IRQ>
[  288.140224]  <TASK>
[  288.140225]  asm_sysvec_apic_timer_interrupt+0x1b/0x20
[  288.140227] RIP: 0010:_raw_spin_unlock_irq+0x17/0x40
[  288.140229] Code: e9 be b8 30 00 66 66 2e 0f 1f 84 00 00 00 00 00
0f 1f 00 0f 1f 44 00 00 55 48 89 e5 c6 07 00 e8 df 9d 1f ff fb bf 01
00 00 00 <e8> 34 45 11 ff 65 8b 05 4d 29 32 51 85 c0 74 06 5d e9 83 b8
30 00
[  288.140231] RSP: 0018:ffff9cc040857ec8 EFLAGS: 00000246
[  288.140232] RAX: 0000000000000000 RBX: ffff8f66021c2080 RCX: 0000000080000000
[  288.140233] RDX: 0000000000000001 RSI: ffffffffaddd9a56 RDI: 0000000000000001
[  288.140234] RBP: ffff9cc040857ec8 R08: ffffffffafb60cc8 R09: 00000000ffffdfff
[  288.140235] R10: ffffffffafa80ce0 R11: ffffffffafb30ce0 R12: 0000000000000000
[  288.140236] R13: 0000000000000009 R14: ffff9cc040857b38 R15: 0000000000000046
[  288.140238]  ? do_exit+0x66/0xae0
[  288.140242]  ? _raw_spin_unlock_irq+0x11/0x40
[  288.140248]  do_exit+0x66/0xae0
[  288.140251]  make_task_dead+0x5a/0x60
[  288.140253]  rewind_stack_and_make_dead+0x17/0x20
[  288.140256] RIP: 0033:0x7fe321cd925b
[  288.140270] Code: Unable to access opcode bytes at RIP 0x7fe321cd9231.
[  288.140271] RSP: 002b:00007ffc5a199828 EFLAGS: 00000202 ORIG_RAX:
000000000000003b
[  288.140273] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007fe321cd925b
[  288.140274] RDX: 0000000002809980 RSI: 00007ffc5a19f100 RDI: 00007ffc5a199830
[  288.140275] RBP: 00007ffc5a199940 R08: 0000000000000fff R09: 00000000027c9bff
[  288.140276] R10: 00007fe321c14680 R11: 0000000000000202 R12: 00007ffc5a19f100
[  288.140277] R13: 0000000002809980 R14: 00007ffc5a1a0d59 R15: 00000000027c9bfb
[  288.140279]  </TASK>
[  351.145086] rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
[  351.147106] rcu: 3-...0: (2 ticks this GP)
idle=0cfc/1/0x4000000000000000 softirq=4348/4348 fqs=35304
[  351.149375] (detected by 0, t=147026 jiffies, g=9861, q=650 ncpus=4)
[  351.150955] Sending NMI from CPU 0 to CPUs 3:
[  351.152253] NMI backtrace for cpu 3
[  351.152273] CPU: 3 PID: 574 Comm: perf-exec Tainted: G      D
     6.0.3-rc1 #1
[  351.152282] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996),
BIOS 1.12.0-1 04/01/2014
[  351.152296] RIP: 0010:queued_spin_lock_slowpath+0x3b/0x2f0
[  351.152356] Code: fc 53 66 90 ba 01 00 00 00 41 8b 04 24 85 c0 75
17 f0 41 0f b1 14 24 85 c0 75 ee 5b 41 5c 41 5d 41 5e 5d e9 c7 7f 1c
01 f3 90 <eb> dd 81 fe 00 01 00 00 74 57 40 30 f6 85 f6 75 7b f0 41 0f
ba 2c
[  351.152359] RSP: 0018:ffff9cc040130dd8 EFLAGS: 00000002
[  351.152365] RAX: 0000000000000001 RBX: ffff8f660089b400 RCX: ffff8f663bda6ac0
[  351.152367] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff8f660089b408
[  351.152369] RBP: ffff9cc040130df8 R08: ffff8f66027f1900 R09: 000000000000010b
[  351.152370] R10: 0000000000000033 R11: 0000000000000227 R12: ffff8f660089b408
[  351.152371] R13: ffff8f663bda6a90 R14: 0000000000026ac0 R15: ffff8f660089b408
[  351.152375] FS:  00007fe3226d77c0(0000) GS:ffff8f663bd80000(0000)
knlGS:0000000000000000
[  351.152381] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  351.152382] CR2: 00007fe321cd9231 CR3: 00000001056a2000 CR4: 00000000003506e0
[  351.152384] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[  351.152384] DR3: 0000000000000000 DR6: 00000000ffff4ff0 DR7: 0000000000000400
[  351.152388] Call Trace:
[  351.152392]  <IRQ>
[  351.152399]  _raw_spin_lock+0x3b/0x50
[  351.152421]  perf_event_task_tick+0xa2/0x3d0
[  351.152439]  ? task_tick_fair+0x76/0x2d0
[  351.152446]  scheduler_tick+0xda/0x280
[  351.152451]  update_process_times+0x95/0xb0
[  351.152463]  tick_sched_handle+0x38/0x50
[  351.152471]  tick_sched_timer+0x7b/0xa0
[  351.152473]  ? tick_sched_do_timer+0xa0/0xa0
[  351.152475]  __hrtimer_run_queues+0xa7/0x300
[  351.152478]  hrtimer_interrupt+0x110/0x230
[  351.152480]  __sysvec_apic_timer_interrupt+0x84/0x170
[  351.152490]  sysvec_apic_timer_interrupt+0xab/0xd0
[  351.152494]  </IRQ>
[  351.152495]  <TASK>
[  351.152496]  asm_sysvec_apic_timer_interrupt+0x1b/0x20
[  351.152498] RIP: 0010:_raw_spin_unlock_irq+0x17/0x40
[  351.152500] Code: e9 be b8 30 00 66 66 2e 0f 1f 84 00 00 00 00 00
0f 1f 00 0f 1f 44 00 00 55 48 89 e5 c6 07 00 e8 df 9d 1f ff fb bf 01
00 00 00 <e8> 34 45 11 ff 65 8b 05 4d 29 32 51 85 c0 74 06 5d e9 83 b8
30 00
[  351.152501] RSP: 0018:ffff9cc040857ec8 EFLAGS: 00000246
[  351.152503] RAX: 0000000000000000 RBX: ffff8f66021c2080 RCX: 0000000080000000
[  351.152504] RDX: 0000000000000001 RSI: ffffffffaddd9a56 RDI: 0000000000000001
[  351.152505] RBP: ffff9cc040857ec8 R08: ffffffffafb60cc8 R09: 00000000ffffdfff
[  351.152506] R10: ffffffffafa80ce0 R11: ffffffffafb30ce0 R12: 0000000000000000
[  351.152507] R13: 0000000000000009 R14: ffff9cc040857b38 R15: 0000000000000046
[  351.152509]  ? do_exit+0x66/0xae0
[  351.152524]  ? _raw_spin_unlock_irq+0x11/0x40
[  351.152525]  do_exit+0x66/0xae0
[  351.152528]  make_task_dead+0x5a/0x60
[  351.152531]  rewind_stack_and_make_dead+0x17/0x20
[  351.152540] RIP: 0033:0x7fe321cd925b
[  351.152576] Code: Unable to access opcode bytes at RIP 0x7fe321cd9231.
[  351.152581] RSP: 002b:00007ffc5a199828 EFLAGS: 00000202 ORIG_RAX:
000000000000003b
[  351.152583] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007fe321cd925b
[  351.152584] RDX: 0000000002809980 RSI: 00007ffc5a19f100 RDI: 00007ffc5a199830
[  351.152585] RBP: 00007ffc5a199940 R08: 0000000000000fff R09: 00000000027c9bff
[  351.152586] R10: 00007fe321c14680 R11: 0000000000000202 R12: 00007ffc5a19f100
[  351.152586] R13: 0000000002809980 R14: 00007ffc5a1a0d59 R15: 00000000027c9bfb
[  351.152589]  </TASK>
[  414.154511] rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
[  414.156284] rcu: 3-...0: (2 ticks this GP)
idle=0cfc/1/0x4000000000000000 softirq=4348/4348 fqs=51040
[  414.166067] (detected by 0, t=210046 jiffies, g=9861, q=959 ncpus=4)
[  414.167261] Sending NMI from CPU 0 to CPUs 3:
[  414.168168] NMI backtrace for cpu 3
[  414.168180] CPU: 3 PID: 574 Comm: perf-exec Tainted: G      D
     6.0.3-rc1 #1
[  414.168192] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996),
BIOS 1.12.0-1 04/01/2014
[  414.168196] RIP: 0010:queued_spin_lock_slowpath+0x3b/0x2f0
[  414.168219] Code: fc 53 66 90 ba 01 00 00 00 41 8b 04 24 85 c0 75
17 f0 41 0f b1 14 24 85 c0 75 ee 5b 41 5c 41 5d 41 5e 5d e9 c7 7f 1c
01 f3 90 <eb> dd 81 fe 00 01 00 00 74 57 40 30 f6 85 f6 75 7b f0 41 0f
ba 2c
[  414.168222] RSP: 0018:ffff9cc040130dd8 EFLAGS: 00000002
[  414.168225] RAX: 0000000000000001 RBX: ffff8f660089b400 RCX: ffff8f663bda6ac0
[  414.168227] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff8f660089b408
[  414.168228] RBP: ffff9cc040130df8 R08: ffff8f66027f1900 R09: 000000000000010b
[  414.168230] R10: 0000000000000033 R11: 0000000000000227 R12: ffff8f660089b408
[  414.168231] R13: ffff8f663bda6a90 R14: 0000000000026ac0 R15: ffff8f660089b408
[  414.168234] FS:  00007fe3226d77c0(0000) GS:ffff8f663bd80000(0000)
knlGS:0000000000000000
[  414.168237] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  414.168238] CR2: 00007fe321cd9231 CR3: 00000001056a2000 CR4: 00000000003506e0
[  414.168239] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[  414.168241] DR3: 0000000000000000 DR6: 00000000ffff4ff0 DR7: 0000000000000400
[  414.168242] Call Trace:
[  414.168244]  <IRQ>
[  414.168251]  _raw_spin_lock+0x3b/0x50
[  414.168259]  perf_event_task_tick+0xa2/0x3d0
[  414.168265]  ? task_tick_fair+0x76/0x2d0
[  414.168270]  scheduler_tick+0xda/0x280
[  414.168275]  update_process_times+0x95/0xb0
[  414.168288]  tick_sched_handle+0x38/0x50
[  414.168292]  tick_sched_timer+0x7b/0xa0
[  414.168294]  ? tick_sched_do_timer+0xa0/0xa0
[  414.168297]  __hrtimer_run_queues+0xa7/0x300
[  414.168299]  hrtimer_interrupt+0x110/0x230
[  414.168301]  __sysvec_apic_timer_interrupt+0x84/0x170
[  414.168315]  sysvec_apic_timer_interrupt+0xab/0xd0
[  414.168392]  </IRQ>
[  414.168392]  <TASK>
[  414.168393]  asm_sysvec_apic_timer_interrupt+0x1b/0x20
[  414.168396] RIP: 0010:_raw_spin_unlock_irq+0x17/0x40

[1] https://qa-reports.linaro.org/lkft/linux-stable-rc-linux-6.0.y/build/v6.0-916-g844297340351/testrun/12536939/suite/log-parser-test/test/check-kernel-bug/log
[2] https://lkft.validation.linaro.org/scheduler/job/5708609#L18779

metadata:
  git_ref: linux-6.0.y
  git_repo: https://gitlab.com/Linaro/lkft/mirrors/stable/linux-stable-rc
  git_sha: 84429734035197a6ab8e79c852d5e4e6ed744703
  git_describe: v6.0-916-g844297340351
  kernel_version: 6.0.3-rc1
  kernel-config: https://builds.tuxbuild.com/2GMZJJEvy18jDgLO4utw6cOelj3/config
  build-url: https://gitlab.com/mrchapp/linux/-/pipelines/671394419
  artifact-location: https://builds.tuxbuild.com/2GMZJJEvy18jDgLO4utw6cOelj3
  toolchain: gcc-11

--
Linaro LKFT
https://lkft.linaro.org

Powered by blists - more mailing lists