Stdout
Opened device: /dev/dri/card0
Starting subtest: twice-rebind
Subtest twice-rebind: SUCCESS (0.044s)
Stderr
Starting subtest: twice-rebind
Subtest twice-rebind: SUCCESS (0.044s)
Dmesg
<7> [472.274556] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [472.275342] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [472.275567] xe 0000:00:02.0: [drm:icl_verify_procmon_ref_values [xe]] Combo PHY A Voltage/Process Info : 0.85V dot0 (low-voltage)
<7> [472.275703] xe 0000:00:02.0: [drm:icl_combo_phys_init [xe]] Combo PHY A already enabled, won't reprogram it.
<7> [472.275836] xe 0000:00:02.0: [drm:icl_verify_procmon_ref_values [xe]] Combo PHY B Voltage/Process Info : 0.85V dot0 (low-voltage)
<7> [472.275968] xe 0000:00:02.0: [drm:icl_combo_phys_init [xe]] Combo PHY B already enabled, won't reprogram it.
<7> [472.289888] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [472.290039] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [472.290167] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<6> [472.466390] Console: switching to colour dummy device 80x25
<6> [472.466565] [IGT] xe_exec_compute_mode: executing
<6> [472.477930] [IGT] xe_exec_compute_mode: starting subtest twice-rebind
<7> [472.481197] xe 0000:00:02.0: [drm:__xe_pt_bind_vma [xe]] Preparing bind, with range [1a0000...1a0fff) engine 0000000000000000.
<7> [472.482271] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 1 entries to update
<7> [472.482710] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 0: Update level 3 at (0 + 1) [0...8000000000) f:0
<7> [472.484654] xe 0000:00:02.0: [drm:__xe_pt_unbind_vma [xe]] Preparing unbind, with range [1a0000...1a0fff) engine 0000000000000000.
<7> [472.484776] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 1 entries to update
<7> [472.484877] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 0: Update level 3 at (0 + 1) [0...8000000000) f:0
<7> [472.485821] xe 0000:00:02.0: [drm:__xe_pt_bind_vma [xe]] Preparing bind, with range [1a1000...1a1fff) engine 0000000000000000.
<7> [472.487004] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 1 entries to update
<7> [472.487111] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 0: Update level 3 at (0 + 1) [0...8000000000) f:0
<7> [472.488193] xe 0000:00:02.0: [drm:__xe_pt_unbind_vma [xe]] Preparing unbind, with range [1a1000...1a1fff) engine 0000000000000000.
<7> [472.488307] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 1 entries to update
<7> [472.488574] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 0: Update level 3 at (0 + 1) [0...8000000000) f:0
<7> [472.493058] xe 0000:00:02.0: [drm:__xe_pt_bind_vma [xe]] Preparing bind, with range [1a0000...1a0fff) engine 0000000000000000.
<7> [472.494394] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 1 entries to update
<7> [472.494504] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 0: Update level 3 at (0 + 1) [0...8000000000) f:0
<7> [472.495760] xe 0000:00:02.0: [drm:__xe_pt_unbind_vma [xe]] Preparing unbind, with range [1a0000...1a0fff) engine 0000000000000000.
<7> [472.496098] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 1 entries to update
<7> [472.496452] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 0: Update level 3 at (0 + 1) [0...8000000000) f:0
<4> [472.496866] ------------[ cut here ]------------
<4> [472.496870] list_add double add: new=ffff88815ffc03a0, prev=ffff8881239dba50, next=ffff88815ffc03a0.
<4> [472.496887] WARNING: CPU: 7 PID: 770 at lib/list_debug.c:33 __list_add_valid+0xd3/0xe0
<4> [472.496896] Modules linked in: snd_hda_codec_hdmi snd_hda_intel snd_intel_dspcfg snd_hda_codec snd_hwdep snd_hda_core snd_pcm xe i2c_algo_bit drm_ttm_helper ttm drm_suballoc_helper gpu_sched drm_display_helper drm_kms_helper syscopyarea sysfillrect sysimgblt drm_buddy cfbfillrect cfbimgblt cfbcopyarea fb fbdev fuse x86_pkg_temp_thermal coretemp kvm_intel pmt_telemetry mei_hdcp mei_pxp pmt_class kvm smsc75xx irqbypass crct10dif_pclmul usbnet crc32_pclmul mii ghash_clmulni_intel e1000e ptp i2c_i801 pps_core i2c_smbus mei_me intel_vsec mei wmi_bmof video wmi drm drm_panel_orientation_quirks ip_tables x_tables
<4> [472.497075] CPU: 7 PID: 770 Comm: kworker/u24:4 Tainted: G U 6.3.0-xe #1
<4> [472.497081] Hardware name: Intel Corporation Alder Lake Client Platform/AlderLake-P DDR4 RVP, BIOS ADLPFWI1.R00.2422.A00.2110131104 10/13/2021
<4> [472.497086] Workqueue: events_unbound async_op_work_func [xe]
<4> [472.497201] RIP: 0010:__list_add_valid+0xd3/0xe0
<4> [472.497207] Code: 4c 89 e6 48 c7 c7 e0 15 a5 82 e8 88 65 6b ff 0f 0b 31 c0 eb 93 4c 89 e1 48 89 ea 4c 89 ee 48 c7 c7 60 16 a5 82 e8 6d 65 6b ff <0f> 0b 31 c0 e9 75 ff ff ff 0f 1f 40 00 90 90 90 90 90 90 90 90 90
<4> [472.497212] RSP: 0018:ffff888146227920 EFLAGS: 00010286
<4> [472.497219] RAX: 0000000000000000 RBX: ffff8881239db800 RCX: 0000000000000027
<4> [472.497224] RDX: 0000000000000027 RSI: 0000000000000004 RDI: ffff8888509b8808
<4> [472.497228] RBP: ffff8881239dba50 R08: ffffffff8132d70e R09: ffffed110a137102
<4> [472.497232] R10: ffff8888509b880b R11: ffffed110a137101 R12: ffff88815ffc03a0
<4> [472.497236] R13: ffff88815ffc03a0 R14: ffff8881239dba50 R15: ffff88815ffc03a0
<4> [472.497240] FS: 0000000000000000(0000) GS:ffff888850980000(0000) knlGS:0000000000000000
<4> [472.497244] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [472.497248] CR2: 00005560371faeb0 CR3: 0000000008270005 CR4: 0000000000f70ee0
<4> [472.497253] PKRU: 55555554
<4> [472.497256] Call Trace:
<4> [472.497260] <TASK>
<4> [472.497267] xe_vma_destroy+0x5df/0x690 [xe]
<4> [472.497430] vm_bind_ioctl.isra.0+0xc33/0xf10 [xe]
<4> [472.497612] ? __pfx_vm_bind_ioctl.isra.0+0x10/0x10 [xe]
<4> [472.497727] ? __pfx_rwsem_down_write_slowpath+0x10/0x10
<4> [472.497738] ? __pfx_lock_acquire+0x10/0x10
<4> [472.497760] ? down_write+0x197/0x1a0
<4> [472.497766] ? __pfx_down_write+0x10/0x10
<4> [472.497777] ? async_op_work_func+0x4f2/0x9f0 [xe]
<4> [472.497887] async_op_work_func+0x4f2/0x9f0 [xe]
<4> [472.498007] ? __pfx_async_op_work_func+0x10/0x10 [xe]
<4> [472.498188] ? __pfx___lock_acquire+0x10/0x10
<4> [472.498200] ? mark_held_locks+0x24/0x90
<4> [472.498224] process_one_work+0x527/0x990
<4> [472.498240] ? __pfx_process_one_work+0x10/0x10
<4> [472.498251] ? __pfx_do_raw_spin_lock+0x10/0x10
<4> [472.498255] ? mark_held_locks+0x24/0x90
<4> [472.498269] worker_thread+0x2d1/0x640
<4> [472.498281] ? __kthread_parkme+0xd1/0xf0
<4> [472.498290] ? __pfx_worker_thread+0x10/0x10
<4> [472.498295] kthread+0x174/0x1b0
<4> [472.498300] ? __pfx_kthread+0x10/0x10
<4> [472.498308] ret_from_fork+0x29/0x50
<4> [472.498327] </TASK>
<4> [472.498331] irq event stamp: 163153
<4> [472.498335] hardirqs last enabled at (163159): [<ffffffff811f45db>] __up_console_sem+0x6b/0x80
<4> [472.498341] hardirqs last disabled at (163164): [<ffffffff811f45c0>] __up_console_sem+0x50/0x80
<4> [472.498347] softirqs last enabled at (163016): [<ffffffff825417b5>] __do_softirq+0x385/0x485
<4> [472.498352] softirqs last disabled at (163011): [<ffffffff8111be95>] irq_exit_rcu+0x95/0xc0
<4> [472.498377] ---[ end trace 0000000000000000 ]---
<7> [472.498663] xe 0000:00:02.0: [drm:__xe_pt_bind_vma [xe]] Preparing bind, with range [1a1000...1a1fff) engine 0000000000000000.
<7> [472.499726] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 1 entries to update
<7> [472.499835] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 0: Update level 3 at (0 + 1) [0...8000000000) f:0
<7> [472.500877] xe 0000:00:02.0: [drm:__xe_pt_unbind_vma [xe]] Preparing unbind, with range [1a1000...1a1fff) engine 0000000000000000.
<7> [472.500987] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 1 entries to update
<7> [472.501093] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 0: Update level 3 at (0 + 1) [0...8000000000) f:0
<7> [472.504983] xe 0000:00:02.0: [drm:__xe_pt_bind_vma [xe]] Preparing bind, with range [1a0000...1a0fff) engine 0000000000000000.
<7> [472.506726] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 1 entries to update
<7> [472.506922] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 0: Update level 3 at (0 + 1) [0...8000000000) f:0
<7> [472.508116] xe 0000:00:02.0: [drm:__xe_pt_unbind_vma [xe]] Preparing unbind, with range [1a0000...1a0fff) engine 0000000000000000.
<7> [472.508236] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 1 entries to update
<7> [472.508343] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 0: Update level 3 at (0 + 1) [0...8000000000) f:0
<7> [472.509202] xe 0000:00:02.0: [drm:__xe_pt_bind_vma [xe]] Preparing bind, with range [1a1000...1a1fff) engine 0000000000000000.
<7> [472.510254] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 1 entries to update
<7> [472.510371] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 0: Update level 3 at (0 + 1) [0...8000000000) f:0
<7> [472.511161] xe 0000:00:02.0: [drm:__xe_pt_unbind_vma [xe]] Preparing unbind, with range [1a1000...1a1fff) engine 0000000000000000.
<7> [472.511378] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 1 entries to update
<7> [472.511585] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 0: Update level 3 at (0 + 1) [0...8000000000) f:0
<7> [472.514996] xe 0000:00:02.0: [drm:__xe_pt_bind_vma [xe]] Preparing bind, with range [1a0000...1a0fff) engine 0000000000000000.
<7> [472.516152] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 1 entries to update
<7> [472.516261] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 0: Update level 3 at (0 + 1) [0...8000000000) f:0
<7> [472.517433] xe 0000:00:02.0: [drm:__xe_pt_unbind_vma [xe]] Preparing unbind, with range [1a0000...1a0fff) engine 0000000000000000.
<7> [472.517623] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 1 entries to update
<7> [472.517831] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 0: Update level 3 at (0 + 1) [0...8000000000) f:0
<7> [472.518542] xe 0000:00:02.0: [drm:__xe_pt_bind_vma [xe]] Preparing bind, with range [1a1000...1a1fff) engine 0000000000000000.
<7> [472.519796] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 1 entries to update
<7> [472.519904] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 0: Update level 3 at (0 + 1) [0...8000000000) f:0
<7> [472.520708] xe 0000:00:02.0: [drm:__xe_pt_unbind_vma [xe]] Preparing unbind, with range [1a1000...1a1fff) engine 0000000000000000.
<7> [472.520823] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 1 entries to update
<7> [472.520931] xe 0000:00:02.0: [drm:xe_vm_dbg_print_entries [xe]] 0: Update level 3 at (0 + 1) [0...8000000000) f:0
<7> [472.524650] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [472.525442] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [472.525585] xe 0000:00:02.0: [drm:icl_verify_procmon_ref_values [xe]] Combo PHY A Voltage/Process Info : 0.85V dot0 (low-voltage)
<7> [472.525723] xe 0000:00:02.0: [drm:icl_combo_phys_init [xe]] Combo PHY A already enabled, won't reprogram it.
<7> [472.525856] xe 0000:00:02.0: [drm:icl_verify_procmon_ref_values [xe]] Combo PHY B Voltage/Process Info : 0.85V dot0 (low-voltage)
<7> [472.525988] xe 0000:00:02.0: [drm:icl_combo_phys_init [xe]] Combo PHY B already enabled, won't reprogram it.
<7> [472.539989] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [472.540121] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [472.540241] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<6> [472.540957] [IGT] xe_exec_compute_mode: exiting, ret=0
<7> [472.546350] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [472.546519] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [472.546654] xe 0000:00:02.0: [drm:icl_verify_procmon_ref_values [xe]] Combo PHY A Voltage/Process Info : 0.85V dot0 (low-voltage)
<7> [472.546780] xe 0000:00:02.0: [drm:icl_combo_phys_init [xe]] Combo PHY A already enabled, won't reprogram it.
<7> [472.546903] xe 0000:00:02.0: [drm:icl_verify_procmon_ref_values [xe]] Combo PHY B Voltage/Process Info : 0.85V dot0 (low-voltage)
<7> [472.547025] xe 0000:00:02.0: [drm:icl_combo_phys_init [xe]] Combo PHY B already enabled, won't reprogram it.
<7> [472.551109] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [472.551235] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [472.551353] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<6> [472.552035] Console: switching to colour frame buffer device 240x67
<7> [472.553129] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [472.553258] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [472.554112] xe 0000:00:02.0: [drm:icl_verify_procmon_ref_values [xe]] Combo PHY A Voltage/Process Info : 0.85V dot0 (low-voltage)
<7> [472.554247] xe 0000:00:02.0: [drm:icl_combo_phys_init [xe]] Combo PHY A already enabled, won't reprogram it.
<7> [472.554391] xe 0000:00:02.0: [drm:icl_verify_procmon_ref_values [xe]] Combo PHY B Voltage/Process Info : 0.85V dot0 (low-voltage)
<7> [472.554522] xe 0000:00:02.0: [drm:icl_combo_phys_init [xe]] Combo PHY B already enabled, won't reprogram it.
<7> [472.562125] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [472.562251] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [472.562413] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [472.565839] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [472.565964] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [472.566096] xe 0000:00:02.0: [drm:icl_verify_procmon_ref_values [xe]] Combo PHY A Voltage/Process Info : 0.85V dot0 (low-voltage)
<7> [472.566224] xe 0000:00:02.0: [drm:icl_combo_phys_init [xe]] Combo PHY A already enabled, won't reprogram it.
<7> [472.566347] xe 0000:00:02.0: [drm:icl_verify_procmon_ref_values [xe]] Combo PHY B Voltage/Process Info : 0.85V dot0 (low-voltage)
<7> [472.566525] xe 0000:00:02.0: [drm:icl_combo_phys_init [xe]] Combo PHY B already enabled, won't reprogram it.
<7> [472.573294] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [472.573459] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [472.573583] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [472.575268] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [472.575415] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [472.575610] xe 0000:00:02.0: [drm:icl_verify_procmon_ref_values [xe]] Combo PHY A Voltage/Process Info : 0.85V dot0 (low-voltage)
<7> [472.575769] xe 0000:00:02.0: [drm:icl_combo_phys_init [xe]] Combo PHY A already enabled, won't reprogram it.
<7> [472.575927] xe 0000:00:02.0: [drm:icl_verify_procmon_ref_values [xe]] Combo PHY B Voltage/Process Info : 0.85V dot0 (low-voltage)
<7> [472.576075] xe 0000:00:02.0: [drm:icl_combo_phys_init [xe]] Combo PHY B already enabled, won't reprogram it.
<7> [472.584319] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [472.584835] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [472.584964] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02