[Bug 111895] [CI][BAT] igt at i915_selftest@live_execlists/live_unlite_switch - dmesg-fail: timed out, cancelling all further testing
bugzilla-daemon at freedesktop.org
bugzilla-daemon at freedesktop.org
Thu Oct 3 13:13:31 UTC 2019
https://bugs.freedesktop.org/show_bug.cgi?id=111895
Chris Wilson <chris at chris-wilson.co.uk> changed:
What |Removed |Added
----------------------------------------------------------------------------
Status|NEEDINFO |NEW
--- Comment #4 from Chris Wilson <chris at chris-wilson.co.uk> ---
<0> [561.060235] i915_sel-4367 0.... 539658733us : __i915_subtests: Running
intel_execlists_live_selftests/live_unlite_switch
<0> [561.060594] i915_sel-4367 0.... 539662660us : __intel_context_do_pin:
rcs0 context:efc0 pin ring:{head:0000, tail:0000}
<0> [561.060953] i915_sel-4367 0.... 539664389us : __intel_context_do_pin:
rcs0 context:efc1 pin ring:{head:0000, tail:0000}
<0> [561.061312] i915_sel-4367 0.... 539665442us : __engine_unpark: rcs0
<0> [561.061671] i915_sel-4367 0.... 539665496us : __gt_unpark:
<0> [561.062057] i915_sel-4367 0.... 539669438us : __i915_request_commit:
rcs0 fence efc0:2
<0> [561.062444] i915_sel-4367 0d..1 539669467us : __i915_request_submit:
rcs0 fence efc0:2, current 0
<0> [561.062810] i915_sel-4367 0d..1 539669474us :
__execlists_submission_tasklet: rcs0: queue_priority_hint:-2147483648,
submit:yes
<0> [561.063177] i915_sel-4367 0d..1 539669479us : trace_ports: rcs0: submit
{ efc0:2, 0:0 }
<0> [561.063544] jbd2/sda-215 1..s. 539669531us : process_csb: rcs0 cs-irq
head=3, tail=4
<0> [561.063910] jbd2/sda-215 1..s. 539669535us : process_csb: rcs0 csb[4]:
status=0x00000001:0x00000000
<0> [561.064279] jbd2/sda-215 1..s. 539669539us : trace_ports: rcs0:
promote { efc0:2*, 0:0 }
<0> [561.064646] jbd2/sda-215 1d.s1 539669547us :
__execlists_submission_tasklet: rcs0: queue_priority_hint:-2147483648,
submit:no
<0> [561.065032] i915_sel-4367 0.... 539669654us : __i915_request_commit:
rcs0 fence efc1:2
<0> [561.065419] i915_sel-4367 0d..1 539669692us : __i915_request_submit:
rcs0 fence efc1:2, current 0
<0> [561.065787] i915_sel-4367 0d..1 539669697us :
__execlists_submission_tasklet: rcs0: queue_priority_hint:-2147483648,
submit:yes
<0> [561.066155] i915_sel-4367 0d..1 539669701us : trace_ports: rcs0: submit
{ efc0:2*, efc1:2 }
<0> [561.066542] i915_sel-4367 0.... 539669796us : __i915_request_commit:
rcs0 fence efc0:4
<0> [561.066908] ksoftirq-16 1..s. 539669909us : process_csb: rcs0 cs-irq
head=4, tail=5
<0> [561.067274] ksoftirq-16 1..s. 539669911us : process_csb: rcs0 csb[5]:
status=0x00008002:0x00000001
<0> [561.067642] ksoftirq-16 1..s. 539669914us : trace_ports: rcs0:
preempted { efc0:2*, 0:0 }
<0> [561.068009] ksoftirq-16 1..s. 539669916us : trace_ports: rcs0:
promote { efc0:2*, efc1:2 }
<0> [561.068377] ksoftirq-16 1d.s1 539669948us :
__execlists_submission_tasklet: rcs0: expired last=efc0:2, prio=3, hint=3
<0> [561.068764] ksoftirq-16 1d.s1 539669952us : __i915_request_unsubmit:
rcs0 fence efc1:2, current 0
<0> [561.069151] ksoftirq-16 1d.s1 539670042us : __i915_request_unsubmit:
rcs0 fence efc0:2, current 1
<0> [561.069537] ksoftirq-16 1d.s1 539670050us : __i915_request_submit:
rcs0 fence efc0:2, current 1
<0> [561.069924] ksoftirq-16 1d.s1 539670056us : __i915_request_submit:
rcs0 fence efc1:2, current 0
<0> [561.070290] ksoftirq-16 1d.s1 539670060us :
__execlists_submission_tasklet: rcs0: queue_priority_hint:2, submit:yes
<0> [561.070657] ksoftirq-16 1d.s1 539670062us : trace_ports: rcs0: submit
{ efc0:2*, efc1:2 }
<0> [561.071043] i915_sel-4367 0.... 539670069us : i915_gem_wait_for_idle:
flags=2 (locked), timeout=200
<0> [561.071408] ksoftirq-16 1..s. 539670171us : process_csb: rcs0 cs-irq
head=5, tail=0
<0> [561.071775] ksoftirq-16 1..s. 539670172us : process_csb: rcs0 csb[0]:
status=0x00008002:0x00000001
<0> [561.072142] ksoftirq-16 1..s. 539670174us : trace_ports: rcs0:
preempted { efc0:2!, efc1:2 }
<0> [561.072509] ksoftirq-16 1..s. 539670177us : trace_ports: rcs0:
promote { efc0:2!, efc1:2 }
<0> [561.072896] ksoftirq-16 1d.s1 539670184us : __i915_request_submit:
rcs0 fence efc0:4, current 2
<0> [561.073263] ksoftirq-16 1d.s1 539670188us :
__execlists_submission_tasklet: rcs0: queue_priority_hint:-2147483648,
submit:yes
<0> [561.073630] ksoftirq-16 1d.s1 539670190us : trace_ports: rcs0: submit
{ efc1:2, efc0:4 }
<0> [561.073996] ksoftirq-16 1..s. 539670222us : process_csb: rcs0 cs-irq
head=0, tail=1
<0> [561.074363] ksoftirq-16 1..s. 539670224us : process_csb: rcs0 csb[1]:
status=0x00000814:0x00000001
<0> [561.074730] ksoftirq-16 1..s. 539670325us : trace_ports: rcs0:
completed { efc0:2!, efc1:2 }
<0> [561.075095] ksoftirq-16 1..s. 539670329us : process_csb: rcs0 cs-irq
head=1, tail=4
<0> [561.075462] ksoftirq-16 1..s. 539670330us : process_csb: rcs0 csb[2]:
status=0x00008002:0x00000001
<0> [561.075829] ksoftirq-16 1..s. 539670331us : trace_ports: rcs0:
preempted { efc1:2!, 0:0 }
<0> [561.076197] ksoftirq-16 1..s. 539670333us : trace_ports: rcs0:
promote { efc1:2!, efc0:4 }
<0> [561.076564] ksoftirq-16 1..s. 539670334us : process_csb: rcs0 csb[3]:
status=0x00000814:0x00000001
<0> [561.076931] ksoftirq-16 1..s. 539670335us : trace_ports: rcs0:
completed { efc1:2!, efc0:4 }
<0> [561.077298] ksoftirq-16 1..s. 539670338us : process_csb: rcs0 csb[4]:
status=0x00000818:0x00000001
<0> [561.077665] ksoftirq-16 1..s. 539670340us : trace_ports: rcs0:
completed { efc0:4!, 0:0 }
<0> [561.078032] ksoftirq-16 1d.s1 539670342us :
__execlists_submission_tasklet: rcs0: queue_priority_hint:-2147483648,
submit:no
<0> [561.078418] i915_sel-4367 0.... 539670348us : i915_request_retire: rcs0
fence efc1:2, current 2
<0> [561.078776] i915_sel-4367 0.... 539670357us : intel_context_unpin: rcs0
context:efc1 retire
<0> [561.079135] i915_sel-4367 0.... 539670418us : __intel_context_retire:
rcs0 context:efc1 retire
<0> [561.079522] i915_sel-4367 0.... 539671236us : i915_request_retire: rcs0
fence efc0:2, current 4
<0> [561.079909] i915_sel-4367 0.... 539671302us : i915_request_retire: rcs0
fence efc0:4, current 4
<0> [561.080295] i915_sel-4367 0d... 539671354us : __i915_request_commit:
rcs0 fence efb0:8
<0> [561.080681] i915_sel-4367 0d..1 539671396us : __i915_request_submit:
rcs0 fence efb0:8, current 6
<0> [561.081049] i915_sel-4367 0d..1 539671421us :
__execlists_submission_tasklet: rcs0: queue_priority_hint:-2147483648,
submit:yes
<0> [561.081416] i915_sel-4367 0d..1 539671427us : trace_ports: rcs0: submit
{ efb0:8, 0:0 }
<0> [561.081774] i915_sel-4367 0.... 539671438us : intel_context_unpin: rcs0
context:efc0 retire
<0> [561.082132] i915_sel-4367 0.... 539671498us : __intel_context_retire:
rcs0 context:efc0 retire
<0> [561.082497] kworker/-155 1..s. 539671601us : process_csb: rcs0 cs-irq
head=4, tail=5
<0> [561.082864] kworker/-155 1..s. 539671603us : process_csb: rcs0 csb[5]:
status=0x00000001:0x00000000
<0> [561.083231] kworker/-155 1..s. 539671606us : trace_ports: rcs0:
promote { efb0:8!, 0:0 }
<0> [561.083597] kworker/-155 1d.s1 539671611us :
__execlists_submission_tasklet: rcs0: queue_priority_hint:-2147483648,
submit:no
<0> [561.083963] kworker/-155 1..s. 539671613us : process_csb: rcs0 cs-irq
head=5, tail=0
<0> [561.084330] kworker/-155 1..s. 539671614us : process_csb: rcs0 csb[0]:
status=0x00000818:0x00000000
<0> [561.084697] kworker/-155 1..s. 539671616us : trace_ports: rcs0:
completed { efb0:8!, 0:0 }
<0> [561.085064] kworker/-155 1d.s1 539671620us :
__execlists_submission_tasklet: rcs0: queue_priority_hint:-2147483648,
submit:no
<0> [561.085450] i915_sel-4367 0.N.. 539672266us : i915_request_retire: rcs0
fence efb0:8, current 8
<0> [561.085809] i915_sel-4367 0.... 539672832us : __engine_park: rcs0
<0> [561.086170] kworker/-2966 0.... 539672877us : __gt_park:
<0> [561.086529] i915_sel-4367 0.... 539676058us : __intel_context_do_pin:
bcs0 context:efc2 pin ring:{head:0000, tail:0000}
<0> [561.086889] i915_sel-4367 0.... 539677466us : __intel_context_do_pin:
bcs0 context:efc3 pin ring:{head:0000, tail:0000}
<0> [561.087247] i915_sel-4367 0.... 539677497us : __engine_unpark: bcs0
<0> [561.087607] i915_sel-4367 0.... 539677511us : __gt_unpark:
<0> [561.087993] i915_sel-4367 0.... 539679446us : __i915_request_commit:
bcs0 fence efc2:2
<0> [561.088380] i915_sel-4367 0d..1 539679473us : __i915_request_submit:
bcs0 fence efc2:2, current 0
<0> [561.088747] i915_sel-4367 0d..1 539679480us :
__execlists_submission_tasklet: bcs0: queue_priority_hint:-2147483648,
submit:yes
<0> [561.089115] i915_sel-4367 0d..1 539679485us : trace_ports: bcs0: submit
{ efc2:2, 0:0 }
<0> [561.089481] jbd2/sda-215 1.Ns1 539679542us : process_csb: bcs0 cs-irq
head=3, tail=4
<0> [561.089848] jbd2/sda-215 1.Ns1 539679544us : process_csb: bcs0 csb[4]:
status=0x00000001:0x00000000
<0> [561.090215] jbd2/sda-215 1.Ns1 539679548us : trace_ports: bcs0:
promote { efc2:2*, 0:0 }
<0> [561.090581] jbd2/sda-215 1dNs2 539679555us :
__execlists_submission_tasklet: bcs0: queue_priority_hint:-2147483648,
submit:no
<0> [561.090967] i915_sel-4367 0.... 539679561us : __i915_request_commit:
bcs0 fence efc3:2
<0> [561.091355] i915_sel-4367 0d..1 539679585us : __i915_request_submit:
bcs0 fence efc3:2, current 0
<0> [561.091721] i915_sel-4367 0d..1 539679590us :
__execlists_submission_tasklet: bcs0: queue_priority_hint:-2147483648,
submit:yes
<0> [561.092088] i915_sel-4367 0d..1 539679593us : trace_ports: bcs0: submit
{ efc2:2*, efc3:2 }
<0> [561.092474] i915_sel-4367 0.... 539679674us : __i915_request_commit:
bcs0 fence efc2:4
<0> [561.092839] ksoftirq-16 1..s. 539679717us : process_csb: bcs0 cs-irq
head=4, tail=5
<0> [561.093205] ksoftirq-16 1..s. 539679718us : process_csb: bcs0 csb[5]:
status=0x00008802:0x00000001
<0> [561.093573] ksoftirq-16 1..s. 539679721us : trace_ports: bcs0:
preempted { efc2:2*, 0:0 }
<0> [561.093940] ksoftirq-16 1..s. 539679724us : trace_ports: bcs0:
promote { efc2:2*, efc3:2 }
<0> [561.094311] ksoftirq-16 1d.s1 539679731us :
__execlists_submission_tasklet: bcs0: expired last=efc2:2, prio=3, hint=3
<0> [561.094698] ksoftirq-16 1d.s1 539679734us : __i915_request_unsubmit:
bcs0 fence efc3:2, current 0
<0> [561.095086] ksoftirq-16 1d.s1 539679739us : __i915_request_unsubmit:
bcs0 fence efc2:2, current 1
<0> [561.095473] ksoftirq-16 1d.s1 539679746us : __i915_request_submit:
bcs0 fence efc2:2, current 1
<0> [561.095860] ksoftirq-16 1d.s1 539679752us : __i915_request_submit:
bcs0 fence efc3:2, current 0
<0> [561.096227] ksoftirq-16 1d.s1 539679756us :
__execlists_submission_tasklet: bcs0: queue_priority_hint:2, submit:yes
<0> [561.096595] ksoftirq-16 1d.s1 539679758us : trace_ports: bcs0: submit
{ efc2:2*, efc3:2 }
<0> [561.096977] i915_sel-4367 0.... 539679795us : i915_gem_wait_for_idle:
flags=2 (locked), timeout=200
<0> [561.097678] i915_sel-4367 0.... 539887173us : igt_flush_test:
igt_live_test_end+0x1f/0xf0 [i915] timed out.
That's the entire sequence. Looks suspiciously like we succeeded in killing the
HW as intended...
There is a redundant preemption we can fixup though.
--
You are receiving this mail because:
You are the assignee for the bug.
You are the QA Contact for the bug.
You are on the CC list for the bug.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/intel-gfx-bugs/attachments/20191003/130087f1/attachment.html>
More information about the intel-gfx-bugs
mailing list