Results for igt@xe_query@query-cs-cycles

shard-bmg-8

IGT-Version: 2.4-g4568b2c14 (x86_64) (Linux: 7.1.0-rc1-lgci-xe-xe-4943-1c60044e7e22a4629-debug+ x86_64)

Using IGT_SRANDOM=1777350515 for randomisation
Opened device: /dev/dri/card0
Starting subtest: query-cs-cycles
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/intel/xe_query.c:767 test_query_engine_cycles()
  #2 ../tests/intel/xe_query.c:1116 __igt_unique____real_main1085()
  #3 ../tests/intel/xe_query.c:1085 main()
  #4 [__libc_init_first+0x8a]
  #5 [__libc_start_main+0x8b]
  #6 [_start+0x25]
Subtest query-cs-cycles: FAIL (0.048s)

Starting subtest: query-cs-cycles
(xe_query:6419) CRITICAL: Test assertion failure function __engine_cycles, file ../tests/intel/xe_query.c:743:
(xe_query:6419) CRITICAL: Failed assertion: 2 < usable
(xe_query:6419) CRITICAL: error: 2 >= 0
Subtest query-cs-cycles failed.
**** DEBUG ****
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20107927 Hz, err 4.729%
(xe_query:6419) DEBUG: delta_cpu[15566], delta_cs[16302]
(xe_query:6419) DEBUG: delta_delta 736
(xe_query:6419) DEBUG: [1] cpu_ts before 1777350515510563836, reg read time 1715
(xe_query:6419) DEBUG: [1] engine_ts 5787922645, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 1777350515510579357, reg read time 1758
(xe_query:6419) DEBUG: [2] engine_ts 5787922958, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20166226 Hz, err 5.032%
(xe_query:6419) DEBUG: delta_cpu[15521], delta_cs[16302]
(xe_query:6419) DEBUG: delta_delta 781
(xe_query:6419) DEBUG: [1] cpu_ts before 1777350515510597781, reg read time 1762
(xe_query:6419) DEBUG: [1] engine_ts 5787923328, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 1777350515510613290, reg read time 1752
(xe_query:6419) DEBUG: [2] engine_ts 5787923640, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20117351 Hz, err 4.778%
(xe_query:6419) DEBUG: delta_cpu[15509], delta_cs[16250]
(xe_query:6419) DEBUG: delta_delta 741
(xe_query:6419) DEBUG: [1] cpu_ts before 1777350515510631813, reg read time 1761
(xe_query:6419) DEBUG: [1] engine_ts 5787924013, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 1777350515510647327, reg read time 1758
(xe_query:6419) DEBUG: [2] engine_ts 5787924325, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20110867 Hz, err 4.744%
(xe_query:6419) DEBUG: delta_cpu[15514], delta_cs[16250]
(xe_query:6419) DEBUG: delta_delta 736
(xe_query:6419) DEBUG: clock CLOCK_REALTIME
(xe_query:6419) DEBUG: usable 10
(xe_query:6419) DEBUG: [1] cpu_ts before 276250848397, reg read time 1779
(xe_query:6419) DEBUG: [1] engine_ts 5787924714, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 276250863926, reg read time 1758
(xe_query:6419) DEBUG: [2] engine_ts 5787925026, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20091441 Hz, err 4.643%
(xe_query:6419) DEBUG: delta_cpu[15529], delta_cs[16250]
(xe_query:6419) DEBUG: delta_delta 721
(xe_query:6419) DEBUG: [1] cpu_ts before 276250882589, reg read time 1780
(xe_query:6419) DEBUG: [1] engine_ts 5787925402, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 276250898080, reg read time 1760
(xe_query:6419) DEBUG: [2] engine_ts 5787925713, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20076173 Hz, err 4.563%
(xe_query:6419) DEBUG: delta_cpu[15491], delta_cs[16197]
(xe_query:6419) DEBUG: delta_delta 706
(xe_query:6419) DEBUG: [1] cpu_ts before 276250916698, reg read time 1758
(xe_query:6419) DEBUG: [1] engine_ts 5787926088, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 276250932133, reg read time 1756
(xe_query:6419) DEBUG: [2] engine_ts 5787926398, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20084224 Hz, err 4.605%
(xe_query:6419) DEBUG: delta_cpu[15435], delta_cs[16145]
(xe_query:6419) DEBUG: delta_delta 710
(xe_query:6419) DEBUG: [1] cpu_ts before 276250950783, reg read time 1764
(xe_query:6419) DEBUG: [1] engine_ts 5787926773, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 276250966339, reg read time 1760
(xe_query:6419) DEBUG: [2] engine_ts 5787927086, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20120853 Hz, err 4.796%
(xe_query:6419) DEBUG: delta_cpu[15556], delta_cs[16302]
(xe_query:6419) DEBUG: delta_delta 746
(xe_query:6419) DEBUG: [1] cpu_ts before 276250984892, reg read time 1725
(xe_query:6419) DEBUG: [1] engine_ts 5787927459, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 276251000423, reg read time 1758
(xe_query:6419) DEBUG: [2] engine_ts 5787927772, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20153241 Hz, err 4.965%
(xe_query:6419) DEBUG: delta_cpu[15531], delta_cs[16302]
(xe_query:6419) DEBUG: delta_delta 771
(xe_query:6419) DEBUG: [1] cpu_ts before 276251019042, reg read time 1758
(xe_query:6419) DEBUG: [1] engine_ts 5787928146, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 276251034603, reg read time 1754
(xe_query:6419) DEBUG: [2] engine_ts 5787928459, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20114388 Hz, err 4.762%
(xe_query:6419) DEBUG: delta_cpu[15561], delta_cs[16302]
(xe_query:6419) DEBUG: delta_delta 741
(xe_query:6419) DEBUG: [1] cpu_ts before 276251053201, reg read time 1761
(xe_query:6419) DEBUG: [1] engine_ts 5787928833, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 276251068758, reg read time 1755
(xe_query:6419) DEBUG: [2] engine_ts 5787929146, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20119560 Hz, err 4.789%
(xe_query:6419) DEBUG: delta_cpu[15557], delta_cs[16302]
(xe_query:6419) DEBUG: delta_delta 745
(xe_query:6419) DEBUG: [1] cpu_ts before 276251087300, reg read time 1729
(xe_query:6419) DEBUG: [1] engine_ts 5787929518, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 276251102733, reg read time 1773
(xe_query:6419) DEBUG: [2] engine_ts 5787929830, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20216419 Hz, err 5.294%
(xe_query:6419) DEBUG: delta_cpu[15433], delta_cs[16250]
(xe_query:6419) DEBUG: delta_delta 817
(xe_query:6419) DEBUG: [1] cpu_ts before 276251121309, reg read time 1781
(xe_query:6419) DEBUG: [1] engine_ts 5787930203, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 276251136599, reg read time 1780
(xe_query:6419) DEBUG: [2] engine_ts 5787930511, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20143884 Hz, err 4.916%
(xe_query:6419) DEBUG: delta_cpu[15290], delta_cs[16041]
(xe_query:6419) DEBUG: delta_delta 751
(xe_query:6419) DEBUG: [1] cpu_ts before 276251155147, reg read time 1760
(xe_query:6419) DEBUG: [1] engine_ts 5787930884, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 276251170723, reg read time 1772
(xe_query:6419) DEBUG: [2] engine_ts 5787931197, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20095017 Hz, err 4.662%
(xe_query:6419) DEBUG: delta_cpu[15576], delta_cs[16302]
(xe_query:6419) DEBUG: delta_delta 726
(xe_query:6419) DEBUG: clock CLOCK_BOOTTIME
(xe_query:6419) DEBUG: usable 10
(xe_query:6419) DEBUG: [1] cpu_ts before 1777350515511008233, reg read time 1777
(xe_query:6419) DEBUG: [1] engine_ts 5787931584, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 1777350515511023777, reg read time 1751
(xe_query:6419) DEBUG: [2] engine_ts 5787931897, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20136387 Hz, err 4.877%
(xe_query:6419) DEBUG: delta_cpu[15544], delta_cs[16302]
(xe_query:6419) DEBUG: delta_delta 758
(xe_query:6419) DEBUG: [1] cpu_ts before 1777350515511042428, reg read time 1784
(xe_query:6419) DEBUG: [1] engine_ts 5787932272, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 1777350515511057967, reg read time 1756
(xe_query:6419) DEBUG: [2] engine_ts 5787932584, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20078512 Hz, err 4.576%
(xe_query:6419) DEBUG: delta_cpu[15539], delta_cs[16250]
(xe_query:6419) DEBUG: delta_delta 711
(xe_query:6419) DEBUG: [1] cpu_ts before 1777350515511095883, reg read time 1766
(xe_query:6419) DEBUG: [1] engine_ts 5787933347, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 1777350515511111511, reg read time 1731
(xe_query:6419) DEBUG: [2] engine_ts 5787933660, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20028154 Hz, err 4.313%
(xe_query:6419) DEBUG: delta_cpu[15628], delta_cs[16302]
(xe_query:6419) DEBUG: delta_delta 674
(xe_query:6419) DEBUG: [1] cpu_ts before 1777350515511130083, reg read time 1759
(xe_query:6419) DEBUG: [1] engine_ts 5787934035, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 1777350515511145692, reg read time 1751
(xe_query:6419) DEBUG: [2] engine_ts 5787934349, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20116599 Hz, err 4.774%
(xe_query:6419) DEBUG: delta_cpu[15609], delta_cs[16354]
(xe_query:6419) DEBUG: delta_delta 745
(xe_query:6419) DEBUG: [1] cpu_ts before 1777350515511164235, reg read time 1777
(xe_query:6419) DEBUG: [1] engine_ts 5787934722, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 1777350515511179718, reg read time 1776
(xe_query:6419) DEBUG: [2] engine_ts 5787935033, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20086546 Hz, err 4.617%
(xe_query:6419) DEBUG: delta_cpu[15483], delta_cs[16197]
(xe_query:6419) DEBUG: delta_delta 714
(xe_query:6419) DEBUG: [1] cpu_ts before 1777350515511198281, reg read time 1768
(xe_query:6419) DEBUG: [1] engine_ts 5787935406, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 1777350515511213804, reg read time 1755
(xe_query:6419) DEBUG: [2] engine_ts 5787935719, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20163628 Hz, err 5.019%
(xe_query:6419) DEBUG: delta_cpu[15523], delta_cs[16302]
(xe_query:6419) DEBUG: delta_delta 779
(xe_query:6419) DEBUG: [1] cpu_ts before 1777350515511232340, reg read time 1765
(xe_query:6419) DEBUG: [1] engine_ts 5787936092, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 1777350515511247913, reg read time 1763
(xe_query:6419) DEBUG: [2] engine_ts 5787936404, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20034675 Hz, err 4.347%
(xe_query:6419) DEBUG: delta_cpu[15573], delta_cs[16250]
(xe_query:6419) DEBUG: delta_delta 677
(xe_query:6419) DEBUG: [1] cpu_ts before 1777350515511266382, reg read time 1761
(xe_query:6419) DEBUG: [1] engine_ts 5787936776, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 1777350515511281945, reg read time 1757
(xe_query:6419) DEBUG: [2] engine_ts 5787937089, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20111803 Hz, err 4.749%
(xe_query:6419) DEBUG: delta_cpu[15563], delta_cs[16302]
(xe_query:6419) DEBUG: delta_delta 739
(xe_query:6419) DEBUG: [1] cpu_ts before 1777350515511300493, reg read time 1787
(xe_query:6419) DEBUG: [1] engine_ts 5787937462, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 1777350515511315971, reg read time 1749
(xe_query:6419) DEBUG: [2] engine_ts 5787937774, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20157643 Hz, err 4.988%
(xe_query:6419) DEBUG: delta_cpu[15478], delta_cs[16250]
(xe_query:6419) DEBUG: delta_delta 772
(xe_query:6419) DEBUG: [1] cpu_ts before 1777350515511334496, reg read time 1757
(xe_query:6419) DEBUG: [1] engine_ts 5787938146, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 1777350515511350078, reg read time 1776
(xe_query:6419) DEBUG: [2] engine_ts 5787938460, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20151456 Hz, err 4.955%
(xe_query:6419) DEBUG: delta_cpu[15582], delta_cs[16354]
(xe_query:6419) DEBUG: delta_delta 772
(xe_query:6419) DEBUG: clock CLOCK_TAI
(xe_query:6419) DEBUG: usable 10
(xe_query:6419) DEBUG: engine[2:0]
(xe_query:6419) intel_allocator_reloc-DEBUG: Using reloc allocator
(xe_query:6419) DEBUG: [1] cpu_ts before 261397176763, reg read time 2264
(xe_query:6419) DEBUG: [1] engine_ts 5788036809, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 261397288092, reg read time 2190
(xe_query:6419) DEBUG: [2] engine_ts 5788039048, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20111561 Hz, err 4.748%
(xe_query:6419) DEBUG: delta_cpu[111329], delta_cs[116614]
(xe_query:6419) DEBUG: delta_delta 5285
(xe_query:6419) DEBUG: [1] cpu_ts before 261397404799, reg read time 2260
(xe_query:6419) DEBUG: [1] engine_ts 5788041396, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 261397594965, reg read time 2249
(xe_query:6419) DEBUG: [2] engine_ts 5788045221, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20114005 Hz, err 4.760%
(xe_query:6419) DEBUG: delta_cpu[190166], delta_cs[199218]
(xe_query:6419) DEBUG: delta_delta 9052
(xe_query:6419) DEBUG: [1] cpu_ts before 261397709370, reg read time 2202
(xe_query:6419) DEBUG: [1] engine_ts 5788047522, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 261397820740, reg read time 2250
(xe_query:6419) DEBUG: [2] engine_ts 5788049762, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20113136 Hz, err 4.756%
(xe_query:6419) DEBUG: delta_cpu[111370], delta_cs[116666]
(xe_query:6419) DEBUG: delta_delta 5296
(xe_query:6419) DEBUG: [1] cpu_ts before 261397935459, reg read time 2257
(xe_query:6419) DEBUG: [1] engine_ts 5788052069, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 261398126275, reg read time 2250
(xe_query:6419) DEBUG: [2] engine_ts 5788055907, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20113617 Hz, err 4.758%
(xe_query:6419) DEBUG: delta_cpu[190816], delta_cs[199895]
(xe_query:6419) DEBUG: delta_delta 9079
(xe_query:6419) DEBUG: [1] cpu_ts before 261398240545, reg read time 2251
(xe_query:6419) DEBUG: [1] engine_ts 5788058206, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 261398350281, reg read time 2260
(xe_query:6419) DEBUG: [2] engine_ts 5788060413, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20111904 Hz, err 4.750%
(xe_query:6419) DEBUG: delta_cpu[109736], delta_cs[114947]
(xe_query:6419) DEBUG: delta_delta 5211
(xe_query:6419) DEBUG: [1] cpu_ts before 261398464601, reg read time 2257
(xe_query:6419) DEBUG: [1] engine_ts 5788062712, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 261398576041, reg read time 2258
(xe_query:6419) DEBUG: [2] engine_ts 5788064954, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20118449 Hz, err 4.784%
(xe_query:6419) DEBUG: delta_cpu[111440], delta_cs[116770]
(xe_query:6419) DEBUG: delta_delta 5330
(xe_query:6419) DEBUG: [1] cpu_ts before 261398690445, reg read time 2256
(xe_query:6419) DEBUG: [1] engine_ts 5788067255, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 261398800444, reg read time 2260
(xe_query:6419) DEBUG: [2] engine_ts 5788069467, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20109273 Hz, err 4.736%
(xe_query:6419) DEBUG: delta_cpu[109999], delta_cs[115208]
(xe_query:6419) DEBUG: delta_delta 5209
(xe_query:6419) DEBUG: [1] cpu_ts before 261398913102, reg read time 2254
(xe_query:6419) DEBUG: [1] engine_ts 5788071733, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 261399105055, reg read time 2263
(xe_query:6419) DEBUG: [2] engine_ts 5788075594, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20114298 Hz, err 4.762%
(xe_query:6419) DEBUG: delta_cpu[191953], delta_cs[201093]
(xe_query:6419) DEBUG: delta_delta 9140
(xe_query:6419) DEBUG: [1] cpu_ts before 261399219436, reg read time 2250
(xe_query:6419) DEBUG: [1] engine_ts 5788077895, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 261399329371, reg read time 2267
(xe_query:6419) DEBUG: [2] engine_ts 5788080106, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20111884 Hz, err 4.749%
(xe_query:6419) DEBUG: delta_cpu[109935], delta_cs[115156]
(xe_query:6419) DEBUG: delta_delta 5221
(xe_query:6419) DEBUG: [1] cpu_ts before 261399442222, reg read time 2257
(xe_query:6419) DEBUG: [1] engine_ts 5788082376, width 64
(xe_query:6419) DEBUG: [2] cpu_ts before 261399553532, reg read time 2289
(xe_query:6419) DEBUG: [2] engine_ts 5788084615, width 64
(xe_query:6419) DEBUG: freq 19200000 Hz, calc_freq 20114994 Hz, err 4.766%
(xe_query:6419) DEBUG: delta_cpu[111310], delta_cs[116614]
(xe_query:6419) DEBUG: delta_delta 5304
(xe_query:6419) DEBUG: clock CLOCK_MONOTONIC
(xe_query:6419) DEBUG: usable 0
(xe_query:6419) CRITICAL: Test assertion failure function __engine_cycles, file ../tests/intel/xe_query.c:743:
(xe_query:6419) CRITICAL: Failed assertion: 2 < usable
(xe_query:6419) CRITICAL: error: 2 >= 0
(xe_query:6419) igt_core-INFO: Stack trace:
(xe_query:6419) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(xe_query:6419) igt_core-INFO:   #1 ../tests/intel/xe_query.c:767 test_query_engine_cycles()
(xe_query:6419) igt_core-INFO:   #2 ../tests/intel/xe_query.c:1116 __igt_unique____real_main1085()
(xe_query:6419) igt_core-INFO:   #3 ../tests/intel/xe_query.c:1085 main()
(xe_query:6419) igt_core-INFO:   #4 [__libc_init_first+0x8a]
(xe_query:6419) igt_core-INFO:   #5 [__libc_start_main+0x8b]
(xe_query:6419) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Subtest query-cs-cycles: FAIL (0.048s)