Results for igt@kms_busy@basic

Result: Abort 67 Warning(s)

integration-manifest git-log-oneline i915_display_info0 igt_runner0 run0 runtimes0 results0.json guc_logs0.tar i915_display_info_post_exec0 boot0 dmesg0

DetailValue
Duration unknown
Igt-Version 
IGT-Version: 2.4-ge6b603e49 (x86_64) (Linux: 7.0.0-rc6-Patchwork_164182v1-g779129c7868f+ x86_64)
Out 
Using IGT_SRANDOM=1774975638 for randomisation
Opened device: /dev/dri/card0
Starting subtest: basic
Starting dynamic subtest: flip
Using (pipe A + VGA-1) to run the subtest.
Using timeout of 54ms
Dynamic subtest flip: SUCCESS (1.027s)
Starting dynamic subtest: modeset
Test requirement not met in function test_flip, file ../tests/intel/kms_busy.c:185:
Test requirement: dpy->is_atomic
Dynamic subtest modeset: SKIP (0.000s)
Subtest basic: SUCCESS (1.041s)
Test requirement not met in function __igt_unique____real_main419, file ../tests/intel/kms_busy.c:529:
Test requirement: display.is_atomic
Last errno: 9, Bad file descriptor

This test caused an abort condition: Child refuses to die, tainted 0x42c0.
Err 
Starting subtest: basic
Starting dynamic subtest: flip
Dynamic subtest flip: SUCCESS (1.027s)
Starting dynamic subtest: modeset
Dynamic subtest modeset: SKIP (0.000s)
Subtest basic: SUCCESS (1.041s)
Dmesg

Scroll to first warning
<0> [307.281365] kms_busy-3678 3...1. 248218229us : tracing_mark_write: kms_busy: starting subtest basic
<0> [307.281365] kms_busy-3678 3..... 248566476us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [307.281365] kworker/-97 3..... 248615041us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [307.281365] kms_busy-3678 3..... 248693300us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [307.281365] kms_busy-3678 3..... 248714617us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [307.281365] kworker/-70 2..... 248717677us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [307.281365] kworker/-72 1..... 248730144us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [307.281365] kms_busy-3678 3..... 248730531us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [307.281365] kworker/-70 2..... 248746873us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [307.312346] kms_busy-3678 3..... 248747891us : __gt_unpark: 0000:00:02.0
<0> [307.312346] kms_busy-3678 3..... 248748328us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 active
<0> [307.312346] kms_busy-3678 3..... 248748448us : __engine_unpark: 0000:00:02.0 rcs0:
<0> [307.312346] kms_busy-3678 3..... 248748549us : __engine_unpark: 0000:00:02.0 rcs0: context:3 reset { seqno:4c4d, *hwsp:4c4d, ring:18a0 }
<0> [307.312346] kms_busy-3678 3..... 248748566us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 pin ring:{start:001db000, head:18a0, tail:18a0}
<0> [307.312346] kms_busy-3678 3..... 248749519us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:3 unpin
<0> [307.312346] kms_busy-3678 3..... 248749559us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 active
<0> [307.312346] kms_busy-3678 3..... 248749584us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 pin ring:{start:001db000, head:18a0, tail:18a0}
<0> [307.312346] kms_busy-3678 3..... 248749853us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:3 unpin
<0> [307.312346] kms_busy-3678 3..... 248750026us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 active
<0> [307.312346] kms_busy-3678 3..... 248750052us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 pin ring:{start:001db000, head:18a0, tail:18a0}
<0> [307.312346] kms_busy-3678 3..... 248750358us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:3 unpin
<0> [307.312346] kworker/-38 3..... 248752126us : __engine_park: 0000:00:02.0 rcs0: parked
<0> [307.312346] kworker/-38 3..... 248752143us : __intel_context_retire: 0000:00:02.0 rcs0: context:3 retire runtime: { total:0ns, avg:0ns }
<0> [307.312346] kworker/-38 3..... 248752226us : __gt_park: 0000:00:02.0
<0> [307.312346] kms_busy-3678 3..... 248797662us : __gt_unpark: 0000:00:02.0
<0> [307.312346] kms_busy-3678 3..... 248798031us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 active
<0> [307.312346] kms_busy-3678 3..... 248798112us : __engine_unpark: 0000:00:02.0 rcs0:
<0> [307.312346] kms_busy-3678 3..... 248798180us : __engine_unpark: 0000:00:02.0 rcs0: context:3 reset { seqno:4c4d, *hwsp:4c4d, ring:18a0 }
<0> [307.312346] kms_busy-3678 3..... 248798190us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 pin ring:{start:001db000, head:18a0, tail:18a0}
<0> [307.312346] kms_busy-3678 3..... 248800397us : __i915_request_commit: 0000:00:02.0 rcs0: fence 3:19534, current 19533
<0> [307.312346] kms_busy-3681 0..... 248850058us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [307.312346] kms_busy-3681 0d..2. 248905242us : __i915_request_submit: 0000:00:02.0 rcs0: fence 3:19534, current 19533
<0> [307.312346] kworker/-26 2..... 248958035us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 3:19534, current 19534
<0> [307.312346] kworker/-26 2..... 248959021us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:3 unpin
<0> [307.312346] kworker/-26 2..... 248962122us : __i915_request_commit: 0000:00:02.0 rcs0: fence 3:19535, current 19534
<0> [307.312346] kworker/-26 2..... 248962139us : __engine_park: 0000:00:02.0 rcs0: parking
<0> [307.312346] kworker/-26 2d..2. 248962215us : __i915_request_submit: 0000:00:02.0 rcs0: fence 3:19535, current 19534
<0> [307.312346] kworker/-26 2d.h3. 248962275us : __intel_context_retire: 0000:00:02.0 rcs0: context:3 retire runtime: { total:0ns, avg:0ns }
<0> [307.312346] kworker/-26 2..... 248962335us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 3:19535, current 19535
<0> [307.312346] kworker/-26 2..... 248962382us : __engine_park: 0000:00:02.0 rcs0: parked
<0> [307.312346] kworker/-26 2..... 248962542us : __gt_park: 0000:00:02.0
<0> [307.312346] kworker/-72 1..... 248963621us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [307.312346] kms_busy-3678 3..... 248964620us : __gt_unpark: 0000:00:02.0
<0> [307.312346] kms_busy-3678 3..... 248964811us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 active
<0> [307.312346] kms_busy-3678 3..... 248964891us : __engine_unpark: 0000:00:02.0 rcs0:
<0> [307.312346] kms_busy-3678 3..... 248965034us : __engine_unpark: 0000:00:02.0 rcs0: context:3 reset { seqno:4c4f, *hwsp:4c4f, ring:1c88 }
<0> [307.312346] kms_busy-3678 3..... 248965046us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 pin ring:{start:001db000, head:1c88, tail:1c88}
<0> [307.312346] kms_busy-3678 3..... 248965651us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:3 unpin
<0> [307.312346] kms_busy-3678 3..... 248965676us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 active
<0> [307.312346] kms_busy-3678 3..... 248965691us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 pin ring:{start:001db000, head:1c88, tail:1c88}
<0> [307.312346] kms_busy-3678 3..... 248965869us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:3 unpin
<0> [307.312346] kms_busy-3678 3..... 248966275us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 active
<0> [307.312346] kms_busy-3678 3..... 248966295us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 pin ring:{start:001db000, head:1c88, tail:1c88}
<0> [307.312346] kms_busy-3678 3..... 248966510us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:3 unpin
<0> [307.312346] kworker/-38 3..... 248969126us : __engine_park: 0000:00:02.0 rcs0: parked
<0> [307.312346] kworker/-38 3..... 248969139us : __intel_context_retire: 0000:00:02.0 rcs0: context:3 retire runtime: { total:0ns, avg:0ns }
<0> [307.312346] kworker/-38 3..... 248969242us : __gt_park: 0000:00:02.0
<0> [307.312346] kms_busy-3678 3..... 248971478us : __gt_unpark: 0000:00:02.0
<0> [307.312346] kms_busy-3678 3..... 248971713us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 active
<0> [307.312346] kms_busy-3678 3..... 248971793us : __engine_unpark: 0000:00:02.0 rcs0:
<0> [307.312346] kms_busy-3678 3..... 248971859us : __engine_unpark: 0000:00:02.0 rcs0: context:3 reset { seqno:4c4f, *hwsp:4c4f, ring:1c88 }
<0> [307.312346] kms_busy-3678 3..... 248971869us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 pin ring:{start:001db000, head:1c88, tail:1c88}
<0> [307.312346] kms_busy-3678 3..... 248972095us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:3 unpin
<0> [307.312346] kms_busy-3678 3..... 248972113us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 active
<0> [307.312346] kms_busy-3678 3..... 248972128us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3 pin ring:{start:001db000, head:1c88, tail:1c88}
<0> [307.312346] kms_busy-3678 3..... 248973003us : __i915_request_commit: 0000:00:02.0 rcs0: fence 3:19536, current 19535
<0> [307.312346] kms_busy-3683 0..... 249022358us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [307.312346] kms_busy-3683 0d..2. 249076740us : __i915_request_submit: 0000:00:02.0 rcs0: fence 3:19536, current 19535
<0> [307.312346] kworker/-26 2..... 249124735us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 3:19536, current 19536
<0> [307.312346] kworker/-26 2..... 249124837us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:3 unpin
<0> [307.312346] kworker/-26 2..... 249127072us : __i915_request_commit: 0000:00:02.0 rcs0: fence 3:19537, current 19536
<0> [307.312346] kworker/-26 2..... 249127088us : __engine_park: 0000:00:02.0 rcs0: parking
<0> [307.312346] kworker/-26 2d..2. 249127162us : __i915_request_submit: 0000:00:02.0 rcs0: fence 3:19537, current 19536
<0> [307.312346] kworker/-26 2d.h3. 249127217us : __intel_context_retire: 0000:00:02.0 rcs0: context:3 retire runtime: { total:0ns, avg:0ns }
<0> [307.312346] kworker/-26 2..... 249127271us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 3:19537, current 19537
<0> [307.312346] kworker/-26 2..... 249127315us : __engine_park: 0000:00:02.0 rcs0: parked
<0> [307.312346] kworker/-26 2..... 249127479us : __gt_park: 0000:00:02.0
<0> [307.380346] kworker/-72 1..... 249130369us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [307.380346] kworker/-65 0.l... 249254068us : i915_vma_revoke_fence: i915_vma_revoke_fence:306 GEM_BUG_ON(atomic_read(&fence->pin_count))
<0> [307.380346] ---------------------------------
<4> [307.391952] ------------[ cut here ]------------
<2> [307.391989] kernel BUG at drivers/gpu/drm/i915/gt/intel_ggtt_fencing.c:306!
<4> [307.392164] Oops: invalid opcode: 0000 [#1] SMP NOPTI
<4> [307.392222] CPU: 1 UID: 0 PID: 65 Comm: kworker/u16:1 Tainted: G U W L 7.0.0-rc6-Patchwork_164182v1-g779129c7868f+ #1 PREEMPT(lazy)
<4> [307.392258] Tainted: [U]=USER, [W]=WARN, [L]=SOFTLOCKUP
<4> [307.392258] Hardware name: /D510MO, BIOS MOPNV10J.86A.0311.2010.0802.2346 08/02/2010
<4> [307.392258] Workqueue: i915 __i915_gem_free_work [i915]
<4> [307.392258] RIP: 0010:i915_vma_revoke_fence+0x20e/0x210 [i915]
<4> [307.392258] Code: 00 48 c7 c2 c0 e4 c0 a0 48 8d 3d 00 00 00 00 e8 d8 a2 d6 e0 bf 01 00 00 00 e8 5e 49 d6 e0 31 f6 bf 09 00 00 00 e8 62 50 b5 e0 <0f> 0b 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 0f 1f 44 00
<4> [307.392258] RSP: 0018:ffffc90000247cf0 EFLAGS: 00010246
<4> [307.392258] RAX: 0000000000000000 RBX: ffff88800d00ad80 RCX: 0000000000000000
<4> [307.392258] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
<4> [307.392258] RBP: ffffc90000247d00 R08: 0000000000000000 R09: 0000000000000000
<4> [307.392258] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88800a2d4040
<4> [307.392258] R13: ffff88800a2d4040 R14: ffff8880392e0e00 R15: ffff888009412c40
<4> [307.392258] FS: 0000000000000000(0000) GS:ffff8880f8b18000(0000) knlGS:0000000000000000
<4> [307.392258] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [307.392258] CR2: 000076854f4201a0 CR3: 000000000d8e5000 CR4: 00000000000006f0
<4> [307.392258] Call Trace:
<4> [307.392258] <TASK>
<4> [307.392258] __i915_vma_evict+0x3dc/0x5f0 [i915]
<4> [307.397533] __i915_vma_unbind+0x7a/0x160 [i915]
<4> [307.397533] force_unbind+0x3a/0xd0 [i915]
<4> [307.400730] i915_vma_destroy+0x35/0xc0 [i915]
<4> [307.400730] __i915_gem_object_pages_fini+0x57/0x310 [i915]
<4> [307.400730] __i915_gem_free_objects.isra.0+0x73/0xd0 [i915]
<4> [307.400730] __i915_gem_free_work+0x25/0x30 [i915]
<4> [307.400730] process_one_work+0x239/0x760
<4> [307.400730] worker_thread+0x200/0x3f0
<4> [307.400730] ? __pfx_worker_thread+0x10/0x10
<4> [307.400730] kthread+0x10d/0x150
<4> [307.400730] ? __pfx_kthread+0x10/0x10
<4> [307.400730] ret_from_fork+0x3d4/0x480
<4> [307.400730] ? __pfx_kthread+0x10/0x10
<4> [307.400730] ret_from_fork_asm+0x1a/0x30
<4> [307.400730] </TASK>
<4> [307.400730] Modules linked in: vgem i915 prime_numbers drm_buddy ttm drm_display_helper cec rc_core i2c_algo_bit video wmi overlay at24 gpio_ich ppdev binfmt_misc snd_hda_codec_alc662 snd_hda_codec_realtek_lib nls_iso8859_1 snd_hda_codec_generic coretemp i2c_i801 i2c_mux i2c_smbus lpc_ich snd_hda_intel snd_intel_dspcfg snd_hda_codec snd_hda_core snd_hwdep r8169 parport_pc snd_pcm parport snd_timer snd realtek soundcore nvme_fabrics fuse efi_pstore dm_multipath msr nfnetlink autofs4
<4> [307.411716] ---[ end trace 0000000000000000 ]---
<3> [309.070301] pstore: backend (efi_pstore) writing error (-28)
<4> [309.070383] RIP: 0010:i915_vma_revoke_fence+0x20e/0x210 [i915]
<4> [309.075006] Code: 00 48 c7 c2 c0 e4 c0 a0 48 8d 3d 00 00 00 00 e8 d8 a2 d6 e0 bf 01 00 00 00 e8 5e 49 d6 e0 31 f6 bf 09 00 00 00 e8 62 50 b5 e0 <0f> 0b 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 0f 1f 44 00
<4> [309.075109] RSP: 0018:ffffc90000247cf0 EFLAGS: 00010246
<4> [309.075159] RAX: 0000000000000000 RBX: ffff88800d00ad80 RCX: 0000000000000000
<4> [309.075481] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
<4> [309.075535] RBP: ffffc90000247d00 R08: 0000000000000000 R09: 0000000000000000
<4> [309.075580] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88800a2d4040
<4> [309.075625] R13: ffff88800a2d4040 R14: ffff8880392e0e00 R15: ffff888009412c40
<4> [309.075670] FS: 0000000000000000(0000) GS:ffff8880f8a98000(0000) knlGS:0000000000000000
<4> [309.075725] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [309.075767] CR2: 000000c0005ac028 CR3: 000000000d8e5000 CR4: 00000000000006f0
Created at 2026-03-31 16:57:03