igt@dmabuf@all-tests@dma_fence on fi-kbl-7567u@linus-v6.4-rc5 Test details

Results for igt@dmabuf@all-tests@dma_fence

Result: Dmesg-Fail 1 Warning(s)

Detail	Value
Igt-Version	IGT-Version: 1.27.1-gc2d8ef8b9 (x86_64) (Linux: 6.4.0-rc5-linus-v6.4-rc5-g9561de3a55be+ x86_64)
Out	This test caused an abort condition: Lockdep not active /proc/lockdep_stats contents: lock-classes: 1615 [max: 8192] direct dependencies: 15632 [max: 32768] indirect dependencies: 86403 all direct dependencies: 269063 dependency chains: 22407 [max: 131072] dependency chain hlocks used: 90254 [max: 655360] dependency chain hlocks lost: 0 in-hardirq chains: 119 in-softirq chains: 677 in-process chains: 21611 stack-trace entries: 271365 [max: 1048576] number of stack traces: 12377 number of stack hash chains: 8704 combined max dependencies: 1758352320 hardirq-safe locks: 65 hardirq-unsafe locks: 971 softirq-safe locks: 189 softirq-unsafe locks: 883 irq-safe locks: 196 irq-unsafe locks: 971 hardirq-read-safe locks: 3 hardirq-read-unsafe locks: 291 softirq-read-safe locks: 7 softirq-read-unsafe locks: 286 irq-read-safe locks: 7 irq-read-unsafe locks: 291 uncategorized locks: 265 unused locks: 2 max locking depth: 18 max bfs queue depth: 413 max lock class index: 1784 chain lookup misses: 68685 chain lookup hits: 421636382 cyclic checks: 67614 redundant checks: 0 redundant links: 0 find-mask forwards checks: 14746 find-mask backwards checks: 8622 hardirq on events: 289760108 hardirq off events: 289760106 redundant hardirq ons: 90 redundant hardirq offs: 29 softirq on events: 6016546 softirq off events: 6016546 redundant softirq ons: 0 redundant softirq offs: 0 debug_locks: 0 zapped classes: 4761 zapped lock chains: 46209 large chain blocks: 3 Starting dynamic subtest: dma_fence Stack trace: #0 ../../../usr/src/igt-gpu-tools/lib/igt_core.c:1963 __igt_fail_assert() #1 [igt_kselftest_execute+0x38b] #2 ../../../usr/src/igt-gpu-tools/lib/igt_kmod.c:869 igt_kselftests() #3 ../../../usr/src/igt-gpu-tools/tests/dmabuf.c:49 main() #4 [__libc_start_main+0xf3] #5 [_start+0x2e] Dynamic subtest dma_fence: FAIL (0.214s) Kernel tainted, not executing more selftests. Subtest all-tests: FAIL (0.288s)
Err	Starting dynamic subtest: dma_fence (dmabuf:6047) igt_kmod-CRITICAL: Test assertion failure function igt_kselftest_execute, file ../../../usr/src/igt-gpu-tools/lib/igt_kmod.c:820: (dmabuf:6047) igt_kmod-CRITICAL: Failed assertion: igt_kernel_tainted(&taints) == 0 (dmabuf:6047) igt_kmod-CRITICAL: error: 512 != 0 Dynamic subtest dma_fence failed. ** DEBUG (dmabuf:6047) igt_kmod-DEBUG: Test requirement passed: !(igt_kernel_tainted(&taints)) (dmabuf:6047) igt_kmod-CRITICAL: Test assertion failure function igt_kselftest_execute, file ../../../usr/src/igt-gpu-tools/lib/igt_kmod.c:820: (dmabuf:6047) igt_kmod-CRITICAL: Failed assertion: igt_kernel_tainted(&taints) == 0 (dmabuf:6047) igt_kmod-CRITICAL: error: 512 != 0 (dmabuf:6047) igt_core-INFO: Stack trace: (dmabuf:6047) igt_core-INFO: #0 ../../../usr/src/igt-gpu-tools/lib/igt_core.c:1963 __igt_fail_assert() (dmabuf:6047) igt_core-INFO: #1 [igt_kselftest_execute+0x38b] (dmabuf:6047) igt_core-INFO: #2 ../../../usr/src/igt-gpu-tools/lib/igt_kmod.c:869 igt_kselftests() (dmabuf:6047) igt_core-INFO: #3 ../../../usr/src/igt-gpu-tools/tests/dmabuf.c:49 main() (dmabuf:6047) igt_core-INFO: #4 [__libc_start_main+0xf3] (dmabuf:6047) igt_core-INFO: #5 [_start+0x2e] END ** Dynamic subtest dma_fence: FAIL (0.214s) Subtest all-tests: FAIL (0.288s)
Dmesg Scroll to first warning	<6> [414.899347] [IGT] dmabuf: starting dynamic subtest dma_fence <6> [414.907660] dma-buf: Running dma_fence <6> [414.907673] sizeof(dma_fence)=64 <6> [414.908685] dma-buf: Running dma_fence/sanitycheck <6> [414.909426] dma-buf: Running dma_fence/test_signaling <6> [414.909494] dma-buf: Running dma_fence/test_add_callback <6> [414.909553] dma-buf: Running dma_fence/test_late_add_callback <6> [414.909614] dma-buf: Running dma_fence/test_rm_callback <6> [414.909673] dma-buf: Running dma_fence/test_late_rm_callback <6> [414.909730] dma-buf: Running dma_fence/test_status <6> [414.909788] dma-buf: Running dma_fence/test_error <6> [414.909846] dma-buf: Running dma_fence/test_wait <6> [414.909971] dma-buf: Running dma_fence/test_wait_timeout <6> [414.915097] dma-buf: Running dma_fence/test_stub <6> [414.915180] dma-buf: Running dma_fence/race_signal_callback <4> [414.916079] ------------[ cut here ]------------ <4> [414.916092] DEBUG_LOCKS_WARN_ON(debug_atomic_read(nr_unused_locks) != nr_unused) <4> [414.916109] WARNING: CPU: 2 PID: 875 at kernel/locking/lockdep_proc.c:283 lockdep_stats_show+0xc42/0xc50 <4> [414.916136] Modules linked in: dmabuf_selftests(+) vgem drm_shmem_helper snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic ledtrig_audio snd_intel_dspcfg snd_hda_codec snd_hwdep snd_hda_core snd_pcm prime_numbers i2c_algo_bit ttm drm_buddy syscopyarea sysfillrect sysimgblt fuse x86_pkg_temp_thermal coretemp btusb btrtl kvm_intel btbcm wmi_bmof kvm btintel irqbypass crct10dif_pclmul bluetooth ftdi_sio usbserial crc32_pclmul ghash_clmulni_intel e1000e ecdh_generic ecc mei_me i2c_i801 ptp pps_core video mei i2c_smbus wmi [last unloaded: dmabuf_selftests] <4> [414.916343] CPU: 2 PID: 875 Comm: igt_runner Tainted: G U 6.4.0-rc5-linus-v6.4-rc5-g9561de3a55be+ #1 <4> [414.916358] Hardware name: /NUC7i7BNB, BIOS BNKBL357.86A.0083.2020.0714.1344 07/14/2020 <4> [414.916367] RIP: 0010:lockdep_stats_show+0xc42/0xc50 <4> [414.916384] Code: 00 85 c0 0f 84 09 f6 ff ff 8b 05 55 c6 72 01 85 c0 0f 85 fb f5 ff ff 48 c7 c6 60 27 42 82 48 c7 c7 e6 a6 3b 82 e8 5e 1d f7 ff <0f> 0b e9 e1 f5 ff ff 0f 1f 80 00 00 00 00 90 90 90 90 90 90 90 90 <4> [414.916396] RSP: 0018:ffffc90002057d60 EFLAGS: 00010282 <4> [414.916410] RAX: 0000000000000000 RBX: 0000000000015183 RCX: 0000000000000000 <4> [414.916421] RDX: 0000000000000002 RSI: 000000010000248a RDI: 00000000ffffffff <4> [414.916430] RBP: ffff888103842a48 R08: 0000000000000000 R09: ffffc90002057c00 <4> [414.916440] R10: 0000000000388070 R11: 00000000003880e0 R12: 00000000000003cb <4> [414.916450] R13: 0000000000000041 R14: 0000000000000123 R15: 0000000000000003 <4> [414.916459] FS: 00007f264708d100(0000) GS:ffff888276900000(0000) knlGS:0000000000000000 <4> [414.916471] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 <4> [414.916481] CR2: 00007ffcc1b8cc80 CR3: 000000010cd74002 CR4: 00000000003706e0 <4> [414.916492] Call Trace: <4> [414.916500] <TASK> <4> [414.916508] ? __warn+0x7f/0x170 <4> [414.916525] ? lockdep_stats_show+0xc42/0xc50 <4> [414.916542] ? report_bug+0x1f8/0x200 <4> [414.916560] ? handle_bug+0x3c/0x70 <4> [414.916574] ? exc_invalid_op+0x18/0x70 <4> [414.916589] ? asm_exc_invalid_op+0x1a/0x20 <4> [414.916611] ? lockdep_stats_show+0xc42/0xc50 <4> [414.916638] seq_read_iter+0x111/0x450 <4> [414.916657] proc_reg_read_iter+0x46/0x70 <4> [414.916672] vfs_read+0x1b5/0x2f0 <4> [414.916699] ksys_read+0x64/0xe0 <4> [414.916713] do_syscall_64+0x3c/0x90 <4> [414.916728] entry_SYSCALL_64_after_hwframe+0x72/0xdc <4> [414.916739] RIP: 0033:0x7f2649881392 <4> [414.916751] Code: ac 00 00 f7 d8 64 89 02 48 c7 c0 ff ff ff ff eb be 0f 1f 80 00 00 00 00 f3 0f 1e fa 64 8b 04 25 18 00 00 00 85 c0 75 10 0f 05 <48> 3d 00 f0 ff ff 77 56 c3 0f 1f 44 00 00 48 83 ec 28 48 89 54 24 <4> [414.916762] RSP: 002b:00007ffcc1b8e9a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000000 <4> [414.916776] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f2649881392 <4> [414.916786] RDX: 0000000000000fcd RSI: 00007ffcc1b8e9f2 RDI: 000000000000000f <4> [414.916796] RBP: 000000000000000f R08: 0000000000000000 R09: 0000000000000000 <4> [414.916804] R10: 0000000000000000 R11: 0000000000000246 R12: 00007ffcc1b8e9c0 <4> [414.916813] R13: 0000000000000001 R14: 00005627e77cd808 R15: 00007ffcc1b901c0 <4> [414.916834] </TASK> <4> [414.916841] irq event stamp: 8732249 <4> [414.916849] hardirqs last enabled at (8732249): [<ffffffff81e0148a>] asm_sysvec_apic_timer_interrupt+0x1a/0x20 <4> [414.916864] hardirqs last disabled at (8732248): [<ffffffff81c897cf>] sysvec_apic_timer_interrupt+0xf/0xd0 <4> [414.916878] softirqs last enabled at (8732238): [<ffffffff81c9af5d>] __do_softirq+0x2bd/0x3a9 <4> [414.916912] softirqs last disabled at (8732217): [<ffffffff810d721e>] irq_exit_rcu+0xae/0xf0 <4> [414.916925] ---[ end trace 0000000000000000 ]--- <6> [414.967890] thread_signal_callback[0] completed 6410 passes, 463 misses <6> [414.967905] thread_signal_callback[1] completed 6736 passes, 449 misses <6> [415.019889] thread_signal_callback[0] completed 10656 passes, 10653 misses <6> [415.019902] thread_signal_callback[1] completed 10509 passes, 10506 misses <6> [415.117025] [IGT] dmabuf: exiting, ret=98

Detail

Value

Igt-Version

IGT-Version: 1.27.1-gc2d8ef8b9 (x86_64) (Linux: 6.4.0-rc5-linus-v6.4-rc5-g9561de3a55be+ x86_64)

Out

This test caused an abort condition: Lockdep not active

/proc/lockdep_stats contents:
 lock-classes:                         1615 [max: 8192]
 direct dependencies:                 15632 [max: 32768]
 indirect dependencies:               86403
 all direct dependencies:            269063
 dependency chains:                   22407 [max: 131072]
 dependency chain hlocks used:        90254 [max: 655360]
 dependency chain hlocks lost:            0
 in-hardirq chains:                     119
 in-softirq chains:                     677
 in-process chains:                   21611
 stack-trace entries:                271365 [max: 1048576]
 number of stack traces:              12377
 number of stack hash chains:          8704
 combined max dependencies:      1758352320
 hardirq-safe locks:                     65
 hardirq-unsafe locks:                  971
 softirq-safe locks:                    189
 softirq-unsafe locks:                  883
 irq-safe locks:                        196
 irq-unsafe locks:                      971
 hardirq-read-safe locks:                 3
 hardirq-read-unsafe locks:             291
 softirq-read-safe locks:                 7
 softirq-read-unsafe locks:             286
 irq-read-safe locks:                     7
 irq-read-unsafe locks:                 291
 uncategorized locks:                   265
 unused locks:                            2
 max locking depth:                      18
 max bfs queue depth:                   413
 max lock class index:                 1784
 chain lookup misses:                 68685
 chain lookup hits:               421636382
 cyclic checks:                       67614
 redundant checks:                        0
 redundant links:                         0
 find-mask forwards checks:           14746
 find-mask backwards checks:           8622
 hardirq on events:               289760108
 hardirq off events:              289760106
 redundant hardirq ons:                  90
 redundant hardirq offs:                 29
 softirq on events:                 6016546
 softirq off events:                6016546
 redundant softirq ons:                   0
 redundant softirq offs:                  0
 debug_locks:                             0

 zapped classes:                       4761
 zapped lock chains:                  46209
 large chain blocks:                      3
Starting dynamic subtest: dma_fence
Stack trace:
  #0 ../../../usr/src/igt-gpu-tools/lib/igt_core.c:1963 __igt_fail_assert()
  #1 [igt_kselftest_execute+0x38b]
  #2 ../../../usr/src/igt-gpu-tools/lib/igt_kmod.c:869 igt_kselftests()
  #3 ../../../usr/src/igt-gpu-tools/tests/dmabuf.c:49 main()
  #4 [__libc_start_main+0xf3]
  #5 [_start+0x2e]
Dynamic subtest dma_fence: FAIL (0.214s)
Kernel tainted, not executing more selftests.
Subtest all-tests: FAIL (0.288s)

Err

Starting dynamic subtest: dma_fence
(dmabuf:6047) igt_kmod-CRITICAL: Test assertion failure function igt_kselftest_execute, file ../../../usr/src/igt-gpu-tools/lib/igt_kmod.c:820:
(dmabuf:6047) igt_kmod-CRITICAL: Failed assertion: igt_kernel_tainted(&taints) == 0
(dmabuf:6047) igt_kmod-CRITICAL: error: 512 != 0
Dynamic subtest dma_fence failed.
**** DEBUG ****
(dmabuf:6047) igt_kmod-DEBUG: Test requirement passed: !(igt_kernel_tainted(&taints))
(dmabuf:6047) igt_kmod-CRITICAL: Test assertion failure function igt_kselftest_execute, file ../../../usr/src/igt-gpu-tools/lib/igt_kmod.c:820:
(dmabuf:6047) igt_kmod-CRITICAL: Failed assertion: igt_kernel_tainted(&taints) == 0
(dmabuf:6047) igt_kmod-CRITICAL: error: 512 != 0
(dmabuf:6047) igt_core-INFO: Stack trace:
(dmabuf:6047) igt_core-INFO:   #0 ../../../usr/src/igt-gpu-tools/lib/igt_core.c:1963 __igt_fail_assert()
(dmabuf:6047) igt_core-INFO:   #1 [igt_kselftest_execute+0x38b]
(dmabuf:6047) igt_core-INFO:   #2 ../../../usr/src/igt-gpu-tools/lib/igt_kmod.c:869 igt_kselftests()
(dmabuf:6047) igt_core-INFO:   #3 ../../../usr/src/igt-gpu-tools/tests/dmabuf.c:49 main()
(dmabuf:6047) igt_core-INFO:   #4 [__libc_start_main+0xf3]
(dmabuf:6047) igt_core-INFO:   #5 [_start+0x2e]
****  END  ****
Dynamic subtest dma_fence: FAIL (0.214s)
Subtest all-tests: FAIL (0.288s)

Dmesg

Scroll to first warning

<6> [414.899347] [IGT] dmabuf: starting dynamic subtest dma_fence

<6> [414.907660] dma-buf: Running dma_fence

<6> [414.907673] sizeof(dma_fence)=64

<6> [414.908685] dma-buf: Running dma_fence/sanitycheck

<6> [414.909426] dma-buf: Running dma_fence/test_signaling

<6> [414.909494] dma-buf: Running dma_fence/test_add_callback

<6> [414.909553] dma-buf: Running dma_fence/test_late_add_callback

<6> [414.909614] dma-buf: Running dma_fence/test_rm_callback

<6> [414.909673] dma-buf: Running dma_fence/test_late_rm_callback

<6> [414.909730] dma-buf: Running dma_fence/test_status

<6> [414.909788] dma-buf: Running dma_fence/test_error

<6> [414.909846] dma-buf: Running dma_fence/test_wait

<6> [414.909971] dma-buf: Running dma_fence/test_wait_timeout

<6> [414.915097] dma-buf: Running dma_fence/test_stub

<6> [414.915180] dma-buf: Running dma_fence/race_signal_callback

<4> [414.916079] ------------[ cut here ]------------

<4> [414.916092] DEBUG_LOCKS_WARN_ON(debug_atomic_read(nr_unused_locks) != nr_unused)

<4> [414.916109] WARNING: CPU: 2 PID: 875 at kernel/locking/lockdep_proc.c:283 lockdep_stats_show+0xc42/0xc50

<4> [414.916136] Modules linked in: dmabuf_selftests(+) vgem drm_shmem_helper snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic ledtrig_audio snd_intel_dspcfg snd_hda_codec snd_hwdep snd_hda_core snd_pcm prime_numbers i2c_algo_bit ttm drm_buddy syscopyarea sysfillrect sysimgblt fuse x86_pkg_temp_thermal coretemp btusb btrtl kvm_intel btbcm wmi_bmof kvm btintel irqbypass crct10dif_pclmul bluetooth ftdi_sio usbserial crc32_pclmul ghash_clmulni_intel e1000e ecdh_generic ecc mei_me i2c_i801 ptp pps_core video mei i2c_smbus wmi [last unloaded: dmabuf_selftests]

<4> [414.916343] CPU: 2 PID: 875 Comm: igt_runner Tainted: G U 6.4.0-rc5-linus-v6.4-rc5-g9561de3a55be+ #1

<4> [414.916358] Hardware name: /NUC7i7BNB, BIOS BNKBL357.86A.0083.2020.0714.1344 07/14/2020

<4> [414.916367] RIP: 0010:lockdep_stats_show+0xc42/0xc50

<4> [414.916384] Code: 00 85 c0 0f 84 09 f6 ff ff 8b 05 55 c6 72 01 85 c0 0f 85 fb f5 ff ff 48 c7 c6 60 27 42 82 48 c7 c7 e6 a6 3b 82 e8 5e 1d f7 ff <0f> 0b e9 e1 f5 ff ff 0f 1f 80 00 00 00 00 90 90 90 90 90 90 90 90

<4> [414.916396] RSP: 0018:ffffc90002057d60 EFLAGS: 00010282

<4> [414.916410] RAX: 0000000000000000 RBX: 0000000000015183 RCX: 0000000000000000

<4> [414.916421] RDX: 0000000000000002 RSI: 000000010000248a RDI: 00000000ffffffff

<4> [414.916430] RBP: ffff888103842a48 R08: 0000000000000000 R09: ffffc90002057c00

<4> [414.916440] R10: 0000000000388070 R11: 00000000003880e0 R12: 00000000000003cb

<4> [414.916450] R13: 0000000000000041 R14: 0000000000000123 R15: 0000000000000003

<4> [414.916459] FS: 00007f264708d100(0000) GS:ffff888276900000(0000) knlGS:0000000000000000

<4> [414.916471] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033

<4> [414.916481] CR2: 00007ffcc1b8cc80 CR3: 000000010cd74002 CR4: 00000000003706e0

<4> [414.916492] Call Trace:

<4> [414.916500] <TASK>

<4> [414.916508] ? __warn+0x7f/0x170

<4> [414.916525] ? lockdep_stats_show+0xc42/0xc50

<4> [414.916542] ? report_bug+0x1f8/0x200

<4> [414.916560] ? handle_bug+0x3c/0x70

<4> [414.916574] ? exc_invalid_op+0x18/0x70

<4> [414.916589] ? asm_exc_invalid_op+0x1a/0x20

<4> [414.916611] ? lockdep_stats_show+0xc42/0xc50

<4> [414.916638] seq_read_iter+0x111/0x450

<4> [414.916657] proc_reg_read_iter+0x46/0x70

<4> [414.916672] vfs_read+0x1b5/0x2f0

<4> [414.916699] ksys_read+0x64/0xe0

<4> [414.916713] do_syscall_64+0x3c/0x90

<4> [414.916728] entry_SYSCALL_64_after_hwframe+0x72/0xdc

<4> [414.916739] RIP: 0033:0x7f2649881392

<4> [414.916751] Code: ac 00 00 f7 d8 64 89 02 48 c7 c0 ff ff ff ff eb be 0f 1f 80 00 00 00 00 f3 0f 1e fa 64 8b 04 25 18 00 00 00 85 c0 75 10 0f 05 <48> 3d 00 f0 ff ff 77 56 c3 0f 1f 44 00 00 48 83 ec 28 48 89 54 24

<4> [414.916762] RSP: 002b:00007ffcc1b8e9a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000000

<4> [414.916776] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f2649881392

<4> [414.916786] RDX: 0000000000000fcd RSI: 00007ffcc1b8e9f2 RDI: 000000000000000f

<4> [414.916796] RBP: 000000000000000f R08: 0000000000000000 R09: 0000000000000000

<4> [414.916804] R10: 0000000000000000 R11: 0000000000000246 R12: 00007ffcc1b8e9c0

<4> [414.916813] R13: 0000000000000001 R14: 00005627e77cd808 R15: 00007ffcc1b901c0

<4> [414.916834] </TASK>

<4> [414.916841] irq event stamp: 8732249

<4> [414.916849] hardirqs last enabled at (8732249): [<ffffffff81e0148a>] asm_sysvec_apic_timer_interrupt+0x1a/0x20

<4> [414.916864] hardirqs last disabled at (8732248): [<ffffffff81c897cf>] sysvec_apic_timer_interrupt+0xf/0xd0

<4> [414.916878] softirqs last enabled at (8732238): [<ffffffff81c9af5d>] __do_softirq+0x2bd/0x3a9

<4> [414.916912] softirqs last disabled at (8732217): [<ffffffff810d721e>] irq_exit_rcu+0xae/0xf0

<4> [414.916925] ---[ end trace 0000000000000000 ]---

<6> [414.967890] thread_signal_callback[0] completed 6410 passes, 463 misses

<6> [414.967905] thread_signal_callback[1] completed 6736 passes, 449 misses

<6> [415.019889] thread_signal_callback[0] completed 10656 passes, 10653 misses

<6> [415.019902] thread_signal_callback[1] completed 10509 passes, 10506 misses

<6> [415.117025] [IGT] dmabuf: exiting, ret=98

Created at 2023-06-05 22:57:30