igt@i915_selftest@live@hangcheck - Incomplete - *ERROR* engine 'rcs0' resumed still in error: 00000004
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_10452/fi-ehl-2/igt@i915_selftest@live@hangcheck.html
Out
Starting dynamic subtest: hangcheck
runner: This test was killed due to a kernel taint (0x240).
runner: This test was killed due to exceeding disk usage limit. (Used 11089913 bytes, limit 10485760)
Err
Starting dynamic subtest: hangcheck
Received signal SIGQUIT.
Stack trace:
#0 [fatal_sig_handler+0xd6]
#1 [killpg+0x40]
#2 [syscall+0x1d]
#3 [kmod_module_insert_module+0x93]
#4 [kmod_module_probe_insert_module+0x38b]
#5 [igt_kselftest_execute+0xc8]
#6 [igt_kselftests+0x350]
#7 [__real_main29+0xcd]
#8 [main+0x27]
#9 [__libc_start_main+0xf3]
#10 [_start+0x2a]
<7> [423.105185] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:238:DDI A/PHY A]
<6> [423.421645] rcs0: Completed 648 idle resets
<6> [423.922443] bcs0: Completed 301 idle resets
<6> [424.423713] vcs0: Completed 293 idle resets
<6> [424.925696] vecs0: Completed 294 idle resets
<6> [424.926050] i915: Running intel_hangcheck_live_selftests/igt_reset_active_engine
<6> [425.428778] rcs0: Completed 176 active resets
<6> [425.931747] bcs0: Completed 204 active resets
<6> [426.432396] vcs0: Completed 231 active resets
<6> [426.934426] vecs0: Completed 217 active resets
<6> [426.969417] i915: Running intel_hangcheck_live_selftests/igt_reset_fail_engine
<6> [427.471374] igt_reset_fail_engine(rcs0): 239 resets
<6> [427.973303] igt_reset_fail_engine(bcs0): 365 resets
<6> [428.474167] igt_reset_fail_engine(vcs0): 290 resets
<6> [428.976606] igt_reset_fail_engine(vecs0): 304 resets
<6> [428.992315] i915: Running intel_hangcheck_live_selftests/igt_reset_engines
<6> [429.496402] i915_reset_engine(rcs0:idle): 148 resets
<6> [429.997383] i915_reset_engine(bcs0:idle): 189 resets
<6> [430.500240] i915_reset_engine(vcs0:idle): 179 resets
<6> [431.003137] i915_reset_engine(vecs0:idle): 186 resets
<6> [431.507850] i915_reset_engine(rcs0:active): 134 resets
<6> [432.008901] i915_reset_engine(bcs0:active): 126 resets
<6> [432.512067] i915_reset_engine(vcs0:active): 120 resets
<6> [433.013270] i915_reset_engine(vecs0:active): 173 resets
<6> [433.515708] i915_reset_engine(rcs0:others-idle): 207 resets
<6> [434.020625] i915_reset_engine(bcs0:others-idle): 283 resets
<6> [434.525205] i915_reset_engine(vcs0:others-idle): 273 resets
<6> [435.030640] i915_reset_engine(vecs0:others-idle): 284 resets
<6> [435.537709] i915_reset_engine(rcs0:others-active): 142 resets
<6> [436.046558] i915_reset_engine(bcs0:others-active): 183 resets
<6> [436.552229] i915_reset_engine(vcs0:others-active): 179 resets
<6> [437.060061] i915_reset_engine(vecs0:others-active): 138 resets
<3> [437.170900] i915 0000:00:02.0: [drm] *ERROR* engine 'rcs0' resumed still in error: 00000004
<3> [438.178163] __igt_reset_engines: Failed to start request 36, at 34
<6> [438.178204] i915 0000:00:02.0: [drm] rcs0
<6> [438.178208] i915 0000:00:02.0: [drm] Awake? 3
<6> [438.178212] i915 0000:00:02.0: [drm] Barriers?: no
<6> [438.178215] i915 0000:00:02.0: [drm] Latency: 141us
<6> [438.178220] i915 0000:00:02.0: [drm] Runtime: 4774ms
<6> [438.178223] i915 0000:00:02.0: [drm] Forcewake: 0 domains, 0 active
<6> [438.178226] i915 0000:00:02.0: [drm] Reset count: 2078 (global 51)
<6> [438.178229] i915 0000:00:02.0: [drm] Properties:
<6> [438.178231] i915 0000:00:02.0: [drm] heartbeat_interval_ms: 0 [default 2500]
<6> [438.178235] i915 0000:00:02.0: [drm] max_busywait_duration_ns: 8000 [default 8000]
<6> [438.178238] i915 0000:00:02.0: [drm] preempt_timeout_ms: 10 [default 640]
<6> [438.178240] i915 0000:00:02.0: [drm] stop_timeout_ms: 100 [default 100]
<6> [438.178243] i915 0000:00:02.0: [drm] timeslice_duration_ms: 5 [default 1]
<6> [438.178247] i915 0000:00:02.0: [drm] Requests:
<6> [438.178498] i915 0000:00:02.0: [drm] On hold?: 0
<6> [438.178501] i915 0000:00:02.0: [drm] MMIO base: 0x00002000
<6> [438.179761] i915 0000:00:02.0: [drm] EL_STAT_HI: 0x00000000
<6> [438.179771] i915 0000:00:02.0: [drm] EL_STAT_LO: 0x00018001
<6> [438.179779] i915 0000:00:02.0: [drm] RING_START: 0x00091000
<6> [438.179785] i915 0000:00:02.0: [drm] RING_HEAD: 0x00000dc8
<6> [438.179791] i915 0000:00:02.0: [drm] RING_TAIL: 0x00000dc8
<6> [438.179817] i915 0000:00:02.0: [drm] RING_CTL: 0x00003000
<6> [438.179827] i915 0000:00:02.0: [drm] RING_MODE: 0x00000200 [idle]
<6> [438.179833] i915 0000:00:02.0: [drm] RING_IMR: 0x00000000
<6> [438.180611] i915 0000:00:02.0: [drm] RING_ESR: 0x00000000
<6> [438.181462] i915 0000:00:02.0: [drm] RING_EMR: 0xfffffffe
<6> [438.181471] i915 0000:00:02.0: [drm] RING_EIR: 0x00000000
<6> [438.181483] i915 0000:00:02.0: [drm] ACTHD: 0x00000000_00000dc8
<6> [438.181495] i915 0000:00:02.0: [drm] BBADDR: 0x00000000_00000000
<6> [438.181512] i915 0000:00:02.0: [drm] DMA_FADDR: 0x00000000_00000000
<6> [438.181518] i915 0000:00:02.0: [drm] IPEIR: 0x00000000
<6> [438.181527] i915 0000:00:02.0: [drm] IPEHR: 0x00000000
<6> [438.181529] i915 0000:00:02.0: [drm] Execlist tasklet queued? no (enabled), preempt? inactive, timeslice? inactive
<6> [438.181542] i915 0000:00:02.0: [drm] Execlist status: 0x00018001 00000000; CSB read:11, write:11, entries:12
<6> [438.181548] i915 0000:00:02.0: [drm] Pending[0]: ccid:00000020, rq: E c459:52!+ prio=1024 @ 1013ms: signaled
<6> [438.181561] i915 0000:00:02.0: [drm] Queue priority hint: 2147483646
<6> [438.181565] i915 0000:00:02.0: [drm] R c2a0:330*- prio=2147483646 @ 1005ms: [i915]
<6> [438.181569] i915 0000:00:02.0: [drm] R c459:54 prio=1024 @ 1004ms:
<6> [438.181573] i915 0000:00:02.0: [drm] HWSP:
<6> [438.181578] i915 0000:00:02.0: [drm] [0000] 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a
<6> [438.181581] i915 0000:00:02.0: [drm] *
<6> [438.181584] i915 0000:00:02.0: [drm] [0040] ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff
<6> [438.181586] i915 0000:00:02.0: [drm] *
<6> [438.181589] i915 0000:00:02.0: [drm] [00a0] 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 0000000b
<6> [438.181592] i915 0000:00:02.0: [drm] [00c0] 5a5a5a5a 5a5a5a5a 00000000 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a
<6> [438.181596] i915 0000:00:02.0: [drm] [00e0] 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a
<6> [438.181599] i915 0000:00:02.0: [drm] [0100] 00000149 00000000 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a
<6> [438.181602] i915 0000:00:02.0: [drm] [0120] 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a 5a5a5a5a
<6> [438.181605] i915 0000:00:02.0: [drm] *
<6> [438.181612] i915 0000:00:02.0: [drm] Idle? no
<6> [438.181614] i915 0000:00:02.0: [drm] IRQ: disabled
<6> [438.181618] i915_reset_engine(rcs0:others-priority): 26 resets
<0> [439.240199] igt/vecs-5911 1.... 436359252us : __i915_request_commit: 0000:00:02.0 vecs0: fence c460:1530, current 1528
<0> [439.240317] igt/vecs-5911 1.... 436359281us : __i915_request_commit: 0000:00:02.0 vecs0: fence c461:1530, current 1528
<0> [439.240426] igt/vecs-5911 1..s1 436359322us : process_csb: 0000:00:02.0 vecs0: cs-irq head=7, tail=9
<0> [439.240530] igt/vecs-5911 1..s1 436359322us : process_csb: 0000:00:02.0 vecs0: csb[8]: status=0x40000000:0x00000001
<0> [439.240637] igt/vecs-5911 1..s1 436359323us : trace_ports: 0000:00:02.0 vecs0: promote { ccid:40000020 c460:1530! prio 265 }
<0> [439.240748] igt/vecs-5911 1..s1 436359326us : process_csb: 0000:00:02.0 vecs0: csb[9]: status=0x40000020:0x00000018
<0> [439.240855] igt/vecs-5911 1..s1 436359326us : trace_ports: 0000:00:02.0 vecs0: completed { ccid:40000020 c460:1530! prio 265 }
Edited by Tejasree Illipilli