[38.772900] Initializing watchdogs [38.772992] /dev/watchdog0 [38.779122] [001/160] (960s left) i915_module_load (load) [38.839894] Starting subtest: load [41.077859] Subtest load: SUCCESS (2.234s) [41.091546] [002/160] (958s left) core_auth (basic-auth) [41.175300] Starting subtest: basic-auth [41.177857] Subtest basic-auth: SUCCESS (0.002s) [41.246886] [003/160] (958s left) debugfs_test (read_all_entries) [41.320292] Starting subtest: read_all_entries [41.580188] Subtest read_all_entries: SUCCESS (0.259s) [41.667816] [004/160] (957s left) debugfs_test (basic-hwmon) [41.736296] Starting subtest: basic-hwmon [41.738183] Subtest basic-hwmon: SKIP (0.000s) [41.833843] [005/160] (957s left) fbdev (eof) [41.890260] Starting subtest: eof [41.891419] Subtest eof: SUCCESS (0.001s) [41.947332] [006/160] (957s left) fbdev (info) [41.998391] Starting subtest: info [41.999273] Subtest info: SUCCESS (0.000s) [42.063732] [007/160] (957s left) fbdev (nullptr) [42.123022] Starting subtest: nullptr [42.124181] Subtest nullptr: SUCCESS (0.000s) [42.178810] [008/160] (957s left) fbdev (read) [42.240560] Starting subtest: read [49.573092] Subtest read: SUCCESS (7.298s) [49.609091] [009/160] (949s left) fbdev (write) [49.670482] Starting subtest: write [53.400890] Subtest write: SUCCESS (3.696s) [53.440591] [010/160] (945s left) gem_basic (bad-close) [53.501012] Starting subtest: bad-close [53.502863] Subtest bad-close: SUCCESS (0.000s) [53.571205] [011/160] (945s left) gem_basic (create-close) [53.630330] Starting subtest: create-close [53.632182] Subtest create-close: SUCCESS (0.000s) [53.705454] [012/160] (945s left) gem_basic (create-fd-close) [53.762902] Starting subtest: create-fd-close [53.764695] Subtest create-fd-close: SUCCESS (0.000s) [53.841087] [013/160] (945s left) gem_busy (busy@all-engines) [53.907783] Starting subtest: busy [53.908776] Starting dynamic subtest: all-engines [53.944070] Dynamic subtest all-engines: SUCCESS (0.036s) [53.944932] Subtest busy: SUCCESS (0.036s) [54.298660] [014/160] (945s left) gem_close_race (basic-process) [54.362617] Starting subtest: basic-process [54.394767] Subtest basic-process: SUCCESS (0.032s) [54.590349] [015/160] (944s left) gem_close_race (basic-threads) [54.657722] Starting subtest: basic-threads [55.809437] Subtest basic-threads: SUCCESS (1.148s) [56.003471] [016/160] (943s left) gem_ctx_create (basic) [56.100964] Starting subtest: basic [56.102065] Subtest basic: SUCCESS (0.000s) [56.296754] [017/160] (943s left) gem_ctx_create (basic-files) [56.375300] Starting subtest: basic-files [58.393340] Subtest basic-files: SUCCESS (2.018s) [58.595921] [018/160] (940s left) gem_ctx_exec (basic) [58.668443] Starting subtest: basic [58.673160] Subtest basic: SUCCESS (0.005s) [58.787791] [019/160] (940s left) gem_exec_basic (basic) [58.864102] Starting subtest: basic [58.865217] Starting dynamic subtest: rcs0-smem [58.866198] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [58.875761] Starting dynamic subtest: bcs0-smem [58.877136] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [58.885435] Starting dynamic subtest: vcs0-smem [58.886421] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [58.893028] Starting dynamic subtest: vecs0-smem [58.894032] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [58.895022] Subtest basic: SUCCESS (0.005s) [59.064413] [020/160] (940s left) gem_exec_create (basic) [59.138693] Starting subtest: basic [59.139804] Starting dynamic subtest: smem [61.234935] Dynamic subtest smem: SUCCESS (2.097s) [61.236412] Subtest basic: SUCCESS (2.097s) [61.425216] [021/160] (938s left) gem_exec_fence (basic-busy) [61.624949] Starting subtest: basic-busy [61.626127] Starting dynamic subtest: rcs0 [61.672270] Dynamic subtest rcs0: SUCCESS (0.055s) [61.685050] Starting dynamic subtest: bcs0 [61.729375] Dynamic subtest bcs0: SUCCESS (0.057s) [61.736918] Starting dynamic subtest: vcs0 [61.783121] Dynamic subtest vcs0: SUCCESS (0.053s) [61.794820] Starting dynamic subtest: vecs0 [61.832996] Dynamic subtest vecs0: SUCCESS (0.050s) [61.834597] Subtest basic-busy: SUCCESS (0.216s) [62.375729] [022/160] (937s left) gem_exec_fence (basic-wait) [62.564222] Starting subtest: basic-wait [62.565473] Starting dynamic subtest: rcs0 [62.632903] Dynamic subtest rcs0: SUCCESS (0.077s) [62.644609] Starting dynamic subtest: bcs0 [62.687815] Dynamic subtest bcs0: SUCCESS (0.054s) [62.699412] Starting dynamic subtest: vcs0 [62.738294] Dynamic subtest vcs0: SUCCESS (0.048s) [62.748535] Starting dynamic subtest: vecs0 [62.787861] Dynamic subtest vecs0: SUCCESS (0.051s) [62.790164] Subtest basic-wait: SUCCESS (0.232s) [63.283033] [023/160] (936s left) gem_exec_fence (basic-await) [63.476413] Starting subtest: basic-await [63.477252] Starting dynamic subtest: rcs0 [63.550497] Dynamic subtest rcs0: SUCCESS (0.082s) [63.565814] Starting dynamic subtest: bcs0 [63.640434] Dynamic subtest bcs0: SUCCESS (0.090s) [63.655685] Starting dynamic subtest: vcs0 [63.727641] Dynamic subtest vcs0: SUCCESS (0.087s) [63.744969] Starting dynamic subtest: vecs0 [63.819851] Dynamic subtest vecs0: SUCCESS (0.090s) [63.821649] Subtest basic-await: SUCCESS (0.350s) [64.318593] [024/160] (935s left) gem_exec_fence (nb-await) [64.506883] Starting subtest: nb-await [64.507748] Starting dynamic subtest: rcs0 [64.566488] Dynamic subtest rcs0: SUCCESS (0.065s) [64.577061] Starting dynamic subtest: bcs0 [64.624990] Dynamic subtest bcs0: SUCCESS (0.060s) [64.643116] Starting dynamic subtest: vcs0 [64.689798] Dynamic subtest vcs0: SUCCESS (0.065s) [64.695742] Starting dynamic subtest: vecs0 [64.749113] Dynamic subtest vecs0: SUCCESS (0.059s) [64.750905] Subtest nb-await: SUCCESS (0.250s) [65.255217] [025/160] (934s left) gem_exec_gttfill (basic) [65.320109] Starting subtest: basic [68.846678] Subtest basic: SUCCESS (3.526s) [69.040581] [026/160] (930s left) gem_exec_parallel (engines) [69.186395] Starting subtest: engines [69.187398] Starting dynamic subtest: basic [71.343029] Dynamic subtest basic: SUCCESS (2.155s) [71.354778] Starting dynamic subtest: contexts [75.371454] Dynamic subtest contexts: SUCCESS (4.022s) [75.381630] Starting dynamic subtest: fds [81.614094] Dynamic subtest fds: SUCCESS (6.242s) [81.625736] Starting dynamic subtest: userptr [83.718987] Dynamic subtest userptr: SUCCESS (2.113s) [83.720319] Subtest engines: SUCCESS (14.534s) [83.921913] [027/160] (915s left) gem_exec_store (basic) [83.994119] Starting subtest: basic [84.099433] Subtest basic: SUCCESS (0.104s) [84.290925] [028/160] (915s left) gem_flink_basic (bad-flink) [84.345748] Starting subtest: bad-flink [84.346621] Subtest bad-flink: SUCCESS (0.000s) [84.425888] [029/160] (915s left) gem_flink_basic (bad-open) [84.484850] Starting subtest: bad-open [84.485745] Subtest bad-open: SUCCESS (0.000s) [84.557249] [030/160] (915s left) gem_flink_basic (basic) [84.616988] Starting subtest: basic [84.617925] Subtest basic: SUCCESS (0.000s) [84.709966] [031/160] (915s left) gem_flink_basic (double-flink) [84.765888] Starting subtest: double-flink [84.766751] Subtest double-flink: SUCCESS (0.000s) [84.859568] [032/160] (914s left) gem_flink_basic (flink-lifetime) [84.915259] Starting subtest: flink-lifetime [84.917589] Subtest flink-lifetime: SUCCESS (0.003s) [85.005666] [033/160] (914s left) gem_huc_copy (huc-copy) [85.068400] Starting subtest: huc-copy [85.071693] Subtest huc-copy: SUCCESS (0.003s) [85.182375] [034/160] (914s left) gem_linear_blits (basic) [85.351905] Starting subtest: basic [85.361872] Subtest basic: SUCCESS (0.010s) [85.475163] [035/160] (914s left) gem_mmap (basic) [85.573859] Starting subtest: basic [85.577991] Subtest basic: SUCCESS (0.000s) [85.662735] [036/160] (914s left) gem_mmap_gtt (basic) [85.724767] Starting subtest: basic [85.726024] Subtest basic: SUCCESS (0.002s) [85.816795] [037/160] (914s left) gem_render_linear_blits (basic) [85.882236] Starting subtest: basic [85.908077] Subtest basic: SUCCESS (0.026s) [85.977046] [038/160] (913s left) gem_render_tiled_blits (basic) [86.034927] Starting subtest: basic [86.070495] Subtest basic: SUCCESS (0.034s) [86.136593] [039/160] (913s left) gem_ringfill (basic-all) [86.443913] Starting subtest: basic-all [87.600761] Subtest basic-all: SUCCESS (1.148s) [87.709808] [040/160] (912s left) gem_softpin (allocator-basic) [87.793903] Starting subtest: allocator-basic [87.929864] Subtest allocator-basic: SUCCESS (0.128s) [88.042463] [041/160] (911s left) gem_softpin (allocator-basic-reserve) [88.116087] Starting subtest: allocator-basic-reserve [88.241442] Subtest allocator-basic-reserve: SUCCESS (0.116s) [88.355643] [042/160] (911s left) gem_softpin (safe-alignment) [88.430958] Starting subtest: safe-alignment [88.449425] Subtest safe-alignment: SUCCESS (0.018s) [88.576386] [043/160] (911s left) gem_sync (basic-all) [88.653154] Starting subtest: basic-all [91.458131] Subtest basic-all: SUCCESS (2.804s) [91.652308] [044/160] (908s left) gem_sync (basic-each) [91.723187] Starting subtest: basic-each [94.638950] Subtest basic-each: SUCCESS (2.915s) [94.831511] [045/160] (905s left) gem_tiled_blits (basic) [94.987331] Starting subtest: basic [95.009455] Subtest basic: SUCCESS (0.022s) [95.139215] [046/160] (904s left) gem_tiled_fence_blits (basic) [95.344868] Starting subtest: basic [95.366111] Subtest basic: SUCCESS (0.021s) [95.487687] [047/160] (904s left) gem_tiled_pread_basic [95.635444] [048/160] (904s left) gem_wait (busy@all-engines) [95.708052] Starting subtest: busy [95.708931] Starting dynamic subtest: all-engines [96.264999] Dynamic subtest all-engines: SUCCESS (0.556s) [96.266634] Subtest busy: SUCCESS (0.556s) [96.483289] [049/160] (903s left) gem_wait (wait@all-engines) [96.549902] Starting subtest: wait [96.550811] Starting dynamic subtest: all-engines [97.605384] Dynamic subtest all-engines: SUCCESS (1.055s) [97.606973] Subtest wait: SUCCESS (1.055s) [97.819584] [050/160] (902s left) i915_getparams_basic (basic-eu-total) [97.881471] Starting subtest: basic-eu-total [97.883280] Subtest basic-eu-total: SUCCESS (0.000s) [97.967510] [051/160] (902s left) i915_getparams_basic (basic-subslice-total) [98.028521] Starting subtest: basic-subslice-total [98.030384] Subtest basic-subslice-total: SUCCESS (0.000s) [98.116190] [052/160] (902s left) i915_hangman (error-state-basic) [98.173442] Starting subtest: error-state-basic [98.286880] Subtest error-state-basic: SUCCESS (0.113s) [98.374951] [053/160] (901s left) i915_pciid [98.510711] [054/160] (901s left) kms_addfb_basic (addfb25-4-tiled) [98.583513] Starting subtest: addfb25-4-tiled [98.584278] Subtest addfb25-4-tiled: SUCCESS (0.000s) [98.680453] [055/160] (901s left) kms_addfb_basic (addfb25-bad-modifier) [98.733483] Starting subtest: addfb25-bad-modifier [98.734566] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [98.851364] [056/160] (901s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [98.903333] Starting subtest: addfb25-framebuffer-vs-set-tiling [98.904510] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [99.022987] [057/160] (901s left) kms_addfb_basic (addfb25-modifier-no-flag) [99.081231] Starting subtest: addfb25-modifier-no-flag [99.082520] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [99.193302] [058/160] (901s left) kms_addfb_basic (addfb25-x-tiled-legacy) [99.245688] Starting subtest: addfb25-x-tiled-legacy [99.246764] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [99.367149] [059/160] (900s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [99.427681] Starting subtest: addfb25-x-tiled-mismatch-legacy [99.428725] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s) [99.545771] [060/160] (900s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [99.623699] Starting subtest: addfb25-yf-tiled-legacy [99.624520] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [99.724601] [061/160] (900s left) kms_addfb_basic (addfb25-y-tiled-legacy) [99.800532] Starting subtest: addfb25-y-tiled-legacy [99.801383] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [99.900674] [062/160] (900s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [99.968411] Starting subtest: addfb25-y-tiled-small-legacy [99.969224] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [100.048950] [063/160] (900s left) kms_addfb_basic (bad-pitch-0) [100.107677] Starting subtest: bad-pitch-0 [100.108937] Subtest bad-pitch-0: SUCCESS (0.000s) [100.221873] [064/160] (900s left) kms_addfb_basic (bad-pitch-1024) [100.277192] Starting subtest: bad-pitch-1024 [100.278151] Subtest bad-pitch-1024: SUCCESS (0.000s) [100.404238] [065/160] (900s left) kms_addfb_basic (bad-pitch-128) [100.462019] Starting subtest: bad-pitch-128 [100.463339] Subtest bad-pitch-128: SUCCESS (0.000s) [100.581578] [066/160] (899s left) kms_addfb_basic (bad-pitch-256) [100.639706] Starting subtest: bad-pitch-256 [100.640806] Subtest bad-pitch-256: SUCCESS (0.000s) [100.729258] [067/160] (899s left) kms_addfb_basic (bad-pitch-32) [100.787611] Starting subtest: bad-pitch-32 [100.788762] Subtest bad-pitch-32: SUCCESS (0.000s) [100.916577] [068/160] (899s left) kms_addfb_basic (bad-pitch-63) [100.972207] Starting subtest: bad-pitch-63 [100.973510] Subtest bad-pitch-63: SUCCESS (0.000s) [101.095148] [069/160] (899s left) kms_addfb_basic (bad-pitch-65536) [101.150286] Starting subtest: bad-pitch-65536 [101.151446] Subtest bad-pitch-65536: SUCCESS (0.000s) [101.246238] [070/160] (899s left) kms_addfb_basic (bad-pitch-999) [101.304500] Starting subtest: bad-pitch-999 [101.305785] Subtest bad-pitch-999: SUCCESS (0.000s) [101.394363] [071/160] (899s left) kms_addfb_basic (basic) [101.448078] Starting subtest: basic [101.448962] Subtest basic: SUCCESS (0.000s) [101.567382] [072/160] (898s left) kms_addfb_basic (basic-x-tiled-legacy) [101.626587] Starting subtest: basic-x-tiled-legacy [101.627753] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [101.717964] [073/160] (898s left) kms_addfb_basic (basic-y-tiled-legacy) [101.772139] Starting subtest: basic-y-tiled-legacy [101.773172] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [101.867654] [074/160] (898s left) kms_addfb_basic (bo-too-small) [101.947249] Starting subtest: bo-too-small [101.948241] Subtest bo-too-small: SUCCESS (0.000s) [102.047295] [075/160] (898s left) kms_addfb_basic (bo-too-small-due-to-tiling) [102.120926] Starting subtest: bo-too-small-due-to-tiling [102.121884] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [102.218645] [076/160] (898s left) kms_addfb_basic (clobberred-modifier) [102.278861] Starting subtest: clobberred-modifier [102.279984] Subtest clobberred-modifier: SUCCESS (0.000s) [102.400581] [077/160] (898s left) kms_addfb_basic (framebuffer-vs-set-tiling) [102.460617] Starting subtest: framebuffer-vs-set-tiling [102.461788] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [102.575055] [078/160] (898s left) kms_addfb_basic (invalid-get-prop) [102.628819] Starting subtest: invalid-get-prop [102.629639] Subtest invalid-get-prop: SUCCESS (0.000s) [102.746255] [079/160] (897s left) kms_addfb_basic (invalid-get-prop-any) [102.798390] Starting subtest: invalid-get-prop-any [102.799569] Subtest invalid-get-prop-any: SUCCESS (0.000s) [102.919806] [080/160] (897s left) kms_addfb_basic (invalid-set-prop) [102.972749] Starting subtest: invalid-set-prop [102.973746] Subtest invalid-set-prop: SUCCESS (0.000s) [103.090604] [081/160] (897s left) kms_addfb_basic (invalid-set-prop-any) [103.144230] Starting subtest: invalid-set-prop-any [103.145619] Subtest invalid-set-prop-any: SUCCESS (0.000s) [103.272799] [082/160] (897s left) kms_addfb_basic (no-handle) [103.334124] Starting subtest: no-handle [103.335302] Subtest no-handle: SUCCESS (0.000s) [103.462299] [083/160] (897s left) kms_addfb_basic (size-max) [103.539505] Starting subtest: size-max [103.540368] Subtest size-max: SUCCESS (0.000s) [103.635758] [084/160] (897s left) kms_addfb_basic (small-bo) [103.710307] Starting subtest: small-bo [103.711185] Subtest small-bo: SUCCESS (0.000s) [103.811751] [085/160] (896s left) kms_addfb_basic (tile-pitch-mismatch) [103.872267] Starting subtest: tile-pitch-mismatch [103.873420] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [103.997072] [086/160] (896s left) kms_addfb_basic (too-high) [104.067490] Starting subtest: too-high [104.068281] Subtest too-high: SUCCESS (0.001s) [104.144951] [087/160] (896s left) kms_addfb_basic (too-wide) [104.222050] Starting subtest: too-wide [104.222959] Subtest too-wide: SUCCESS (0.000s) [104.294793] [088/160] (896s left) kms_addfb_basic (unused-handle) [104.357567] Starting subtest: unused-handle [104.358573] Subtest unused-handle: SUCCESS (0.000s) [104.470648] [089/160] (896s left) kms_addfb_basic (unused-modifier) [104.524119] Starting subtest: unused-modifier [104.524938] Subtest unused-modifier: SUCCESS (0.000s) [104.639131] [090/160] (896s left) kms_addfb_basic (unused-offsets) [104.691282] Starting subtest: unused-offsets [104.692531] Subtest unused-offsets: SUCCESS (0.000s) [104.817657] [091/160] (896s left) kms_addfb_basic (unused-pitches) [104.882752] Starting subtest: unused-pitches [104.883916] Subtest unused-pitches: SUCCESS (0.000s) [105.001764] [092/160] (895s left) kms_busy (basic) [105.071633] Starting subtest: basic [105.072619] Starting dynamic subtest: flip [105.935723] Dynamic subtest flip: SUCCESS (0.863s) [105.954623] Starting dynamic subtest: modeset [109.814729] Dynamic subtest modeset: SUCCESS (3.876s) [109.816098] Subtest basic: SUCCESS (4.739s) [110.774990] [093/160] (890s left) kms_prop_blob (basic) [110.830723] Starting subtest: basic [110.831543] Subtest basic: SUCCESS (0.000s) [110.908506] [094/160] (890s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [111.023994] Starting subtest: basic-busy-flip-before-cursor-atomic [111.026507] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [111.161189] [095/160] (889s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [111.272441] Starting subtest: basic-busy-flip-before-cursor-legacy [111.274513] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [111.378660] [096/160] (889s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [111.490716] Starting subtest: basic-flip-after-cursor-atomic [112.687237] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.196s) [113.651811] [097/160] (887s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [113.780840] Starting subtest: basic-flip-after-cursor-legacy [114.991208] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.210s) [115.952521] [098/160] (885s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [116.063567] Starting subtest: basic-flip-after-cursor-varying-size [117.280870] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.217s) [118.193535] [099/160] (882s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [118.322673] Starting subtest: basic-flip-before-cursor-atomic [119.532047] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.210s) [120.435841] [100/160] (880s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [120.548382] Starting subtest: basic-flip-before-cursor-legacy [121.766580] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.215s) [122.676952] [101/160] (878s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [122.789555] Starting subtest: basic-flip-before-cursor-varying-size [124.000641] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.212s) [124.920496] [102/160] (876s left) kms_dsc (dsc-basic) [124.993775] Starting subtest: dsc-basic [124.995822] Subtest dsc-basic: SKIP (0.001s) [125.070876] [103/160] (876s left) kms_flip (basic-flip-vs-dpms) [125.137599] Starting subtest: basic-flip-vs-dpms [125.138548] Starting dynamic subtest: A-eDP1 [127.959547] Dynamic subtest A-eDP1: SUCCESS (2.817s) [127.975609] Starting dynamic subtest: B-eDP1 [130.397114] Dynamic subtest B-eDP1: SUCCESS (2.432s) [130.405922] Starting dynamic subtest: C-eDP1 [132.787022] Dynamic subtest C-eDP1: SUCCESS (2.398s) [132.788043] Subtest basic-flip-vs-dpms: SUCCESS (7.651s) [133.743629] [104/160] (867s left) kms_flip (basic-flip-vs-modeset) [133.811602] Starting subtest: basic-flip-vs-modeset [133.812451] Starting dynamic subtest: A-eDP1 [136.624374] Dynamic subtest A-eDP1: SUCCESS (2.812s) [136.639343] Starting dynamic subtest: B-eDP1 [139.001517] Dynamic subtest B-eDP1: SUCCESS (2.376s) [139.022323] Starting dynamic subtest: C-eDP1 [141.361882] Dynamic subtest C-eDP1: SUCCESS (2.359s) [141.362782] Subtest basic-flip-vs-modeset: SUCCESS (7.551s) [142.323916] [105/160] (858s left) kms_flip (basic-flip-vs-wf_vblank) [142.407617] Starting subtest: basic-flip-vs-wf_vblank [142.409620] Starting dynamic subtest: A-eDP1 [144.673507] Dynamic subtest A-eDP1: SUCCESS (2.263s) [144.692589] Starting dynamic subtest: B-eDP1 [146.911361] Dynamic subtest B-eDP1: SUCCESS (2.236s) [146.921038] Starting dynamic subtest: C-eDP1 [149.132854] Dynamic subtest C-eDP1: SUCCESS (2.220s) [149.136538] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.729s) [150.387696] [106/160] (850s left) kms_flip (basic-plain-flip) [150.456589] Starting subtest: basic-plain-flip [150.458618] Starting dynamic subtest: A-eDP1 [152.454677] Dynamic subtest A-eDP1: SUCCESS (1.995s) [152.475099] Starting dynamic subtest: B-eDP1 [154.436871] Dynamic subtest B-eDP1: SUCCESS (1.980s) [154.446859] Starting dynamic subtest: C-eDP1 [156.398930] Dynamic subtest C-eDP1: SUCCESS (1.960s) [156.402134] Subtest basic-plain-flip: SUCCESS (5.946s) [157.685405] [107/160] (843s left) kms_force_connector_basic (force-connector-state) [157.748538] Starting subtest: force-connector-state [158.197305] Subtest force-connector-state: SUCCESS (0.449s) [159.401829] [108/160] (841s left) kms_force_connector_basic (force-edid) [159.462500] Starting subtest: force-edid [159.492540] Subtest force-edid: SUCCESS (0.030s) [159.607576] [109/160] (841s left) kms_force_connector_basic (force-load-detect) [159.667821] Starting subtest: force-load-detect [159.670269] Subtest force-load-detect: SKIP (0.000s) [159.795236] [110/160] (841s left) kms_force_connector_basic (prune-stale-modes) [159.855870] Starting subtest: prune-stale-modes [159.889356] Subtest prune-stale-modes: SUCCESS (0.030s) [160.012095] [111/160] (841s left) kms_frontbuffer_tracking (basic) [160.076289] Starting subtest: basic [164.843481] Subtest basic: SUCCESS (4.767s) [164.938816] [112/160] (836s left) kms_hdmi_inject (inject-audio) [165.294946] Starting subtest: inject-audio [165.399978] Subtest inject-audio: SUCCESS (0.102s) [166.238279] [113/160] (835s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [166.326377] Starting subtest: compare-crc-sanitycheck-xr24 [166.327586] Starting dynamic subtest: pipe-A-eDP-1 [167.093334] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.767s) [167.112496] Starting dynamic subtest: pipe-B-eDP-1 [168.635410] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.537s) [168.653395] Starting dynamic subtest: pipe-C-eDP-1 [170.130235] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.493s) [170.131601] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.806s) [171.061699] [114/160] (830s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [171.137578] Starting subtest: compare-crc-sanitycheck-nv12 [171.138547] Starting dynamic subtest: pipe-A-eDP-1 [172.010973] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.870s) [172.029721] Starting dynamic subtest: pipe-B-eDP-1 [173.464825] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.452s) [173.484466] Starting dynamic subtest: pipe-C-eDP-1 [174.892327] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.425s) [174.893149] Subtest compare-crc-sanitycheck-nv12: SUCCESS (3.756s) [175.799502] [115/160] (825s left) kms_pipe_crc_basic (hang-read-crc) [175.872700] Starting subtest: hang-read-crc [175.873522] Starting dynamic subtest: pipe-A-eDP-1 [178.234714] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.361s) [178.246196] Starting dynamic subtest: pipe-B-eDP-1 [181.370417] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.135s) [181.384374] Starting dynamic subtest: pipe-C-eDP-1 [184.441324] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.068s) [184.442221] Subtest hang-read-crc: SUCCESS (8.568s) [185.401234] [116/160] (815s left) kms_pipe_crc_basic (nonblocking-crc) [185.484130] Starting subtest: nonblocking-crc [185.485324] Starting dynamic subtest: pipe-A-eDP-1 [186.409589] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.923s) [186.419709] Starting dynamic subtest: pipe-B-eDP-1 [188.060775] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.651s) [188.069296] Starting dynamic subtest: pipe-C-eDP-1 [189.651957] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.589s) [189.652828] Subtest nonblocking-crc: SUCCESS (4.169s) [190.580811] [117/160] (810s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [190.651640] Starting subtest: nonblocking-crc-frame-sequence [190.652880] Starting dynamic subtest: pipe-A-eDP-1 [191.555325] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.903s) [191.563013] Starting dynamic subtest: pipe-B-eDP-1 [193.239903] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.683s) [193.253485] Starting dynamic subtest: pipe-C-eDP-1 [194.836244] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.594s) [194.837069] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.187s) [195.773602] [118/160] (805s left) kms_pipe_crc_basic (read-crc) [195.839102] Starting subtest: read-crc [195.841914] Starting dynamic subtest: pipe-A-eDP-1 [196.636605] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.796s) [196.650382] Starting dynamic subtest: pipe-B-eDP-1 [198.197754] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.559s) [198.210173] Starting dynamic subtest: pipe-C-eDP-1 [199.759984] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.560s) [199.760847] Subtest read-crc: SUCCESS (3.921s) [200.694621] [119/160] (800s left) kms_pipe_crc_basic (read-crc-frame-sequence) [200.763620] Starting subtest: read-crc-frame-sequence [200.766511] Starting dynamic subtest: pipe-A-eDP-1 [201.565228] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.799s) [201.578127] Starting dynamic subtest: pipe-B-eDP-1 [203.124910] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.557s) [203.137054] Starting dynamic subtest: pipe-C-eDP-1 [204.687019] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.561s) [204.687951] Subtest read-crc-frame-sequence: SUCCESS (3.923s) [205.618443] [120/160] (795s left) kms_pm_backlight (basic-brightness) [205.701340] Starting subtest: basic-brightness [205.770540] Starting dynamic subtest: eDP-1 [206.100185] Dynamic subtest eDP-1: SUCCESS (0.333s) [206.101224] Subtest basic-brightness: SUCCESS (0.401s) [207.031717] [121/160] (794s left) kms_pm_rpm (basic-pci-d3-state) [208.558054] Starting subtest: basic-pci-d3-state [213.146145] Subtest basic-pci-d3-state: SUCCESS (4.588s) [213.316838] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 1775 [max: 8192] direct dependencies: 17778 [max: 32768] indirect dependencies: 133830 all direct dependencies: 360804 dependency chains: 26192 [max: 131072] dependency chain hlocks used: 110248 [max: 655360] dependency chain hlocks lost: 0 in-hardirq chains: 266 in-softirq chains: 606 in-process chains: 25320 stack-trace entries: 169393 [max: 1048576] number of stack traces: 8565 number of stack hash chains: 6688 combined max dependencies: 4103749149 hardirq-safe locks: 100 hardirq-unsafe locks: 1062 softirq-safe locks: 212 softirq-unsafe locks: 975 irq-safe locks: 223 irq-unsafe locks: 1062 hardirq-read-safe locks: 3 hardirq-read-unsafe locks: 308 softirq-read-safe locks: 7 softirq-read-unsafe locks: 303 irq-read-safe locks: 7 irq-read-unsafe locks: 308 uncategorized locks: 292 unused locks: 1 max locking depth: 20 max bfs queue depth: 428 max lock class index: 1774 chain lookup misses: 26357 chain lookup hits: 133674784 cyclic checks: 26560 redundant checks: 0 redundant links: 0 find-mask forwards checks: 4474 find-mask backwards checks: 2650 hardirq on events: 74268307 hardirq off events: 74268303 redundant hardirq ons: 60 redundant hardirq offs: 24 softirq on events: 279445 softirq off events: 279445 redundant softirq ons: 0 redundant softirq offs: 0 debug_locks: 0 zapped classes: 1 zapped lock chains: 99 large chain blocks: 1 [213.318310] Closing watchdogs Done.