[39.768556] Initializing watchdogs [39.768637] /dev/watchdog0 [39.773249] [001/165] (960s left) i915_module_load (load) [39.837431] Starting subtest: load [43.024641] Subtest load: SUCCESS (3.186s) [43.036608] [002/165] (957s left) core_auth (basic-auth) [43.104191] Starting subtest: basic-auth [43.104821] Subtest basic-auth: SUCCESS (0.001s) [43.161727] [003/165] (957s left) debugfs_test (read_all_entries) [43.218935] Starting subtest: read_all_entries [43.918911] Subtest read_all_entries: SUCCESS (0.699s) [44.453253] [004/165] (955s left) debugfs_test (basic-hwmon) [44.525255] Starting subtest: basic-hwmon [44.526316] Subtest basic-hwmon: SKIP (0.000s) [45.030995] [005/165] (955s left) fbdev (eof) [45.107292] Starting subtest: eof [45.107888] Subtest eof: SUCCESS (0.001s) [45.159716] [006/165] (955s left) fbdev (info) [45.232570] Starting subtest: info [45.233108] Subtest info: SUCCESS (0.000s) [45.299870] [007/165] (955s left) fbdev (nullptr) [45.373545] Starting subtest: nullptr [45.374230] Subtest nullptr: SUCCESS (0.000s) [45.420780] [008/165] (954s left) fbdev (read) [45.490340] Starting subtest: read [45.732812] Subtest read: SUCCESS (0.243s) [45.777527] [009/165] (954s left) fbdev (write) [45.840181] Starting subtest: write [45.964193] Subtest write: SUCCESS (0.124s) [46.011681] [010/165] (954s left) gem_basic (bad-close) [46.063428] Starting subtest: bad-close [46.064786] Subtest bad-close: SUCCESS (0.000s) [46.129961] [011/165] (954s left) gem_basic (create-close) [46.187868] Starting subtest: create-close [46.188847] Subtest create-close: SUCCESS (0.000s) [46.270301] [012/165] (954s left) gem_basic (create-fd-close) [46.342280] Starting subtest: create-fd-close [46.343568] Subtest create-fd-close: SUCCESS (0.000s) [46.438062] [013/165] (954s left) gem_busy (busy@all-engines) [46.530906] Starting subtest: busy [46.531522] Starting dynamic subtest: all-engines [46.588311] Dynamic subtest all-engines: SUCCESS (0.057s) [46.588935] Subtest busy: SUCCESS (0.058s) [46.986125] [014/165] (953s left) gem_close_race (basic-process) [47.068605] Starting subtest: basic-process [47.123127] Subtest basic-process: SUCCESS (0.054s) [47.336665] [015/165] (953s left) gem_close_race (basic-threads) [47.421389] Starting subtest: basic-threads [48.700366] Subtest basic-threads: SUCCESS (1.279s) [48.931247] [016/165] (951s left) gem_ctx_create (basic) [49.047832] Starting subtest: basic [49.048658] Subtest basic: SUCCESS (0.000s) [49.253866] [017/165] (951s left) gem_ctx_create (basic-files) [49.320287] Starting subtest: basic-files [51.336241] Subtest basic-files: SUCCESS (2.016s) [51.568885] [018/165] (949s left) gem_ctx_exec (basic) [51.635850] Starting subtest: basic [51.638807] Subtest basic: SUCCESS (0.003s) [51.777616] [019/165] (948s left) gem_exec_basic (basic) [51.855902] Starting subtest: basic [51.856499] Starting dynamic subtest: rcs0-smem [51.857823] Dynamic subtest rcs0-smem: SUCCESS (0.001s) [51.864453] Starting dynamic subtest: bcs0-smem [51.865040] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [51.871050] Starting dynamic subtest: vcs0-smem [51.871788] Dynamic subtest vcs0-smem: SUCCESS (0.000s) [51.876968] Starting dynamic subtest: vcs1-smem [51.877866] Dynamic subtest vcs1-smem: SUCCESS (0.000s) [51.882690] Starting dynamic subtest: vecs0-smem [51.883379] Dynamic subtest vecs0-smem: SUCCESS (0.000s) [51.884064] Subtest basic: SUCCESS (0.004s) [52.093217] [020/165] (948s left) gem_exec_create (basic) [52.157625] Starting subtest: basic [52.158189] Starting dynamic subtest: smem [54.198091] Dynamic subtest smem: SUCCESS (2.040s) [54.198641] Subtest basic: SUCCESS (2.040s) [54.402775] [021/165] (946s left) gem_exec_fence (basic-busy) [54.610807] Starting subtest: basic-busy [54.611760] Starting dynamic subtest: rcs0 [54.635453] Dynamic subtest rcs0: SUCCESS (0.024s) [54.647402] Starting dynamic subtest: bcs0 [54.660307] Dynamic subtest bcs0: SUCCESS (0.024s) [54.671702] Starting dynamic subtest: vcs0 [54.686364] Dynamic subtest vcs0: SUCCESS (0.026s) [54.697420] Starting dynamic subtest: vcs1 [54.715491] Dynamic subtest vcs1: SUCCESS (0.029s) [54.727904] Starting dynamic subtest: vecs0 [54.738269] Dynamic subtest vecs0: SUCCESS (0.023s) [54.739352] Subtest basic-busy: SUCCESS (0.128s) [55.421635] [022/165] (945s left) gem_exec_fence (basic-wait) [55.628318] Starting subtest: basic-wait [55.630137] Starting dynamic subtest: rcs0 [55.665798] Dynamic subtest rcs0: SUCCESS (0.038s) [55.683360] Starting dynamic subtest: bcs0 [55.696901] Dynamic subtest bcs0: SUCCESS (0.029s) [55.707357] Starting dynamic subtest: vcs0 [55.720149] Dynamic subtest vcs0: SUCCESS (0.025s) [55.727644] Starting dynamic subtest: vcs1 [55.744918] Dynamic subtest vcs1: SUCCESS (0.025s) [55.752469] Starting dynamic subtest: vecs0 [55.766855] Dynamic subtest vecs0: SUCCESS (0.022s) [55.767632] Subtest basic-wait: SUCCESS (0.140s) [56.402971] [023/165] (944s left) gem_exec_fence (basic-await) [56.621312] Starting subtest: basic-await [56.622808] Starting dynamic subtest: rcs0 [56.724952] Dynamic subtest rcs0: SUCCESS (0.106s) [56.732213] Starting dynamic subtest: bcs0 [56.822594] Dynamic subtest bcs0: SUCCESS (0.097s) [56.830755] Starting dynamic subtest: vcs0 [56.926745] Dynamic subtest vcs0: SUCCESS (0.104s) [56.934767] Starting dynamic subtest: vcs1 [57.038223] Dynamic subtest vcs1: SUCCESS (0.108s) [57.050088] Starting dynamic subtest: vecs0 [57.163174] Dynamic subtest vecs0: SUCCESS (0.128s) [57.164080] Subtest basic-await: SUCCESS (0.545s) [57.842383] [024/165] (942s left) gem_exec_fence (nb-await) [58.064115] Starting subtest: nb-await [58.065941] Starting dynamic subtest: rcs0 [58.142896] Dynamic subtest rcs0: SUCCESS (0.076s) [58.163293] Starting dynamic subtest: bcs0 [58.222361] Dynamic subtest bcs0: SUCCESS (0.079s) [58.248483] Starting dynamic subtest: vcs0 [58.307976] Dynamic subtest vcs0: SUCCESS (0.084s) [58.334414] Starting dynamic subtest: vcs1 [58.396427] Dynamic subtest vcs1: SUCCESS (0.089s) [58.423674] Starting dynamic subtest: vecs0 [58.482353] Dynamic subtest vecs0: SUCCESS (0.086s) [58.484450] Subtest nb-await: SUCCESS (0.418s) [59.157848] [025/165] (941s left) gem_exec_gttfill (basic) [59.241960] Starting subtest: basic [67.401559] Subtest basic: SUCCESS (8.160s) [67.573265] [026/165] (933s left) gem_exec_parallel (engines) [67.710036] Starting subtest: engines [67.710628] Starting dynamic subtest: basic [69.992763] Dynamic subtest basic: SUCCESS (2.282s) [70.000149] Starting dynamic subtest: contexts [72.680355] Dynamic subtest contexts: SUCCESS (2.688s) [72.686466] Starting dynamic subtest: fds [77.675549] Dynamic subtest fds: SUCCESS (4.996s) [77.682889] Starting dynamic subtest: userptr [79.586651] Dynamic subtest userptr: SUCCESS (1.911s) [79.587168] Subtest engines: SUCCESS (11.877s) [79.793044] [027/165] (921s left) gem_exec_store (basic) [79.861809] Starting subtest: basic [79.918150] Subtest basic: SUCCESS (0.056s) [80.120307] [028/165] (920s left) gem_flink_basic (bad-flink) [80.194500] Starting subtest: bad-flink [80.195062] Subtest bad-flink: SUCCESS (0.000s) [80.255916] [029/165] (920s left) gem_flink_basic (bad-open) [80.309944] Starting subtest: bad-open [80.310655] Subtest bad-open: SUCCESS (0.000s) [80.373819] [030/165] (920s left) gem_flink_basic (basic) [80.436226] Starting subtest: basic [80.437035] Subtest basic: SUCCESS (0.000s) [80.522358] [031/165] (920s left) gem_flink_basic (double-flink) [80.587622] Starting subtest: double-flink [80.588068] Subtest double-flink: SUCCESS (0.000s) [80.665459] [032/165] (920s left) gem_flink_basic (flink-lifetime) [80.727507] Starting subtest: flink-lifetime [80.728128] Subtest flink-lifetime: SUCCESS (0.001s) [80.819101] [033/165] (920s left) gem_huc_copy (huc-copy) [80.903065] Starting subtest: huc-copy [80.904191] Subtest huc-copy: SKIP (0.000s) [81.052851] [034/165] (919s left) gem_linear_blits (basic) [81.232083] Starting subtest: basic [81.241849] Subtest basic: SUCCESS (0.009s) [81.387869] [035/165] (919s left) gem_mmap (basic) [81.559554] Starting subtest: basic [81.562561] Subtest basic: SUCCESS (0.000s) [81.658239] [036/165] (919s left) gem_mmap_gtt (basic) [81.736272] Starting subtest: basic [81.737155] Subtest basic: SUCCESS (0.001s) [81.788192] [037/165] (919s left) gem_render_linear_blits (basic) [81.843096] Starting subtest: basic [81.874815] Subtest basic: SUCCESS (0.031s) [81.914353] [038/165] (919s left) gem_render_tiled_blits (basic) [81.969609] Starting subtest: basic [82.005858] Subtest basic: SUCCESS (0.036s) [82.038124] [039/165] (919s left) gem_ringfill (basic-all) [82.334812] Starting subtest: basic-all [83.498474] Subtest basic-all: SUCCESS (1.164s) [83.593886] [040/165] (917s left) gem_softpin (allocator-basic) [83.663265] Starting subtest: allocator-basic [83.768014] Subtest allocator-basic: SUCCESS (0.102s) [83.867542] [041/165] (917s left) gem_softpin (allocator-basic-reserve) [83.940322] Starting subtest: allocator-basic-reserve [84.039744] Subtest allocator-basic-reserve: SUCCESS (0.099s) [84.139975] [042/165] (917s left) gem_softpin (safe-alignment) [84.210662] Starting subtest: safe-alignment [84.224154] Subtest safe-alignment: SUCCESS (0.013s) [84.331305] [043/165] (916s left) gem_sync (basic-all) [84.398841] Starting subtest: basic-all [86.493946] Subtest basic-all: SUCCESS (2.095s) [86.705180] [044/165] (914s left) gem_sync (basic-each) [86.793113] Starting subtest: basic-each [88.973056] Subtest basic-each: SUCCESS (2.182s) [89.201838] [045/165] (912s left) gem_tiled_blits (basic) [89.423131] Starting subtest: basic [89.439914] Subtest basic: SUCCESS (0.018s) [89.595871] [046/165] (911s left) gem_tiled_fence_blits (basic) [89.860988] Starting subtest: basic [89.869971] Subtest basic: SUCCESS (0.010s) [90.032553] [047/165] (911s left) gem_tiled_pread_basic [90.381701] [048/165] (911s left) gem_wait (busy@all-engines) [90.464786] Starting subtest: busy [90.465291] Starting dynamic subtest: all-engines [90.990791] Dynamic subtest all-engines: SUCCESS (0.526s) [90.991407] Subtest busy: SUCCESS (0.526s) [91.214907] [049/165] (910s left) gem_wait (wait@all-engines) [91.304207] Starting subtest: wait [91.305032] Starting dynamic subtest: all-engines [92.338131] Dynamic subtest all-engines: SUCCESS (1.032s) [92.338692] Subtest wait: SUCCESS (1.032s) [92.612772] [050/165] (908s left) i915_getparams_basic (basic-eu-total) [92.669231] Starting subtest: basic-eu-total [92.670433] Subtest basic-eu-total: SUCCESS (0.000s) [92.732071] [051/165] (908s left) i915_getparams_basic (basic-subslice-total) [92.791555] Starting subtest: basic-subslice-total [92.792754] Subtest basic-subslice-total: SUCCESS (0.000s) [92.856102] [052/165] (908s left) i915_hangman (error-state-basic) [92.921338] Starting subtest: error-state-basic [92.927970] Subtest error-state-basic: SUCCESS (0.006s) [92.996747] [053/165] (908s left) i915_pciid [93.116339] [054/165] (908s left) kms_addfb_basic (addfb25-4-tiled) [93.594026] Starting subtest: addfb25-4-tiled [93.594963] Subtest addfb25-4-tiled: SUCCESS (0.000s) [93.668098] [055/165] (907s left) kms_addfb_basic (addfb25-bad-modifier) [93.722757] Starting subtest: addfb25-bad-modifier [93.723314] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [94.221953] [056/165] (907s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [94.276951] Starting subtest: addfb25-framebuffer-vs-set-tiling [94.280112] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [94.755584] [057/165] (906s left) kms_addfb_basic (addfb25-modifier-no-flag) [94.808360] Starting subtest: addfb25-modifier-no-flag [94.808963] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [95.297578] [058/165] (906s left) kms_addfb_basic (addfb25-x-tiled-legacy) [95.351195] Starting subtest: addfb25-x-tiled-legacy [95.351778] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [95.837949] [059/165] (905s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [95.891904] Starting subtest: addfb25-x-tiled-mismatch-legacy [95.892522] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [96.371435] [060/165] (905s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [96.838473] Starting subtest: addfb25-yf-tiled-legacy [96.839408] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [96.922105] [061/165] (904s left) kms_addfb_basic (addfb25-y-tiled-legacy) [97.397411] Starting subtest: addfb25-y-tiled-legacy [97.398348] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [97.480595] [062/165] (904s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [97.951403] Starting subtest: addfb25-y-tiled-small-legacy [97.952471] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s) [98.035575] [063/165] (903s left) kms_addfb_basic (bad-pitch-0) [98.090328] Starting subtest: bad-pitch-0 [98.090952] Subtest bad-pitch-0: SUCCESS (0.000s) [98.596639] [064/165] (903s left) kms_addfb_basic (bad-pitch-1024) [98.651730] Starting subtest: bad-pitch-1024 [98.652331] Subtest bad-pitch-1024: SUCCESS (0.000s) [99.142376] [065/165] (902s left) kms_addfb_basic (bad-pitch-128) [99.195997] Starting subtest: bad-pitch-128 [99.196620] Subtest bad-pitch-128: SUCCESS (0.000s) [99.698439] [066/165] (902s left) kms_addfb_basic (bad-pitch-256) [99.751674] Starting subtest: bad-pitch-256 [99.752112] Subtest bad-pitch-256: SUCCESS (0.000s) [100.244820] [067/165] (901s left) kms_addfb_basic (bad-pitch-32) [100.310611] Starting subtest: bad-pitch-32 [100.311260] Subtest bad-pitch-32: SUCCESS (0.000s) [100.822779] [068/165] (900s left) kms_addfb_basic (bad-pitch-63) [100.878285] Starting subtest: bad-pitch-63 [100.878887] Subtest bad-pitch-63: SUCCESS (0.000s) [101.381615] [069/165] (900s left) kms_addfb_basic (bad-pitch-65536) [101.433323] Starting subtest: bad-pitch-65536 [101.433828] Subtest bad-pitch-65536: SUCCESS (0.000s) [101.922870] [070/165] (899s left) kms_addfb_basic (bad-pitch-999) [101.978136] Starting subtest: bad-pitch-999 [101.978657] Subtest bad-pitch-999: SUCCESS (0.000s) [102.479286] [071/165] (899s left) kms_addfb_basic (basic) [102.532817] Starting subtest: basic [102.533479] Subtest basic: SUCCESS (0.000s) [103.029497] [072/165] (898s left) kms_addfb_basic (basic-x-tiled-legacy) [103.083175] Starting subtest: basic-x-tiled-legacy [103.083781] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [103.573214] [073/165] (898s left) kms_addfb_basic (basic-y-tiled-legacy) [103.626302] Starting subtest: basic-y-tiled-legacy [103.626953] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [104.128265] [074/165] (897s left) kms_addfb_basic (bo-too-small) [104.602416] Starting subtest: bo-too-small [104.603098] Subtest bo-too-small: SUCCESS (0.000s) [104.684750] [075/165] (897s left) kms_addfb_basic (bo-too-small-due-to-tiling) [105.145368] Starting subtest: bo-too-small-due-to-tiling [105.146416] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s) [105.218977] [076/165] (896s left) kms_addfb_basic (clobberred-modifier) [105.272713] Starting subtest: clobberred-modifier [105.273355] Subtest clobberred-modifier: SUCCESS (0.000s) [105.782533] [077/165] (896s left) kms_addfb_basic (framebuffer-vs-set-tiling) [105.837695] Starting subtest: framebuffer-vs-set-tiling [105.838324] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [106.328629] [078/165] (895s left) kms_addfb_basic (invalid-get-prop) [106.383537] Starting subtest: invalid-get-prop [106.384150] Subtest invalid-get-prop: SUCCESS (0.000s) [106.874981] [079/165] (895s left) kms_addfb_basic (invalid-get-prop-any) [106.928583] Starting subtest: invalid-get-prop-any [106.929162] Subtest invalid-get-prop-any: SUCCESS (0.000s) [107.438619] [080/165] (894s left) kms_addfb_basic (invalid-set-prop) [107.492255] Starting subtest: invalid-set-prop [107.492861] Subtest invalid-set-prop: SUCCESS (0.000s) [107.998072] [081/165] (893s left) kms_addfb_basic (invalid-set-prop-any) [108.053674] Starting subtest: invalid-set-prop-any [108.054265] Subtest invalid-set-prop-any: SUCCESS (0.000s) [108.548940] [082/165] (893s left) kms_addfb_basic (no-handle) [108.607135] Starting subtest: no-handle [108.607730] Subtest no-handle: SUCCESS (0.000s) [109.111326] [083/165] (892s left) kms_addfb_basic (size-max) [109.588029] Starting subtest: size-max [109.588660] Subtest size-max: SUCCESS (0.000s) [109.662435] [084/165] (892s left) kms_addfb_basic (small-bo) [110.124984] Starting subtest: small-bo [110.125877] Subtest small-bo: SUCCESS (0.000s) [110.204878] [085/165] (891s left) kms_addfb_basic (tile-pitch-mismatch) [110.260302] Starting subtest: tile-pitch-mismatch [110.260982] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [110.756053] [086/165] (891s left) kms_addfb_basic (too-high) [111.225451] Starting subtest: too-high [111.226500] Subtest too-high: SUCCESS (0.001s) [111.302153] [087/165] (890s left) kms_addfb_basic (too-wide) [111.786638] Starting subtest: too-wide [111.787351] Subtest too-wide: SUCCESS (0.000s) [111.864439] [088/165] (890s left) kms_addfb_basic (unused-handle) [111.920305] Starting subtest: unused-handle [111.920984] Subtest unused-handle: SUCCESS (0.000s) [112.422511] [089/165] (889s left) kms_addfb_basic (unused-modifier) [112.475362] Starting subtest: unused-modifier [112.476011] Subtest unused-modifier: SUCCESS (0.000s) [112.975527] [090/165] (889s left) kms_addfb_basic (unused-offsets) [113.028264] Starting subtest: unused-offsets [113.028878] Subtest unused-offsets: SUCCESS (0.000s) [113.523353] [091/165] (888s left) kms_addfb_basic (unused-pitches) [113.577711] Starting subtest: unused-pitches [113.578878] Subtest unused-pitches: SUCCESS (0.000s) [114.065660] [092/165] (888s left) kms_busy (basic) [114.550850] Starting subtest: basic [114.553345] Starting dynamic subtest: flip [116.125793] Dynamic subtest flip: SUCCESS (1.571s) [116.132458] Starting dynamic subtest: modeset [117.210994] Dynamic subtest modeset: SUCCESS (1.082s) [117.211949] Subtest basic: SUCCESS (2.659s) [117.768458] [093/165] (884s left) kms_prop_blob (basic) [117.835949] Starting subtest: basic [117.836474] Subtest basic: SUCCESS (0.000s) [117.906410] [094/165] (884s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [118.410616] Starting subtest: basic-busy-flip-before-cursor-atomic [118.411822] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [118.469906] [095/165] (883s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [118.944921] Starting subtest: basic-busy-flip-before-cursor-legacy [118.946231] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [119.019491] [096/165] (883s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [119.483839] Starting subtest: basic-flip-after-cursor-atomic [120.858332] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.375s) [121.385630] [097/165] (880s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [121.859152] Starting subtest: basic-flip-after-cursor-legacy [123.416590] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.558s) [123.973731] [098/165] (878s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [124.446172] Starting subtest: basic-flip-after-cursor-varying-size [125.867584] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.421s) [126.415094] [099/165] (875s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [126.891474] Starting subtest: basic-flip-before-cursor-atomic [128.328571] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.436s) [128.857304] [100/165] (873s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [129.331431] Starting subtest: basic-flip-before-cursor-legacy [130.874479] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.544s) [131.410271] [101/165] (870s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [131.874506] Starting subtest: basic-flip-before-cursor-varying-size [133.206280] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.330s) [133.753768] [102/165] (868s left) kms_dsc (dsc-basic) [134.240240] Subtest dsc-basic: SKIP (0.000s) [134.285518] [103/165] (868s left) kms_flip (basic-flip-vs-dpms) [134.778718] Starting subtest: basic-flip-vs-dpms [134.780175] Starting dynamic subtest: A-DP1 [136.343768] Dynamic subtest A-DP1: SUCCESS (1.562s) [136.367268] Starting dynamic subtest: B-DP1 [137.313284] Dynamic subtest B-DP1: SUCCESS (0.962s) [137.335843] Starting dynamic subtest: C-DP1 [138.375963] Dynamic subtest C-DP1: SUCCESS (1.057s) [138.390872] Starting dynamic subtest: A-DP3 [139.632188] Dynamic subtest A-DP3: SUCCESS (1.250s) [139.681969] Starting dynamic subtest: B-DP3 [141.602078] Dynamic subtest B-DP3: SUCCESS (1.957s) [141.645472] Starting dynamic subtest: C-DP3 [142.913517] Dynamic subtest C-DP3: SUCCESS (1.297s) [142.914916] Subtest basic-flip-vs-dpms: SUCCESS (8.137s) [143.576297] [104/165] (858s left) kms_flip (basic-flip-vs-modeset) [144.067581] Starting subtest: basic-flip-vs-modeset [144.069720] Starting dynamic subtest: A-DP1 [145.370340] Dynamic subtest A-DP1: SUCCESS (1.295s) [145.381651] Starting dynamic subtest: B-DP1 [146.328171] Dynamic subtest B-DP1: SUCCESS (0.957s) [146.343792] Starting dynamic subtest: C-DP1 [147.326140] Dynamic subtest C-DP1: SUCCESS (0.996s) [147.340775] Starting dynamic subtest: A-DP3 [148.603638] Dynamic subtest A-DP3: SUCCESS (1.268s) [148.646633] Starting dynamic subtest: B-DP3 [150.557348] Dynamic subtest B-DP3: SUCCESS (1.935s) [150.593420] Starting dynamic subtest: C-DP3 [151.859147] Dynamic subtest C-DP3: SUCCESS (1.297s) [151.860738] Subtest basic-flip-vs-modeset: SUCCESS (7.796s) [152.536775] [105/165] (850s left) kms_flip (basic-flip-vs-wf_vblank) [153.044719] Starting subtest: basic-flip-vs-wf_vblank [153.050295] Starting dynamic subtest: A-DP1 [154.780309] Dynamic subtest A-DP1: SUCCESS (1.730s) [154.789852] Starting dynamic subtest: B-DP1 [155.899762] Dynamic subtest B-DP1: SUCCESS (1.117s) [155.911227] Starting dynamic subtest: C-DP1 [157.003098] Dynamic subtest C-DP1: SUCCESS (1.101s) [157.014877] Starting dynamic subtest: A-DP3 [158.217240] Dynamic subtest A-DP3: SUCCESS (1.208s) [158.223897] Starting dynamic subtest: B-DP3 [159.679081] Dynamic subtest B-DP3: SUCCESS (1.462s) [159.690381] Starting dynamic subtest: C-DP3 [161.164198] Dynamic subtest C-DP3: SUCCESS (1.480s) [161.164773] Subtest basic-flip-vs-wf_vblank: SUCCESS (8.120s) [161.974088] [106/165] (840s left) kms_flip (basic-plain-flip) [162.471054] Starting subtest: basic-plain-flip [162.476796] Starting dynamic subtest: A-DP1 [163.710675] Dynamic subtest A-DP1: SUCCESS (1.234s) [163.721311] Starting dynamic subtest: B-DP1 [164.542359] Dynamic subtest B-DP1: SUCCESS (0.829s) [164.552320] Starting dynamic subtest: C-DP1 [165.361211] Dynamic subtest C-DP1: SUCCESS (0.816s) [165.373249] Starting dynamic subtest: A-DP3 [166.290165] Dynamic subtest A-DP3: SUCCESS (0.924s) [166.302075] Starting dynamic subtest: B-DP3 [167.499975] Dynamic subtest B-DP3: SUCCESS (1.207s) [167.511138] Starting dynamic subtest: C-DP3 [168.623141] Dynamic subtest C-DP3: SUCCESS (1.123s) [168.625851] Subtest basic-plain-flip: SUCCESS (6.155s) [169.455178] [107/165] (833s left) kms_force_connector_basic (force-connector-state) [169.716911] Starting subtest: force-connector-state [171.545383] Subtest force-connector-state: SUCCESS (1.827s) [172.619923] [108/165] (830s left) kms_force_connector_basic (force-edid) [172.858631] Starting subtest: force-edid [173.261265] Subtest force-edid: SUCCESS (0.394s) [173.881431] [109/165] (828s left) kms_force_connector_basic (force-load-detect) [174.132896] Starting subtest: force-load-detect [174.137960] Subtest force-load-detect: SKIP (0.000s) [174.598565] [110/165] (828s left) kms_force_connector_basic (prune-stale-modes) [174.853722] Starting subtest: prune-stale-modes [175.321858] Subtest prune-stale-modes: SUCCESS (0.462s) [175.944882] [111/165] (826s left) kms_frontbuffer_tracking (basic) [176.423047] Starting subtest: basic [179.874311] Subtest basic: SUCCESS (3.447s) [180.506469] [112/165] (822s left) kms_hdmi_inject (inject-audio) [180.954673] Starting subtest: inject-audio [181.521840] Subtest inject-audio: SUCCESS (0.559s) [182.352690] [113/165] (820s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [182.847361] Starting subtest: compare-crc-sanitycheck-xr24 [182.848149] Starting dynamic subtest: pipe-A-DP-1 [184.161541] Dynamic subtest pipe-A-DP-1: SUCCESS (1.308s) [184.178058] Starting dynamic subtest: pipe-B-DP-3 [185.041220] Dynamic subtest pipe-B-DP-3: SUCCESS (0.870s) [185.096622] Starting dynamic subtest: pipe-C-DP-1 [185.724053] Dynamic subtest pipe-C-DP-1: SUCCESS (0.666s) [185.725863] Subtest compare-crc-sanitycheck-xr24: SUCCESS (2.877s) [186.216291] [114/165] (816s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [186.693196] Starting subtest: compare-crc-sanitycheck-nv12 [186.695763] Starting dynamic subtest: pipe-A-DP-1 [187.790130] Dynamic subtest pipe-A-DP-1: SUCCESS (1.091s) [187.805802] Starting dynamic subtest: pipe-B-DP-3 [188.626869] Dynamic subtest pipe-B-DP-3: SUCCESS (0.833s) [188.651567] Starting dynamic subtest: pipe-C-DP-1 [188.654348] Dynamic subtest pipe-C-DP-1: SKIP (0.002s) [188.655698] Subtest compare-crc-sanitycheck-nv12: SUCCESS (1.945s) [189.247757] [115/165] (813s left) kms_pipe_crc_basic (hang-read-crc) [189.733259] Starting subtest: hang-read-crc [189.734051] Starting dynamic subtest: pipe-A-DP-1 [191.598977] Dynamic subtest pipe-A-DP-1: SUCCESS (1.865s) [191.613840] Starting dynamic subtest: pipe-B-DP-3 [193.569953] Dynamic subtest pipe-B-DP-3: SUCCESS (1.965s) [193.583285] Starting dynamic subtest: pipe-C-DP-1 [194.996608] Dynamic subtest pipe-C-DP-1: SUCCESS (1.422s) [194.997640] Subtest hang-read-crc: SUCCESS (5.266s) [195.532214] [116/165] (807s left) kms_pipe_crc_basic (nonblocking-crc) [196.003553] Starting subtest: nonblocking-crc [196.004384] Starting dynamic subtest: pipe-A-DP-1 [197.263673] Dynamic subtest pipe-A-DP-1: SUCCESS (1.260s) [197.278512] Starting dynamic subtest: pipe-B-DP-3 [198.340394] Dynamic subtest pipe-B-DP-3: SUCCESS (1.066s) [198.389895] Starting dynamic subtest: pipe-C-DP-1 [199.177684] Dynamic subtest pipe-C-DP-1: SUCCESS (0.827s) [199.178657] Subtest nonblocking-crc: SUCCESS (3.179s) [199.714224] [117/165] (803s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [200.216359] Starting subtest: nonblocking-crc-frame-sequence [200.217163] Starting dynamic subtest: pipe-A-DP-1 [201.483186] Dynamic subtest pipe-A-DP-1: SUCCESS (1.264s) [201.497207] Starting dynamic subtest: pipe-B-DP-3 [202.477768] Dynamic subtest pipe-B-DP-3: SUCCESS (0.992s) [202.493406] Starting dynamic subtest: pipe-C-DP-1 [203.268110] Dynamic subtest pipe-C-DP-1: SUCCESS (0.784s) [203.268964] Subtest nonblocking-crc-frame-sequence: SUCCESS (3.055s) [203.815645] [118/165] (799s left) kms_pipe_crc_basic (read-crc) [204.315644] Starting subtest: read-crc [204.320742] Starting dynamic subtest: pipe-A-DP-1 [205.465924] Dynamic subtest pipe-A-DP-1: SUCCESS (1.148s) [205.483023] Starting dynamic subtest: pipe-B-DP-3 [206.362562] Dynamic subtest pipe-B-DP-3: SUCCESS (0.891s) [206.375566] Starting dynamic subtest: pipe-C-DP-1 [207.081449] Dynamic subtest pipe-C-DP-1: SUCCESS (0.714s) [207.082404] Subtest read-crc: SUCCESS (2.767s) [207.596582] [119/165] (795s left) kms_pipe_crc_basic (read-crc-frame-sequence) [208.129197] Starting subtest: read-crc-frame-sequence [208.129906] Starting dynamic subtest: pipe-A-DP-1 [209.203683] Dynamic subtest pipe-A-DP-1: SUCCESS (1.074s) [209.221249] Starting dynamic subtest: pipe-B-DP-3 [210.106920] Dynamic subtest pipe-B-DP-3: SUCCESS (0.898s) [210.120086] Starting dynamic subtest: pipe-C-DP-1 [210.821393] Dynamic subtest pipe-C-DP-1: SUCCESS (0.708s) [210.822706] Subtest read-crc-frame-sequence: SUCCESS (2.695s) [211.377701] [120/165] (791s left) kms_pm_backlight (basic-brightness) [211.863587] Subtest basic-brightness: SKIP (0.000s) [211.903029] [121/165] (791s left) kms_pm_rpm (basic-pci-d3-state) [214.183351] Starting subtest: basic-pci-d3-state [214.970744] Subtest basic-pci-d3-state: SUCCESS (0.787s) [215.793852] [122/165] (787s left) kms_pm_rpm (basic-rte) [215.879286] Starting subtest: basic-rte [218.621223] Subtest basic-rte: SUCCESS (2.742s) [219.913567] [123/165] (783s left) kms_psr (psr-primary-page-flip) [220.390784] Subtest psr-primary-page-flip: SKIP (0.000s) [220.436517] [124/165] (782s left) kms_psr (psr-cursor-plane-move) [220.957980] Subtest psr-cursor-plane-move: SKIP (0.000s) [221.000672] [125/165] (782s left) kms_psr (psr-sprite-plane-onoff) [221.524991] Subtest psr-sprite-plane-onoff: SKIP (0.000s) [221.566123] [126/165] (781s left) kms_psr (psr-primary-mmap-gtt) [222.097584] Subtest psr-primary-mmap-gtt: SKIP (0.000s) [222.136697] [127/165] (781s left) kms_setmode (basic-clone-single-crtc) [222.202814] Starting subtest: basic-clone-single-crtc [222.743661] Subtest basic-clone-single-crtc: SKIP (0.541s) [223.269581] [128/165] (780s left) i915_pm_rps (basic-api) [223.335185] Starting subtest: basic-api [223.338899] Subtest basic-api: SUCCESS (0.004s) [223.406813] [129/165] (780s left) prime_self_import (basic-llseek-bad) [223.462658] Starting subtest: basic-llseek-bad [223.463945] Subtest basic-llseek-bad: SUCCESS (0.002s) [223.548345] [130/165] (779s left) prime_self_import (basic-llseek-size) [223.618983] Starting subtest: basic-llseek-size [223.621966] Subtest basic-llseek-size: SUCCESS (0.003s) [223.707904] [131/165] (779s left) prime_self_import (basic-with_fd_dup) [223.771728] Starting subtest: basic-with_fd_dup [223.774744] Subtest basic-with_fd_dup: SUCCESS (0.003s) [223.867878] [132/165] (779s left) prime_self_import (basic-with_one_bo) [223.939695] Starting subtest: basic-with_one_bo [223.946199] Subtest basic-with_one_bo: SUCCESS (0.002s) [223.997671] [133/165] (779s left) prime_self_import (basic-with_one_bo_two_files) [224.054142] Starting subtest: basic-with_one_bo_two_files [224.055194] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s) [224.152339] [134/165] (779s left) prime_self_import (basic-with_two_bos) [224.224157] Starting subtest: basic-with_two_bos [224.226876] Subtest basic-with_two_bos: SUCCESS (0.004s) [224.309164] [135/165] (779s left) prime_vgem (basic-fence-flip) [224.389037] Starting subtest: basic-fence-flip [225.251143] Subtest basic-fence-flip: SUCCESS (0.860s) [225.356551] [136/165] (778s left) prime_vgem (basic-fence-mmap) [225.446771] Starting subtest: basic-fence-mmap [225.474133] Subtest basic-fence-mmap: SUCCESS (0.027s) [225.572672] [137/165] (778s left) prime_vgem (basic-fence-read) [225.677525] Starting subtest: basic-fence-read [225.719443] Subtest basic-fence-read: SUCCESS (0.042s) [225.797052] [138/165] (777s left) prime_vgem (basic-gtt) [225.877265] Starting subtest: basic-gtt [225.890160] Subtest basic-gtt: SUCCESS (0.012s) [225.972022] [139/165] (777s left) prime_vgem (basic-read) [226.055015] Starting subtest: basic-read [226.075536] Subtest basic-read: SUCCESS (0.019s) [226.200188] [140/165] (777s left) prime_vgem (basic-write) [226.302756] Starting subtest: basic-write [226.324305] Subtest basic-write: SUCCESS (0.021s) [226.390280] [141/165] (777s left) vgem_basic (setversion) [226.456833] Starting subtest: setversion [226.457897] Subtest setversion: SUCCESS (0.000s) [226.503688] [142/165] (777s left) vgem_basic (create) [226.571540] Starting subtest: create [226.572090] Subtest create: SUCCESS (0.000s) [226.621666] [143/165] (777s left) vgem_basic (debugfs) [226.688774] Starting subtest: debugfs [226.690450] Subtest debugfs: SUCCESS (0.000s) [226.738112] [144/165] (777s left) vgem_basic (dmabuf-export) [226.804883] Starting subtest: dmabuf-export [226.807660] Subtest dmabuf-export: SUCCESS (0.002s) [226.904624] [145/165] (777s left) vgem_basic (dmabuf-fence) [226.988216] Starting subtest: dmabuf-fence [226.990551] Subtest dmabuf-fence: SUCCESS (0.001s) [227.037370] [146/165] (776s left) vgem_basic (dmabuf-fence-before) [227.105121] Starting subtest: dmabuf-fence-before [227.105858] Subtest dmabuf-fence-before: SUCCESS (0.000s) [227.156374] [147/165] (776s left) vgem_basic (dmabuf-mmap) [227.221437] Starting subtest: dmabuf-mmap [227.234454] Subtest dmabuf-mmap: SUCCESS (0.013s) [227.288892] [148/165] (776s left) vgem_basic (mmap) [227.356948] Starting subtest: mmap [227.363822] Subtest mmap: SUCCESS (0.009s) [227.419823] [149/165] (776s left) vgem_basic (second-client) [227.488141] Starting subtest: second-client [227.521697] Subtest second-client: SUCCESS (0.033s) [227.582939] [150/165] (776s left) vgem_basic (sysfs) [227.671992] Starting subtest: sysfs [227.672674] Subtest sysfs: SUCCESS (0.001s) [227.725985] [151/165] (776s left) core_hotunplug (unbind-rebind) [227.849910] Starting subtest: unbind-rebind [232.270017] Subtest unbind-rebind: SUCCESS (4.420s) [232.299293] [152/165] (771s left) vgem_basic (unload) [232.355952] Starting subtest: unload [261.451089] Subtest unload: SUCCESS (29.094s) [261.560756] [153/165] (742s left) i915_module_load (reload) [261.624872] Starting subtest: reload [266.215274] Subtest reload: SUCCESS (4.589s) [266.229237] [154/165] (737s left) gem_lmem_swapping (basic) [266.295582] Subtest basic: SKIP (0.000s) [266.328915] [155/165] (737s left) gem_lmem_swapping (parallel-random-engines) [266.386015] Subtest parallel-random-engines: SKIP (0.000s) [266.421770] [156/165] (737s left) gem_lmem_swapping (random-engines) [266.478718] Subtest random-engines: SKIP (0.000s) [266.511618] [157/165] (737s left) gem_lmem_swapping (verify-random) [266.568664] Subtest verify-random: SKIP (0.000s) [266.605207] [158/165] (737s left) i915_pm_rpm (module-reload) [269.760866] Starting subtest: module-reload [281.748206] Subtest module-reload: SUCCESS (11.994s) [281.769377] [159/165] (722s left) i915_selftest (live) [281.840028] Starting subtest: live [281.840638] Starting dynamic subtest: sanitycheck [283.070981] Dynamic subtest sanitycheck: SUCCESS (1.231s) [283.084033] Starting dynamic subtest: uncore [284.405935] Dynamic subtest uncore: SUCCESS (1.332s) [284.417077] Starting dynamic subtest: workarounds [285.935658] Dynamic subtest workarounds: SUCCESS (1.527s) [285.943187] Starting dynamic subtest: gt_engines [287.698583] Dynamic subtest gt_engines: SUCCESS (1.761s) [287.706453] Starting dynamic subtest: gt_timelines [298.638586] Dynamic subtest gt_timelines: SUCCESS (10.938s) [298.647523] Starting dynamic subtest: gt_contexts [300.018528] Dynamic subtest gt_contexts: SUCCESS (1.378s) [300.027793] Starting dynamic subtest: gt_lrc [304.268873] Dynamic subtest gt_lrc: SUCCESS (4.248s) [304.276740] Starting dynamic subtest: gt_mocs [305.769912] Dynamic subtest gt_mocs: SUCCESS (1.499s) [305.778646] Starting dynamic subtest: gt_pm [313.000589] Dynamic subtest gt_pm: SUCCESS (7.230s) [313.010187] Starting dynamic subtest: gt_heartbeat [314.334487] Dynamic subtest gt_heartbeat: SUCCESS (1.332s) [314.341584] Starting dynamic subtest: gt_tlb [316.789693] Dynamic subtest gt_tlb: SUCCESS (2.453s) [316.797338] Starting dynamic subtest: requests [325.499168] Dynamic subtest requests: SUCCESS (8.706s) [325.505582] Starting dynamic subtest: migrate [329.622163] Dynamic subtest migrate: SUCCESS (4.121s) [329.628620] Starting dynamic subtest: active [330.870594] Dynamic subtest active: SUCCESS (1.248s) [330.878559] Starting dynamic subtest: objects [333.016556] Dynamic subtest objects: SUCCESS (2.144s) [333.025285] Starting dynamic subtest: mman [338.093470] Dynamic subtest mman: SUCCESS (5.075s) [338.102077] Starting dynamic subtest: dmabuf [339.474595] Dynamic subtest dmabuf: SUCCESS (1.379s) [339.482957] Starting dynamic subtest: vma [340.736798] Dynamic subtest vma: SUCCESS (1.260s) [340.744670] Starting dynamic subtest: coherency [342.969101] Dynamic subtest coherency: SUCCESS (2.229s) [342.976243] Starting dynamic subtest: gtt [351.672027] Dynamic subtest gtt: SUCCESS (8.702s) [351.680753] Starting dynamic subtest: gem [353.086824] Dynamic subtest gem: SUCCESS (1.413s) [353.096720] Starting dynamic subtest: evict [355.822421] Dynamic subtest evict: SUCCESS (2.734s) [355.829583] Starting dynamic subtest: hugepages [362.980610] Dynamic subtest hugepages: SUCCESS (7.157s) [362.987653] Starting dynamic subtest: gem_contexts [379.719491] Dynamic subtest gem_contexts: SUCCESS (16.737s) [379.728930] Starting dynamic subtest: client [381.007562] Dynamic subtest client: SUCCESS (1.286s) [381.015226] Starting dynamic subtest: gem_migrate [382.298748] Dynamic subtest gem_migrate: SUCCESS (1.290s) [382.306749] Starting dynamic subtest: reset [384.216874] Dynamic subtest reset: SUCCESS (1.916s) [384.227948] Starting dynamic subtest: memory_region [385.434584] Dynamic subtest memory_region: SUCCESS (1.216s) [385.442499] Starting dynamic subtest: hangcheck [415.240724] Dynamic subtest hangcheck: SUCCESS (29.805s) [415.249282] Starting dynamic subtest: execlists [429.053772] Dynamic subtest execlists: SUCCESS (13.811s) [429.061441] Starting dynamic subtest: ring_submission [430.295540] Dynamic subtest ring_submission: SUCCESS (1.240s) [430.303483] Starting dynamic subtest: perf [431.671256] Dynamic subtest perf: SUCCESS (1.372s) [431.678225] Starting dynamic subtest: slpc [432.938531] Dynamic subtest slpc: SUCCESS (1.267s) [432.946984] Starting dynamic subtest: guc [434.193145] Dynamic subtest guc: SUCCESS (1.251s) [434.199088] Starting dynamic subtest: guc_multi_lrc [435.432097] Dynamic subtest guc_multi_lrc: SUCCESS (1.238s) [435.439217] Starting dynamic subtest: guc_hang [436.684665] Dynamic subtest guc_hang: SUCCESS (1.252s) [436.692723] Starting dynamic subtest: late_gt_pm [438.002599] Dynamic subtest late_gt_pm: SUCCESS (1.316s) [438.003834] Subtest live: SUCCESS (156.164s) [438.023477] [160/165] (566s left) dmabuf (all-tests) [438.084595] Starting subtest: all-tests [438.088498] Starting dynamic subtest: sanitycheck [438.152815] Dynamic subtest sanitycheck: SUCCESS (0.068s) [438.180285] Starting dynamic subtest: dma_fence [438.345050] Dynamic subtest dma_fence: SUCCESS (0.189s) [438.357105] Starting dynamic subtest: dma_fence_chain [452.736228] Dynamic subtest dma_fence_chain: SUCCESS (14.389s) [452.754328] Starting dynamic subtest: dma_fence_unwrap [452.814316] Dynamic subtest dma_fence_unwrap: SUCCESS (0.075s) [452.844505] Starting dynamic subtest: dma_resv [452.909541] Dynamic subtest dma_resv: SUCCESS (0.089s) [452.913290] Subtest all-tests: SUCCESS (14.828s) [452.956882] [161/165] (551s left) i915_suspend (basic-s2idle-without-i915) [453.022216] Starting subtest: basic-s2idle-without-i915 [474.480105] Subtest basic-s2idle-without-i915: SUCCESS (6.540s) [477.296717] [162/165] (543s left) i915_suspend (basic-s3-without-i915) [477.355310] Starting subtest: basic-s3-without-i915 [498.709738] Subtest basic-s3-without-i915: SUCCESS (4.406s) [500.902152] [163/165] (539s left) gem_exec_suspend (basic-s0) [501.017225] Starting subtest: basic-S0 [501.018854] Starting dynamic subtest: smem [518.696813] Dynamic subtest smem: SUCCESS (3.452s) [518.697820] Subtest basic-S0: SUCCESS (3.453s) [522.723387] [164/165] (534s left) gem_exec_suspend (basic-s3) [522.794310] Starting subtest: basic-S3 [522.795889] Starting dynamic subtest: smem [542.957008] Dynamic subtest smem: SUCCESS (3.667s) [542.957705] Subtest basic-S3: SUCCESS (3.669s) [546.130114] [165/165] (530s left) kms_pipe_crc_basic (suspend-read-crc) [546.648060] Starting subtest: suspend-read-crc [546.651144] Starting dynamic subtest: pipe-A-DP-1 [566.916619] Dynamic subtest pipe-A-DP-1: SUCCESS (3.735s) [566.925479] Starting dynamic subtest: pipe-B-DP-4 [586.905177] Dynamic subtest pipe-B-DP-4: FAIL (2.943s) [586.913416] Starting dynamic subtest: pipe-C-DP-1 [605.736385] Dynamic subtest pipe-C-DP-1: SUCCESS (2.903s) [605.737921] Subtest suspend-read-crc: FAIL (9.595s) [610.561200] Closing watchdogs Done.