Results for igt@xe_query@query-cs-cycles

shard-bmg-5

IGT-Version: 2.3-ge90a75275 (x86_64) (Linux: 7.0.0-rc4-lgci-xe-xe-4750-e4242b1733f42c5cf-debug+ x86_64)

Using IGT_SRANDOM=1774076229 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.047s)

Starting subtest: query-cs-cycles
(xe_query:2788) CRITICAL: Test assertion failure function __engine_cycles, file ../tests/intel/xe_query.c:743:
(xe_query:2788) CRITICAL: Failed assertion: 2 < usable
(xe_query:2788) CRITICAL: error: 2 >= 0
Subtest query-cs-cycles failed.
**** DEBUG ****
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18379330 Hz, err 4.274%
(xe_query:2788) DEBUG: delta_cpu[17030], delta_cs[16302]
(xe_query:2788) DEBUG: delta_delta 728
(xe_query:2788) DEBUG: [1] cpu_ts before 1774076229326108393, reg read time 1768
(xe_query:2788) DEBUG: [1] engine_ts 246249218, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 1774076229326125382, reg read time 1759
(xe_query:2788) DEBUG: [2] engine_ts 246249530, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18364824 Hz, err 4.350%
(xe_query:2788) DEBUG: delta_cpu[16989], delta_cs[16250]
(xe_query:2788) DEBUG: delta_delta 739
(xe_query:2788) DEBUG: [1] cpu_ts before 1774076229326145567, reg read time 1768
(xe_query:2788) DEBUG: [1] engine_ts 246249902, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 1774076229326162628, reg read time 1753
(xe_query:2788) DEBUG: [2] engine_ts 246250215, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18345935 Hz, err 4.448%
(xe_query:2788) DEBUG: delta_cpu[17061], delta_cs[16302]
(xe_query:2788) DEBUG: delta_delta 759
(xe_query:2788) DEBUG: [1] cpu_ts before 1774076229326182920, reg read time 1763
(xe_query:2788) DEBUG: [1] engine_ts 246250588, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 1774076229326199963, reg read time 1778
(xe_query:2788) DEBUG: [2] engine_ts 246250902, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18423986 Hz, err 4.042%
(xe_query:2788) DEBUG: delta_cpu[17043], delta_cs[16354]
(xe_query:2788) DEBUG: delta_delta 689
(xe_query:2788) DEBUG: clock CLOCK_REALTIME
(xe_query:2788) DEBUG: usable 10
(xe_query:2788) DEBUG: [1] cpu_ts before 108646004661, reg read time 1753
(xe_query:2788) DEBUG: [1] engine_ts 246251290, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 108646021585, reg read time 1759
(xe_query:2788) DEBUG: [2] engine_ts 246251602, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18435358 Hz, err 3.983%
(xe_query:2788) DEBUG: delta_cpu[16924], delta_cs[16250]
(xe_query:2788) DEBUG: delta_delta 674
(xe_query:2788) DEBUG: [1] cpu_ts before 108646044441, reg read time 1777
(xe_query:2788) DEBUG: [1] engine_ts 246252022, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 108646061638, reg read time 1761
(xe_query:2788) DEBUG: [2] engine_ts 246252338, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18375298 Hz, err 4.295%
(xe_query:2788) DEBUG: delta_cpu[17197], delta_cs[16458]
(xe_query:2788) DEBUG: delta_delta 739
(xe_query:2788) DEBUG: [1] cpu_ts before 108646081898, reg read time 1766
(xe_query:2788) DEBUG: [1] engine_ts 246252711, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 108646098746, reg read time 1721
(xe_query:2788) DEBUG: [2] engine_ts 246253021, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18399810 Hz, err 4.168%
(xe_query:2788) DEBUG: delta_cpu[16848], delta_cs[16145]
(xe_query:2788) DEBUG: delta_delta 703
(xe_query:2788) DEBUG: [1] cpu_ts before 108646119144, reg read time 1769
(xe_query:2788) DEBUG: [1] engine_ts 246253396, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 108646136167, reg read time 1783
(xe_query:2788) DEBUG: [2] engine_ts 246253709, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18386888 Hz, err 4.235%
(xe_query:2788) DEBUG: delta_cpu[17023], delta_cs[16302]
(xe_query:2788) DEBUG: delta_delta 721
(xe_query:2788) DEBUG: [1] cpu_ts before 108646156455, reg read time 1784
(xe_query:2788) DEBUG: [1] engine_ts 246254081, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 108646173423, reg read time 1762
(xe_query:2788) DEBUG: [2] engine_ts 246254394, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18446487 Hz, err 3.925%
(xe_query:2788) DEBUG: delta_cpu[16968], delta_cs[16302]
(xe_query:2788) DEBUG: delta_delta 666
(xe_query:2788) DEBUG: [1] cpu_ts before 108646193416, reg read time 1766
(xe_query:2788) DEBUG: [1] engine_ts 246254761, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 108646210465, reg read time 1764
(xe_query:2788) DEBUG: [2] engine_ts 246255075, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18417502 Hz, err 4.076%
(xe_query:2788) DEBUG: delta_cpu[17049], delta_cs[16354]
(xe_query:2788) DEBUG: delta_delta 695
(xe_query:2788) DEBUG: [1] cpu_ts before 108646230666, reg read time 1759
(xe_query:2788) DEBUG: [1] engine_ts 246255446, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 108646247718, reg read time 1763
(xe_query:2788) DEBUG: [2] engine_ts 246255760, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18414262 Hz, err 4.092%
(xe_query:2788) DEBUG: delta_cpu[17052], delta_cs[16354]
(xe_query:2788) DEBUG: delta_delta 698
(xe_query:2788) DEBUG: [1] cpu_ts before 108646267971, reg read time 1783
(xe_query:2788) DEBUG: [1] engine_ts 246256132, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 108646284910, reg read time 1758
(xe_query:2788) DEBUG: [2] engine_ts 246256444, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18419033 Hz, err 4.068%
(xe_query:2788) DEBUG: delta_cpu[16939], delta_cs[16250]
(xe_query:2788) DEBUG: delta_delta 689
(xe_query:2788) DEBUG: [1] cpu_ts before 108646305090, reg read time 1686
(xe_query:2788) DEBUG: [1] engine_ts 246256815, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 108646322078, reg read time 1751
(xe_query:2788) DEBUG: [2] engine_ts 246257127, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18365905 Hz, err 4.344%
(xe_query:2788) DEBUG: delta_cpu[16988], delta_cs[16250]
(xe_query:2788) DEBUG: delta_delta 738
(xe_query:2788) DEBUG: [1] cpu_ts before 108646342366, reg read time 1777
(xe_query:2788) DEBUG: [1] engine_ts 246257500, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 108646359393, reg read time 1754
(xe_query:2788) DEBUG: [2] engine_ts 246257813, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18382568 Hz, err 4.257%
(xe_query:2788) DEBUG: delta_cpu[17027], delta_cs[16302]
(xe_query:2788) DEBUG: delta_delta 725
(xe_query:2788) DEBUG: clock CLOCK_BOOTTIME
(xe_query:2788) DEBUG: usable 10
(xe_query:2788) DEBUG: [1] cpu_ts before 1774076229326596845, reg read time 1792
(xe_query:2788) DEBUG: [1] engine_ts 246258200, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 1774076229326613853, reg read time 1749
(xe_query:2788) DEBUG: [2] engine_ts 246258512, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18344308 Hz, err 4.457%
(xe_query:2788) DEBUG: delta_cpu[17008], delta_cs[16250]
(xe_query:2788) DEBUG: delta_delta 758
(xe_query:2788) DEBUG: [1] cpu_ts before 1774076229326634180, reg read time 1768
(xe_query:2788) DEBUG: [1] engine_ts 246258886, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 1774076229326651172, reg read time 1759
(xe_query:2788) DEBUG: [2] engine_ts 246259198, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18361581 Hz, err 4.367%
(xe_query:2788) DEBUG: delta_cpu[16992], delta_cs[16250]
(xe_query:2788) DEBUG: delta_delta 742
(xe_query:2788) DEBUG: [1] cpu_ts before 1774076229326671269, reg read time 1767
(xe_query:2788) DEBUG: [1] engine_ts 246259568, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 1774076229326688232, reg read time 1762
(xe_query:2788) DEBUG: [2] engine_ts 246259880, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18392972 Hz, err 4.203%
(xe_query:2788) DEBUG: delta_cpu[16963], delta_cs[16250]
(xe_query:2788) DEBUG: delta_delta 713
(xe_query:2788) DEBUG: [1] cpu_ts before 1774076229326708413, reg read time 1761
(xe_query:2788) DEBUG: [1] engine_ts 246260251, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 1774076229326725430, reg read time 1757
(xe_query:2788) DEBUG: [2] engine_ts 246260564, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18393371 Hz, err 4.201%
(xe_query:2788) DEBUG: delta_cpu[17017], delta_cs[16302]
(xe_query:2788) DEBUG: delta_delta 715
(xe_query:2788) DEBUG: [1] cpu_ts before 1774076229326745661, reg read time 1761
(xe_query:2788) DEBUG: [1] engine_ts 246260936, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 1774076229326762651, reg read time 1753
(xe_query:2788) DEBUG: [2] engine_ts 246261248, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18363743 Hz, err 4.356%
(xe_query:2788) DEBUG: delta_cpu[16990], delta_cs[16250]
(xe_query:2788) DEBUG: delta_delta 740
(xe_query:2788) DEBUG: [1] cpu_ts before 1774076229326782865, reg read time 1769
(xe_query:2788) DEBUG: [1] engine_ts 246261620, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 1774076229326799773, reg read time 1753
(xe_query:2788) DEBUG: [2] engine_ts 246261931, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18393659 Hz, err 4.200%
(xe_query:2788) DEBUG: delta_cpu[16908], delta_cs[16197]
(xe_query:2788) DEBUG: delta_delta 711
(xe_query:2788) DEBUG: [1] cpu_ts before 1774076229326820057, reg read time 1775
(xe_query:2788) DEBUG: [1] engine_ts 246262304, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 1774076229326837126, reg read time 1758
(xe_query:2788) DEBUG: [2] engine_ts 246262618, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18395922 Hz, err 4.188%
(xe_query:2788) DEBUG: delta_cpu[17069], delta_cs[16354]
(xe_query:2788) DEBUG: delta_delta 715
(xe_query:2788) DEBUG: [1] cpu_ts before 1774076229326857375, reg read time 1760
(xe_query:2788) DEBUG: [1] engine_ts 246262990, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 1774076229326874310, reg read time 1764
(xe_query:2788) DEBUG: [2] engine_ts 246263301, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18364334 Hz, err 4.352%
(xe_query:2788) DEBUG: delta_cpu[16935], delta_cs[16197]
(xe_query:2788) DEBUG: delta_delta 738
(xe_query:2788) DEBUG: [1] cpu_ts before 1774076229326894437, reg read time 1762
(xe_query:2788) DEBUG: [1] engine_ts 246263671, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 1774076229326911403, reg read time 1765
(xe_query:2788) DEBUG: [2] engine_ts 246263983, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18389720 Hz, err 4.220%
(xe_query:2788) DEBUG: delta_cpu[16966], delta_cs[16250]
(xe_query:2788) DEBUG: delta_delta 716
(xe_query:2788) DEBUG: [1] cpu_ts before 1774076229326931628, reg read time 1758
(xe_query:2788) DEBUG: [1] engine_ts 246264355, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 1774076229326948622, reg read time 1755
(xe_query:2788) DEBUG: [2] engine_ts 246264668, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18418265 Hz, err 4.072%
(xe_query:2788) DEBUG: delta_cpu[16994], delta_cs[16302]
(xe_query:2788) DEBUG: delta_delta 692
(xe_query:2788) DEBUG: clock CLOCK_TAI
(xe_query:2788) DEBUG: usable 10
(xe_query:2788) DEBUG: engine[2:0]
(xe_query:2788) intel_allocator_reloc-DEBUG: Using reloc allocator
(xe_query:2788) DEBUG: [1] cpu_ts before 74509529558, reg read time 2273
(xe_query:2788) DEBUG: [1] engine_ts 246352671, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 74509651403, reg read time 2255
(xe_query:2788) DEBUG: [2] engine_ts 246354912, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18392219 Hz, err 4.207%
(xe_query:2788) DEBUG: delta_cpu[121845], delta_cs[116718]
(xe_query:2788) DEBUG: delta_delta 5127
(xe_query:2788) DEBUG: [1] cpu_ts before 74509778750, reg read time 2272
(xe_query:2788) DEBUG: [1] engine_ts 246357253, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 74509900632, reg read time 2290
(xe_query:2788) DEBUG: [2] engine_ts 246359495, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18394840 Hz, err 4.194%
(xe_query:2788) DEBUG: delta_cpu[121882], delta_cs[116770]
(xe_query:2788) DEBUG: delta_delta 5112
(xe_query:2788) DEBUG: [1] cpu_ts before 74510025707, reg read time 2257
(xe_query:2788) DEBUG: [1] engine_ts 246361794, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 74510152976, reg read time 2289
(xe_query:2788) DEBUG: [2] engine_ts 246364135, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18394110 Hz, err 4.197%
(xe_query:2788) DEBUG: delta_cpu[127269], delta_cs[121927]
(xe_query:2788) DEBUG: delta_delta 5342
(xe_query:2788) DEBUG: [1] cpu_ts before 74510278175, reg read time 2272
(xe_query:2788) DEBUG: [1] engine_ts 246366437, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 74510398539, reg read time 2266
(xe_query:2788) DEBUG: [2] engine_ts 246368649, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18377587 Hz, err 4.283%
(xe_query:2788) DEBUG: delta_cpu[120364], delta_cs[115208]
(xe_query:2788) DEBUG: delta_delta 5156
(xe_query:2788) DEBUG: [1] cpu_ts before 74510522616, reg read time 2268
(xe_query:2788) DEBUG: [1] engine_ts 246370931, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 74510644710, reg read time 2271
(xe_query:2788) DEBUG: [2] engine_ts 246373176, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18387471 Hz, err 4.232%
(xe_query:2788) DEBUG: delta_cpu[122094], delta_cs[116927]
(xe_query:2788) DEBUG: delta_delta 5167
(xe_query:2788) DEBUG: [1] cpu_ts before 74510855731, reg read time 2264
(xe_query:2788) DEBUG: [1] engine_ts 246377056, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 74510977735, reg read time 2255
(xe_query:2788) DEBUG: [2] engine_ts 246379300, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18392839 Hz, err 4.204%
(xe_query:2788) DEBUG: delta_cpu[122004], delta_cs[116875]
(xe_query:2788) DEBUG: delta_delta 5129
(xe_query:2788) DEBUG: [1] cpu_ts before 74511115691, reg read time 2272
(xe_query:2788) DEBUG: [1] engine_ts 246381836, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 74511237686, reg read time 2268
(xe_query:2788) DEBUG: [2] engine_ts 246384080, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18394196 Hz, err 4.197%
(xe_query:2788) DEBUG: delta_cpu[121995], delta_cs[116875]
(xe_query:2788) DEBUG: delta_delta 5120
(xe_query:2788) DEBUG: [1] cpu_ts before 74511362958, reg read time 2298
(xe_query:2788) DEBUG: [1] engine_ts 246386383, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 74511484973, reg read time 2246
(xe_query:2788) DEBUG: [2] engine_ts 246388627, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18391181 Hz, err 4.213%
(xe_query:2788) DEBUG: delta_cpu[122015], delta_cs[116875]
(xe_query:2788) DEBUG: delta_delta 5140
(xe_query:2788) DEBUG: [1] cpu_ts before 74511610151, reg read time 2264
(xe_query:2788) DEBUG: [1] engine_ts 246390928, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 74511732260, reg read time 2295
(xe_query:2788) DEBUG: [2] engine_ts 246393174, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18393402 Hz, err 4.201%
(xe_query:2788) DEBUG: delta_cpu[122109], delta_cs[116979]
(xe_query:2788) DEBUG: delta_delta 5130
(xe_query:2788) DEBUG: [1] cpu_ts before 74511857592, reg read time 2297
(xe_query:2788) DEBUG: [1] engine_ts 246395478, width 64
(xe_query:2788) DEBUG: [2] cpu_ts before 74511979798, reg read time 2305
(xe_query:2788) DEBUG: [2] engine_ts 246397725, width 64
(xe_query:2788) DEBUG: freq 19200000 Hz, calc_freq 18386985 Hz, err 4.234%
(xe_query:2788) DEBUG: delta_cpu[122206], delta_cs[117031]
(xe_query:2788) DEBUG: delta_delta 5175
(xe_query:2788) DEBUG: clock CLOCK_MONOTONIC
(xe_query:2788) DEBUG: usable 0
(xe_query:2788) CRITICAL: Test assertion failure function __engine_cycles, file ../tests/intel/xe_query.c:743:
(xe_query:2788) CRITICAL: Failed assertion: 2 < usable
(xe_query:2788) CRITICAL: error: 2 >= 0
(xe_query:2788) igt_core-INFO: Stack trace:
(xe_query:2788) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(xe_query:2788) igt_core-INFO:   #1 ../tests/intel/xe_query.c:767 test_query_engine_cycles()
(xe_query:2788) igt_core-INFO:   #2 ../tests/intel/xe_query.c:1116 __igt_unique____real_main1085()
(xe_query:2788) igt_core-INFO:   #3 ../tests/intel/xe_query.c:1085 main()
(xe_query:2788) igt_core-INFO:   #4 [__libc_init_first+0x8a]
(xe_query:2788) igt_core-INFO:   #5 [__libc_start_main+0x8b]
(xe_query:2788) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Subtest query-cs-cycles: FAIL (0.047s)