igt@runner@aborted - fail - *ERROR* AUX B/DDI B/PHY B: did not complete or timeout within 10ms (status 0xac2003ff), WARNING: .* at drivers/gpu/drm/i915/display/intel_dp_aux.c:\d+ intel_dp_aux_xfer
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_11481/bat-adlm-1/igt@runner@aborted.html https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_11481/bat-adlm-1/boot0.txt
<7>[ 25.236117] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:236:DP-1]
<7>[ 25.267010] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7c2003ff)
<7>[ 25.277336] i915 0000:00:02.0: [drm:asle_work [i915]] bclp = 0x800000ff
<3>[ 25.292850] i915 0000:00:02.0: [drm] *ERROR* AUX B/DDI B/PHY B: did not complete or timeout within 10ms (status 0xac2003ff)
<7>[ 25.295445] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7c2003ff)
<3>[ 25.307725] i915 0000:00:02.0: [drm] *ERROR* AUX B/DDI B/PHY B: did not complete or timeout within 10ms (status 0xac2003ff)
<7>[ 25.331821] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7c2003ff)
<7>[ 25.362207] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7c2003ff)
<3>[ 25.377724] i915 0000:00:02.0: [drm] *ERROR* AUX B/DDI B/PHY B: did not complete or timeout within 10ms (status 0xac2003ff)
<7>[ 25.396916] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7c2003ff)
<7>[ 25.420774] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7c2003ff)
<3>[ 25.452724] i915 0000:00:02.0: [drm] *ERROR* AUX B/DDI B/PHY B: did not complete or timeout within 10ms (status 0xac2003ff)
<7>[ 25.455160] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7c2003ff)
<3>[ 25.466724] i915 0000:00:02.0: [drm] *ERROR* AUX B/DDI B/PHY B: did not complete or timeout within 10ms (status 0xac2003ff)
<7>[ 25.486597] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7c2003ff)
<3>[ 25.502724] i915 0000:00:02.0: [drm] *ERROR* AUX B/DDI B/PHY B: did not complete or timeout within 10ms (status 0xac2003ff)
<7>[ 25.525058] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7c2003ff)
<3>[ 25.558724] i915 0000:00:02.0: [drm] *ERROR* AUX B/DDI B/PHY B: did not complete or timeout within 10ms (status 0xac2003ff)
<3>[ 25.558732] i915 0000:00:02.0: [drm] *ERROR* AUX B/DDI B/PHY B: not done (status 0xac2003ff)
<7>[ 25.561826] i915 0000:00:02.0: [drm:intel_tc_port_update_mode [i915]] Port D/TC#1: TC port mode reset (legacy -> disconnected)
<7>[ 25.561997] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling AUX USBC1
<4>[ 25.567721] ------------[ cut here ]------------
<4>[ 25.567725] i915 0000:00:02.0: AUX B/DDI B/PHY B: not started (status 0x7c2003ff)
<4>[ 25.567763] WARNING: CPU: 5 PID: 111 at drivers/gpu/drm/i915/display/intel_dp_aux.c:243 intel_dp_aux_xfer+0x8a6/0x8e0 [i915]
<4>[ 25.567967] Modules linked in: snd_hda_codec_hdmi i915 mei_hdcp smsc75xx usbnet mii x86_pkg_temp_thermal coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel ttm snd_hda_intel drm_buddy snd_intel_dspcfg drm_dp_helper snd_hda_codec drm_kms_helper snd_hwdep syscopyarea snd_hda_core mei_me sysfillrect sysimgblt i2c_i801 fb_sys_fops snd_pcm mei i2c_smbus prime_numbers intel_lpss_pci
<4>[ 25.568065] CPU: 5 PID: 111 Comm: kworker/5:1 Not tainted 5.18.0-rc2-CI-CI_DRM_11481+ #1
<4>[ 25.568071] Hardware name: Intel Corporation Alder Lake Client Platform/AlderLake-M LP5 RVP, BIOS ADLPFWI1.R00.2494.A00.2112031013 12/03/2021
<4>[ 25.568076] Workqueue: events output_poll_execute [drm_kms_helper]
<4>[ 25.568099] RIP: 0010:intel_dp_aux_xfer+0x8a6/0x8e0 [i915]
<4>[ 25.568296] Code: 00 48 8b 6f 50 48 85 ed 75 03 48 8b 2f e8 a2 54 19 e1 41 89 d8 4c 89 e1 48 89 ea 48 89 c6 48 c7 c7 f8 14 6c a0 e8 e0 f8 50 e1 <0f> 0b 48 8b 44 24 10 41 bc f0 ff ff ff 89 98 c0 0a 00 00 e9 41 fd
<4>[ 25.568301] RSP: 0018:ffffc90000d2f8c8 EFLAGS: 00010282
<4>[ 25.568308] RAX: 0000000000000000 RBX: 000000007c2003ff RCX: 0000000000000001
<4>[ 25.568312] RDX: 0000000080000001 RSI: ffffffff82315d9d RDI: 00000000ffffffff
<4>[ 25.568316] RBP: ffff8881034d3880 R08: 0000000000000001 R09: 0000000000000001
<4>[ 25.568319] R10: 000000001fef2c7b R11: 00000000041cd75d R12: ffff888136ceb0e0
<4>[ 25.568323] R13: ffff888136c50ef0 R14: 0000000000000004 R15: ffff888136c50000
<4>[ 25.568327] FS: 0000000000000000(0000) GS:ffff88849f480000(0000) knlGS:0000000000000000
<4>[ 25.568331] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4>[ 25.568335] CR2: 00007fac14001428 CR3: 00000001074e2001 CR4: 0000000000770ee0
<4>[ 25.568339] PKRU: 55555554
<4>[ 25.568342] Call Trace:
<4>[ 25.568346] <TASK>
<4>[ 25.568354] ? mark_held_locks+0x48/0x70
<4>[ 25.568367] ? _raw_spin_unlock_irqrestore+0x50/0x60
<4>[ 25.568403] intel_dp_aux_transfer+0xc5/0x2b0 [i915]
<4>[ 25.568602] ? usleep_range_state+0x55/0xa0
<4>[ 25.568610] ? usleep_range_state+0x75/0xa0
<4>[ 25.568624] drm_dp_dpcd_access+0x79/0x130 [drm_dp_helper]
<4>[ 25.568657] drm_dp_dpcd_read+0xcd/0x110 [drm_dp_helper]
<4>[ 25.568675] drm_dp_read_dpcd_caps+0x2b/0x180 [drm_dp_helper]
<4>[ 25.568687] ? ktime_get_mono_fast_ns+0x4b/0xa0
<4>[ 25.568727] intel_dp_init_lttpr_and_dprx_caps+0x35/0x270 [i915]
<4>[ 25.568928] ? __intel_runtime_pm_put+0x14a/0x2a0 [i915]
<4>[ 25.569088] intel_dp_get_dpcd+0xc/0xb0 [i915]
<4>[ 25.569287] intel_dp_detect+0xeb/0x620 [i915]
<4>[ 25.569485] ? drm_modeset_lock+0x48/0x130
<4>[ 25.569501] drm_helper_probe_detect+0x43/0xa0 [drm_kms_helper]
<4>[ 25.569523] drm_helper_probe_single_connector_modes+0x678/0x840 [drm_kms_helper]
<4>[ 25.569582] drm_client_modeset_probe+0x261/0xca0
<4>[ 25.569608] ? drm_dev_dbg+0x8c/0xb0
<4>[ 25.569631] drm_fb_helper_hotplug_event.part.25+0x99/0xd0 [drm_kms_helper]
<4>[ 25.569650] drm_kms_helper_hotplug_event+0x1e/0x30 [drm_kms_helper]
<4>[ 25.569667] output_poll_execute+0x1ed/0x200 [drm_kms_helper]
<4>[ 25.569696] process_one_work+0x272/0x5c0
<4>[ 25.569746] worker_thread+0x1ee/0x370
<4>[ 25.569757] ? process_one_work+0x5c0/0x5c0
<4>[ 25.569763] kthread+0xed/0x120
<4>[ 25.569770] ? kthread_complete_and_exit+0x20/0x20
<4>[ 25.569782] ret_from_fork+0x1f/0x30
<4>[ 25.569817] </TASK>
<4>[ 25.569820] irq event stamp: 43527
<4>[ 25.569824] hardirqs last enabled at (43533): [<ffffffff8112f7ab>] vprintk_emit+0x34b/0x370
<4>[ 25.569831] hardirqs last disabled at (43538): [<ffffffff8112f75a>] vprintk_emit+0x2fa/0x370
<4>[ 25.569836] softirqs last enabled at (40730): [<ffffffff81e0032d>] __do_softirq+0x32d/0x493
<4>[ 25.569842] softirqs last disabled at (40725): [<ffffffff810b83a6>] irq_exit_rcu+0xa6/0xe0
<4>[ 25.569847] ---[ end trace 0000000000000000 ]---
Edited by SAI NANDAN