few tests - abort - kworker * blocked for more than * seconds
Stdout
Opened device: /dev/dri/card0
FBC last action not supported
Can't test PSR: no usable eDP screen.
Can't test DRRS: no usable screen.
Starting subtest: fbc-1p-pri-indfb-multidraw
runner: This test was killed due to a kernel taint (0x200).
This test caused an abort condition: Kernel badly tainted (0x200, 0x200) (check dmesg for details):
TAINT_WARN: WARN_ON has happened.
Stderr
Starting subtest: fbc-1p-pri-indfb-multidraw
Received signal SIGQUIT.
Stack trace:
#0 [fatal_sig_handler+0x10f]
#1 [killpg+0x40]
#2 [openat+0x46]
#3 [igt_pipe_crc_start+0x9b]
#4 [igt_pipe_crc_collect_crc+0x25]
#5 [do_crc_assertions+0x52]
#6 [__do_assertions+0x233]
#7 [__igt_unique____real_main3434+0x3261]
#8 [main+0x3f]
#9 [__libc_start_main+0xf3]
#10 [_start+0x2e]
Dmesg
<6> [443.841694] Console: switching to colour dummy device 80x25
<6> [443.841803] [IGT] kms_frontbuffer_tracking: executing
<7> [443.844263] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [443.845182] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [443.845515] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [443.849424] [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:HDMI-A-1]
<7> [443.849452] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:64:HDMI-A-1]
<7> [443.849843] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0050 w(1)
<7> [443.850058] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry
<7> [443.850393] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0050 w(1)
<7> [443.850579] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus dpd
<7> [443.850586] i915 0000:00:02.0: [drm:intel_hdmi_set_edid [i915]] HDMI GMBUS EDID read failed, retry using GPIO bit-banging
<7> [443.850777] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus dpd. force bit now 1
<7> [443.852097] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus dpd
<7> [443.852103] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus dpd. force bit now 0
<7> [443.852263] [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:HDMI-A-1] disconnected
<7> [443.852663] [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:72:DP-1]
<7> [443.852680] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:72:DP-1]
<7> [443.852851] [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:72:DP-1] disconnected
<7> [443.853258] [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:61:VGA-1]
<7> [443.853277] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:61:VGA-1] force=1
<7> [443.853458] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0x83f40000, result 1
<7> [443.853632] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7> [443.854026] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [443.854259] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [443.854646] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [443.854815] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [443.854819] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [443.855050] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [443.856404] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [443.856411] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [443.856634] [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:61:VGA-1] probed modes :
<7> [443.856651] [drm:drm_mode_debug_printmodeline] Modeline "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [443.856656] [drm:drm_mode_debug_printmodeline] Modeline "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [443.856661] [drm:drm_mode_debug_printmodeline] Modeline "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [443.856665] [drm:drm_mode_debug_printmodeline] Modeline "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7> [443.856669] [drm:drm_mode_debug_printmodeline] Modeline "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [443.856689] [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:HDMI-A-1]
<7> [443.856705] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:64:HDMI-A-1]
<7> [443.857068] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0050 w(1)
<7> [443.857239] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry
<7> [443.857602] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0050 w(1)
<7> [443.857829] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus dpd
<7> [443.857836] i915 0000:00:02.0: [drm:intel_hdmi_set_edid [i915]] HDMI GMBUS EDID read failed, retry using GPIO bit-banging
<7> [443.858273] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus dpd. force bit now 1
<7> [443.859670] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus dpd
<7> [443.859676] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus dpd. force bit now 0
<7> [443.859843] [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:HDMI-A-1] disconnected
<7> [443.859879] [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:72:DP-1]
<7> [443.859896] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:72:DP-1]
<7> [443.860109] [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:72:DP-1] disconnected
<7> [443.861268] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:79]
<7> [443.861350] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [443.861469] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [443.861585] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [443.861701] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<6> [443.862733] [IGT] kms_frontbuffer_tracking: starting subtest fbc-1p-pri-indfb-multidraw
<7> [443.863635] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:79]
<7> [443.863770] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [443.863967] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:81]
<7> [443.864136] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:82]
<7> [443.864340] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [443.864578] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [443.903837] [drm:drm_mode_setcrtc] [CRTC:45:pipe A]
<7> [443.903862] [drm:drm_mode_setcrtc] [CONNECTOR:61:VGA-1]
<7> [443.922809] [drm:drm_mode_setcrtc] [CRTC:60:pipe B]
<7> [443.924529] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<7> [443.924877] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<7> [444.004399] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:84] from all active usage due to RMFB ioctl
<7> [444.004461] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:31:primary A] because [FB:84] is removed
<7> [444.021381] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [444.021634] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:86]
<7> [444.021890] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:87]
<7> [444.022151] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:88]
<7> [444.121551] [drm:drm_mode_setcrtc] [CRTC:45:pipe A]
<7> [444.121597] [drm:drm_mode_setcrtc] [CONNECTOR:61:VGA-1]
<7> [444.157006] [drm:drm_mode_setcrtc] [CRTC:60:pipe B]
<7> [444.158872] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<7> [444.159155] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<7> [444.238401] [drm:drm_mode_setcrtc] [CRTC:45:pipe A]
<7> [444.238462] [drm:drm_mode_setcrtc] [CONNECTOR:61:VGA-1]
<7> [444.255353] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<7> [444.338722] [drm:drm_mode_setcrtc] [CRTC:45:pipe A]
<7> [444.338768] [drm:drm_mode_setcrtc] [CONNECTOR:61:VGA-1]
<7> [444.355512] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<7> [444.439073] [drm:drm_mode_setcrtc] [CRTC:45:pipe A]
<7> [444.439126] [drm:drm_mode_setcrtc] [CONNECTOR:61:VGA-1]
<7> [444.455885] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<7> [444.539501] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:88] from all active usage due to RMFB ioctl
<7> [444.539561] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:31:primary A] because [FB:88] is removed
<7> [444.589384] [drm:drm_mode_setcrtc] [CRTC:45:pipe A]
<7> [444.589412] [drm:drm_mode_setcrtc] [CONNECTOR:61:VGA-1]
<7> [444.605918] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 6291456 bytes of contiguous stolen space for FBC, limit: 1
<7> [444.606157] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:31:primary A]
<7> [444.623749] [drm:drm_mode_setcrtc] [CRTC:60:pipe B]
<7> [444.624865] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<7> [444.625100] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [444.635292] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<3> [444.647104] INFO: task kworker/1:3:1370 blocked for more than 61 seconds.
<3> [444.647156] Tainted: G W 6.5.0-rc1-CI_DRM_13368-g5f6430479a12+ #1
<3> [444.647160] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
<6> [444.647163] task:kworker/1:3 state:D stack:13040 pid:1370 ppid:2 flags:0x00004000
<6> [444.647170] Workqueue: events ata_scsi_dev_rescan
<6> [444.647176] Call Trace:
<6> [444.647178] <TASK>
<6> [444.647183] __schedule+0x3a4/0xd70
<6> [444.647193] schedule+0x5c/0xd0
<6> [444.647196] schedule_preempt_disabled+0x13/0x30
<6> [444.647200] __mutex_lock+0x740/0xcc0
<6> [444.647206] ? ata_scsi_dev_rescan+0x36/0x190
<6> [444.647212] ? ata_scsi_dev_rescan+0x36/0x190
<6> [444.647215] ata_scsi_dev_rescan+0x36/0x190
<6> [444.647220] process_one_work+0x251/0x500
<6> [444.647228] worker_thread+0x4f/0x3a0
<6> [444.647234] ? __pfx_worker_thread+0x10/0x10
<6> [444.647238] kthread+0x101/0x130
<6> [444.647242] ? __pfx_kthread+0x10/0x10
<6> [444.647246] ret_from_fork+0x2c/0x50
<6> [444.647257] </TASK>
<4> [444.647259]
Showing all locks held in the system:
<4> [444.647261] 1 lock held by rcu_tasks_kthre/12:
<4> [444.647264] #0: ffffffff82738750 (rcu_tasks.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x2b/0x400
<4> [444.647276] 1 lock held by khungtaskd/57:
<4> [444.647278] #0: ffffffff82739200 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x12/0x1d0
<4> [444.647290] 4 locks held by kworker/1:2/115:
<4> [444.647292] #0: ffff888100081538 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x1bd/0x500
<4> [444.647302] #1: ffffc90000e1fe60 ((work_completion)(&(&ap->hotplug_task)->work)){+.+.}-{0:0}, at: process_one_work+0x1c1/0x500
<4> [444.647311] #2: ffff88810347c350 (&ap->scsi_scan_mutex){+.+.}-{3:3}, at: ata_scsi_hotplug+0x2a/0x80
<4> [444.647319] #3: ffff8881035c10e0 (&shost->scan_mutex){+.+.}-{3:3}, at: __scsi_add_device+0x76/0x180
<4> [444.647330] 1 lock held by in:imklog/287:
<4> [444.647332] #0: ffff888107b0f588 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0x47/0x60
<4> [444.647343] 1 lock held by dmesg/972:
<4> [444.647345] #0: ffff8881218090d0 (&user->lock){+.+.}-{3:3}, at: devkmsg_read+0x67/0x200
<4> [444.647356] 3 locks held by kworker/1:3/1370:
<4> [444.647358] #0: ffff888100081538 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x1bd/0x500
<4> [444.647367] #1: ffffc90002027e60 ((work_completion)(&(&ap->scsi_rescan_task)->work)){+.+.}-{0:0}, at: process_one_work+0x1c1/0x500
<4> [444.647376] #2: ffff88810347c350 (&ap->scsi_scan_mutex){+.+.}-{3:3}, at: ata_scsi_dev_rescan+0x36/0x190
<4> [444.647385]
<4> [444.647387] =============================================
<7> [444.708238] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<7> [444.791653] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [444.792722] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<7> [444.875072] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<7> [444.958191] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [444.958663] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<7> [445.042449] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<7> [445.125221] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [445.125663] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<7> [445.209544] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<7> [445.292158] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]