Machine description: bat-adlp-vf
Result: 10 Warning(s)
i915_display_info0 igt_runner0 results0.json results0-xe-load.json i915_display_info_post_exec0 boot0 dmesg0
| Detail | Value |
|---|---|
| Duration | 0.15 seconds |
| Igt-Version |
IGT-Version: 1.30-gb59dbe3e1 (x86_64) (Linux: 6.14.0-rc4-xe+ x86_64) |
| Out |
Using IGT_SRANDOM=1741029962 for randomisation Starting subtest: xe_migrate Starting dynamic subtest: xe_migrate_sanity_kunit Stack trace: #0 ../lib/igt_core.c:2055 __igt_fail_assert() #1 ../lib/igt_kmod.c:1107 __igt_kunit() #2 ../lib/igt_kmod.c:1221 igt_kunit() #3 ../tests/intel/xe_live_ktest.c:58 main() #4 [__libc_init_first+0x8a] #5 [__libc_start_main+0x8b] #6 [_start+0x25] Dynamic subtest xe_migrate_sanity_kunit: FAIL (0.152s) Kernel tainted, not executing more selftests. Subtest xe_migrate: FAIL (0.155s) |
| Err |
Starting subtest: xe_migrate Starting dynamic subtest: xe_migrate_sanity_kunit (xe_live_ktest:8322) igt_kmod-CRITICAL: Test assertion failure function __igt_kunit, file ../lib/igt_kmod.c:1108: (xe_live_ktest:8322) igt_kmod-CRITICAL: Failed assertion: igt_kernel_tainted(&taints) == 0 (xe_live_ktest:8322) igt_kmod-CRITICAL: Last errno: 2, No such file or directory (xe_live_ktest:8322) igt_kmod-CRITICAL: error: 512 != 0 Dynamic subtest xe_migrate_sanity_kunit failed. **** DEBUG **** (xe_live_ktest:8322) igt_kmod-DEBUG: Test requirement passed: !(kmod_module_remove_module(tst->kmod, 0)) (xe_live_ktest:8322) igt_kmod-DEBUG: Test requirement passed: !(igt_kernel_tainted(&taints)) (xe_live_ktest:8322) igt_kmod-CRITICAL: Test assertion failure function __igt_kunit, file ../lib/igt_kmod.c:1108: (xe_live_ktest:8322) igt_kmod-CRITICAL: Failed assertion: igt_kernel_tainted(&taints) == 0 (xe_live_ktest:8322) igt_kmod-CRITICAL: Last errno: 2, No such file or directory (xe_live_ktest:8322) igt_kmod-CRITICAL: error: 512 != 0 (xe_live_ktest:8322) igt_core-INFO: Stack trace: (xe_live_ktest:8322) igt_core-INFO: #0 ../lib/igt_core.c:2055 __igt_fail_assert() (xe_live_ktest:8322) igt_core-INFO: #1 ../lib/igt_kmod.c:1107 __igt_kunit() (xe_live_ktest:8322) igt_core-INFO: #2 ../lib/igt_kmod.c:1221 igt_kunit() (xe_live_ktest:8322) igt_core-INFO: #3 ../tests/intel/xe_live_ktest.c:58 main() (xe_live_ktest:8322) igt_core-INFO: #4 [__libc_init_first+0x8a] (xe_live_ktest:8322) igt_core-INFO: #5 [__libc_start_main+0x8b] (xe_live_ktest:8322) igt_core-INFO: #6 [_start+0x25] **** END **** Dynamic subtest xe_migrate_sanity_kunit: FAIL (0.152s) Subtest xe_migrate: FAIL (0.155s) |
| Dmesg
|
<6> [273.329752] [IGT] xe_live_ktest: executing
<6> [273.332710] [IGT] xe_live_ktest: starting subtest xe_migrate
<6> [273.334019] KTAP version 1
<6> [273.334021] 1..1
<6> [273.335228] KTAP version 1
<6> [273.335230] # Subtest: xe_migrate
<6> [273.335231] # module: xe
<6> [273.335240] 1..2
<6> [273.335242] ok 1 xe_migrate_sanity_kunit # SKIP
<6> [273.335252] ok 2 xe_validate_ccs_kunit # SKIP
<6> [273.335259] # xe_migrate: pass:0 fail:0 skip:2 total:2
<6> [273.335267] # Totals: pass:0 fail:0 skip:2 total:2
<6> [273.335275] ok 1 xe_migrate # SKIP
<6> [273.335622] [IGT] xe_live_ktest: starting dynamic subtest xe_migrate_sanity_kunit
<6> [273.452061] KTAP version 1
<6> [273.452075] 1..1
<6> [273.453402] KTAP version 1
<6> [273.453410] # Subtest: xe_migrate
<6> [273.453418] # module: xe
<6> [273.453463] 1..1
<6> [273.453476] KTAP version 1
<6> [273.453504] # Subtest: xe_migrate_sanity_kunit
<6> [273.454168] # xe_migrate_sanity_kunit: running on ALDERLAKE_P device
<6> [273.454270] # xe_migrate_sanity_kunit: Testing tile id 0.
<6> [273.456259] # xe_migrate_sanity_kunit: Starting tests, top level PT addr: febf1000, special pagetable base addr: febc0000
<6> [273.457840] # xe_migrate_sanity_kunit: Writing PTE for our fake PT: Job completed
<6> [273.458197] # xe_migrate_sanity_kunit: Writing to our newly mapped pagetable: Job completed
<6> [273.458242] # xe_migrate_sanity_kunit: Clearing small buffer object
<6> [273.458914] # xe_migrate_sanity_kunit: Copying small buffer object to system
<7> [273.459555] xe 0000:00:02.0: [drm:xe_migrate_copy [xe]] Pass 0, sizes: 8192 & 8192
<7> [273.459858] xe 0000:00:02.0: [drm:xe_migrate_copy [xe]] Pass 0, sizes: 8192 & 8192
<6> [273.460146] # xe_migrate_sanity_kunit: Clearing big buffer object
<6> [273.460815] # xe_migrate_sanity_kunit: Copying big buffer object to system
<7> [273.462259] xe 0000:00:02.0: [drm:xe_migrate_copy [xe]] Pass 0, sizes: 4194304 & 4194304
<7> [273.463101] xe 0000:00:02.0: [drm:xe_migrate_copy [xe]] Pass 0, sizes: 4194304 & 4194304
<6> [273.464510] ok 1 0000:00:02.0
<6> [273.464643] # xe_migrate_sanity_kunit: running on ALDERLAKE_P device
<6> [273.464669] # xe_migrate_sanity_kunit: Testing tile id 0.
<6> [273.465191] # xe_migrate_sanity_kunit: Starting tests, top level PT addr: f403e000, special pagetable base addr: f4560000
<6> [273.465526] # xe_migrate_sanity_kunit: Writing PTE for our fake PT: Job completed
<6> [273.465658] # xe_migrate_sanity_kunit: Writing to our newly mapped pagetable: Job completed
<6> [273.465671] # xe_migrate_sanity_kunit: Clearing small buffer object
<7> [273.465824] xe 0000:00:02.1: [drm:xe_guc_exec_queue_memory_cat_error_handler [xe]] GT0: Engine memory cat error: engine_class=bcs, logical_mask: 0x1, guc_id=0
<6> [273.466671] xe 0000:00:02.1: [drm] GT0: Engine reset: engine_class=bcs, logical_mask: 0x1, guc_id=0
<5> [273.466779] xe 0000:00:02.1: [drm] GT0: Timedout job: seqno=4294967171, lrc_seqno=4294967171, guc_id=0, flags=0x3 in no process [-1]
<6> [273.467358] xe 0000:00:02.1: [drm] Xe device coredump has been created
<6> [273.467416] xe 0000:00:02.1: [drm] Check your /sys/class/drm/card1/device/devcoredump/data
<4> [273.467418] ------------[ cut here ]------------
<4> [273.467419] xe 0000:00:02.1: [drm] GT0: Kernel-submitted job timed out
<4> [273.467442] WARNING: CPU: 4 PID: 4658 at drivers/gpu/drm/xe/xe_guc_submit.c:1181 guc_exec_queue_timedout_job+0xad3/0xe40 [xe]
<4> [273.467538] Modules linked in: xe_live_test snd_hda_intel vgem drm_shmem_helper snd_hda_codec_hdmi snd_intel_dspcfg snd_hda_codec snd_hda_core snd_hwdep snd_pcm snd_timer snd soundcore xe drm_gpuvm drm_ttm_helper ttm gpu_sched drm_suballoc_helper drm_exec drm_display_helper cec rc_core drm_buddy drm_kunit_helpers drm_kms_helper i2c_algo_bit kunit overlay hid_sensor_custom hid_sensor_hub hid_generic intel_ishtp_hid hid intel_uncore_frequency intel_uncore_frequency_common x86_pkg_temp_thermal intel_powerclamp cmdlinepart mei_pxp cdc_mbim cdc_wdm cdc_ncm spi_nor mtd cdc_ether usbnet mei_hdcp coretemp intel_rapl_msr wmi_bmof kvm_intel processor_thermal_device_pci processor_thermal_device kvm polyval_clmulni polyval_generic ghash_clmulni_intel spi_pxa2xx_platform processor_thermal_wt_hint sha256_ssse3 sha1_ssse3 dw_dmac processor_thermal_rfim aesni_intel r8152 dw_dmac_core crypto_simd processor_thermal_rapl mii cryptd rapl intel_cstate intel_rapl_common spi_pxa2xx_core spi_intel_pci intel_ish_ipc mei_me binfmt_misc
<4> [273.467603] i2c_i801 processor_thermal_wt_req i2c_mux idma64 spi_intel i2c_smbus mei intel_ishtp processor_thermal_power_floor e1000e thunderbolt processor_thermal_mbox igen6_edac int340x_thermal_zone video nls_iso8859_1 intel_skl_int3472_tps68470 tps68470_regulator clk_tps68470 intel_pmc_core pmt_telemetry pmt_class wmi intel_skl_int3472_discrete int3400_thermal intel_hid intel_skl_int3472_common intel_vsec pinctrl_tigerlake acpi_tad acpi_thermal_rel sparse_keymap acpi_pad dm_multipath msr nvme_fabrics fuse efi_pstore nfnetlink ip_tables x_tables autofs4 [last unloaded: xe_live_test]
<4> [273.467640] CPU: 4 UID: 0 PID: 4658 Comm: kworker/u64:9 Tainted: G U N 6.14.0-rc4-xe+ #1
<4> [273.467643] Tainted: [U]=USER, [N]=TEST
<4> [273.467644] Hardware name: Intel Corporation Alder Lake Client Platform/AlderLake-P DDR5 RVP, BIOS RPLPFWI1.R00.4035.A00.2301200723 01/20/2023
<4> [273.467646] Workqueue: gt-ordered-wq drm_sched_job_timedout [gpu_sched]
<4> [273.467652] RIP: 0010:guc_exec_queue_timedout_job+0xad3/0xe40 [xe]
<4> [273.467716] Code: 48 85 d2 74 30 48 89 95 78 ff ff ff e8 16 5b 55 e1 48 8b 95 78 ff ff ff 44 89 e9 48 c7 c7 88 fe ee a0 48 89 c6 e8 0d 59 7f e0 <0f> 0b 49 8b 46 58 e9 19 f9 ff ff 48 8b 17 eb cb 41 8b 87 c8 20 00
<4> [273.467718] RSP: 0018:ffffc90008443cc0 EFLAGS: 00010246
<4> [273.467721] RAX: 0000000000000000 RBX: ffff888157ff2f00 RCX: 0000000000000000
<4> [273.467722] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
<4> [273.467724] RBP: ffffc90008443db0 R08: 0000000000000000 R09: 0000000000000000
<4> [273.467725] R10: 0000000000000000 R11: 0000000000000000 R12: ffff8881564f8800
<4> [273.467726] R13: 0000000000000000 R14: ffff888144a7ea00 R15: ffff888149b70028
<4> [273.467728] FS: 0000000000000000(0000) GS:ffff88849f200000(0000) knlGS:0000000000000000
<4> [273.467729] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [273.467731] CR2: 000072ec52436020 CR3: 0000000003248005 CR4: 0000000000f72ef0
<4> [273.467732] PKRU: 55555554
<4> [273.467733] Call Trace:
<4> [273.467735] <TASK>
<4> [273.467737] ? show_regs+0x6c/0x80
<4> [273.467742] ? __warn+0x93/0x1c0
<4> [273.467747] ? guc_exec_queue_timedout_job+0xad3/0xe40 [xe]
<4> [273.467810] ? report_bug+0x182/0x1b0
<4> [273.467816] ? handle_bug+0x6e/0xb0
<4> [273.467820] ? exc_invalid_op+0x18/0x80
<4> [273.467823] ? asm_exc_invalid_op+0x1b/0x20
<4> [273.467829] ? guc_exec_queue_timedout_job+0xad3/0xe40 [xe]
<4> [273.467892] ? lock_acquire+0xc9/0x300
<4> [273.467896] ? find_held_lock+0x31/0x90
<4> [273.467900] ? __pfx_autoremove_wake_function+0x10/0x10
<4> [273.467905] drm_sched_job_timedout+0x91/0x130 [gpu_sched]
<4> [273.467910] process_one_work+0x21c/0x740
<4> [273.467917] worker_thread+0x1db/0x3c0
<4> [273.467920] ? __pfx_worker_thread+0x10/0x10
<4> [273.467922] kthread+0x10d/0x270
<4> [273.467925] ? __pfx_kthread+0x10/0x10
<4> [273.467928] ret_from_fork+0x44/0x70
<4> [273.467931] ? __pfx_kthread+0x10/0x10
<4> [273.467933] ret_from_fork_asm+0x1a/0x30
<4> [273.467940] </TASK>
<4> [273.467942] irq event stamp: 86535
<4> [273.467943] hardirqs last enabled at (86541): [<ffffffff814a5d89>] __up_console_sem+0x79/0xa0
<4> [273.467946] hardirqs last disabled at (86546): [<ffffffff814a5d6e>] __up_console_sem+0x5e/0xa0
<4> [273.467948] softirqs last enabled at (85512): [<ffffffff813d4fbf>] __irq_exit_rcu+0x13f/0x160
<4> [273.467951] softirqs last disabled at (85499): [<ffffffff813d4fbf>] __irq_exit_rcu+0x13f/0x160
<4> [273.467952] ---[ end trace 0000000000000000 ]---
<6> [273.467954] xe 0000:00:02.1: [drm] GT0: trying reset from guc_exec_queue_timedout_job [xe]
<6> [273.468018] xe 0000:00:02.1: [drm] GT0: reset queued
<6> [273.468426] xe 0000:00:02.1: [drm] GT0: reset started
<7> [273.469567] xe 0000:00:02.1: [drm:xe_gt_sriov_vf_bootstrap [xe]] GT0: VF: using GuC interface version 0.1.17.0
<7> [273.470138] xe 0000:00:02.1: [drm:xe_gt_sriov_vf_bootstrap [xe]] GT0: VF: using GuC interface version 0.1.17.0
<7> [273.470693] xe 0000:00:02.1: [drm:xe_guc_ct_enable [xe]] GT0: GuC CT communication channel enabled
<7> [273.471073] xe 0000:00:02.0: [drm:xe_gt_sriov_pf_service_process_request [xe]] GT0: PF: VF1 negotiated ABI version 1.0
<7> [273.471366] xe 0000:00:02.1: [drm:xe_gt_sriov_vf_connect [xe]] GT0: VF: using VF/PF ABI 1.0
<6> [273.471687] xe 0000:00:02.1: [drm] GT0: reset done
<6> [273.471743] # xe_migrate_sanity_kunit: Copying small buffer object to system
<7> [273.471893] xe 0000:00:02.1: [drm:guc_exec_queue_timedout_job [xe]] GT0: Check job timeout: seqno=4294967171, lrc_seqno=4294967171, guc_id=0, running_time_ms=1, timeout_ms=5000, diff=0x00000089
<4> [273.472553] xe 0000:00:02.1: [drm] GT0: Check job timeout: seqno=4294967172, lrc_seqno=4294967172, guc_id=0, not started
<7> [273.472623] xe 0000:00:02.1: [drm:xe_migrate_copy [xe]] Pass 0, sizes: 8192 & 8192
<7> [273.473049] xe 0000:00:02.1: [drm:xe_migrate_copy [xe]] Pass 0, sizes: 8192 & 8192
<7> [273.473174] xe 0000:00:02.1: [drm:guc_exec_queue_timedout_job [xe]] GT0: Check job timeout: seqno=4294967173, lrc_seqno=4294967173, guc_id=0, running_time_ms=1, timeout_ms=5000, diff=0x00000097
<4> [273.473817] xe 0000:00:02.1: [drm] GT0: Check job timeout: seqno=4294967174, lrc_seqno=4294967174, guc_id=0, not started
<6> [273.473925] # xe_migrate_sanity_kunit: Clearing big buffer object
<4> [273.474464] xe 0000:00:02.1: [drm] GT0: Check job timeout: seqno=4294967175, lrc_seqno=4294967175, guc_id=0, not started
<6> [273.474803] # xe_migrate_sanity_kunit: Copying big buffer object to system
<7> [273.474847] xe 0000:00:02.1: [drm:guc_exec_queue_timedout_job [xe]] GT0: Check job timeout: seqno=4294967175, lrc_seqno=4294967175, guc_id=0, running_time_ms=1, timeout_ms=5000, diff=0x00000aeb
<4> [273.476050] xe 0000:00:02.1: [drm] GT0: Check job timeout: seqno=4294967176, lrc_seqno=4294967176, guc_id=0, not started
<7> [273.476463] xe 0000:00:02.1: [drm:guc_exec_queue_timedout_job [xe]] GT0: Check job timeout: seqno=4294967176, lrc_seqno=4294967176, guc_id=0, running_time_ms=1, timeout_ms=5000, diff=0x00000bdc
<7> [273.477002] xe 0000:00:02.1: [drm:xe_migrate_copy [xe]] Pass 0, sizes: 4194304 & 4194304
<4> [273.477619] xe 0000:00:02.1: [drm] GT0: Check job timeout: seqno=4294967177, lrc_seqno=4294967177, guc_id=0, not started
<7> [273.478020] xe 0000:00:02.1: [drm:guc_exec_queue_timedout_job [xe]] GT0: Check job timeout: seqno=4294967177, lrc_seqno=4294967177, guc_id=0, running_time_ms=1, timeout_ms=5000, diff=0x00001115
<7> [273.478372] xe 0000:00:02.1: [drm:xe_migrate_copy [xe]] Pass 0, sizes: 4194304 & 4194304
<4> [273.478697] xe 0000:00:02.1: [drm] GT0: Check job timeout: seqno=4294967178, lrc_seqno=4294967178, guc_id=0, not started
<7> [273.479047] xe 0000:00:02.1: [drm:guc_exec_queue_timedout_job [xe]] GT0: Check job timeout: seqno=4294967178, lrc_seqno=4294967178, guc_id=0, running_time_ms=1, timeout_ms=5000, diff=0x000010c3
<6> [273.479548] ok 2 0000:00:02.1
<6> [273.479571] # xe_migrate_sanity_kunit: pass:2 fail:0 skip:0 total:2
<6> [273.479586] ok 1 xe_migrate_sanity_kunit
<6> [273.479612] # Totals: pass:2 fail:0 skip:0 total:2
<6> [273.479624] ok 1 xe_migrate
<6> [273.487773] [IGT] xe_live_ktest: finished subtest xe_migrate_sanity_kunit, FAIL
<6> [273.487879] [IGT] xe_live_ktest: finished subtest xe_migrate, FAIL
<6> [273.576074] [IGT] xe_live_ktest: exiting, ret=98
|
