[111.342675] Initializing watchdogs [111.342716] /dev/watchdog0 [111.346201] [001/160] (960s left) i915_module_load (load) [111.373694] Starting subtest: load [117.960071] Subtest load: SUCCESS (6.586s) [117.969033] [002/160] (953s left) core_auth (basic-auth) [118.014240] Starting subtest: basic-auth [118.015189] Subtest basic-auth: SUCCESS (0.001s) [118.096203] [003/160] (953s left) debugfs_test (read_all_entries) [118.144776] Starting subtest: read_all_entries [118.276940] Subtest read_all_entries: SUCCESS (0.132s) [120.259600] [004/160] (951s left) debugfs_test (basic-hwmon) [120.306894] Starting subtest: basic-hwmon [120.308924] Subtest basic-hwmon: SKIP (0.000s) [122.294200] [005/160] (949s left) fbdev (eof) [122.326840] Starting subtest: eof [122.327836] Subtest eof: SUCCESS (0.000s) [122.374651] [006/160] (949s left) fbdev (info) [122.398853] Starting subtest: info [122.399937] Subtest info: SUCCESS (0.000s) [122.462233] [007/160] (949s left) fbdev (nullptr) [122.493415] Starting subtest: nullptr [122.494304] Subtest nullptr: SUCCESS (0.000s) [122.541940] [008/160] (949s left) fbdev (read) [122.576797] Starting subtest: read [125.068844] Subtest read: SUCCESS (2.483s) [125.108661] [009/160] (946s left) fbdev (write) [125.143495] Starting subtest: write [126.983165] Subtest write: SUCCESS (1.831s) [127.025792] [010/160] (944s left) gem_basic (bad-close) [127.067960] Starting subtest: bad-close [127.069911] Subtest bad-close: SUCCESS (0.000s) [127.146558] [011/160] (944s left) gem_basic (create-close) [127.183875] Starting subtest: create-close [127.186093] Subtest create-close: SUCCESS (0.000s) [127.245377] [012/160] (944s left) gem_basic (create-fd-close) [127.286176] Starting subtest: create-fd-close [127.288631] Subtest create-fd-close: SUCCESS (0.000s) [127.363708] [013/160] (944s left) gem_busy (busy@all-engines) [127.420873] Starting subtest: busy [127.422376] Starting dynamic subtest: all-engines [127.449200] Dynamic subtest all-engines: SUCCESS (0.036s) [127.449928] Subtest busy: SUCCESS (0.037s) [127.783231] [014/160] (944s left) gem_close_race (basic-process) [127.831541] Starting subtest: basic-process [127.867063] Subtest basic-process: SUCCESS (0.035s) [128.030102] [015/160] (943s left) gem_close_race (basic-threads) [128.073027] Starting subtest: basic-threads [129.255259] Subtest basic-threads: SUCCESS (1.182s) [129.430209] [016/160] (942s left) gem_ctx_create (basic) [129.473038] Starting subtest: basic [129.473856] Subtest basic: SUCCESS (0.000s) [129.649059] [017/160] (942s left) gem_ctx_create (basic-files) [129.701448] Starting subtest: basic-files [131.708659] Subtest basic-files: SUCCESS (2.007s) [131.978483] [018/160] (940s left) gem_ctx_exec (basic) [132.019015] Starting subtest: basic [132.021239] Subtest basic: SUCCESS (0.003s) [132.113229] [019/160] (939s left) gem_exec_basic (basic) [132.159865] Starting subtest: basic [132.160803] Starting dynamic subtest: rcs0-smem [132.161817] Dynamic subtest rcs0-smem: SUCCESS (0.001s) [132.165345] Starting dynamic subtest: bcs0-smem [132.166335] Dynamic subtest bcs0-smem: SUCCESS (0.000s) [132.170191] Starting dynamic subtest: vcs0-smem [132.171573] Dynamic subtest vcs0-smem: SUCCESS (0.000s) [132.175797] Starting dynamic subtest: vcs1-smem [132.176681] Dynamic subtest vcs1-smem: SUCCESS (0.000s) [132.180069] Starting dynamic subtest: vecs0-smem [132.180992] Dynamic subtest vecs0-smem: SUCCESS (0.000s) [132.181869] Subtest basic: SUCCESS (0.002s) [132.325876] [020/160] (939s left) gem_exec_create (basic) [132.369957] Starting subtest: basic [132.370800] Starting dynamic subtest: smem [134.456956] Dynamic subtest smem: SUCCESS (2.086s) [134.458607] Subtest basic: SUCCESS (2.086s) [134.630200] [021/160] (937s left) gem_exec_fence (basic-busy) [134.806766] Starting subtest: basic-busy [134.807980] Starting dynamic subtest: rcs0 [134.868132] Dynamic subtest rcs0: SUCCESS (0.068s) [134.879219] Starting dynamic subtest: bcs0 [134.915405] Dynamic subtest bcs0: SUCCESS (0.049s) [134.926361] Starting dynamic subtest: vcs0 [134.963266] Dynamic subtest vcs0: SUCCESS (0.048s) [134.974583] Starting dynamic subtest: vcs1 [135.011423] Dynamic subtest vcs1: SUCCESS (0.048s) [135.022921] Starting dynamic subtest: vecs0 [135.065516] Dynamic subtest vecs0: SUCCESS (0.055s) [135.066319] Subtest basic-busy: SUCCESS (0.269s) [135.566194] [022/160] (936s left) gem_exec_fence (basic-wait) [135.734800] Starting subtest: basic-wait [135.736198] Starting dynamic subtest: rcs0 [135.798632] Dynamic subtest rcs0: SUCCESS (0.072s) [135.808156] Starting dynamic subtest: bcs0 [135.849524] Dynamic subtest bcs0: SUCCESS (0.050s) [135.857650] Starting dynamic subtest: vcs0 [135.896674] Dynamic subtest vcs0: SUCCESS (0.047s) [135.909084] Starting dynamic subtest: vcs1 [135.944370] Dynamic subtest vcs1: SUCCESS (0.048s) [135.957233] Starting dynamic subtest: vecs0 [135.992594] Dynamic subtest vecs0: SUCCESS (0.048s) [135.994355] Subtest basic-wait: SUCCESS (0.266s) [136.479741] [023/160] (935s left) gem_exec_fence (basic-await) [136.658180] Starting subtest: basic-await [136.659206] Starting dynamic subtest: rcs0 [136.718482] Dynamic subtest rcs0: SUCCESS (0.069s) [136.725712] Starting dynamic subtest: bcs0 [136.788503] Dynamic subtest bcs0: SUCCESS (0.070s) [136.796042] Starting dynamic subtest: vcs0 [136.859851] Dynamic subtest vcs0: SUCCESS (0.071s) [136.864776] Starting dynamic subtest: vcs1 [136.928083] Dynamic subtest vcs1: SUCCESS (0.068s) [136.935784] Starting dynamic subtest: vecs0 [136.998989] Dynamic subtest vecs0: SUCCESS (0.071s) [137.000527] Subtest basic-await: SUCCESS (0.350s) [137.468794] [024/160] (934s left) gem_exec_fence (nb-await) [137.640181] Starting subtest: nb-await [137.641240] Starting dynamic subtest: rcs0 [137.689172] Dynamic subtest rcs0: SUCCESS (0.058s) [137.698804] Starting dynamic subtest: bcs0 [137.748703] Dynamic subtest bcs0: SUCCESS (0.059s) [137.754877] Starting dynamic subtest: vcs0 [137.806149] Dynamic subtest vcs0: SUCCESS (0.057s) [137.818241] Starting dynamic subtest: vcs1 [137.867843] Dynamic subtest vcs1: SUCCESS (0.061s) [137.875171] Starting dynamic subtest: vecs0 [137.927761] Dynamic subtest vecs0: SUCCESS (0.060s) [137.930981] Subtest nb-await: SUCCESS (0.297s) [138.402407] [025/160] (933s left) gem_exec_gttfill (basic) [138.455818] Starting subtest: basic [140.599313] Subtest basic: SUCCESS (2.144s) [140.764975] [026/160] (931s left) gem_exec_parallel (engines) [140.837546] Starting subtest: engines [140.838894] Starting dynamic subtest: basic [142.143487] Dynamic subtest basic: SUCCESS (1.306s) [142.150580] Starting dynamic subtest: contexts [144.007193] Dynamic subtest contexts: SUCCESS (1.855s) [144.010977] Starting dynamic subtest: fds [146.160930] Dynamic subtest fds: SUCCESS (2.162s) [146.165056] Starting dynamic subtest: userptr [147.447447] Dynamic subtest userptr: SUCCESS (1.286s) [147.448880] Subtest engines: SUCCESS (6.610s) [147.622725] [027/160] (924s left) gem_exec_store (basic) [147.690824] Starting subtest: basic [147.785746] Subtest basic: SUCCESS (0.094s) [147.946226] [028/160] (924s left) gem_flink_basic (bad-flink) [147.995439] Starting subtest: bad-flink [147.996514] Subtest bad-flink: SUCCESS (0.000s) [148.066504] [029/160] (924s left) gem_flink_basic (bad-open) [148.100562] Starting subtest: bad-open [148.101296] Subtest bad-open: SUCCESS (0.000s) [148.165164] [030/160] (923s left) gem_flink_basic (basic) [148.199875] Starting subtest: basic [148.201050] Subtest basic: SUCCESS (0.000s) [148.265124] [031/160] (923s left) gem_flink_basic (double-flink) [148.299586] Starting subtest: double-flink [148.300385] Subtest double-flink: SUCCESS (0.000s) [148.363187] [032/160] (923s left) gem_flink_basic (flink-lifetime) [148.404239] Starting subtest: flink-lifetime [148.405220] Subtest flink-lifetime: SUCCESS (0.001s) [148.482352] [033/160] (923s left) gem_huc_copy (huc-copy) [148.537547] Starting subtest: huc-copy [148.539333] Subtest huc-copy: SUCCESS (0.002s) [148.649618] [034/160] (923s left) gem_linear_blits (basic) [148.756906] Starting subtest: basic [148.761062] Subtest basic: SUCCESS (0.004s) [148.879308] [035/160] (923s left) gem_mmap (basic) [148.961957] Starting subtest: basic [148.966373] Subtest basic: SUCCESS (0.000s) [149.032126] [036/160] (923s left) gem_mmap_gtt (basic) [149.076562] Starting subtest: basic [149.077375] Subtest basic: SUCCESS (0.001s) [149.133611] [037/160] (923s left) gem_render_linear_blits (basic) [149.183607] Starting subtest: basic [149.203159] Subtest basic: SUCCESS (0.020s) [149.267845] [038/160] (922s left) gem_render_tiled_blits (basic) [149.321071] Starting subtest: basic [149.344966] Subtest basic: SUCCESS (0.024s) [149.402319] [039/160] (922s left) gem_ringfill (basic-all) [149.754827] Starting subtest: basic-all [150.878089] Subtest basic-all: SUCCESS (1.115s) [150.971767] [040/160] (921s left) gem_softpin (allocator-basic) [151.015782] Starting subtest: allocator-basic [151.060953] Subtest allocator-basic: SUCCESS (0.046s) [151.150630] [041/160] (921s left) gem_softpin (allocator-basic-reserve) [151.201453] Starting subtest: allocator-basic-reserve [151.246180] Subtest allocator-basic-reserve: SUCCESS (0.045s) [151.345454] [042/160] (920s left) gem_softpin (safe-alignment) [151.382246] Starting subtest: safe-alignment [151.390774] Subtest safe-alignment: SUCCESS (0.008s) [151.499967] [043/160] (920s left) gem_sync (basic-all) [151.544702] Starting subtest: basic-all [153.985915] Subtest basic-all: SUCCESS (2.440s) [154.168371] [044/160] (918s left) gem_sync (basic-each) [154.221063] Starting subtest: basic-each [156.389439] Subtest basic-each: SUCCESS (2.169s) [156.567123] [045/160] (915s left) gem_tiled_blits (basic) [156.686219] Starting subtest: basic [156.696469] Subtest basic: SUCCESS (0.011s) [156.817467] [046/160] (915s left) gem_tiled_fence_blits (basic) [156.966130] Starting subtest: basic [156.985747] Subtest basic: SUCCESS (0.020s) [157.091986] [047/160] (915s left) gem_tiled_pread_basic [157.220701] [048/160] (915s left) gem_wait (busy@all-engines) [157.274051] Starting subtest: busy [157.275147] Starting dynamic subtest: all-engines [157.822085] Dynamic subtest all-engines: SUCCESS (0.548s) [157.823088] Subtest busy: SUCCESS (0.548s) [158.017444] [049/160] (914s left) gem_wait (wait@all-engines) [158.058685] Starting subtest: wait [158.059521] Starting dynamic subtest: all-engines [159.113605] Dynamic subtest all-engines: SUCCESS (1.053s) [159.115566] Subtest wait: SUCCESS (1.054s) [159.317170] [050/160] (913s left) i915_getparams_basic (basic-eu-total) [159.356631] Starting subtest: basic-eu-total [159.358342] Subtest basic-eu-total: SUCCESS (0.000s) [159.439692] [051/160] (912s left) i915_getparams_basic (basic-subslice-total) [159.477234] Starting subtest: basic-subslice-total [159.479579] Subtest basic-subslice-total: SUCCESS (0.000s) [159.549715] [052/160] (912s left) i915_hangman (error-state-basic) [159.598249] Starting subtest: error-state-basic [159.684604] Subtest error-state-basic: SUCCESS (0.087s) [159.747192] [053/160] (912s left) i915_pciid [159.863738] [054/160] (912s left) kms_addfb_basic (addfb25-4-tiled) [161.829017] Starting subtest: addfb25-4-tiled [161.830147] Subtest addfb25-4-tiled: SUCCESS (0.000s) [161.897754] [055/160] (910s left) kms_addfb_basic (addfb25-bad-modifier) [161.944297] Starting subtest: addfb25-bad-modifier [161.945313] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [163.930652] [056/160] (908s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [163.978290] Starting subtest: addfb25-framebuffer-vs-set-tiling [163.979329] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [165.963801] [057/160] (906s left) kms_addfb_basic (addfb25-modifier-no-flag) [166.009199] Starting subtest: addfb25-modifier-no-flag [166.010164] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [167.997614] [058/160] (904s left) kms_addfb_basic (addfb25-x-tiled-legacy) [168.048508] Starting subtest: addfb25-x-tiled-legacy [168.049467] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [170.028693] [059/160] (902s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [170.065946] Starting subtest: addfb25-x-tiled-mismatch-legacy [170.067043] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [172.065689] [060/160] (900s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [174.031853] Starting subtest: addfb25-yf-tiled-legacy [174.032962] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [174.117333] [061/160] (898s left) kms_addfb_basic (addfb25-y-tiled-legacy) [176.082686] Starting subtest: addfb25-y-tiled-legacy [176.083647] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [176.167336] [062/160] (896s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [178.142881] Starting subtest: addfb25-y-tiled-small-legacy [178.143771] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [178.233305] [063/160] (894s left) kms_addfb_basic (bad-pitch-0) [178.272083] Starting subtest: bad-pitch-0 [178.273031] Subtest bad-pitch-0: SUCCESS (0.000s) [180.265613] [064/160] (892s left) kms_addfb_basic (bad-pitch-1024) [180.301926] Starting subtest: bad-pitch-1024 [180.302775] Subtest bad-pitch-1024: SUCCESS (0.000s) [182.302699] [065/160] (890s left) kms_addfb_basic (bad-pitch-128) [182.344603] Starting subtest: bad-pitch-128 [182.345280] Subtest bad-pitch-128: SUCCESS (0.000s) [184.331587] [066/160] (888s left) kms_addfb_basic (bad-pitch-256) [184.367704] Starting subtest: bad-pitch-256 [184.368409] Subtest bad-pitch-256: SUCCESS (0.000s) [186.366441] [067/160] (886s left) kms_addfb_basic (bad-pitch-32) [186.407344] Starting subtest: bad-pitch-32 [186.408426] Subtest bad-pitch-32: SUCCESS (0.000s) [188.398529] [068/160] (884s left) kms_addfb_basic (bad-pitch-63) [188.449436] Starting subtest: bad-pitch-63 [188.450544] Subtest bad-pitch-63: SUCCESS (0.000s) [190.450734] [069/160] (882s left) kms_addfb_basic (bad-pitch-65536) [190.492121] Starting subtest: bad-pitch-65536 [190.493170] Subtest bad-pitch-65536: SUCCESS (0.000s) [192.483105] [070/160] (880s left) kms_addfb_basic (bad-pitch-999) [192.527055] Starting subtest: bad-pitch-999 [192.527963] Subtest bad-pitch-999: SUCCESS (0.000s) [194.515859] [071/160] (878s left) kms_addfb_basic (basic) [194.561336] Starting subtest: basic [194.562572] Subtest basic: SUCCESS (0.000s) [196.548876] [072/160] (876s left) kms_addfb_basic (basic-x-tiled-legacy) [196.595217] Starting subtest: basic-x-tiled-legacy [196.596099] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [198.593431] [073/160] (874s left) kms_addfb_basic (basic-y-tiled-legacy) [198.640284] Starting subtest: basic-y-tiled-legacy [198.641333] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [200.632751] [074/160] (871s left) kms_addfb_basic (bo-too-small) [202.597709] Starting subtest: bo-too-small [202.598511] Subtest bo-too-small: SUCCESS (0.000s) [202.665597] [075/160] (869s left) kms_addfb_basic (bo-too-small-due-to-tiling) [204.633786] Starting subtest: bo-too-small-due-to-tiling [204.634882] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [204.717533] [076/160] (867s left) kms_addfb_basic (clobberred-modifier) [204.757820] Starting subtest: clobberred-modifier [204.758518] Subtest clobberred-modifier: SUCCESS (0.000s) [206.748714] [077/160] (865s left) kms_addfb_basic (framebuffer-vs-set-tiling) [206.786946] Starting subtest: framebuffer-vs-set-tiling [206.787827] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [208.784678] [078/160] (863s left) kms_addfb_basic (invalid-get-prop) [208.831414] Starting subtest: invalid-get-prop [208.832354] Subtest invalid-get-prop: SUCCESS (0.000s) [210.815442] [079/160] (861s left) kms_addfb_basic (invalid-get-prop-any) [210.849910] Starting subtest: invalid-get-prop-any [210.850880] Subtest invalid-get-prop-any: SUCCESS (0.000s) [212.833344] [080/160] (859s left) kms_addfb_basic (invalid-set-prop) [212.873449] Starting subtest: invalid-set-prop [212.874555] Subtest invalid-set-prop: SUCCESS (0.000s) [214.870425] [081/160] (857s left) kms_addfb_basic (invalid-set-prop-any) [214.913092] Starting subtest: invalid-set-prop-any [214.914135] Subtest invalid-set-prop-any: SUCCESS (0.000s) [216.900668] [082/160] (855s left) kms_addfb_basic (no-handle) [216.949324] Starting subtest: no-handle [216.950493] Subtest no-handle: SUCCESS (0.000s) [218.932844] [083/160] (853s left) kms_addfb_basic (size-max) [220.907822] Starting subtest: size-max [220.908983] Subtest size-max: SUCCESS (0.000s) [220.984869] [084/160] (851s left) kms_addfb_basic (small-bo) [222.950873] Starting subtest: small-bo [222.951949] Subtest small-bo: SUCCESS (0.000s) [223.017251] [085/160] (849s left) kms_addfb_basic (tile-pitch-mismatch) [223.055272] Starting subtest: tile-pitch-mismatch [223.056310] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [225.032108] [086/160] (847s left) kms_addfb_basic (too-high) [226.987730] Starting subtest: too-high [226.988761] Subtest too-high: SUCCESS (0.000s) [227.052877] [087/160] (845s left) kms_addfb_basic (too-wide) [229.018757] Starting subtest: too-wide [229.019854] Subtest too-wide: SUCCESS (0.000s) [229.084622] [088/160] (843s left) kms_addfb_basic (unused-handle) [229.127233] Starting subtest: unused-handle [229.128252] Subtest unused-handle: SUCCESS (0.000s) [231.117543] [089/160] (841s left) kms_addfb_basic (unused-modifier) [231.156063] Starting subtest: unused-modifier [231.156908] Subtest unused-modifier: SUCCESS (0.000s) [233.157393] [090/160] (839s left) kms_addfb_basic (unused-offsets) [233.202136] Starting subtest: unused-offsets [233.203216] Subtest unused-offsets: SUCCESS (0.000s) [235.185538] [091/160] (837s left) kms_addfb_basic (unused-pitches) [235.231489] Starting subtest: unused-pitches [235.232433] Subtest unused-pitches: SUCCESS (0.000s) [237.217661] [092/160] (835s left) kms_busy (basic) [239.192843] Starting subtest: basic [239.193889] Starting dynamic subtest: flip [239.758561] Dynamic subtest flip: SUCCESS (0.565s) [239.762815] Starting dynamic subtest: modeset [240.530820] Dynamic subtest modeset: SUCCESS (0.771s) [240.531658] Subtest basic: SUCCESS (1.337s) [240.690737] [093/160] (832s left) kms_prop_blob (basic) [240.730853] Starting subtest: basic [240.731848] Subtest basic: SUCCESS (0.000s) [240.825076] [094/160] (832s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [242.797943] Starting subtest: basic-busy-flip-before-cursor-atomic [242.800210] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [242.853589] [095/160] (830s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [244.829017] Starting subtest: basic-busy-flip-before-cursor-legacy [244.831098] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [244.886486] [096/160] (827s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [246.850975] Starting subtest: basic-flip-after-cursor-atomic [247.839086] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.980s) [248.001456] [097/160] (824s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [249.997263] Starting subtest: basic-flip-after-cursor-legacy [250.971959] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.984s) [251.129130] [098/160] (821s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [253.102017] Starting subtest: basic-flip-after-cursor-varying-size [254.068196] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.966s) [254.237894] [099/160] (818s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [256.194895] Starting subtest: basic-flip-before-cursor-atomic [257.161286] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.966s) [257.322225] [100/160] (815s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [259.292034] Starting subtest: basic-flip-before-cursor-legacy [260.277619] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.986s) [260.442698] [101/160] (812s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [262.408770] Starting subtest: basic-flip-before-cursor-varying-size [263.400146] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.990s) [263.552295] [102/160] (809s left) kms_dsc (dsc-basic) [265.516998] Starting subtest: dsc-basic [265.519066] Subtest dsc-basic: SKIP (0.000s) [265.585640] [103/160] (807s left) kms_flip (basic-flip-vs-dpms) [267.555542] Starting subtest: basic-flip-vs-dpms [267.557201] Starting dynamic subtest: A-DP1 [268.436949] Dynamic subtest A-DP1: SUCCESS (0.879s) [268.441498] Starting dynamic subtest: B-DP1 [269.194956] Dynamic subtest B-DP1: SUCCESS (0.758s) [269.199567] Starting dynamic subtest: C-DP1 [269.952528] Dynamic subtest C-DP1: SUCCESS (0.757s) [269.957011] Starting dynamic subtest: D-DP1 [270.706154] Dynamic subtest D-DP1: SUCCESS (0.753s) [270.708263] Subtest basic-flip-vs-dpms: SUCCESS (3.153s) [270.890993] [104/160] (802s left) kms_flip (basic-flip-vs-modeset) [272.860380] Starting subtest: basic-flip-vs-modeset [272.862369] Starting dynamic subtest: A-DP1 [273.754938] Dynamic subtest A-DP1: SUCCESS (0.892s) [273.759275] Starting dynamic subtest: B-DP1 [274.513420] Dynamic subtest B-DP1: SUCCESS (0.758s) [274.517924] Starting dynamic subtest: C-DP1 [275.261324] Dynamic subtest C-DP1: SUCCESS (0.748s) [275.265921] Starting dynamic subtest: D-DP1 [276.021316] Dynamic subtest D-DP1: SUCCESS (0.759s) [276.022182] Subtest basic-flip-vs-modeset: SUCCESS (3.162s) [276.175205] [105/160] (796s left) kms_flip (basic-flip-vs-wf_vblank) [278.153309] Starting subtest: basic-flip-vs-wf_vblank [278.155469] Starting dynamic subtest: A-DP1 [279.222464] Dynamic subtest A-DP1: SUCCESS (1.066s) [279.234387] Starting dynamic subtest: B-DP1 [280.238171] Dynamic subtest B-DP1: SUCCESS (1.013s) [280.246476] Starting dynamic subtest: C-DP1 [281.252328] Dynamic subtest C-DP1: SUCCESS (1.011s) [281.261392] Starting dynamic subtest: D-DP1 [282.265966] Dynamic subtest D-DP1: SUCCESS (1.013s) [282.271227] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.118s) [282.490355] [106/160] (790s left) kms_flip (basic-plain-flip) [284.461169] Starting subtest: basic-plain-flip [284.463798] Starting dynamic subtest: A-DP1 [285.254591] Dynamic subtest A-DP1: SUCCESS (0.790s) [285.267591] Starting dynamic subtest: B-DP1 [285.988216] Dynamic subtest B-DP1: SUCCESS (0.728s) [285.995800] Starting dynamic subtest: C-DP1 [286.717903] Dynamic subtest C-DP1: SUCCESS (0.728s) [286.731300] Starting dynamic subtest: D-DP1 [287.468827] Dynamic subtest D-DP1: SUCCESS (0.747s) [287.471993] Subtest basic-plain-flip: SUCCESS (3.011s) [287.690713] [107/160] (785s left) kms_force_connector_basic (force-connector-state) [287.767575] Starting subtest: force-connector-state [289.887995] Subtest force-connector-state: SUCCESS (2.120s) [292.045580] [108/160] (780s left) kms_force_connector_basic (force-edid) [292.109015] Starting subtest: force-edid [292.159014] Subtest force-edid: SUCCESS (0.050s) [294.246282] [109/160] (778s left) kms_force_connector_basic (force-load-detect) [294.313407] Starting subtest: force-load-detect [294.314868] Subtest force-load-detect: SKIP (0.000s) [295.380604] [110/160] (777s left) kms_force_connector_basic (prune-stale-modes) [295.442109] Starting subtest: prune-stale-modes [295.489917] Subtest prune-stale-modes: SUCCESS (0.048s) [297.546551] [111/160] (775s left) kms_frontbuffer_tracking (basic) [299.520120] Starting subtest: basic [302.963579] Subtest basic: SUCCESS (3.444s) [303.067820] [112/160] (769s left) kms_hdmi_inject (inject-audio) [303.190082] Starting subtest: inject-audio [303.322017] Subtest inject-audio: SUCCESS (0.133s) [304.524654] [113/160] (768s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [306.485913] Starting subtest: compare-crc-sanitycheck-xr24 [306.486950] Starting dynamic subtest: pipe-A-DP-1 [306.897144] Dynamic subtest pipe-A-DP-1: SUCCESS (0.410s) [306.901945] Starting dynamic subtest: pipe-B-DP-1 [307.294427] Dynamic subtest pipe-B-DP-1: SUCCESS (0.395s) [307.299493] Starting dynamic subtest: pipe-C-DP-1 [307.695287] Dynamic subtest pipe-C-DP-1: SUCCESS (0.400s) [307.700050] Starting dynamic subtest: pipe-D-DP-1 [308.096135] Dynamic subtest pipe-D-DP-1: SUCCESS (0.399s) [308.096972] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.609s) [308.253330] [114/160] (764s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [310.215950] Starting subtest: compare-crc-sanitycheck-nv12 [310.217268] Starting dynamic subtest: pipe-A-DP-1 [310.793897] Dynamic subtest pipe-A-DP-1: SUCCESS (0.576s) [310.798156] Starting dynamic subtest: pipe-B-DP-1 [311.334716] Dynamic subtest pipe-B-DP-1: SUCCESS (0.541s) [311.340890] Starting dynamic subtest: pipe-C-DP-1 [311.877054] Dynamic subtest pipe-C-DP-1: SUCCESS (0.540s) [311.881675] Starting dynamic subtest: pipe-D-DP-1 [312.434999] Dynamic subtest pipe-D-DP-1: SUCCESS (0.557s) [312.435841] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.219s) [312.607231] [115/160] (760s left) kms_pipe_crc_basic (hang-read-crc) [314.569017] Starting subtest: hang-read-crc [314.570179] Starting dynamic subtest: pipe-A-DP-1 [315.740763] Dynamic subtest pipe-A-DP-1: SUCCESS (1.170s) [315.745461] Starting dynamic subtest: pipe-B-DP-1 [316.853016] Dynamic subtest pipe-B-DP-1: SUCCESS (1.109s) [316.857787] Starting dynamic subtest: pipe-C-DP-1 [317.973833] Dynamic subtest pipe-C-DP-1: SUCCESS (1.121s) [317.978585] Starting dynamic subtest: pipe-D-DP-1 [319.071211] Dynamic subtest pipe-D-DP-1: SUCCESS (1.097s) [319.072153] Subtest hang-read-crc: SUCCESS (4.502s) [319.218355] [116/160] (753s left) kms_pipe_crc_basic (nonblocking-crc) [321.182985] Starting subtest: nonblocking-crc [321.184178] Starting dynamic subtest: pipe-A-DP-1 [321.785015] Dynamic subtest pipe-A-DP-1: SUCCESS (0.601s) [321.790039] Starting dynamic subtest: pipe-B-DP-1 [322.362070] Dynamic subtest pipe-B-DP-1: SUCCESS (0.576s) [322.366937] Starting dynamic subtest: pipe-C-DP-1 [322.924034] Dynamic subtest pipe-C-DP-1: SUCCESS (0.561s) [322.928775] Starting dynamic subtest: pipe-D-DP-1 [323.506886] Dynamic subtest pipe-D-DP-1: SUCCESS (0.581s) [323.507914] Subtest nonblocking-crc: SUCCESS (2.323s) [323.651668] [117/160] (749s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [325.624989] Starting subtest: nonblocking-crc-frame-sequence [325.626100] Starting dynamic subtest: pipe-A-DP-1 [326.239173] Dynamic subtest pipe-A-DP-1: SUCCESS (0.613s) [326.246707] Starting dynamic subtest: pipe-B-DP-1 [326.817145] Dynamic subtest pipe-B-DP-1: SUCCESS (0.577s) [326.821783] Starting dynamic subtest: pipe-C-DP-1 [327.378822] Dynamic subtest pipe-C-DP-1: SUCCESS (0.560s) [327.383643] Starting dynamic subtest: pipe-D-DP-1 [327.959945] Dynamic subtest pipe-D-DP-1: SUCCESS (0.579s) [327.960705] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.334s) [328.105495] [118/160] (745s left) kms_pipe_crc_basic (read-crc) [330.073013] Starting subtest: read-crc [330.074141] Starting dynamic subtest: pipe-A-DP-1 [330.627572] Dynamic subtest pipe-A-DP-1: SUCCESS (0.553s) [330.632533] Starting dynamic subtest: pipe-B-DP-1 [331.119223] Dynamic subtest pipe-B-DP-1: SUCCESS (0.491s) [331.124049] Starting dynamic subtest: pipe-C-DP-1 [331.627237] Dynamic subtest pipe-C-DP-1: SUCCESS (0.507s) [331.632956] Starting dynamic subtest: pipe-D-DP-1 [332.124434] Dynamic subtest pipe-D-DP-1: SUCCESS (0.495s) [332.125167] Subtest read-crc: SUCCESS (2.051s) [332.269129] [119/160] (740s left) kms_pipe_crc_basic (read-crc-frame-sequence) [334.229775] Starting subtest: read-crc-frame-sequence [334.230704] Starting dynamic subtest: pipe-A-DP-1 [334.773569] Dynamic subtest pipe-A-DP-1: SUCCESS (0.543s) [334.779141] Starting dynamic subtest: pipe-B-DP-1 [335.285605] Dynamic subtest pipe-B-DP-1: SUCCESS (0.510s) [335.299139] Starting dynamic subtest: pipe-C-DP-1 [335.796393] Dynamic subtest pipe-C-DP-1: SUCCESS (0.510s) [335.807949] Starting dynamic subtest: pipe-D-DP-1 [336.294034] Dynamic subtest pipe-D-DP-1: SUCCESS (0.496s) [336.294839] Subtest read-crc-frame-sequence: SUCCESS (2.064s) [336.447332] [120/160] (736s left) kms_pm_backlight (basic-brightness) [338.436650] Subtest basic-brightness: SKIP (0.000s) [338.455926] [121/160] (734s left) kms_pm_rpm (basic-pci-d3-state) [342.597770] Starting subtest: basic-pci-d3-state [345.376599] Subtest basic-pci-d3-state: SUCCESS (2.778s) [346.461969] [122/160] (726s left) kms_pm_rpm (basic-rte) [346.492687] Starting subtest: basic-rte [352.823938] Subtest basic-rte: SUCCESS (6.331s) [356.105658] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 1797 [max: 8192] direct dependencies: 18350 [max: 32768] indirect dependencies: 136545 all direct dependencies: 364606 dependency chains: 27129 [max: 131072] dependency chain hlocks used: 114731 [max: 655360] dependency chain hlocks lost: 0 in-hardirq chains: 269 in-softirq chains: 609 in-process chains: 26251 stack-trace entries: 175088 [max: 1048576] number of stack traces: 8788 number of stack hash chains: 6807 combined max dependencies: 28737104 hardirq-safe locks: 98 hardirq-unsafe locks: 1072 softirq-safe locks: 211 softirq-unsafe locks: 980 irq-safe locks: 225 irq-unsafe locks: 1072 hardirq-read-safe locks: 3 hardirq-read-unsafe locks: 326 softirq-read-safe locks: 7 softirq-read-unsafe locks: 321 irq-read-safe locks: 7 irq-read-unsafe locks: 326 uncategorized locks: 290 unused locks: 1 max locking depth: 15 max bfs queue depth: 429 max lock class index: 1796 chain lookup misses: 27565 chain lookup hits: 444778687 cyclic checks: 27710 redundant checks: 0 redundant links: 0 find-mask forwards checks: 4605 find-mask backwards checks: 2701 hardirq on events: 209667584 hardirq off events: 209667568 redundant hardirq ons: 32 redundant hardirq offs: 27 softirq on events: 1182958 softirq off events: 1182958 redundant softirq ons: 0 redundant softirq offs: 0 debug_locks: 0 zapped classes: 1 zapped lock chains: 106 large chain blocks: 1 [356.107203] Closing watchdogs Done.