[42.005420] Initializing watchdogs [42.005510] /dev/watchdog0 [42.010422] [001/165] (960s left) i915_module_load (load) [42.319369] Starting subtest: load [45.761769] Subtest load: SUCCESS (3.444s) [45.778128] [002/165] (956s left) core_auth (basic-auth) [45.901827] Starting subtest: basic-auth [45.903037] Subtest basic-auth: SUCCESS (0.002s) [45.976051] [003/165] (956s left) debugfs_test (read_all_entries) [46.049284] Starting subtest: read_all_entries [47.082347] Subtest read_all_entries: SUCCESS (1.033s) [47.666136] [004/165] (954s left) debugfs_test (basic-hwmon) [47.737311] Starting subtest: basic-hwmon [47.738404] Subtest basic-hwmon: SKIP (0.000s) [48.259108] [005/165] (954s left) fbdev (eof) [48.344012] Starting subtest: eof [48.344690] Subtest eof: SUCCESS (0.001s) [48.401096] [006/165] (954s left) fbdev (info) [48.469787] Starting subtest: info [48.470449] Subtest info: SUCCESS (0.000s) [48.528468] [007/165] (954s left) fbdev (nullptr) [48.595673] Starting subtest: nullptr [48.596312] Subtest nullptr: SUCCESS (0.000s) [48.646842] [008/165] (953s left) fbdev (read) [48.713615] Starting subtest: read [48.956905] Subtest read: SUCCESS (0.245s) [49.006781] [009/165] (953s left) fbdev (write) [49.078264] Starting subtest: write [49.200377] Subtest write: SUCCESS (0.123s) [49.242474] [010/165] (953s left) gem_basic (bad-close) [49.311083] Starting subtest: bad-close [49.312213] Subtest bad-close: SUCCESS (0.000s) [49.377913] [011/165] (953s left) gem_basic (create-close) [49.447696] Starting subtest: create-close [49.448737] Subtest create-close: SUCCESS (0.000s) [49.510976] [012/165] (953s left) gem_basic (create-fd-close) [49.579682] Starting subtest: create-fd-close [49.580963] Subtest create-fd-close: SUCCESS (0.000s) [49.647160] [013/165] (953s left) gem_busy (busy@all-engines) [49.733965] Starting subtest: busy [49.734652] Starting dynamic subtest: all-engines [49.787745] Dynamic subtest all-engines: SUCCESS (0.054s) [49.788328] Subtest busy: SUCCESS (0.054s) [50.194245] [014/165] (952s left) gem_close_race (basic-process) [50.290939] Starting subtest: basic-process [50.354057] Subtest basic-process: SUCCESS (0.063s) [50.591798] [015/165] (952s left) gem_close_race (basic-threads) [50.691101] Starting subtest: basic-threads [51.841391] Subtest basic-threads: SUCCESS (1.151s) [52.047221] [016/165] (950s left) gem_ctx_create (basic) [52.125385] Starting subtest: basic [52.126126] Subtest basic: SUCCESS (0.000s) [52.341256] [017/165] (950s left) gem_ctx_create (basic-files) [52.432035] Starting subtest: basic-files [54.450684] Subtest basic-files: SUCCESS (2.019s) [54.680362] [018/165] (948s left) gem_ctx_exec (basic) [54.746627] Starting subtest: basic [54.750828] Subtest basic: SUCCESS (0.004s) [54.867394] [019/165] (947s left) gem_exec_basic (basic) [54.974130] Starting subtest: basic [54.974894] Starting dynamic subtest: rcs0-smem [54.975566] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [54.980756] Starting dynamic subtest: bcs0-smem [54.981357] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [54.986404] Starting dynamic subtest: vcs0-smem [54.987189] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [54.992562] Starting dynamic subtest: vcs1-smem [54.993613] Dynamic subtest vcs1-smem: SUCCESS (0.000s) [54.998910] Starting dynamic subtest: vecs0-smem [54.999994] Dynamic subtest vecs0-smem: SUCCESS (0.000s) [55.001151] Subtest basic: SUCCESS (0.005s) [55.191055] [020/165] (947s left) gem_exec_create (basic) [55.264850] Starting subtest: basic [55.267409] Starting dynamic subtest: smem [57.305112] Dynamic subtest smem: SUCCESS (2.040s) [57.305860] Subtest basic: SUCCESS (2.040s) [57.510777] [021/165] (945s left) gem_exec_fence (basic-busy) [57.727505] Starting subtest: basic-busy [57.728581] Starting dynamic subtest: rcs0 [57.753237] Dynamic subtest rcs0: SUCCESS (0.026s) [57.763858] Starting dynamic subtest: bcs0 [57.783386] Dynamic subtest bcs0: SUCCESS (0.029s) [57.794314] Starting dynamic subtest: vcs0 [57.808308] Dynamic subtest vcs0: SUCCESS (0.025s) [57.818562] Starting dynamic subtest: vcs1 [57.830239] Dynamic subtest vcs1: SUCCESS (0.023s) [57.840913] Starting dynamic subtest: vecs0 [57.853926] Dynamic subtest vecs0: SUCCESS (0.024s) [57.854737] Subtest basic-busy: SUCCESS (0.127s) [58.547227] [022/165] (944s left) gem_exec_fence (basic-wait) [58.797733] Starting subtest: basic-wait [58.799666] Starting dynamic subtest: rcs0 [58.832336] Dynamic subtest rcs0: SUCCESS (0.033s) [58.850564] Starting dynamic subtest: bcs0 [58.861132] Dynamic subtest bcs0: SUCCESS (0.030s) [58.871654] Starting dynamic subtest: vcs0 [58.884097] Dynamic subtest vcs0: SUCCESS (0.023s) [58.893313] Starting dynamic subtest: vcs1 [58.907041] Dynamic subtest vcs1: SUCCESS (0.023s) [58.916086] Starting dynamic subtest: vecs0 [58.928974] Dynamic subtest vecs0: SUCCESS (0.022s) [58.929937] Subtest basic-wait: SUCCESS (0.133s) [59.540651] [023/165] (943s left) gem_exec_fence (basic-await) [59.777584] Starting subtest: basic-await [59.779281] Starting dynamic subtest: rcs0 [59.886452] Dynamic subtest rcs0: SUCCESS (0.110s) [59.893950] Starting dynamic subtest: bcs0 [59.987176] Dynamic subtest bcs0: SUCCESS (0.100s) [59.997096] Starting dynamic subtest: vcs0 [60.098617] Dynamic subtest vcs0: SUCCESS (0.111s) [60.106989] Starting dynamic subtest: vcs1 [60.206579] Dynamic subtest vcs1: SUCCESS (0.107s) [60.215611] Starting dynamic subtest: vecs0 [60.321831] Dynamic subtest vecs0: SUCCESS (0.115s) [60.322845] Subtest basic-await: SUCCESS (0.545s) [60.984693] [024/165] (942s left) gem_exec_fence (nb-await) [61.199306] Starting subtest: nb-await [61.200501] Starting dynamic subtest: rcs0 [61.273216] Dynamic subtest rcs0: SUCCESS (0.072s) [61.300686] Starting dynamic subtest: bcs0 [61.364589] Dynamic subtest bcs0: SUCCESS (0.089s) [61.393231] Starting dynamic subtest: vcs0 [61.455500] Dynamic subtest vcs0: SUCCESS (0.090s) [61.485941] Starting dynamic subtest: vcs1 [61.546471] Dynamic subtest vcs1: SUCCESS (0.092s) [61.576252] Starting dynamic subtest: vecs0 [61.643846] Dynamic subtest vecs0: SUCCESS (0.096s) [61.646958] Subtest nb-await: SUCCESS (0.444s) [62.324950] [025/165] (940s left) gem_exec_gttfill (basic) [62.412529] Starting subtest: basic [70.773989] Subtest basic: SUCCESS (8.360s) [70.950040] [026/165] (932s left) gem_exec_parallel (engines) [71.094616] Starting subtest: engines [71.095457] Starting dynamic subtest: basic [73.595089] Dynamic subtest basic: SUCCESS (2.500s) [73.601345] Starting dynamic subtest: contexts [79.565945] Dynamic subtest contexts: SUCCESS (5.969s) [79.572190] Starting dynamic subtest: fds [87.561983] Dynamic subtest fds: SUCCESS (7.997s) [87.568719] Starting dynamic subtest: userptr [89.910527] Dynamic subtest userptr: SUCCESS (2.348s) [89.911151] Subtest engines: SUCCESS (18.816s) [90.109336] [027/165] (912s left) gem_exec_store (basic) [90.186982] Starting subtest: basic [90.246913] Subtest basic: SUCCESS (0.060s) [90.449647] [028/165] (912s left) gem_flink_basic (bad-flink) [90.517857] Starting subtest: bad-flink [90.518473] Subtest bad-flink: SUCCESS (0.000s) [90.600605] [029/165] (912s left) gem_flink_basic (bad-open) [90.681219] Starting subtest: bad-open [90.681789] Subtest bad-open: SUCCESS (0.000s) [90.751583] [030/165] (912s left) gem_flink_basic (basic) [90.828536] Starting subtest: basic [90.829077] Subtest basic: SUCCESS (0.000s) [90.896158] [031/165] (912s left) gem_flink_basic (double-flink) [90.964655] Starting subtest: double-flink [90.965254] Subtest double-flink: SUCCESS (0.000s) [91.047859] [032/165] (912s left) gem_flink_basic (flink-lifetime) [91.121379] Starting subtest: flink-lifetime [91.123375] Subtest flink-lifetime: SUCCESS (0.002s) [91.202594] [033/165] (911s left) gem_huc_copy (huc-copy) [91.281650] Starting subtest: huc-copy [91.282969] Subtest huc-copy: SKIP (0.000s) [91.382710] [034/165] (911s left) gem_linear_blits (basic) [91.610153] Starting subtest: basic [91.618574] Subtest basic: SUCCESS (0.010s) [91.776177] [035/165] (911s left) gem_mmap (basic) [91.920637] Starting subtest: basic [91.923265] Subtest basic: SUCCESS (0.000s) [92.010455] [036/165] (911s left) gem_mmap_gtt (basic) [92.098190] Starting subtest: basic [92.098929] Subtest basic: SUCCESS (0.001s) [92.173734] [037/165] (911s left) gem_render_linear_blits (basic) [92.264506] Starting subtest: basic [92.297816] Subtest basic: SUCCESS (0.033s) [92.336339] [038/165] (910s left) gem_render_tiled_blits (basic) [92.400993] Starting subtest: basic [92.438743] Subtest basic: SUCCESS (0.037s) [92.469426] [039/165] (910s left) gem_ringfill (basic-all) [92.731616] Starting subtest: basic-all [93.895577] Subtest basic-all: SUCCESS (1.164s) [93.983566] [040/165] (909s left) gem_softpin (allocator-basic) [94.080516] Starting subtest: allocator-basic [94.193065] Subtest allocator-basic: SUCCESS (0.112s) [94.295064] [041/165] (909s left) gem_softpin (allocator-basic-reserve) [94.372308] Starting subtest: allocator-basic-reserve [94.482948] Subtest allocator-basic-reserve: SUCCESS (0.109s) [94.561202] [042/165] (908s left) gem_softpin (safe-alignment) [94.633845] Starting subtest: safe-alignment [94.650083] Subtest safe-alignment: SUCCESS (0.016s) [94.742907] [043/165] (908s left) gem_sync (basic-all) [94.826888] Starting subtest: basic-all [97.019843] Subtest basic-all: SUCCESS (2.193s) [97.225495] [044/165] (906s left) gem_sync (basic-each) [97.320537] Starting subtest: basic-each [99.517946] Subtest basic-each: SUCCESS (2.197s) [99.745984] [045/165] (903s left) gem_tiled_blits (basic) [99.986779] Starting subtest: basic [100.005667] Subtest basic: SUCCESS (0.019s) [100.155909] [046/165] (903s left) gem_tiled_fence_blits (basic) [100.431975] Starting subtest: basic [100.441870] Subtest basic: SUCCESS (0.010s) [100.606410] [047/165] (902s left) gem_tiled_pread_basic [100.932862] [048/165] (902s left) gem_wait (busy@all-engines) [101.014989] Starting subtest: busy [101.015761] Starting dynamic subtest: all-engines [101.534879] Dynamic subtest all-engines: SUCCESS (0.520s) [101.535590] Subtest busy: SUCCESS (0.520s) [101.748915] [049/165] (901s left) gem_wait (wait@all-engines) [101.853166] Starting subtest: wait [101.853868] Starting dynamic subtest: all-engines [102.902356] Dynamic subtest all-engines: SUCCESS (1.045s) [102.904554] Subtest wait: SUCCESS (1.045s) [103.185607] [050/165] (900s left) i915_getparams_basic (basic-eu-total) [103.266538] Starting subtest: basic-eu-total [103.267691] Subtest basic-eu-total: SUCCESS (0.000s) [103.354217] [051/165] (900s left) i915_getparams_basic (basic-subslice-total) [103.437275] Starting subtest: basic-subslice-total [103.438294] Subtest basic-subslice-total: SUCCESS (0.000s) [103.517293] [052/165] (900s left) i915_hangman (error-state-basic) [103.602450] Starting subtest: error-state-basic [103.611798] Subtest error-state-basic: SUCCESS (0.009s) [103.679790] [053/165] (900s left) i915_pciid [103.843295] [054/165] (899s left) kms_addfb_basic (addfb25-4-tiled) [104.352587] Starting subtest: addfb25-4-tiled [104.353788] Subtest addfb25-4-tiled: SUCCESS (0.001s) [104.434570] [055/165] (899s left) kms_addfb_basic (addfb25-bad-modifier) [104.498799] Starting subtest: addfb25-bad-modifier [104.499291] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [105.021211] [056/165] (898s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [105.104684] Starting subtest: addfb25-framebuffer-vs-set-tiling [105.105184] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [105.613384] [057/165] (898s left) kms_addfb_basic (addfb25-modifier-no-flag) [105.681448] Starting subtest: addfb25-modifier-no-flag [105.682174] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [106.236058] [058/165] (897s left) kms_addfb_basic (addfb25-x-tiled-legacy) [106.301354] Starting subtest: addfb25-x-tiled-legacy [106.302123] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [106.827302] [059/165] (897s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [106.894524] Starting subtest: addfb25-x-tiled-mismatch-legacy [106.895211] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [107.432700] [060/165] (896s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [107.937613] Starting subtest: addfb25-yf-tiled-legacy [107.938729] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [108.022098] [061/165] (895s left) kms_addfb_basic (addfb25-y-tiled-legacy) [108.554065] Starting subtest: addfb25-y-tiled-legacy [108.554812] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [108.631137] [062/165] (895s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [109.145314] Starting subtest: addfb25-y-tiled-small-legacy [109.146274] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s) [109.224779] [063/165] (894s left) kms_addfb_basic (bad-pitch-0) [109.294189] Starting subtest: bad-pitch-0 [109.294769] Subtest bad-pitch-0: SUCCESS (0.000s) [109.831742] [064/165] (894s left) kms_addfb_basic (bad-pitch-1024) [109.916785] Starting subtest: bad-pitch-1024 [109.917370] Subtest bad-pitch-1024: SUCCESS (0.000s) [110.446861] [065/165] (893s left) kms_addfb_basic (bad-pitch-128) [110.514385] Starting subtest: bad-pitch-128 [110.515246] Subtest bad-pitch-128: SUCCESS (0.000s) [111.071962] [066/165] (892s left) kms_addfb_basic (bad-pitch-256) [111.140731] Starting subtest: bad-pitch-256 [111.141361] Subtest bad-pitch-256: SUCCESS (0.000s) [111.674699] [067/165] (892s left) kms_addfb_basic (bad-pitch-32) [111.750713] Starting subtest: bad-pitch-32 [111.751341] Subtest bad-pitch-32: SUCCESS (0.000s) [112.280330] [068/165] (891s left) kms_addfb_basic (bad-pitch-63) [112.343520] Starting subtest: bad-pitch-63 [112.344322] Subtest bad-pitch-63: SUCCESS (0.000s) [112.868172] [069/165] (891s left) kms_addfb_basic (bad-pitch-65536) [112.934659] Starting subtest: bad-pitch-65536 [112.935230] Subtest bad-pitch-65536: SUCCESS (0.000s) [113.464682] [070/165] (890s left) kms_addfb_basic (bad-pitch-999) [113.532795] Starting subtest: bad-pitch-999 [113.533420] Subtest bad-pitch-999: SUCCESS (0.000s) [114.061630] [071/165] (889s left) kms_addfb_basic (basic) [114.124642] Starting subtest: basic [114.125242] Subtest basic: SUCCESS (0.000s) [114.654102] [072/165] (889s left) kms_addfb_basic (basic-x-tiled-legacy) [114.727844] Starting subtest: basic-x-tiled-legacy [114.728459] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [115.257336] [073/165] (888s left) kms_addfb_basic (basic-y-tiled-legacy) [115.346590] Starting subtest: basic-y-tiled-legacy [115.347330] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [115.912220] [074/165] (888s left) kms_addfb_basic (bo-too-small) [116.454274] Starting subtest: bo-too-small [116.455243] Subtest bo-too-small: SUCCESS (0.000s) [116.538359] [075/165] (887s left) kms_addfb_basic (bo-too-small-due-to-tiling) [117.073253] Starting subtest: bo-too-small-due-to-tiling [117.073904] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s) [117.144247] [076/165] (887s left) kms_addfb_basic (clobberred-modifier) [117.210874] Starting subtest: clobberred-modifier [117.211656] Subtest clobberred-modifier: SUCCESS (0.000s) [117.736789] [077/165] (886s left) kms_addfb_basic (framebuffer-vs-set-tiling) [117.800166] Starting subtest: framebuffer-vs-set-tiling [117.800711] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [118.338376] [078/165] (885s left) kms_addfb_basic (invalid-get-prop) [118.409652] Starting subtest: invalid-get-prop [118.410333] Subtest invalid-get-prop: SUCCESS (0.000s) [118.952111] [079/165] (885s left) kms_addfb_basic (invalid-get-prop-any) [119.012656] Starting subtest: invalid-get-prop-any [119.013257] Subtest invalid-get-prop-any: SUCCESS (0.000s) [119.558434] [080/165] (884s left) kms_addfb_basic (invalid-set-prop) [119.630807] Starting subtest: invalid-set-prop [119.631476] Subtest invalid-set-prop: SUCCESS (0.000s) [120.165697] [081/165] (884s left) kms_addfb_basic (invalid-set-prop-any) [120.238785] Starting subtest: invalid-set-prop-any [120.239404] Subtest invalid-set-prop-any: SUCCESS (0.000s) [120.764331] [082/165] (883s left) kms_addfb_basic (no-handle) [120.842642] Starting subtest: no-handle [120.843352] Subtest no-handle: SUCCESS (0.000s) [121.389886] [083/165] (882s left) kms_addfb_basic (size-max) [121.924395] Starting subtest: size-max [121.925356] Subtest size-max: SUCCESS (0.000s) [121.998954] [084/165] (882s left) kms_addfb_basic (small-bo) [122.518666] Starting subtest: small-bo [122.519498] Subtest small-bo: SUCCESS (0.000s) [122.598525] [085/165] (881s left) kms_addfb_basic (tile-pitch-mismatch) [122.667097] Starting subtest: tile-pitch-mismatch [122.667712] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [123.233788] [086/165] (881s left) kms_addfb_basic (too-high) [123.758847] Starting subtest: too-high [123.759797] Subtest too-high: SUCCESS (0.001s) [123.844971] [087/165] (880s left) kms_addfb_basic (too-wide) [124.351643] Starting subtest: too-wide [124.352382] Subtest too-wide: SUCCESS (0.000s) [124.431465] [088/165] (879s left) kms_addfb_basic (unused-handle) [124.497544] Starting subtest: unused-handle [124.498059] Subtest unused-handle: SUCCESS (0.000s) [125.037403] [089/165] (879s left) kms_addfb_basic (unused-modifier) [125.111869] Starting subtest: unused-modifier [125.112720] Subtest unused-modifier: SUCCESS (0.000s) [125.681214] [090/165] (878s left) kms_addfb_basic (unused-offsets) [125.748914] Starting subtest: unused-offsets [125.749710] Subtest unused-offsets: SUCCESS (0.000s) [126.299785] [091/165] (878s left) kms_addfb_basic (unused-pitches) [126.367888] Starting subtest: unused-pitches [126.368592] Subtest unused-pitches: SUCCESS (0.000s) [126.927868] [092/165] (877s left) kms_busy (basic) [127.440890] Starting subtest: basic [127.442283] Starting dynamic subtest: flip [128.908494] Dynamic subtest flip: SUCCESS (1.466s) [128.915911] Starting dynamic subtest: modeset [130.053822] Dynamic subtest modeset: SUCCESS (1.145s) [130.054805] Subtest basic: SUCCESS (2.617s) [130.613369] [093/165] (873s left) kms_prop_blob (basic) [130.693935] Starting subtest: basic [130.694702] Subtest basic: SUCCESS (0.000s) [130.764016] [094/165] (873s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [131.275972] Starting subtest: basic-busy-flip-before-cursor-atomic [131.278086] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [131.357366] [095/165] (873s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [131.881276] Starting subtest: basic-busy-flip-before-cursor-legacy [131.882992] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [131.956089] [096/165] (872s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [132.490434] Starting subtest: basic-flip-after-cursor-atomic [133.943039] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.454s) [134.500585] [097/165] (870s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [135.019080] Starting subtest: basic-flip-after-cursor-legacy [136.481285] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.462s) [137.101826] [098/165] (867s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [137.628241] Starting subtest: basic-flip-after-cursor-varying-size [139.070635] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.445s) [139.625722] [099/165] (865s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [140.150582] Starting subtest: basic-flip-before-cursor-atomic [141.694383] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.544s) [142.267200] [100/165] (862s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [142.800094] Starting subtest: basic-flip-before-cursor-legacy [144.367611] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.567s) [145.001066] [101/165] (859s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [145.511093] Starting subtest: basic-flip-before-cursor-varying-size [147.003845] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.496s) [147.560552] [102/165] (857s left) kms_dsc (dsc-basic) [148.105849] Subtest dsc-basic: SKIP (0.000s) [148.155245] [103/165] (856s left) kms_flip (basic-flip-vs-dpms) [148.690810] Starting subtest: basic-flip-vs-dpms [148.693132] Starting dynamic subtest: A-DP1 [150.304632] Dynamic subtest A-DP1: SUCCESS (1.610s) [150.323608] Starting dynamic subtest: B-DP1 [151.288490] Dynamic subtest B-DP1: SUCCESS (0.974s) [151.304395] Starting dynamic subtest: C-DP1 [152.245230] Dynamic subtest C-DP1: SUCCESS (0.954s) [152.266131] Starting dynamic subtest: A-DP3 [153.586620] Dynamic subtest A-DP3: SUCCESS (1.336s) [153.601790] Starting dynamic subtest: B-DP3 [154.942855] Dynamic subtest B-DP3: SUCCESS (1.345s) [154.978661] Starting dynamic subtest: C-DP3 [156.953534] Dynamic subtest C-DP3: SUCCESS (2.000s) [156.955968] Subtest basic-flip-vs-dpms: SUCCESS (8.261s) [157.616407] [104/165] (847s left) kms_flip (basic-flip-vs-modeset) [158.152305] Starting subtest: basic-flip-vs-modeset [158.156730] Starting dynamic subtest: A-DP1 [159.665630] Dynamic subtest A-DP1: SUCCESS (1.504s) [159.696745] Starting dynamic subtest: B-DP1 [160.649171] Dynamic subtest B-DP1: SUCCESS (0.978s) [160.663361] Starting dynamic subtest: C-DP1 [161.664462] Dynamic subtest C-DP1: SUCCESS (1.011s) [161.684129] Starting dynamic subtest: A-DP3 [162.905542] Dynamic subtest A-DP3: SUCCESS (1.235s) [162.945264] Starting dynamic subtest: B-DP3 [164.305951] Dynamic subtest B-DP3: SUCCESS (1.383s) [164.354106] Starting dynamic subtest: C-DP3 [166.283286] Dynamic subtest C-DP3: SUCCESS (1.972s) [166.284739] Subtest basic-flip-vs-modeset: SUCCESS (8.134s) [166.898607] [105/165] (838s left) kms_flip (basic-flip-vs-wf_vblank) [167.427074] Starting subtest: basic-flip-vs-wf_vblank [167.431595] Starting dynamic subtest: A-DP1 [169.083584] Dynamic subtest A-DP1: SUCCESS (1.652s) [169.095876] Starting dynamic subtest: B-DP1 [170.238831] Dynamic subtest B-DP1: SUCCESS (1.153s) [170.249172] Starting dynamic subtest: C-DP1 [171.378680] Dynamic subtest C-DP1: SUCCESS (1.137s) [171.390955] Starting dynamic subtest: A-DP3 [172.589301] Dynamic subtest A-DP3: SUCCESS (1.207s) [172.600487] Starting dynamic subtest: B-DP3 [174.632394] Dynamic subtest B-DP3: SUCCESS (2.038s) [174.642782] Starting dynamic subtest: C-DP3 [176.587766] Dynamic subtest C-DP3: SUCCESS (1.955s) [176.591537] Subtest basic-flip-vs-wf_vblank: SUCCESS (9.168s) [177.550450] [106/165] (827s left) kms_flip (basic-plain-flip) [178.098254] Starting subtest: basic-plain-flip [178.108638] Starting dynamic subtest: A-DP1 [179.228810] Dynamic subtest A-DP1: SUCCESS (1.120s) [179.237770] Starting dynamic subtest: B-DP1 [180.066677] Dynamic subtest B-DP1: SUCCESS (0.830s) [180.118739] Starting dynamic subtest: C-DP1 [180.987129] Dynamic subtest C-DP1: SUCCESS (0.912s) [181.000510] Starting dynamic subtest: A-DP3 [181.938224] Dynamic subtest A-DP3: SUCCESS (0.947s) [181.988325] Starting dynamic subtest: B-DP3 [183.363446] Dynamic subtest B-DP3: SUCCESS (1.411s) [183.412785] Starting dynamic subtest: C-DP3 [184.612250] Dynamic subtest C-DP3: SUCCESS (1.240s) [184.614907] Subtest basic-plain-flip: SUCCESS (6.516s) [185.395655] [107/165] (819s left) kms_force_connector_basic (force-connector-state) [185.685739] Starting subtest: force-connector-state [187.627321] Subtest force-connector-state: SUCCESS (1.949s) [188.736656] [108/165] (816s left) kms_force_connector_basic (force-edid) [189.003488] Starting subtest: force-edid [189.408291] Subtest force-edid: SUCCESS (0.404s) [190.067586] [109/165] (815s left) kms_force_connector_basic (force-load-detect) [190.346320] Starting subtest: force-load-detect [190.352674] Subtest force-load-detect: SKIP (0.000s) [190.835576] [110/165] (814s left) kms_force_connector_basic (prune-stale-modes) [191.109745] Starting subtest: prune-stale-modes [191.571194] Subtest prune-stale-modes: SUCCESS (0.459s) [192.233954] [111/165] (813s left) kms_frontbuffer_tracking (basic) [192.752847] Starting subtest: basic [196.318569] Subtest basic: SUCCESS (3.565s) [196.953842] [112/165] (808s left) kms_hdmi_inject (inject-audio) [197.439176] Starting subtest: inject-audio [197.978942] Subtest inject-audio: SUCCESS (0.540s) [198.867239] [113/165] (806s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [199.422053] Starting subtest: compare-crc-sanitycheck-xr24 [199.424226] Starting dynamic subtest: pipe-A-DP-1 [200.930298] Dynamic subtest pipe-A-DP-1: SUCCESS (1.503s) [200.946998] Starting dynamic subtest: pipe-B-DP-3 [201.730727] Dynamic subtest pipe-B-DP-3: SUCCESS (0.788s) [201.749195] Starting dynamic subtest: pipe-C-DP-1 [202.360134] Dynamic subtest pipe-C-DP-1: SUCCESS (0.626s) [202.361795] Subtest compare-crc-sanitycheck-xr24: SUCCESS (2.938s) [202.938940] [114/165] (802s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [203.464374] Starting subtest: compare-crc-sanitycheck-nv12 [203.465451] Starting dynamic subtest: pipe-A-DP-1 [204.756233] Dynamic subtest pipe-A-DP-1: SUCCESS (1.289s) [204.773545] Starting dynamic subtest: pipe-B-DP-3 [205.612742] Dynamic subtest pipe-B-DP-3: SUCCESS (0.847s) [205.635066] Starting dynamic subtest: pipe-C-DP-1 [205.637358] Dynamic subtest pipe-C-DP-1: SKIP (0.002s) [205.638517] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.157s) [206.240202] [115/165] (799s left) kms_pipe_crc_basic (hang-read-crc) [206.759325] Starting subtest: hang-read-crc [206.762741] Starting dynamic subtest: pipe-A-DP-1 [209.000929] Dynamic subtest pipe-A-DP-1: SUCCESS (2.238s) [209.018438] Starting dynamic subtest: pipe-B-DP-3 [211.325898] Dynamic subtest pipe-B-DP-3: SUCCESS (2.319s) [211.338483] Starting dynamic subtest: pipe-C-DP-1 [212.794411] Dynamic subtest pipe-C-DP-1: SUCCESS (1.463s) [212.795767] Subtest hang-read-crc: SUCCESS (6.035s) [213.350077] [116/165] (792s left) kms_pipe_crc_basic (nonblocking-crc) [213.875137] Starting subtest: nonblocking-crc [213.878160] Starting dynamic subtest: pipe-A-DP-1 [215.102365] Dynamic subtest pipe-A-DP-1: SUCCESS (1.223s) [215.117810] Starting dynamic subtest: pipe-B-DP-3 [216.094236] Dynamic subtest pipe-B-DP-3: SUCCESS (0.979s) [216.133391] Starting dynamic subtest: pipe-C-DP-1 [216.939518] Dynamic subtest pipe-C-DP-1: SUCCESS (0.840s) [216.940603] Subtest nonblocking-crc: SUCCESS (3.066s) [217.511426] [117/165] (788s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [218.025829] Starting subtest: nonblocking-crc-frame-sequence [218.026844] Starting dynamic subtest: pipe-A-DP-1 [219.255987] Dynamic subtest pipe-A-DP-1: SUCCESS (1.229s) [219.272026] Starting dynamic subtest: pipe-B-DP-3 [220.336778] Dynamic subtest pipe-B-DP-3: SUCCESS (1.072s) [220.378356] Starting dynamic subtest: pipe-C-DP-1 [221.185604] Dynamic subtest pipe-C-DP-1: SUCCESS (0.836s) [221.186725] Subtest nonblocking-crc-frame-sequence: SUCCESS (3.165s) [221.747755] [118/165] (783s left) kms_pipe_crc_basic (read-crc) [222.273470] Starting subtest: read-crc [222.276423] Starting dynamic subtest: pipe-A-DP-1 [223.529810] Dynamic subtest pipe-A-DP-1: SUCCESS (1.251s) [223.545985] Starting dynamic subtest: pipe-B-DP-3 [224.647674] Dynamic subtest pipe-B-DP-3: SUCCESS (1.109s) [224.688248] Starting dynamic subtest: pipe-C-DP-1 [225.409833] Dynamic subtest pipe-C-DP-1: SUCCESS (0.749s) [225.411703] Subtest read-crc: SUCCESS (3.138s) [226.610100] [119/165] (779s left) kms_pipe_crc_basic (read-crc-frame-sequence) [227.147771] Starting subtest: read-crc-frame-sequence [227.152283] Starting dynamic subtest: pipe-A-DP-1 [228.433154] Dynamic subtest pipe-A-DP-1: SUCCESS (1.279s) [228.448814] Starting dynamic subtest: pipe-B-DP-3 [229.302878] Dynamic subtest pipe-B-DP-3: SUCCESS (0.863s) [229.325085] Starting dynamic subtest: pipe-C-DP-1 [230.060607] Dynamic subtest pipe-C-DP-1: SUCCESS (0.752s) [230.061208] Subtest read-crc-frame-sequence: SUCCESS (2.915s) [230.644953] [120/165] (775s left) kms_pm_backlight (basic-brightness) [231.176025] Subtest basic-brightness: SKIP (0.000s) [231.216032] [121/165] (774s left) kms_pm_rpm (basic-pci-d3-state) [233.741515] Starting subtest: basic-pci-d3-state [234.554856] Subtest basic-pci-d3-state: SUCCESS (0.817s) [235.433272] [122/165] (770s left) kms_pm_rpm (basic-rte) [235.515852] Starting subtest: basic-rte [238.520230] Subtest basic-rte: SUCCESS (3.005s) [239.985358] [123/165] (765s left) kms_psr (psr-primary-page-flip) [240.534497] Subtest psr-primary-page-flip: SKIP (0.000s) [240.582647] [124/165] (765s left) kms_psr (psr-cursor-plane-move) [241.150209] Subtest psr-cursor-plane-move: SKIP (0.000s) [241.191798] [125/165] (764s left) kms_psr (psr-sprite-plane-onoff) [241.747270] Subtest psr-sprite-plane-onoff: SKIP (0.000s) [241.794112] [126/165] (764s left) kms_psr (psr-primary-mmap-gtt) [242.349084] Subtest psr-primary-mmap-gtt: SKIP (0.000s) [242.391887] [127/165] (763s left) kms_setmode (basic-clone-single-crtc) [242.479545] Starting subtest: basic-clone-single-crtc [243.063652] Subtest basic-clone-single-crtc: SKIP (0.583s) [243.624157] [128/165] (762s left) i915_pm_rps (basic-api) [243.717667] Starting subtest: basic-api [243.721451] Subtest basic-api: SUCCESS (0.004s) [243.801341] [129/165] (762s left) prime_self_import (basic-llseek-bad) [243.866715] Starting subtest: basic-llseek-bad [243.880323] Subtest basic-llseek-bad: SUCCESS (0.012s) [243.970616] [130/165] (762s left) prime_self_import (basic-llseek-size) [244.045711] Starting subtest: basic-llseek-size [244.058415] Subtest basic-llseek-size: SUCCESS (0.013s) [244.142226] [131/165] (761s left) prime_self_import (basic-with_fd_dup) [244.205090] Starting subtest: basic-with_fd_dup [244.216340] Subtest basic-with_fd_dup: SUCCESS (0.011s) [244.307929] [132/165] (761s left) prime_self_import (basic-with_one_bo) [244.381789] Starting subtest: basic-with_one_bo [244.391498] Subtest basic-with_one_bo: SUCCESS (0.010s) [244.465133] [133/165] (761s left) prime_self_import (basic-with_one_bo_two_files) [244.532575] Starting subtest: basic-with_one_bo_two_files [244.549557] Subtest basic-with_one_bo_two_files: SUCCESS (0.017s) [244.655979] [134/165] (761s left) prime_self_import (basic-with_two_bos) [244.711615] Starting subtest: basic-with_two_bos [244.725212] Subtest basic-with_two_bos: SUCCESS (0.014s) [244.839670] [135/165] (761s left) prime_vgem (basic-fence-flip) [244.948072] Starting subtest: basic-fence-flip [245.895888] Subtest basic-fence-flip: SUCCESS (0.948s) [245.994516] [136/165] (760s left) prime_vgem (basic-fence-mmap) [246.096211] Starting subtest: basic-fence-mmap [246.121434] Subtest basic-fence-mmap: SUCCESS (0.028s) [246.221728] [137/165] (760s left) prime_vgem (basic-fence-read) [246.327255] Starting subtest: basic-fence-read [246.387361] Subtest basic-fence-read: SUCCESS (0.060s) [246.481742] [138/165] (759s left) prime_vgem (basic-gtt) [246.585884] Starting subtest: basic-gtt [246.598085] Subtest basic-gtt: SUCCESS (0.012s) [246.671367] [139/165] (759s left) prime_vgem (basic-read) [246.755857] Starting subtest: basic-read [246.783387] Subtest basic-read: SUCCESS (0.027s) [246.871492] [140/165] (759s left) prime_vgem (basic-write) [246.966868] Starting subtest: basic-write [246.996262] Subtest basic-write: SUCCESS (0.030s) [247.070499] [141/165] (759s left) vgem_basic (setversion) [247.136433] Starting subtest: setversion [247.137835] Subtest setversion: SUCCESS (0.000s) [247.186914] [142/165] (759s left) vgem_basic (create) [247.252840] Starting subtest: create [247.253387] Subtest create: SUCCESS (0.000s) [247.304527] [143/165] (759s left) vgem_basic (debugfs) [247.381826] Starting subtest: debugfs [247.382459] Subtest debugfs: SUCCESS (0.000s) [247.435181] [144/165] (758s left) vgem_basic (dmabuf-export) [247.519568] Starting subtest: dmabuf-export [247.528853] Subtest dmabuf-export: SUCCESS (0.008s) [247.656364] [145/165] (758s left) vgem_basic (dmabuf-fence) [247.746398] Starting subtest: dmabuf-fence [247.747122] Subtest dmabuf-fence: SUCCESS (0.001s) [247.796811] [146/165] (758s left) vgem_basic (dmabuf-fence-before) [247.869889] Starting subtest: dmabuf-fence-before [247.873144] Subtest dmabuf-fence-before: SUCCESS (0.000s) [247.922307] [147/165] (758s left) vgem_basic (dmabuf-mmap) [247.996453] Starting subtest: dmabuf-mmap [248.009234] Subtest dmabuf-mmap: SUCCESS (0.013s) [248.063421] [148/165] (758s left) vgem_basic (mmap) [248.129858] Starting subtest: mmap [248.138845] Subtest mmap: SUCCESS (0.009s) [248.187341] [149/165] (758s left) vgem_basic (second-client) [248.252902] Starting subtest: second-client [248.285928] Subtest second-client: SUCCESS (0.033s) [248.345417] [150/165] (758s left) vgem_basic (sysfs) [248.430144] Starting subtest: sysfs [248.430766] Subtest sysfs: SUCCESS (0.001s) [248.483586] [151/165] (758s left) core_hotunplug (unbind-rebind) [248.612638] Starting subtest: unbind-rebind [253.127345] Subtest unbind-rebind: SUCCESS (4.512s) [253.162793] [152/165] (753s left) vgem_basic (unload) [253.233447] Starting subtest: unload [282.291921] Subtest unload: SUCCESS (29.056s) [282.428498] [153/165] (724s left) i915_module_load (reload) [282.486259] Starting subtest: reload [287.103053] Subtest reload: SUCCESS (4.616s) [287.136874] [154/165] (719s left) gem_lmem_swapping (basic) [287.240374] Subtest basic: SKIP (0.000s) [287.268477] [155/165] (719s left) gem_lmem_swapping (parallel-random-engines) [287.330127] Subtest parallel-random-engines: SKIP (0.000s) [287.362535] [156/165] (719s left) gem_lmem_swapping (random-engines) [287.424223] Subtest random-engines: SKIP (0.000s) [287.451701] [157/165] (719s left) gem_lmem_swapping (verify-random) [287.511040] Subtest verify-random: SKIP (0.000s) [287.545575] [158/165] (719s left) i915_pm_rpm (module-reload) [290.515079] Starting subtest: module-reload [302.662927] Subtest module-reload: SUCCESS (12.156s) [302.682792] [159/165] (704s left) i915_selftest (live) [302.758187] Starting subtest: live [302.758943] Starting dynamic subtest: sanitycheck [304.010573] Dynamic subtest sanitycheck: SUCCESS (1.254s) [304.017604] Starting dynamic subtest: uncore [305.313159] Dynamic subtest uncore: SUCCESS (1.299s) [305.319027] Starting dynamic subtest: workarounds [306.827488] Dynamic subtest workarounds: SUCCESS (1.514s) [306.834630] Starting dynamic subtest: gt_engines [308.595451] Dynamic subtest gt_engines: SUCCESS (1.766s) [308.602449] Starting dynamic subtest: gt_timelines [322.860475] Dynamic subtest gt_timelines: SUCCESS (14.263s) [322.869766] Starting dynamic subtest: gt_contexts [324.316147] Dynamic subtest gt_contexts: SUCCESS (1.452s) [324.321917] Starting dynamic subtest: gt_lrc [328.712541] Dynamic subtest gt_lrc: SUCCESS (4.396s) [328.720965] Starting dynamic subtest: gt_mocs [330.124672] Dynamic subtest gt_mocs: SUCCESS (1.410s) [330.132217] Starting dynamic subtest: gt_pm [337.361443] Dynamic subtest gt_pm: SUCCESS (7.235s) [337.368787] Starting dynamic subtest: gt_heartbeat [338.786140] Dynamic subtest gt_heartbeat: SUCCESS (1.421s) [338.793726] Starting dynamic subtest: gt_tlb [341.319740] Dynamic subtest gt_tlb: SUCCESS (2.533s) [341.327501] Starting dynamic subtest: requests [350.230505] Dynamic subtest requests: SUCCESS (8.909s) [350.238436] Starting dynamic subtest: migrate [354.419607] Dynamic subtest migrate: SUCCESS (4.187s) [354.426692] Starting dynamic subtest: active [355.741966] Dynamic subtest active: SUCCESS (1.321s) [355.751409] Starting dynamic subtest: objects [357.926691] Dynamic subtest objects: SUCCESS (2.183s) [357.936098] Starting dynamic subtest: mman [363.025492] Dynamic subtest mman: SUCCESS (5.097s) [363.032667] Starting dynamic subtest: dmabuf [364.413944] Dynamic subtest dmabuf: SUCCESS (1.386s) [364.421330] Starting dynamic subtest: vma [365.711633] Dynamic subtest vma: SUCCESS (1.296s) [365.721838] Starting dynamic subtest: coherency [368.304151] Dynamic subtest coherency: SUCCESS (2.589s) [368.311790] Starting dynamic subtest: gtt [376.931731] Dynamic subtest gtt: SUCCESS (8.627s) [376.941123] Starting dynamic subtest: gem [378.379524] Dynamic subtest gem: SUCCESS (1.446s) [378.387438] Starting dynamic subtest: evict [381.556514] Dynamic subtest evict: SUCCESS (3.175s) [381.563965] Starting dynamic subtest: hugepages [388.735479] Dynamic subtest hugepages: SUCCESS (7.177s) [388.743829] Starting dynamic subtest: gem_contexts [406.742165] Dynamic subtest gem_contexts: SUCCESS (18.003s) [406.748540] Starting dynamic subtest: client [408.040176] Dynamic subtest client: SUCCESS (1.296s) [408.047818] Starting dynamic subtest: gem_migrate [409.348765] Dynamic subtest gem_migrate: SUCCESS (1.308s) [409.358992] Starting dynamic subtest: reset [411.299142] Dynamic subtest reset: SUCCESS (1.947s) [411.305068] Starting dynamic subtest: memory_region [412.660692] Dynamic subtest memory_region: SUCCESS (1.361s) [412.668118] Starting dynamic subtest: hangcheck [442.512167] Dynamic subtest hangcheck: SUCCESS (29.848s) [442.519330] Starting dynamic subtest: execlists [456.602211] Dynamic subtest execlists: SUCCESS (14.088s) [456.608435] Starting dynamic subtest: ring_submission [457.891563] Dynamic subtest ring_submission: SUCCESS (1.289s) [457.899024] Starting dynamic subtest: perf [459.296171] Dynamic subtest perf: SUCCESS (1.402s) [459.302437] Starting dynamic subtest: slpc [460.590548] Dynamic subtest slpc: SUCCESS (1.294s) [460.598430] Starting dynamic subtest: guc [461.895159] Dynamic subtest guc: SUCCESS (1.301s) [461.901585] Starting dynamic subtest: guc_multi_lrc [463.151577] Dynamic subtest guc_multi_lrc: SUCCESS (1.256s) [463.160104] Starting dynamic subtest: guc_hang [464.502581] Dynamic subtest guc_hang: SUCCESS (1.349s) [464.511815] Starting dynamic subtest: late_gt_pm [465.841680] Dynamic subtest late_gt_pm: SUCCESS (1.337s) [465.842275] Subtest live: SUCCESS (163.087s) [465.864483] [160/165] (540s left) dmabuf (all-tests) [465.933533] Starting subtest: all-tests [465.934327] Starting dynamic subtest: sanitycheck [466.002325] Dynamic subtest sanitycheck: SUCCESS (0.068s) [466.020240] Starting dynamic subtest: dma_fence [466.189068] Dynamic subtest dma_fence: SUCCESS (0.184s) [466.199040] Starting dynamic subtest: dma_fence_chain [480.197222] Dynamic subtest dma_fence_chain: SUCCESS (14.004s) [480.210249] Starting dynamic subtest: dma_fence_unwrap [480.272642] Dynamic subtest dma_fence_unwrap: SUCCESS (0.074s) [480.303117] Starting dynamic subtest: dma_resv [480.366521] Dynamic subtest dma_resv: SUCCESS (0.087s) [480.370607] Subtest all-tests: SUCCESS (14.435s) [480.412900] [161/165] (526s left) i915_suspend (basic-s2idle-without-i915) [480.471380] Starting subtest: basic-s2idle-without-i915 [501.633088] Subtest basic-s2idle-without-i915: SUCCESS (6.309s) [504.649510] [162/165] (519s left) i915_suspend (basic-s3-without-i915) [504.708823] Starting subtest: basic-s3-without-i915 [525.969874] Subtest basic-s3-without-i915: SUCCESS (4.515s) [528.898630] [163/165] (513s left) gem_exec_suspend (basic-s0) [529.013004] Starting subtest: basic-S0 [529.015225] Starting dynamic subtest: smem [548.286700] Dynamic subtest smem: SUCCESS (4.039s) [548.287611] Subtest basic-S0: SUCCESS (4.041s) [553.481162] [164/165] (509s left) gem_exec_suspend (basic-s3) [553.576525] Starting subtest: basic-S3 [553.579055] Starting dynamic subtest: smem [574.288082] Dynamic subtest smem: SUCCESS (4.133s) [574.288892] Subtest basic-S3: SUCCESS (4.136s) [577.595464] [165/165] (504s left) kms_pipe_crc_basic (suspend-read-crc) [578.188369] Starting subtest: suspend-read-crc [578.189028] Starting dynamic subtest: pipe-A-DP-1 [599.070541] Dynamic subtest pipe-A-DP-1: SUCCESS (4.200s) [599.088178] Starting dynamic subtest: pipe-B-DP-3 [619.359040] Dynamic subtest pipe-B-DP-3: SUCCESS (3.618s) [619.375506] Starting dynamic subtest: pipe-C-DP-1 [639.080434] Dynamic subtest pipe-C-DP-1: SUCCESS (3.198s) [639.085682] Subtest suspend-read-crc: SUCCESS (11.033s) [643.619592] Closing watchdogs Done.