Results for igt@xe_query@query-cs-cycles

shard-bmg-4

IGT-Version: 2.3-gf38f4d8e9 (x86_64) (Linux: 6.19.0-rc1-lgci-xe-xe-4268-d2fe88bbc66599aed+ x86_64)

Using IGT_SRANDOM=1766171183 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.041s)

Starting subtest: query-cs-cycles
(xe_query:7900) CRITICAL: Test assertion failure function __engine_cycles, file ../tests/intel/xe_query.c:743:
(xe_query:7900) CRITICAL: Failed assertion: 2 < usable
(xe_query:7900) CRITICAL: error: 2 >= 0
Subtest query-cs-cycles failed.
**** DEBUG ****
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18425305 Hz, err 4.035%
(xe_query:7900) DEBUG: delta_cpu[21655], delta_cs[20781]
(xe_query:7900) DEBUG: delta_delta 874
(xe_query:7900) DEBUG: [1] cpu_ts before 1766171183496571372, reg read time 1775
(xe_query:7900) DEBUG: [1] engine_ts 3013868744, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 1766171183496588439, reg read time 1769
(xe_query:7900) DEBUG: [2] engine_ts 3013869058, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18398078 Hz, err 4.177%
(xe_query:7900) DEBUG: delta_cpu[17067], delta_cs[16354]
(xe_query:7900) DEBUG: delta_delta 713
(xe_query:7900) DEBUG: [1] cpu_ts before 1766171183496608590, reg read time 1775
(xe_query:7900) DEBUG: [1] engine_ts 3013869428, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 1766171183496625713, reg read time 1768
(xe_query:7900) DEBUG: [2] engine_ts 3013869742, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18337908 Hz, err 4.490%
(xe_query:7900) DEBUG: delta_cpu[17123], delta_cs[16354]
(xe_query:7900) DEBUG: delta_delta 769
(xe_query:7900) DEBUG: [1] cpu_ts before 1766171183496645953, reg read time 1795
(xe_query:7900) DEBUG: [1] engine_ts 3013870114, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 1766171183496663030, reg read time 1787
(xe_query:7900) DEBUG: [2] engine_ts 3013870428, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18387304 Hz, err 4.233%
(xe_query:7900) DEBUG: delta_cpu[17077], delta_cs[16354]
(xe_query:7900) DEBUG: delta_delta 723
(xe_query:7900) DEBUG: clock CLOCK_REALTIME
(xe_query:7900) DEBUG: usable 10
(xe_query:7900) DEBUG: [1] cpu_ts before 132610387342, reg read time 1781
(xe_query:7900) DEBUG: [1] engine_ts 3013870813, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 132610404399, reg read time 1805
(xe_query:7900) DEBUG: [2] engine_ts 3013871126, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18350237 Hz, err 4.426%
(xe_query:7900) DEBUG: delta_cpu[17057], delta_cs[16302]
(xe_query:7900) DEBUG: delta_delta 755
(xe_query:7900) DEBUG: [1] cpu_ts before 132610424560, reg read time 1768
(xe_query:7900) DEBUG: [1] engine_ts 3013871497, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 132610441526, reg read time 1718
(xe_query:7900) DEBUG: [2] engine_ts 3013871807, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18271837 Hz, err 4.834%
(xe_query:7900) DEBUG: delta_cpu[16966], delta_cs[16145]
(xe_query:7900) DEBUG: delta_delta 821
(xe_query:7900) DEBUG: [1] cpu_ts before 132610461606, reg read time 1775
(xe_query:7900) DEBUG: [1] engine_ts 3013872177, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 132610478696, reg read time 1766
(xe_query:7900) DEBUG: [2] engine_ts 3013872490, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18314803 Hz, err 4.610%
(xe_query:7900) DEBUG: delta_cpu[17090], delta_cs[16302]
(xe_query:7900) DEBUG: delta_delta 788
(xe_query:7900) DEBUG: [1] cpu_ts before 132610498877, reg read time 1767
(xe_query:7900) DEBUG: [1] engine_ts 3013872860, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 132610515808, reg read time 1761
(xe_query:7900) DEBUG: [2] engine_ts 3013873172, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18427736 Hz, err 4.022%
(xe_query:7900) DEBUG: delta_cpu[16931], delta_cs[16250]
(xe_query:7900) DEBUG: delta_delta 681
(xe_query:7900) DEBUG: [1] cpu_ts before 132610535780, reg read time 1769
(xe_query:7900) DEBUG: [1] engine_ts 3013873539, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 132610552881, reg read time 1772
(xe_query:7900) DEBUG: [2] engine_ts 3013873853, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18361499 Hz, err 4.367%
(xe_query:7900) DEBUG: delta_cpu[17101], delta_cs[16354]
(xe_query:7900) DEBUG: delta_delta 747
(xe_query:7900) DEBUG: [1] cpu_ts before 132610573129, reg read time 1786
(xe_query:7900) DEBUG: [1] engine_ts 3013874224, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 132610590151, reg read time 1762
(xe_query:7900) DEBUG: [2] engine_ts 3013874537, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18387968 Hz, err 4.229%
(xe_query:7900) DEBUG: delta_cpu[17022], delta_cs[16302]
(xe_query:7900) DEBUG: delta_delta 720
(xe_query:7900) DEBUG: [1] cpu_ts before 132610610263, reg read time 1725
(xe_query:7900) DEBUG: [1] engine_ts 3013874906, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 132610627327, reg read time 1770
(xe_query:7900) DEBUG: [2] engine_ts 3013875219, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18342709 Hz, err 4.465%
(xe_query:7900) DEBUG: delta_cpu[17064], delta_cs[16302]
(xe_query:7900) DEBUG: delta_delta 762
(xe_query:7900) DEBUG: [1] cpu_ts before 132610647342, reg read time 1765
(xe_query:7900) DEBUG: [1] engine_ts 3013875587, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 132610664414, reg read time 1763
(xe_query:7900) DEBUG: [2] engine_ts 3013875900, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18334114 Hz, err 4.510%
(xe_query:7900) DEBUG: delta_cpu[17072], delta_cs[16302]
(xe_query:7900) DEBUG: delta_delta 770
(xe_query:7900) DEBUG: [1] cpu_ts before 132610684624, reg read time 1766
(xe_query:7900) DEBUG: [1] engine_ts 3013876272, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 132610701595, reg read time 1769
(xe_query:7900) DEBUG: [2] engine_ts 3013876583, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18325378 Hz, err 4.555%
(xe_query:7900) DEBUG: delta_cpu[16971], delta_cs[16197]
(xe_query:7900) DEBUG: delta_delta 774
(xe_query:7900) DEBUG: [1] cpu_ts before 132610721771, reg read time 1769
(xe_query:7900) DEBUG: [1] engine_ts 3013876954, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 132610738873, reg read time 1795
(xe_query:7900) DEBUG: [2] engine_ts 3013877268, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18360425 Hz, err 4.373%
(xe_query:7900) DEBUG: delta_cpu[17102], delta_cs[16354]
(xe_query:7900) DEBUG: delta_delta 748
(xe_query:7900) DEBUG: clock CLOCK_BOOTTIME
(xe_query:7900) DEBUG: usable 10
(xe_query:7900) DEBUG: [1] cpu_ts before 1766171183497056542, reg read time 1780
(xe_query:7900) DEBUG: [1] engine_ts 3013877653, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 1766171183497073696, reg read time 1799
(xe_query:7900) DEBUG: [2] engine_ts 3013877968, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18363064 Hz, err 4.359%
(xe_query:7900) DEBUG: delta_cpu[17154], delta_cs[16406]
(xe_query:7900) DEBUG: delta_delta 748
(xe_query:7900) DEBUG: [1] cpu_ts before 1766171183497093839, reg read time 1790
(xe_query:7900) DEBUG: [1] engine_ts 3013878337, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 1766171183497110890, reg read time 1797
(xe_query:7900) DEBUG: [2] engine_ts 3013878651, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18415342 Hz, err 4.087%
(xe_query:7900) DEBUG: delta_cpu[17051], delta_cs[16354]
(xe_query:7900) DEBUG: delta_delta 697
(xe_query:7900) DEBUG: [1] cpu_ts before 1766171183497131173, reg read time 1763
(xe_query:7900) DEBUG: [1] engine_ts 3013879023, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 1766171183497148244, reg read time 1779
(xe_query:7900) DEBUG: [2] engine_ts 3013879336, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18335188 Hz, err 4.504%
(xe_query:7900) DEBUG: delta_cpu[17071], delta_cs[16302]
(xe_query:7900) DEBUG: delta_delta 769
(xe_query:7900) DEBUG: [1] cpu_ts before 1766171183497168454, reg read time 1778
(xe_query:7900) DEBUG: [1] engine_ts 3013879707, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 1766171183497185554, reg read time 1766
(xe_query:7900) DEBUG: [2] engine_ts 3013880020, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18304093 Hz, err 4.666%
(xe_query:7900) DEBUG: delta_cpu[17100], delta_cs[16302]
(xe_query:7900) DEBUG: delta_delta 798
(xe_query:7900) DEBUG: [1] cpu_ts before 1766171183497205752, reg read time 1803
(xe_query:7900) DEBUG: [1] engine_ts 3013880392, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 1766171183497222844, reg read time 1774
(xe_query:7900) DEBUG: [2] engine_ts 3013880706, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18371167 Hz, err 4.317%
(xe_query:7900) DEBUG: delta_cpu[17092], delta_cs[16354]
(xe_query:7900) DEBUG: delta_delta 738
(xe_query:7900) DEBUG: [1] cpu_ts before 1766171183497242967, reg read time 1769
(xe_query:7900) DEBUG: [1] engine_ts 3013881075, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 1766171183497259918, reg read time 1758
(xe_query:7900) DEBUG: [2] engine_ts 3013881387, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18405993 Hz, err 4.135%
(xe_query:7900) DEBUG: delta_cpu[16951], delta_cs[16250]
(xe_query:7900) DEBUG: delta_delta 701
(xe_query:7900) DEBUG: [1] cpu_ts before 1766171183497279973, reg read time 1772
(xe_query:7900) DEBUG: [1] engine_ts 3013881753, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 1766171183497296944, reg read time 1806
(xe_query:7900) DEBUG: [2] engine_ts 3013882067, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18502150 Hz, err 3.635%
(xe_query:7900) DEBUG: delta_cpu[16971], delta_cs[16354]
(xe_query:7900) DEBUG: delta_delta 617
(xe_query:7900) DEBUG: [1] cpu_ts before 1766171183497317167, reg read time 1787
(xe_query:7900) DEBUG: [1] engine_ts 3013882438, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 1766171183497334162, reg read time 1773
(xe_query:7900) DEBUG: [2] engine_ts 3013882750, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18358340 Hz, err 4.384%
(xe_query:7900) DEBUG: delta_cpu[16995], delta_cs[16250]
(xe_query:7900) DEBUG: delta_delta 745
(xe_query:7900) DEBUG: [1] cpu_ts before 1766171183497354354, reg read time 1804
(xe_query:7900) DEBUG: [1] engine_ts 3013883120, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 1766171183497371251, reg read time 1768
(xe_query:7900) DEBUG: [2] engine_ts 3013883431, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18405634 Hz, err 4.137%
(xe_query:7900) DEBUG: delta_cpu[16897], delta_cs[16197]
(xe_query:7900) DEBUG: delta_delta 700
(xe_query:7900) DEBUG: [1] cpu_ts before 1766171183497391385, reg read time 1776
(xe_query:7900) DEBUG: [1] engine_ts 3013883801, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 1766171183497408448, reg read time 1761
(xe_query:7900) DEBUG: [2] engine_ts 3013884114, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18343784 Hz, err 4.459%
(xe_query:7900) DEBUG: delta_cpu[17063], delta_cs[16302]
(xe_query:7900) DEBUG: delta_delta 761
(xe_query:7900) DEBUG: clock CLOCK_TAI
(xe_query:7900) DEBUG: usable 10
(xe_query:7900) DEBUG: engine[2:0]
(xe_query:7900) intel_allocator_reloc-DEBUG: Using reloc allocator
(xe_query:7900) DEBUG: [1] cpu_ts before 117842788181, reg read time 2304
(xe_query:7900) DEBUG: [1] engine_ts 3013936945, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 117842910362, reg read time 2259
(xe_query:7900) DEBUG: [2] engine_ts 3013939188, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18358009 Hz, err 4.385%
(xe_query:7900) DEBUG: delta_cpu[122181], delta_cs[116822]
(xe_query:7900) DEBUG: delta_delta 5359
(xe_query:7900) DEBUG: [1] cpu_ts before 117843036155, reg read time 2310
(xe_query:7900) DEBUG: [1] engine_ts 3013941498, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 117843171778, reg read time 2286
(xe_query:7900) DEBUG: [2] engine_ts 3013943988, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18359717 Hz, err 4.376%
(xe_query:7900) DEBUG: delta_cpu[135623], delta_cs[129687]
(xe_query:7900) DEBUG: delta_delta 5936
(xe_query:7900) DEBUG: [1] cpu_ts before 117843297280, reg read time 2260
(xe_query:7900) DEBUG: [1] engine_ts 3013946292, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 117843417945, reg read time 2269
(xe_query:7900) DEBUG: [2] engine_ts 3013948508, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18364894 Hz, err 4.350%
(xe_query:7900) DEBUG: delta_cpu[120665], delta_cs[115416]
(xe_query:7900) DEBUG: delta_delta 5249
(xe_query:7900) DEBUG: [1] cpu_ts before 117843544096, reg read time 2289
(xe_query:7900) DEBUG: [1] engine_ts 3013950824, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 117843665211, reg read time 2257
(xe_query:7900) DEBUG: [2] engine_ts 3013953048, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18362713 Hz, err 4.361%
(xe_query:7900) DEBUG: delta_cpu[121115], delta_cs[115833]
(xe_query:7900) DEBUG: delta_delta 5282
(xe_query:7900) DEBUG: [1] cpu_ts before 117843789591, reg read time 2245
(xe_query:7900) DEBUG: [1] engine_ts 3013955332, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 117843910095, reg read time 2291
(xe_query:7900) DEBUG: [2] engine_ts 3013957544, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18356237 Hz, err 4.395%
(xe_query:7900) DEBUG: delta_cpu[120504], delta_cs[115208]
(xe_query:7900) DEBUG: delta_delta 5296
(xe_query:7900) DEBUG: [1] cpu_ts before 117844034284, reg read time 2257
(xe_query:7900) DEBUG: [1] engine_ts 3013959824, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 117844158699, reg read time 2297
(xe_query:7900) DEBUG: [2] engine_ts 3013962109, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18365952 Hz, err 4.344%
(xe_query:7900) DEBUG: delta_cpu[124415], delta_cs[119010]
(xe_query:7900) DEBUG: delta_delta 5405
(xe_query:7900) DEBUG: [1] cpu_ts before 117844284112, reg read time 2256
(xe_query:7900) DEBUG: [1] engine_ts 3013964411, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 117844404094, reg read time 2259
(xe_query:7900) DEBUG: [2] engine_ts 3013966614, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18361087 Hz, err 4.369%
(xe_query:7900) DEBUG: delta_cpu[119982], delta_cs[114739]
(xe_query:7900) DEBUG: delta_delta 5243
(xe_query:7900) DEBUG: [1] cpu_ts before 117844527829, reg read time 2286
(xe_query:7900) DEBUG: [1] engine_ts 3013968886, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 117844648943, reg read time 2283
(xe_query:7900) DEBUG: [2] engine_ts 3013971110, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18362864 Hz, err 4.360%
(xe_query:7900) DEBUG: delta_cpu[121114], delta_cs[115833]
(xe_query:7900) DEBUG: delta_delta 5281
(xe_query:7900) DEBUG: [1] cpu_ts before 117844858106, reg read time 2267
(xe_query:7900) DEBUG: [1] engine_ts 3013974950, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 117844978716, reg read time 2263
(xe_query:7900) DEBUG: [2] engine_ts 3013977165, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18364978 Hz, err 4.349%
(xe_query:7900) DEBUG: delta_cpu[120610], delta_cs[115364]
(xe_query:7900) DEBUG: delta_delta 5246
(xe_query:7900) DEBUG: [1] cpu_ts before 117845191973, reg read time 2199
(xe_query:7900) DEBUG: [1] engine_ts 3013981080, width 64
(xe_query:7900) DEBUG: [2] cpu_ts before 117845398376, reg read time 2246
(xe_query:7900) DEBUG: [2] engine_ts 3013984870, width 64
(xe_query:7900) DEBUG: freq 19200000 Hz, calc_freq 18362136 Hz, err 4.364%
(xe_query:7900) DEBUG: delta_cpu[206403], delta_cs[197395]
(xe_query:7900) DEBUG: delta_delta 9008
(xe_query:7900) DEBUG: clock CLOCK_MONOTONIC
(xe_query:7900) DEBUG: usable 0
(xe_query:7900) CRITICAL: Test assertion failure function __engine_cycles, file ../tests/intel/xe_query.c:743:
(xe_query:7900) CRITICAL: Failed assertion: 2 < usable
(xe_query:7900) CRITICAL: error: 2 >= 0
(xe_query:7900) igt_core-INFO: Stack trace:
(xe_query:7900) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(xe_query:7900) igt_core-INFO:   #1 ../tests/intel/xe_query.c:767 test_query_engine_cycles()
(xe_query:7900) igt_core-INFO:   #2 ../tests/intel/xe_query.c:1116 __igt_unique____real_main1085()
(xe_query:7900) igt_core-INFO:   #3 ../tests/intel/xe_query.c:1085 main()
(xe_query:7900) igt_core-INFO:   #4 [__libc_init_first+0x8a]
(xe_query:7900) igt_core-INFO:   #5 [__libc_start_main+0x8b]
(xe_query:7900) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Subtest query-cs-cycles: FAIL (0.041s)