[43.669288] Initializing watchdogs
[43.669470]   /dev/watchdog0
[43.708745] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:4e51 Intel Jasperlake (Gen11) 
[43.716912] [001/161] (960s left) i915_module_load (load)
[44.026435] Starting subtest: load
[48.663263] Subtest load: SUCCESS (4.632s)
[48.734105] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[48.737493] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[48.742653] [002/161] (955s left) core_auth (basic-auth)
[48.858278] Starting subtest: basic-auth
[48.859614] Subtest basic-auth: SUCCESS (0.001s)
[48.990835] [003/161] (955s left) debugfs_test (read_all_entries)
[49.103660] Starting subtest: read_all_entries
[49.209493] Subtest read_all_entries: SUCCESS (0.105s)
[49.435953] [004/161] (955s left) debugfs_test (basic-hwmon)
[49.580537] Starting subtest: basic-hwmon
[49.582759] Subtest basic-hwmon: SKIP (0.000s)
[49.757292] [005/161] (954s left) debugfs_test (sysfs)
[49.863249] Starting subtest: sysfs
[49.983304] Subtest sysfs: SUCCESS (0.120s)
[50.177923] [006/161] (954s left) fbdev (eof)
[50.292410] Starting subtest: eof
[50.293525] Subtest eof: SUCCESS (0.001s)
[50.415771] [007/161] (954s left) fbdev (info)
[50.512800] Starting subtest: info
[50.513937] Subtest info: SUCCESS (0.000s)
[50.643407] [008/161] (954s left) fbdev (nullptr)
[50.757664] Starting subtest: nullptr
[50.758741] Subtest nullptr: SUCCESS (0.000s)
[50.872589] [009/161] (954s left) fbdev (read)
[50.975081] Starting subtest: read
[58.002406] Subtest read: SUCCESS (7.059s)
[58.130387] [010/161] (946s left) fbdev (write)
[58.250827] Starting subtest: write
[61.968999] Subtest write: SUCCESS (3.770s)
[62.051861] [011/161] (942s left) gem_basic (bad-close)
[62.151721] Starting subtest: bad-close
[62.154116] Subtest bad-close: SUCCESS (0.000s)
[62.286112] [012/161] (942s left) gem_basic (create-close)
[62.382780] Starting subtest: create-close
[62.385298] Subtest create-close: SUCCESS (0.000s)
[62.530072] [013/161] (942s left) gem_basic (create-fd-close)
[62.628115] Starting subtest: create-fd-close
[62.630168] Subtest create-fd-close: SUCCESS (0.000s)
[62.792114] [014/161] (942s left) gem_busy (busy@all-engines)
[62.917370] Starting subtest: busy
[62.918795] Starting dynamic subtest: all-engines
[63.003225] Dynamic subtest all-engines: SUCCESS (0.087s)
[63.004465] Subtest busy: SUCCESS (0.088s)
[63.450926] [015/161] (941s left) gem_close_race (basic-process)
[63.578347] Starting subtest: basic-process
[63.655712] Subtest basic-process: SUCCESS (0.079s)
[63.911856] [016/161] (941s left) gem_close_race (basic-threads)
[64.036550] Starting subtest: basic-threads
[65.249376] Subtest basic-threads: SUCCESS (1.230s)
[65.535818] [017/161] (939s left) gem_ctx_create (basic)
[65.658856] Starting subtest: basic
[65.659928] Subtest basic: SUCCESS (0.000s)
[65.915319] [018/161] (939s left) gem_ctx_create (basic-files)
[66.034665] Starting subtest: basic-files
[68.066664] Subtest basic-files: SUCCESS (2.052s)
[68.361191] [019/161] (937s left) gem_ctx_exec (basic)
[68.466104] Starting subtest: basic
[68.472091] Subtest basic: SUCCESS (0.005s)
[68.636816] [020/161] (937s left) gem_exec_basic (basic)
[68.750536] Starting subtest: basic
[68.751721] Starting dynamic subtest: rcs0-smem
[68.752966] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[68.769910] Starting dynamic subtest: bcs0-smem
[68.771031] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[68.787123] Starting dynamic subtest: vcs0-smem
[68.788175] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[68.803983] Starting dynamic subtest: vecs0-smem
[68.805075] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[68.806081] Subtest basic: SUCCESS (0.006s)
[69.003426] [021/161] (936s left) gem_exec_create (basic)
[69.127479] Starting subtest: basic
[69.128725] Starting dynamic subtest: smem
[71.247369] Dynamic subtest smem: SUCCESS (2.134s)
[71.248642] Subtest basic: SUCCESS (2.134s)
[71.492149] [022/161] (934s left) gem_exec_fence (basic-busy)
[71.751087] Starting subtest: basic-busy
[71.752404] Starting dynamic subtest: rcs0
[71.855475] Dynamic subtest rcs0: SUCCESS (0.107s)
[71.874457] Starting dynamic subtest: bcs0
[72.151088] Dynamic subtest bcs0: SUCCESS (0.286s)
[72.168777] Starting dynamic subtest: vcs0
[72.541830] Dynamic subtest vcs0: SUCCESS (0.392s)
[72.559901] Starting dynamic subtest: vecs0
[72.951845] Dynamic subtest vecs0: SUCCESS (0.411s)
[72.953250] Subtest basic-busy: SUCCESS (1.200s)
[73.843519] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[73.857956] [023/161] (932s left) gem_exec_fence (basic-wait)
[74.189233] Starting subtest: basic-wait
[74.190571] Starting dynamic subtest: rcs0
[74.505718] Dynamic subtest rcs0: SUCCESS (0.322s)
[74.526867] Starting dynamic subtest: bcs0
[74.587462] Dynamic subtest bcs0: SUCCESS (0.082s)
[74.608874] Starting dynamic subtest: vcs0
[74.612361] Dynamic subtest vcs0: SUCCESS (0.025s)
[74.630241] Starting dynamic subtest: vecs0
[74.636390] Dynamic subtest vecs0: SUCCESS (0.024s)
[74.637531] Subtest basic-wait: SUCCESS (0.455s)
[75.245233] [024/161] (930s left) gem_exec_fence (basic-await)
[75.508824] Starting subtest: basic-await
[75.510051] Starting dynamic subtest: rcs0
[75.639787] Dynamic subtest rcs0: SUCCESS (0.121s)
[75.660764] Starting dynamic subtest: bcs0
[75.755124] Dynamic subtest bcs0: SUCCESS (0.122s)
[75.795719] Starting dynamic subtest: vcs0
[75.897448] Dynamic subtest vcs0: SUCCESS (0.143s)
[75.938326] Starting dynamic subtest: vecs0
[76.038186] Dynamic subtest vecs0: SUCCESS (0.140s)
[76.040560] Subtest basic-await: SUCCESS (0.531s)
[76.690096] [025/161] (929s left) gem_exec_fence (nb-await)
[76.951437] Starting subtest: nb-await
[76.952708] Starting dynamic subtest: rcs0
[77.017958] Dynamic subtest rcs0: SUCCESS (0.065s)
[77.044260] Starting dynamic subtest: bcs0
[77.089827] Dynamic subtest bcs0: SUCCESS (0.071s)
[77.116173] Starting dynamic subtest: vcs0
[77.163091] Dynamic subtest vcs0: SUCCESS (0.072s)
[77.205599] Starting dynamic subtest: vecs0
[77.239964] Dynamic subtest vecs0: SUCCESS (0.076s)
[77.242250] Subtest nb-await: SUCCESS (0.289s)
[77.870662] [026/161] (928s left) gem_exec_gttfill (basic)
[77.996456] Starting subtest: basic
[91.033961] Subtest basic: SUCCESS (13.040s)
[91.283367] [027/161] (915s left) gem_exec_parallel (engines)
[91.479054] Starting subtest: engines
[91.480252] Starting dynamic subtest: basic
[94.745494] Dynamic subtest basic: SUCCESS (3.233s)
[94.784986] Starting dynamic subtest: contexts
[103.177940] Dynamic subtest contexts: SUCCESS (8.468s)
[103.221901] Starting dynamic subtest: fds
[106.719846] Dynamic subtest fds: SUCCESS (3.541s)
[106.766903] Starting dynamic subtest: userptr
[109.495098] Dynamic subtest userptr: SUCCESS (2.742s)
[109.496897] Subtest engines: SUCCESS (17.986s)
[109.738986] [028/161] (896s left) gem_exec_store (basic)
[109.861714] Starting subtest: basic
[109.930681] Subtest basic: SUCCESS (0.069s)
[110.195366] [029/161] (896s left) gem_flink_basic (bad-flink)
[110.295814] Starting subtest: bad-flink
[110.297024] Subtest bad-flink: SUCCESS (0.000s)
[110.442241] [030/161] (896s left) gem_flink_basic (bad-open)
[110.541808] Starting subtest: bad-open
[110.543258] Subtest bad-open: SUCCESS (0.000s)
[110.691047] [031/161] (895s left) gem_flink_basic (basic)
[110.789379] Starting subtest: basic
[110.790518] Subtest basic: SUCCESS (0.000s)
[110.957974] [032/161] (895s left) gem_flink_basic (double-flink)
[111.056407] Starting subtest: double-flink
[111.057550] Subtest double-flink: SUCCESS (0.000s)
[111.222876] [033/161] (895s left) gem_flink_basic (flink-lifetime)
[111.321232] Starting subtest: flink-lifetime
[111.324418] Subtest flink-lifetime: SUCCESS (0.002s)
[111.473129] [034/161] (895s left) gem_huc_copy (huc-copy)
[111.581130] Starting subtest: huc-copy
[111.583442] Subtest huc-copy: SKIP (0.000s)
[111.773494] [035/161] (895s left) gem_linear_blits (basic)
[112.023366] Starting subtest: basic
[112.040700] Subtest basic: SUCCESS (0.017s)
[112.250963] [036/161] (894s left) gem_mmap (basic)
[112.416195] Starting subtest: basic
[112.421267] Subtest basic: SUCCESS (0.001s)
[112.577473] [037/161] (894s left) gem_mmap_gtt (basic)
[112.680958] Starting subtest: basic
[112.682228] Subtest basic: SUCCESS (0.002s)
[112.821621] [038/161] (894s left) gem_render_linear_blits (basic)
[112.929594] Starting subtest: basic
[112.986635] Subtest basic: SUCCESS (0.057s)
[113.110282] [039/161] (894s left) gem_render_tiled_blits (basic)
[113.218805] Starting subtest: basic
[113.284659] Subtest basic: SUCCESS (0.066s)
[113.409393] [040/161] (894s left) gem_ringfill (basic-all)
[113.689051] Starting subtest: basic-all
[114.970164] Subtest basic-all: SUCCESS (1.272s)
[115.107765] [041/161] (892s left) gem_softpin (allocator-basic)
[115.219330] Starting subtest: allocator-basic
[115.406465] Subtest allocator-basic: SUCCESS (0.177s)
[115.572310] [042/161] (892s left) gem_softpin (allocator-basic-reserve)
[115.680188] Starting subtest: allocator-basic-reserve
[115.872295] Subtest allocator-basic-reserve: SUCCESS (0.182s)
[116.046225] [043/161] (891s left) gem_softpin (safe-alignment)
[116.157035] Starting subtest: safe-alignment
[116.176807] Subtest safe-alignment: SUCCESS (0.020s)
[116.360813] [044/161] (891s left) gem_sync (basic-all)
[116.486190] Starting subtest: basic-all
[118.968401] Subtest basic-all: SUCCESS (2.448s)
[119.212791] [045/161] (888s left) gem_sync (basic-each)
[119.335075] Starting subtest: basic-each
[121.611686] Subtest basic-each: SUCCESS (2.275s)
[121.908984] [046/161] (886s left) gem_tiled_blits (basic)
[122.099345] Starting subtest: basic
[122.134933] Subtest basic: SUCCESS (0.036s)
[122.329467] [047/161] (885s left) gem_tiled_fence_blits (basic)
[122.621606] Starting subtest: basic
[122.653866] Subtest basic: SUCCESS (0.033s)
[122.862187] [048/161] (885s left) gem_tiled_pread_basic
[123.457655] [049/161] (884s left) gem_wait (busy@all-engines)
[123.592407] Starting subtest: busy
[123.593621] Starting dynamic subtest: all-engines
[124.116925] Dynamic subtest all-engines: SUCCESS (0.524s)
[124.118153] Subtest busy: SUCCESS (0.525s)
[124.427276] [050/161] (884s left) gem_wait (wait@all-engines)
[124.561341] Starting subtest: wait
[124.562551] Starting dynamic subtest: all-engines
[125.602314] Dynamic subtest all-engines: SUCCESS (1.040s)
[125.604191] Subtest wait: SUCCESS (1.041s)
[125.910165] [051/161] (882s left) i915_getparams_basic (basic-eu-total)
[126.010283] Starting subtest: basic-eu-total
[126.012492] Subtest basic-eu-total: SUCCESS (0.000s)
[126.157776] [052/161] (882s left) i915_getparams_basic (basic-subslice-total)
[126.257438] Starting subtest: basic-subslice-total
[126.259613] Subtest basic-subslice-total: SUCCESS (0.000s)
[126.407870] [053/161] (882s left) i915_hangman (error-state-basic)
[126.530537] Starting subtest: error-state-basic
[126.544391] Subtest error-state-basic: SUCCESS (0.014s)
[126.691791] [054/161] (882s left) i915_pciid
[126.985218] [055/161] (882s left) kms_addfb_basic (addfb25-4-tiled)
[127.146604] Starting subtest: addfb25-4-tiled
[127.147732] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[127.324143] [056/161] (881s left) kms_addfb_basic (addfb25-bad-modifier)
[127.481156] Starting subtest: addfb25-bad-modifier
[127.482383] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[127.647070] [057/161] (881s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[127.800188] Starting subtest: addfb25-framebuffer-vs-set-tiling
[127.801384] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[127.987634] [058/161] (881s left) kms_addfb_basic (addfb25-modifier-no-flag)
[128.139178] Starting subtest: addfb25-modifier-no-flag
[128.140367] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[128.312538] [059/161] (881s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[128.464169] Starting subtest: addfb25-x-tiled-legacy
[128.465300] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[128.625950] [060/161] (880s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[128.777103] Starting subtest: addfb25-x-tiled-mismatch-legacy
[128.778444] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[128.953107] [061/161] (880s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[129.107738] Starting subtest: addfb25-yf-tiled-legacy
[129.108909] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[129.277127] [062/161] (880s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[129.429456] Starting subtest: addfb25-y-tiled-legacy
[129.430613] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[129.595387] [063/161] (880s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[129.748566] Starting subtest: addfb25-y-tiled-small-legacy
[129.749794] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[129.913650] [064/161] (879s left) kms_addfb_basic (bad-pitch-0)
[130.016093] Starting subtest: bad-pitch-0
[130.017294] Subtest bad-pitch-0: SUCCESS (0.000s)
[130.256979] [065/161] (879s left) kms_addfb_basic (bad-pitch-1024)
[130.359230] Starting subtest: bad-pitch-1024
[130.360334] Subtest bad-pitch-1024: SUCCESS (0.000s)
[130.570809] [066/161] (879s left) kms_addfb_basic (bad-pitch-128)
[130.671152] Starting subtest: bad-pitch-128
[130.672257] Subtest bad-pitch-128: SUCCESS (0.000s)
[130.904703] [067/161] (879s left) kms_addfb_basic (bad-pitch-256)
[131.004376] Starting subtest: bad-pitch-256
[131.005520] Subtest bad-pitch-256: SUCCESS (0.000s)
[131.224841] [068/161] (878s left) kms_addfb_basic (bad-pitch-32)
[131.323421] Starting subtest: bad-pitch-32
[131.324533] Subtest bad-pitch-32: SUCCESS (0.000s)
[131.525220] [069/161] (878s left) kms_addfb_basic (bad-pitch-63)
[131.625638] Starting subtest: bad-pitch-63
[131.626807] Subtest bad-pitch-63: SUCCESS (0.000s)
[131.867628] [070/161] (878s left) kms_addfb_basic (bad-pitch-65536)
[131.967161] Starting subtest: bad-pitch-65536
[131.968491] Subtest bad-pitch-65536: SUCCESS (0.000s)
[132.201119] [071/161] (878s left) kms_addfb_basic (bad-pitch-999)
[132.301473] Starting subtest: bad-pitch-999
[132.302563] Subtest bad-pitch-999: SUCCESS (0.000s)
[132.529292] [072/161] (878s left) kms_addfb_basic (basic)
[132.630525] Starting subtest: basic
[132.631706] Subtest basic: SUCCESS (0.000s)
[132.849442] [073/161] (877s left) kms_addfb_basic (basic-x-tiled-legacy)
[132.952389] Starting subtest: basic-x-tiled-legacy
[132.953483] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[133.184578] [074/161] (877s left) kms_addfb_basic (basic-y-tiled-legacy)
[133.285984] Starting subtest: basic-y-tiled-legacy
[133.287281] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[133.490984] [075/161] (877s left) kms_addfb_basic (bo-too-small)
[133.643829] Starting subtest: bo-too-small
[133.645238] Subtest bo-too-small: SUCCESS (0.001s)
[133.825487] [076/161] (877s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[133.976833] Starting subtest: bo-too-small-due-to-tiling
[133.978119] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[134.142880] [077/161] (876s left) kms_addfb_basic (clobberred-modifier)
[134.247294] Starting subtest: clobberred-modifier
[134.248381] Subtest clobberred-modifier: SUCCESS (0.000s)
[134.458031] [078/161] (876s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[134.560392] Starting subtest: framebuffer-vs-set-tiling
[134.561480] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[134.780429] [079/161] (876s left) kms_addfb_basic (invalid-get-prop)
[134.880567] Starting subtest: invalid-get-prop
[134.881671] Subtest invalid-get-prop: SUCCESS (0.000s)
[135.118597] [080/161] (876s left) kms_addfb_basic (invalid-get-prop-any)
[135.219990] Starting subtest: invalid-get-prop-any
[135.221396] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[135.446677] [081/161] (875s left) kms_addfb_basic (invalid-set-prop)
[135.546580] Starting subtest: invalid-set-prop
[135.547746] Subtest invalid-set-prop: SUCCESS (0.000s)
[135.761140] [082/161] (875s left) kms_addfb_basic (invalid-set-prop-any)
[135.860221] Starting subtest: invalid-set-prop-any
[135.861512] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[136.078153] [083/161] (875s left) kms_addfb_basic (no-handle)
[136.178600] Starting subtest: no-handle
[136.179698] Subtest no-handle: SUCCESS (0.000s)
[136.405098] [084/161] (875s left) kms_addfb_basic (size-max)
[136.555827] Starting subtest: size-max
[136.557156] Subtest size-max: SUCCESS (0.000s)
[136.739957] [085/161] (875s left) kms_addfb_basic (small-bo)
[136.893042] Starting subtest: small-bo
[136.894398] Subtest small-bo: SUCCESS (0.000s)
[137.063039] [086/161] (874s left) kms_addfb_basic (tile-pitch-mismatch)
[137.166538] Starting subtest: tile-pitch-mismatch
[137.167606] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[137.392187] [087/161] (874s left) kms_addfb_basic (too-high)
[137.542953] Starting subtest: too-high
[137.546010] Subtest too-high: SUCCESS (0.001s)
[137.712954] [088/161] (874s left) kms_addfb_basic (too-wide)
[137.866109] Starting subtest: too-wide
[137.867349] Subtest too-wide: SUCCESS (0.000s)
[138.042813] [089/161] (874s left) kms_addfb_basic (unused-handle)
[138.142541] Starting subtest: unused-handle
[138.143613] Subtest unused-handle: SUCCESS (0.000s)
[138.371148] [090/161] (873s left) kms_addfb_basic (unused-modifier)
[138.469601] Starting subtest: unused-modifier
[138.470746] Subtest unused-modifier: SUCCESS (0.000s)
[138.691934] [091/161] (873s left) kms_addfb_basic (unused-offsets)
[138.791680] Starting subtest: unused-offsets
[138.792835] Subtest unused-offsets: SUCCESS (0.000s)
[139.019291] [092/161] (873s left) kms_addfb_basic (unused-pitches)
[139.117069] Starting subtest: unused-pitches
[139.118346] Subtest unused-pitches: SUCCESS (0.000s)
[139.352703] [093/161] (873s left) kms_busy (basic)
[139.509532] Starting subtest: basic
[139.511968] Starting dynamic subtest: flip
[140.503115] Dynamic subtest flip: SUCCESS (0.991s)
[140.529017] Starting dynamic subtest: modeset
[144.162795] Dynamic subtest modeset: SUCCESS (3.659s)
[144.164884] Subtest basic: SUCCESS (4.653s)
[145.148797] [094/161] (867s left) kms_prop_blob (basic)
[145.250935] Starting subtest: basic
[145.252159] Subtest basic: SUCCESS (0.000s)
[145.396701] [095/161] (867s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[145.549410] Starting subtest: basic-busy-flip-before-cursor-atomic
[145.551857] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[145.697330] [096/161] (867s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[145.849697] Starting subtest: basic-busy-flip-before-cursor-legacy
[145.851976] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[146.002407] [097/161] (866s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[146.154421] Starting subtest: basic-flip-after-cursor-atomic
[147.425898] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.270s)
[148.333261] [098/161] (864s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[148.486449] Starting subtest: basic-flip-after-cursor-legacy
[149.744619] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.257s)
[150.673242] [099/161] (862s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[150.826472] Starting subtest: basic-flip-after-cursor-varying-size
[152.081035] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.254s)
[153.009555] [100/161] (860s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[153.162542] Starting subtest: basic-flip-before-cursor-atomic
[154.416499] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.254s)
[155.340715] [101/161] (857s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[155.493399] Starting subtest: basic-flip-before-cursor-legacy
[156.765676] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.269s)
[157.673866] [102/161] (855s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[157.826505] Starting subtest: basic-flip-before-cursor-varying-size
[159.084247] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.257s)
[160.010954] [103/161] (853s left) kms_dsc (dsc-basic)
[160.162036] Starting subtest: dsc-basic
[160.167377] Subtest dsc-basic: SKIP (0.001s)
[160.308991] [104/161] (853s left) kms_flip (basic-flip-vs-dpms)
[160.467430] Starting subtest: basic-flip-vs-dpms
[160.468656] Starting dynamic subtest: A-eDP1
[163.317393] Dynamic subtest A-eDP1: SUCCESS (2.852s)
[163.354896] Starting dynamic subtest: B-eDP1
[165.683376] Dynamic subtest B-eDP1: SUCCESS (2.360s)
[165.720522] Starting dynamic subtest: C-eDP1
[168.041922] Dynamic subtest C-eDP1: SUCCESS (2.358s)
[168.046480] Subtest basic-flip-vs-dpms: SUCCESS (7.583s)
[169.008817] [105/161] (844s left) kms_flip (basic-flip-vs-modeset)
[169.164080] Starting subtest: basic-flip-vs-modeset
[169.165827] Starting dynamic subtest: A-eDP1
[171.994476] Dynamic subtest A-eDP1: SUCCESS (2.827s)
[172.040906] Starting dynamic subtest: B-eDP1
[174.334879] Dynamic subtest B-eDP1: SUCCESS (2.337s)
[174.373957] Starting dynamic subtest: C-eDP1
[176.695802] Dynamic subtest C-eDP1: SUCCESS (2.359s)
[176.701339] Subtest basic-flip-vs-modeset: SUCCESS (7.538s)
[177.682708] [106/161] (836s left) kms_flip (basic-flip-vs-wf_vblank)
[177.834062] Starting subtest: basic-flip-vs-wf_vblank
[177.836106] Starting dynamic subtest: A-eDP1
[180.149793] Dynamic subtest A-eDP1: SUCCESS (2.312s)
[180.178113] Starting dynamic subtest: B-eDP1
[182.468127] Dynamic subtest B-eDP1: SUCCESS (2.316s)
[182.493470] Starting dynamic subtest: C-eDP1
[184.702816] Dynamic subtest C-eDP1: SUCCESS (2.232s)
[184.706292] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.872s)
[186.084336] [107/161] (827s left) kms_flip (basic-plain-flip)
[186.238266] Starting subtest: basic-plain-flip
[186.240196] Starting dynamic subtest: A-eDP1
[188.266574] Dynamic subtest A-eDP1: SUCCESS (2.025s)
[188.294636] Starting dynamic subtest: B-eDP1
[190.268229] Dynamic subtest B-eDP1: SUCCESS (1.999s)
[190.294197] Starting dynamic subtest: C-eDP1
[192.288365] Dynamic subtest C-eDP1: SUCCESS (2.019s)
[192.291556] Subtest basic-plain-flip: SUCCESS (6.054s)
[193.644944] [108/161] (820s left) kms_force_connector_basic (force-connector-state)
[193.774121] Starting subtest: force-connector-state
[194.587832] Subtest force-connector-state: SUCCESS (0.814s)
[195.638843] [109/161] (818s left) kms_force_connector_basic (force-edid)
[195.766205] Starting subtest: force-edid
[196.045046] Subtest force-edid: SUCCESS (0.278s)
[196.334503] [110/161] (817s left) kms_force_connector_basic (force-load-detect)
[196.458974] Starting subtest: force-load-detect
[196.461209] Subtest force-load-detect: SKIP (0.000s)
[196.680969] [111/161] (817s left) kms_force_connector_basic (prune-stale-modes)
[196.808153] Starting subtest: prune-stale-modes
[197.139817] Subtest prune-stale-modes: SUCCESS (0.331s)
[197.414591] [112/161] (817s left) kms_frontbuffer_tracking (basic)
[197.574804] Starting subtest: basic
[201.566148] Subtest basic: SUCCESS (3.990s)
[201.788330] [113/161] (812s left) kms_hdmi_inject (inject-audio)
[202.226537] Starting subtest: inject-audio
[202.548480] Subtest inject-audio: SUCCESS (0.324s)
[203.217373] [114/161] (811s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[203.371876] Starting subtest: compare-crc-sanitycheck-xr24
[203.373105] Starting dynamic subtest: pipe-A-eDP-1
[204.147190] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.772s)
[204.191406] Starting dynamic subtest: pipe-B-eDP-1
[205.577455] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.421s)
[205.623152] Starting dynamic subtest: pipe-C-eDP-1
[206.928166] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.344s)
[206.929549] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.555s)
[207.867432] [115/161] (806s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[208.021961] Starting subtest: compare-crc-sanitycheck-nv12
[208.023163] Starting dynamic subtest: pipe-A-eDP-1
[209.126579] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.101s)
[209.170659] Starting dynamic subtest: pipe-B-eDP-1
[210.524469] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.390s)
[210.561145] Starting dynamic subtest: pipe-C-eDP-1
[211.910594] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.377s)
[211.912406] Subtest compare-crc-sanitycheck-nv12: SUCCESS (3.887s)
[212.817760] [116/161] (802s left) kms_pipe_crc_basic (hang-read-crc)
[212.971131] Starting subtest: hang-read-crc
[212.972337] Starting dynamic subtest: pipe-A-eDP-1
[215.437719] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.462s)
[215.481437] Starting dynamic subtest: pipe-B-eDP-1
[218.441079] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.998s)
[218.480440] Starting dynamic subtest: pipe-C-eDP-1
[221.384618] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.935s)
[221.386438] Subtest hang-read-crc: SUCCESS (8.415s)
[222.280501] [117/161] (792s left) kms_pipe_crc_basic (nonblocking-crc)
[222.436146] Starting subtest: nonblocking-crc
[222.437270] Starting dynamic subtest: pipe-A-eDP-1
[223.438512] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.998s)
[223.487065] Starting dynamic subtest: pipe-B-eDP-1
[225.026161] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.581s)
[225.072673] Starting dynamic subtest: pipe-C-eDP-1
[226.588813] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.554s)
[226.590667] Subtest nonblocking-crc: SUCCESS (4.154s)
[227.543670] [118/161] (787s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[227.697154] Starting subtest: nonblocking-crc-frame-sequence
[227.698388] Starting dynamic subtest: pipe-A-eDP-1
[228.700197] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.998s)
[228.752778] Starting dynamic subtest: pipe-B-eDP-1
[230.285405] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.579s)
[230.315963] Starting dynamic subtest: pipe-C-eDP-1
[231.849624] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.557s)
[231.851647] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.154s)
[232.812617] [119/161] (782s left) kms_pipe_crc_basic (read-crc)
[232.966077] Starting subtest: read-crc
[232.967160] Starting dynamic subtest: pipe-A-eDP-1
[233.901523] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.931s)
[233.946052] Starting dynamic subtest: pipe-B-eDP-1
[235.393669] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.485s)
[235.443114] Starting dynamic subtest: pipe-C-eDP-1
[236.947203] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.542s)
[236.949252] Subtest read-crc: SUCCESS (3.980s)
[237.854123] [120/161] (777s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[238.008206] Starting subtest: read-crc-frame-sequence
[238.009468] Starting dynamic subtest: pipe-A-eDP-1
[238.928275] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.915s)
[238.975781] Starting dynamic subtest: pipe-B-eDP-1
[240.430915] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.494s)
[240.469873] Starting dynamic subtest: pipe-C-eDP-1
[241.888563] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.447s)
[241.890322] Subtest read-crc-frame-sequence: SUCCESS (3.879s)
[242.801670] [121/161] (772s left) kms_pm_backlight (basic-brightness)
[242.959233] Starting subtest: basic-brightness
[243.068511] Starting dynamic subtest: eDP-1
[243.672165] Dynamic subtest eDP-1: SUCCESS (0.601s)
[243.674120] Subtest basic-brightness: SUCCESS (0.713s)
[244.592347] [122/161] (770s left) kms_pm_rpm (basic-pci-d3-state)
[246.381426] Starting subtest: basic-pci-d3-state
[246.992357] Subtest basic-pci-d3-state: SUCCESS (0.610s)
[247.214575] [123/161] (768s left) kms_pm_rpm (basic-rte)
[247.313360] Starting subtest: basic-rte
[249.611690] Subtest basic-rte: SUCCESS (2.298s)
[250.898830] [124/161] (764s left) kms_psr (psr-primary-page-flip)
[251.056843] Starting subtest: psr-primary-page-flip
[251.058148] Starting dynamic subtest: eDP-1
[255.012688] Dynamic subtest eDP-1: SUCCESS (3.954s)
[255.019036] Subtest psr-primary-page-flip: SUCCESS (3.959s)
[255.950819] [125/161] (759s left) kms_psr (psr-cursor-plane-move)
[256.106612] Starting subtest: psr-cursor-plane-move
[256.107797] Starting dynamic subtest: eDP-1
[259.833801] Dynamic subtest eDP-1: SUCCESS (3.721s)
[259.835429] Subtest psr-cursor-plane-move: SUCCESS (3.726s)
[260.797138] [126/161] (755s left) kms_psr (psr-sprite-plane-onoff)
[260.952381] Starting subtest: psr-sprite-plane-onoff
[260.953494] Starting dynamic subtest: eDP-1
[264.977689] Dynamic subtest eDP-1: SUCCESS (4.018s)
[264.979746] Subtest psr-sprite-plane-onoff: SUCCESS (4.023s)
[265.948429] [127/161] (749s left) kms_psr (psr-primary-mmap-gtt)
[266.104240] Starting subtest: psr-primary-mmap-gtt
[266.105380] Starting dynamic subtest: eDP-1
[270.028784] Dynamic subtest eDP-1: SUCCESS (3.922s)
[270.029861] Subtest psr-primary-mmap-gtt: SUCCESS (3.925s)
[270.997844] [128/161] (744s left) kms_setmode (basic-clone-single-crtc)
[271.101414] Starting subtest: basic-clone-single-crtc
[271.104701] Subtest basic-clone-single-crtc: SKIP (0.000s)
[271.249523] [129/161] (744s left) i915_pm_rps (basic-api)
[271.359664] Starting subtest: basic-api
[271.364719] Subtest basic-api: SUCCESS (0.005s)
[271.530885] [130/161] (744s left) prime_self_import (basic-llseek-bad)
[271.631544] Starting subtest: basic-llseek-bad
[271.636476] Subtest basic-llseek-bad: SUCCESS (0.005s)
[271.799531] [131/161] (744s left) prime_self_import (basic-llseek-size)
[271.897427] Starting subtest: basic-llseek-size
[271.903841] Subtest basic-llseek-size: SUCCESS (0.006s)
[272.062363] [132/161] (744s left) prime_self_import (basic-with_fd_dup)
[272.159561] Starting subtest: basic-with_fd_dup
[272.164950] Subtest basic-with_fd_dup: SUCCESS (0.005s)
[272.329881] [133/161] (744s left) prime_self_import (basic-with_one_bo)
[272.425774] Starting subtest: basic-with_one_bo
[272.433289] Subtest basic-with_one_bo: SUCCESS (0.006s)
[272.577185] [134/161] (743s left) prime_self_import (basic-with_one_bo_two_files)
[272.675906] Starting subtest: basic-with_one_bo_two_files
[272.681178] Subtest basic-with_one_bo_two_files: SUCCESS (0.005s)
[272.849457] [135/161] (743s left) prime_self_import (basic-with_two_bos)
[272.949584] Starting subtest: basic-with_two_bos
[272.954735] Subtest basic-with_two_bos: SUCCESS (0.005s)
[273.115485] [136/161] (743s left) prime_vgem (basic-fence-flip)
[273.250365] Starting subtest: basic-fence-flip
[273.811442] Subtest basic-fence-flip: SUCCESS (0.563s)
[273.998999] [137/161] (742s left) prime_vgem (basic-fence-mmap)
[274.133456] Starting subtest: basic-fence-mmap
[274.186500] Subtest basic-fence-mmap: SUCCESS (0.055s)
[274.349211] [138/161] (742s left) prime_vgem (basic-fence-read)
[274.474344] Starting subtest: basic-fence-read
[274.573108] Subtest basic-fence-read: SUCCESS (0.099s)
[274.731552] [139/161] (742s left) prime_vgem (basic-gtt)
[274.859507] Starting subtest: basic-gtt
[274.882860] Subtest basic-gtt: SUCCESS (0.025s)
[275.047832] [140/161] (742s left) prime_vgem (basic-read)
[275.182300] Starting subtest: basic-read
[275.228913] Subtest basic-read: SUCCESS (0.046s)
[275.416159] [141/161] (741s left) prime_vgem (basic-write)
[275.541139] Starting subtest: basic-write
[275.605436] Subtest basic-write: SUCCESS (0.064s)
[275.765765] [142/161] (741s left) vgem_basic (setversion)
[275.879933] Starting subtest: setversion
[275.882203] Subtest setversion: SUCCESS (0.000s)
[276.014730] [143/161] (741s left) vgem_basic (create)
[276.132199] Starting subtest: create
[276.133333] Subtest create: SUCCESS (0.000s)
[276.262117] [144/161] (741s left) vgem_basic (debugfs)
[276.380241] Starting subtest: debugfs
[276.381454] Subtest debugfs: SUCCESS (0.001s)
[276.517379] [145/161] (741s left) vgem_basic (dmabuf-export)
[276.629828] Starting subtest: dmabuf-export
[276.634764] Subtest dmabuf-export: SUCCESS (0.004s)
[276.803613] [146/161] (740s left) vgem_basic (dmabuf-fence)
[276.915104] Starting subtest: dmabuf-fence
[276.916400] Subtest dmabuf-fence: SUCCESS (0.001s)
[277.048411] [147/161] (740s left) vgem_basic (dmabuf-fence-before)
[277.162829] Starting subtest: dmabuf-fence-before
[277.164181] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[277.296312] [148/161] (740s left) vgem_basic (dmabuf-mmap)
[277.413329] Starting subtest: dmabuf-mmap
[277.438457] Subtest dmabuf-mmap: SUCCESS (0.025s)
[277.580080] [149/161] (740s left) vgem_basic (mmap)
[277.696302] Starting subtest: mmap
[277.712813] Subtest mmap: SUCCESS (0.017s)
[277.845619] [150/161] (740s left) vgem_basic (second-client)
[277.963248] Starting subtest: second-client
[277.998408] Subtest second-client: SUCCESS (0.033s)
[278.131310] [151/161] (740s left) vgem_basic (sysfs)
[278.246513] Starting subtest: sysfs
[278.247658] Subtest sysfs: SUCCESS (0.001s)
[278.380843] [152/161] (739s left) core_hotunplug (unbind-rebind)
[278.580565] Starting subtest: unbind-rebind
[283.589497] Subtest unbind-rebind: SUCCESS (5.009s)
[283.689712] [153/161] (734s left) vgem_basic (unload)
[283.788348] Starting subtest: unload
[312.935924] Subtest unload: SUCCESS (29.098s)
[313.093181] [154/161] (705s left) i915_module_load (reload)
[313.193357] Starting subtest: reload
[317.963574] Subtest reload: SUCCESS (4.766s)
[318.071305] [155/161] (700s left) gem_lmem_swapping (basic)
[318.192536] Subtest basic: SKIP (0.000s)
[318.338517] [156/161] (700s left) gem_lmem_swapping (parallel-random-engines)
[318.451315] Subtest parallel-random-engines: SKIP (0.000s)
[318.555616] [157/161] (700s left) gem_lmem_swapping (random-engines)
[318.667525] Subtest random-engines: SKIP (0.000s)
[318.771911] [158/161] (700s left) gem_lmem_swapping (verify-random)
[318.880287] Subtest verify-random: SKIP (0.000s)
[318.987892] [159/161] (700s left) i915_pm_rpm (module-reload)
[331.475228] Starting subtest: module-reload
[364.755732] Subtest module-reload: SUCCESS (33.271s)
[364.818942] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[364.822276] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[364.826366] [160/161] (654s left) i915_selftest (live)
[364.932369] Starting subtest: live
[364.933539] Starting dynamic subtest: sanitycheck
[368.244302] Dynamic subtest sanitycheck: SUCCESS (3.300s)
[368.266657] Starting dynamic subtest: uncore
[371.522606] Dynamic subtest uncore: SUCCESS (3.273s)
[371.545258] Starting dynamic subtest: workarounds
[375.056331] Dynamic subtest workarounds: SUCCESS (3.528s)
[375.089499] Starting dynamic subtest: gt_engines
[378.832241] Dynamic subtest gt_engines: SUCCESS (3.773s)
[378.854064] Starting dynamic subtest: gt_timelines
[391.951950] Dynamic subtest gt_timelines: SUCCESS (13.114s)
[391.973789] Starting dynamic subtest: gt_contexts
[395.384796] Dynamic subtest gt_contexts: SUCCESS (3.428s)
[395.406157] Starting dynamic subtest: gt_lrc
[400.918593] Dynamic subtest gt_lrc: SUCCESS (5.528s)
[400.940817] Starting dynamic subtest: gt_mocs
[404.339975] Dynamic subtest gt_mocs: SUCCESS (3.416s)
[404.361978] Starting dynamic subtest: gt_pm
[413.341589] Dynamic subtest gt_pm: FAIL (8.754s)
[413.358575] Starting dynamic subtest: gt_heartbeat
[416.395575] Dynamic subtest gt_heartbeat: SUCCESS (3.293s)
[416.417193] Starting dynamic subtest: gt_tlb
[420.976018] Dynamic subtest gt_tlb: SUCCESS (4.575s)
[420.997648] Starting dynamic subtest: requests
[430.184443] Dynamic subtest requests: SUCCESS (9.203s)
[430.206882] Starting dynamic subtest: migrate
[436.136111] Dynamic subtest migrate: SUCCESS (5.946s)
[436.157717] Starting dynamic subtest: active
[439.435224] Dynamic subtest active: SUCCESS (3.294s)
[439.456741] Starting dynamic subtest: objects
[444.038393] Dynamic subtest objects: SUCCESS (4.598s)
[444.061100] Starting dynamic subtest: mman
[451.845345] Dynamic subtest mman: SUCCESS (7.804s)
[451.866851] Starting dynamic subtest: dmabuf
[455.295779] Dynamic subtest dmabuf: SUCCESS (3.445s)
[455.317700] Starting dynamic subtest: vma
[458.597737] Dynamic subtest vma: SUCCESS (3.297s)
[458.619919] Starting dynamic subtest: coherency
[465.964998] Dynamic subtest coherency: SUCCESS (7.362s)
[465.987636] Starting dynamic subtest: gtt
[477.237180] Dynamic subtest gtt: SUCCESS (11.267s)
[477.258900] Starting dynamic subtest: gem
[480.920108] Dynamic subtest gem: SUCCESS (3.678s)
[480.942157] Starting dynamic subtest: evict
[486.342140] Dynamic subtest evict: SUCCESS (5.417s)
[486.363874] Starting dynamic subtest: hugepages
[495.892862] Dynamic subtest hugepages: SUCCESS (9.545s)
[495.915461] Starting dynamic subtest: gem_contexts
[512.993954] Dynamic subtest gem_contexts: SUCCESS (17.096s)
[513.015541] Starting dynamic subtest: client
[516.382372] Dynamic subtest client: SUCCESS (3.384s)
[516.405406] Starting dynamic subtest: gem_migrate
[519.634845] Dynamic subtest gem_migrate: SUCCESS (3.248s)
[519.656603] Starting dynamic subtest: reset
[526.101783] Dynamic subtest reset: SUCCESS (6.461s)
[526.126198] Starting dynamic subtest: memory_region
[529.376098] Dynamic subtest memory_region: SUCCESS (3.271s)
[529.398931] Starting dynamic subtest: hangcheck
[555.474334] Dynamic subtest hangcheck: SUCCESS (26.101s)
[555.503142] Starting dynamic subtest: execlists
[567.747442] Dynamic subtest execlists: SUCCESS (12.261s)
[567.768874] Starting dynamic subtest: ring_submission
[570.998139] Dynamic subtest ring_submission: SUCCESS (3.246s)
[571.020740] Starting dynamic subtest: perf
[574.464357] Dynamic subtest perf: SUCCESS (3.461s)
[574.486121] Starting dynamic subtest: slpc
[577.766653] Dynamic subtest slpc: SUCCESS (3.297s)
[577.788610] Starting dynamic subtest: guc
[581.014796] Dynamic subtest guc: SUCCESS (3.243s)
[581.036632] Starting dynamic subtest: guc_multi_lrc
[584.235108] Dynamic subtest guc_multi_lrc: SUCCESS (3.215s)
[584.256895] Starting dynamic subtest: guc_hang
[587.476398] Dynamic subtest guc_hang: SUCCESS (3.236s)
[587.498203] Starting dynamic subtest: late_gt_pm
[590.794136] Dynamic subtest late_gt_pm: SUCCESS (3.313s)
[590.795405] Subtest live: FAIL (225.849s)
[590.865604] [161/161] (428s left) dmabuf (all-tests)
[590.971312] Starting subtest: all-tests
[590.973262] Starting dynamic subtest: sanitycheck
[591.056932] Dynamic subtest sanitycheck: SUCCESS (0.084s)
[591.083665] Starting dynamic subtest: dma_fence
[591.346578] Dynamic subtest dma_fence: SUCCESS (0.275s)
[591.364274] Starting dynamic subtest: dma_fence_chain
[605.940698] Dynamic subtest dma_fence_chain: SUCCESS (14.589s)
[605.959457] Starting dynamic subtest: dma_fence_unwrap
[606.032952] Dynamic subtest dma_fence_unwrap: SUCCESS (0.097s)
[606.058859] Starting dynamic subtest: dma_resv
[606.130946] Dynamic subtest dma_resv: SUCCESS (0.093s)
[606.133503] Subtest all-tests: SUCCESS (15.161s)
[606.206681] Closing watchdogs
Done.