[140.361426] Initializing watchdogs
[140.361961]   /dev/watchdog0
[140.529502] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:46d3 Intel Alderlake_n (Gen12) 
[140.568561] [001/161] (960s left) i915_module_load (load)
[141.742398] Starting subtest: load
[153.303128] Subtest load: SUCCESS (11.534s)
[153.657210] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[153.673563] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[153.705745] [002/161] (947s left) core_auth (basic-auth)
[154.218495] Starting subtest: basic-auth
[154.223104] Subtest basic-auth: SUCCESS (0.020s)
[154.731723] [003/161] (947s left) debugfs_test (read_all_entries)
[155.177376] Starting subtest: read_all_entries
[156.668255] Subtest read_all_entries: SUCCESS (1.492s)
[157.196355] [004/161] (944s left) debugfs_test (basic-hwmon)
[157.603202] Starting subtest: basic-hwmon
[157.608489] Subtest basic-hwmon: SKIP (0.000s)
[158.134474] [005/161] (944s left) debugfs_test (sysfs)
[158.544471] Starting subtest: sysfs
[159.041320] Subtest sysfs: SUCCESS (0.496s)
[159.551600] [006/161] (943s left) fbdev (eof)
[159.938639] Starting subtest: eof
[159.945425] Subtest eof: SUCCESS (0.004s)
[160.361719] [007/161] (942s left) fbdev (info)
[160.726300] Starting subtest: info
[160.730369] Subtest info: SUCCESS (0.000s)
[161.175146] [008/161] (942s left) fbdev (nullptr)
[161.538941] Starting subtest: nullptr
[161.542109] Subtest nullptr: SUCCESS (0.000s)
[161.971582] [009/161] (941s left) fbdev (read)
[162.358941] Starting subtest: read
[176.123386] Subtest read: SUCCESS (13.729s)
[176.538637] [010/161] (927s left) fbdev (write)
[176.967446] Starting subtest: write
[184.138310] Subtest write: SUCCESS (7.161s)
[184.595060] [011/161] (919s left) gem_basic (bad-close)
[184.993315] Starting subtest: bad-close
[184.999740] Subtest bad-close: SUCCESS (0.000s)
[185.416866] [012/161] (919s left) gem_basic (create-close)
[185.787636] Starting subtest: create-close
[185.793892] Subtest create-close: SUCCESS (0.001s)
[186.226485] [013/161] (918s left) gem_basic (create-fd-close)
[186.594685] Starting subtest: create-fd-close
[186.601264] Subtest create-fd-close: SUCCESS (0.001s)
[187.005463] [014/161] (918s left) gem_busy (busy@all-engines)
[187.473448] Starting subtest: busy
[187.477091] Starting dynamic subtest: all-engines
[187.801964] Dynamic subtest all-engines: SUCCESS (0.326s)
[187.805286] Subtest busy: SUCCESS (0.329s)
[188.656518] [015/161] (917s left) gem_close_race (basic-process)
[189.131901] Starting subtest: basic-process
[189.265472] Subtest basic-process: SUCCESS (0.137s)
[189.815880] [016/161] (916s left) gem_close_race (basic-threads)
[190.289049] Starting subtest: basic-threads
[191.772546] Subtest basic-threads: SUCCESS (1.487s)
[192.330725] [017/161] (914s left) gem_ctx_create (basic)
[192.795903] Starting subtest: basic
[192.799060] Subtest basic: SUCCESS (0.000s)
[193.341699] [018/161] (913s left) gem_ctx_create (basic-files)
[193.826911] Starting subtest: basic-files
[195.950966] Subtest basic-files: SUCCESS (2.121s)
[196.698868] [019/161] (910s left) gem_ctx_exec (basic)
[197.084804] Starting subtest: basic
[197.105866] Subtest basic: SUCCESS (0.020s)
[197.551419] [020/161] (909s left) gem_exec_basic (basic)
[197.977157] Starting subtest: basic
[197.980343] Starting dynamic subtest: rcs0-smem
[197.990744] Dynamic subtest rcs0-smem: SUCCESS (0.010s)
[198.050231] Starting dynamic subtest: bcs0-smem
[198.052794] Dynamic subtest bcs0-smem: SUCCESS (0.004s)
[198.109949] Starting dynamic subtest: vcs0-smem
[198.113217] Dynamic subtest vcs0-smem: SUCCESS (0.005s)
[198.172790] Starting dynamic subtest: vecs0-smem
[198.175985] Dynamic subtest vecs0-smem: SUCCESS (0.004s)
[198.178627] Subtest basic: SUCCESS (0.034s)
[198.571492] [021/161] (909s left) gem_exec_create (basic)
[199.034045] Starting subtest: basic
[199.037181] Starting dynamic subtest: smem
[201.253113] Dynamic subtest smem: SUCCESS (2.219s)
[201.256231] Subtest basic: SUCCESS (2.221s)
[201.812152] [022/161] (906s left) gem_exec_fence (basic-busy)
[202.474292] Starting subtest: basic-busy
[202.477816] Starting dynamic subtest: rcs0
[202.560901] Dynamic subtest rcs0: SUCCESS (0.086s)
[202.623951] Starting dynamic subtest: bcs0
[202.630959] Dynamic subtest bcs0: SUCCESS (0.060s)
[202.690217] Starting dynamic subtest: vcs0
[202.695892] Dynamic subtest vcs0: SUCCESS (0.061s)
[202.758184] Starting dynamic subtest: vecs0
[202.765323] Dynamic subtest vecs0: SUCCESS (0.062s)
[202.768335] Subtest basic-busy: SUCCESS (0.277s)
[204.227172] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[204.249440] [023/161] (904s left) gem_exec_fence (basic-wait)
[204.920099] Starting subtest: basic-wait
[204.923399] Starting dynamic subtest: rcs0
[204.996109] Dynamic subtest rcs0: SUCCESS (0.078s)
[205.060490] Starting dynamic subtest: bcs0
[205.063755] Dynamic subtest bcs0: SUCCESS (0.061s)
[205.127438] Starting dynamic subtest: vcs0
[205.130560] Dynamic subtest vcs0: SUCCESS (0.061s)
[205.191783] Starting dynamic subtest: vecs0
[205.196451] Dynamic subtest vecs0: SUCCESS (0.060s)
[205.199418] Subtest basic-wait: SUCCESS (0.268s)
[206.446666] [024/161] (902s left) gem_exec_fence (basic-await)
[207.102174] Starting subtest: basic-await
[207.105243] Starting dynamic subtest: rcs0
[207.439777] Dynamic subtest rcs0: SUCCESS (0.335s)
[207.501204] Starting dynamic subtest: bcs0
[207.745949] Dynamic subtest bcs0: SUCCESS (0.305s)
[207.813129] Starting dynamic subtest: vcs0
[208.048623] Dynamic subtest vcs0: SUCCESS (0.300s)
[208.110314] Starting dynamic subtest: vecs0
[208.350586] Dynamic subtest vecs0: SUCCESS (0.302s)
[208.353871] Subtest basic-await: SUCCESS (1.251s)
[209.593700] [025/161] (899s left) gem_exec_fence (nb-await)
[210.257094] Starting subtest: nb-await
[210.260380] Starting dynamic subtest: rcs0
[210.373988] Dynamic subtest rcs0: SUCCESS (0.114s)
[210.434173] Starting dynamic subtest: bcs0
[210.461538] Dynamic subtest bcs0: SUCCESS (0.087s)
[210.526542] Starting dynamic subtest: vcs0
[210.552918] Dynamic subtest vcs0: SUCCESS (0.088s)
[210.615275] Starting dynamic subtest: vecs0
[210.644217] Dynamic subtest vecs0: SUCCESS (0.089s)
[210.647194] Subtest nb-await: SUCCESS (0.389s)
[211.940366] [026/161] (897s left) gem_exec_gttfill (basic)
[212.405388] Starting subtest: basic
[250.282103] Subtest basic: SUCCESS (37.845s)
[250.907661] [027/161] (859s left) gem_exec_parallel (engines)
[251.583358] Starting subtest: engines
[251.585985] Starting dynamic subtest: basic
[258.791262] Dynamic subtest basic: SUCCESS (7.209s)
[258.861947] Starting dynamic subtest: contexts
[269.150858] Dynamic subtest contexts: SUCCESS (10.347s)
[269.219877] Starting dynamic subtest: fds
[281.631666] Dynamic subtest fds: SUCCESS (12.478s)
[281.701687] Starting dynamic subtest: userptr
[287.489195] Dynamic subtest userptr: SUCCESS (5.860s)
[287.492520] Subtest engines: SUCCESS (35.903s)
[288.116886] [028/161] (822s left) gem_exec_store (basic)
[288.586204] Starting subtest: basic
[288.766960] Subtest basic: SUCCESS (0.181s)
[289.369852] [029/161] (821s left) gem_flink_basic (bad-flink)
[289.739594] Starting subtest: bad-flink
[289.742842] Subtest bad-flink: SUCCESS (0.000s)
[290.193864] [030/161] (820s left) gem_flink_basic (bad-open)
[290.565895] Starting subtest: bad-open
[290.569454] Subtest bad-open: SUCCESS (0.000s)
[291.023559] [031/161] (820s left) gem_flink_basic (basic)
[291.402662] Starting subtest: basic
[291.405586] Subtest basic: SUCCESS (0.001s)
[291.862520] [032/161] (820s left) gem_flink_basic (double-flink)
[292.235417] Starting subtest: double-flink
[292.238721] Subtest double-flink: SUCCESS (0.000s)
[292.708899] [033/161] (819s left) gem_flink_basic (flink-lifetime)
[293.081102] Starting subtest: flink-lifetime
[293.088343] Subtest flink-lifetime: SUCCESS (0.009s)
[293.570854] [034/161] (819s left) gem_huc_copy (huc-copy)
[293.983757] Starting subtest: huc-copy
[293.999817] Subtest huc-copy: SUCCESS (0.019s)
[294.491667] [035/161] (818s left) gem_linear_blits (basic)
[295.522627] Starting subtest: basic
[295.574125] Subtest basic: SUCCESS (0.059s)
[296.138552] [036/161] (817s left) gem_mmap (basic)
[296.695967] Starting subtest: basic
[296.711198] Subtest basic: SUCCESS (0.003s)
[297.145859] [037/161] (816s left) gem_mmap_gtt (basic)
[297.528908] Starting subtest: basic
[297.531946] Subtest basic: SUCCESS (0.005s)
[297.993426] [038/161] (816s left) gem_render_linear_blits (basic)
[298.395432] Starting subtest: basic
[298.607597] Subtest basic: SUCCESS (0.215s)
[299.116599] [039/161] (815s left) gem_render_tiled_blits (basic)
[299.519730] Starting subtest: basic
[299.814773] Subtest basic: SUCCESS (0.296s)
[300.278334] [040/161] (814s left) gem_ringfill (basic-all)
[301.157419] Starting subtest: basic-all
[302.604249] Subtest basic-all: SUCCESS (1.439s)
[303.107370] [041/161] (812s left) gem_softpin (allocator-basic)
[303.514579] Starting subtest: allocator-basic
[304.191277] Subtest allocator-basic: SUCCESS (0.670s)
[304.746675] [042/161] (810s left) gem_softpin (allocator-basic-reserve)
[305.145758] Starting subtest: allocator-basic-reserve
[305.827142] Subtest allocator-basic-reserve: SUCCESS (0.675s)
[306.403106] [043/161] (809s left) gem_softpin (safe-alignment)
[306.809804] Starting subtest: safe-alignment
[306.895129] Subtest safe-alignment: SUCCESS (0.083s)
[307.381835] [044/161] (809s left) gem_sync (basic-all)
[307.861126] Starting subtest: basic-all
[313.092144] Subtest basic-all: SUCCESS (5.234s)
[313.695105] [045/161] (803s left) gem_sync (basic-each)
[314.186982] Starting subtest: basic-each
[317.801757] Subtest basic-each: SUCCESS (3.617s)
[318.403098] [046/161] (798s left) gem_tiled_blits (basic)
[319.355924] Starting subtest: basic
[319.481998] Subtest basic: SUCCESS (0.128s)
[320.016316] [047/161] (797s left) gem_tiled_fence_blits (basic)
[321.238719] Starting subtest: basic
[321.307469] Subtest basic: SUCCESS (0.077s)
[321.883525] [048/161] (796s left) gem_tiled_pread_basic
[322.872737] [049/161] (795s left) gem_wait (busy@all-engines)
[323.401228] Starting subtest: busy
[323.404521] Starting dynamic subtest: all-engines
[323.988890] Dynamic subtest all-engines: SUCCESS (0.595s)
[323.991953] Subtest busy: SUCCESS (0.598s)
[324.713567] [050/161] (794s left) gem_wait (wait@all-engines)
[325.235035] Starting subtest: wait
[325.238194] Starting dynamic subtest: all-engines
[326.335290] Dynamic subtest all-engines: SUCCESS (1.110s)
[326.338488] Subtest wait: SUCCESS (1.113s)
[327.057640] [051/161] (792s left) i915_getparams_basic (basic-eu-total)
[327.423558] Starting subtest: basic-eu-total
[327.429880] Subtest basic-eu-total: SUCCESS (0.000s)
[327.877392] [052/161] (791s left) i915_getparams_basic (basic-subslice-total)
[328.253792] Starting subtest: basic-subslice-total
[328.259509] Subtest basic-subslice-total: SUCCESS (0.000s)
[328.702853] [053/161] (791s left) i915_hangman (error-state-basic)
[329.182375] Starting subtest: error-state-basic
[330.029712] Subtest error-state-basic: SUCCESS (0.845s)
[330.518455] [054/161] (789s left) i915_pciid
[331.352687] [055/161] (789s left) kms_addfb_basic (addfb25-4-tiled)
[331.868260] Starting subtest: addfb25-4-tiled
[331.871331] Subtest addfb25-4-tiled: SUCCESS (0.002s)
[332.348296] [056/161] (788s left) kms_addfb_basic (addfb25-bad-modifier)
[332.825772] Starting subtest: addfb25-bad-modifier
[332.828927] Subtest addfb25-bad-modifier: SUCCESS (0.002s)
[333.303601] [057/161] (788s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[333.783593] Starting subtest: addfb25-framebuffer-vs-set-tiling
[333.786734] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.002s)
[334.255891] [058/161] (787s left) kms_addfb_basic (addfb25-modifier-no-flag)
[334.738474] Starting subtest: addfb25-modifier-no-flag
[334.741748] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[335.212850] [059/161] (786s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[335.695727] Starting subtest: addfb25-x-tiled-legacy
[335.699094] Subtest addfb25-x-tiled-legacy: SUCCESS (0.001s)
[336.167462] [060/161] (786s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[336.642078] Starting subtest: addfb25-x-tiled-mismatch-legacy
[336.645144] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[337.108371] [061/161] (785s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[337.584965] Starting subtest: addfb25-yf-tiled-legacy
[337.588651] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.002s)
[338.045414] [062/161] (785s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[338.531732] Starting subtest: addfb25-y-tiled-legacy
[338.534844] Subtest addfb25-y-tiled-legacy: SUCCESS (0.001s)
[339.007661] [063/161] (784s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[339.481764] Starting subtest: addfb25-y-tiled-small-legacy
[339.485098] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.002s)
[339.972793] [064/161] (784s left) kms_addfb_basic (bad-pitch-0)
[340.351529] Starting subtest: bad-pitch-0
[340.354541] Subtest bad-pitch-0: SUCCESS (0.000s)
[340.928404] [065/161] (783s left) kms_addfb_basic (bad-pitch-1024)
[341.299705] Starting subtest: bad-pitch-1024
[341.302663] Subtest bad-pitch-1024: SUCCESS (0.000s)
[341.877197] [066/161] (782s left) kms_addfb_basic (bad-pitch-128)
[342.249238] Starting subtest: bad-pitch-128
[342.252305] Subtest bad-pitch-128: SUCCESS (0.000s)
[342.820895] [067/161] (782s left) kms_addfb_basic (bad-pitch-256)
[343.199217] Starting subtest: bad-pitch-256
[343.202666] Subtest bad-pitch-256: SUCCESS (0.000s)
[343.775698] [068/161] (781s left) kms_addfb_basic (bad-pitch-32)
[344.170943] Starting subtest: bad-pitch-32
[344.173564] Subtest bad-pitch-32: SUCCESS (0.000s)
[344.757661] [069/161] (781s left) kms_addfb_basic (bad-pitch-63)
[345.135962] Starting subtest: bad-pitch-63
[345.139423] Subtest bad-pitch-63: SUCCESS (0.000s)
[345.703637] [070/161] (780s left) kms_addfb_basic (bad-pitch-65536)
[346.079694] Starting subtest: bad-pitch-65536
[346.083284] Subtest bad-pitch-65536: SUCCESS (0.002s)
[346.658509] [071/161] (780s left) kms_addfb_basic (bad-pitch-999)
[347.023624] Starting subtest: bad-pitch-999
[347.026642] Subtest bad-pitch-999: SUCCESS (0.000s)
[347.602854] [072/161] (779s left) kms_addfb_basic (basic)
[347.978744] Starting subtest: basic
[347.981941] Subtest basic: SUCCESS (0.000s)
[348.544569] [073/161] (778s left) kms_addfb_basic (basic-x-tiled-legacy)
[348.924277] Starting subtest: basic-x-tiled-legacy
[348.926956] Subtest basic-x-tiled-legacy: SUCCESS (0.001s)
[349.503545] [074/161] (778s left) kms_addfb_basic (basic-y-tiled-legacy)
[349.879985] Starting subtest: basic-y-tiled-legacy
[349.882779] Subtest basic-y-tiled-legacy: SUCCESS (0.002s)
[350.455489] [075/161] (777s left) kms_addfb_basic (bo-too-small)
[350.936430] Starting subtest: bo-too-small
[350.939603] Subtest bo-too-small: SUCCESS (0.002s)
[351.409529] [076/161] (777s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[351.888576] Starting subtest: bo-too-small-due-to-tiling
[351.892555] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[352.366118] [077/161] (776s left) kms_addfb_basic (clobberred-modifier)
[352.734233] Starting subtest: clobberred-modifier
[352.737312] Subtest clobberred-modifier: SUCCESS (0.001s)
[353.315089] [078/161] (775s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[353.692285] Starting subtest: framebuffer-vs-set-tiling
[353.695256] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[354.277270] [079/161] (775s left) kms_addfb_basic (invalid-get-prop)
[354.652440] Starting subtest: invalid-get-prop
[354.655489] Subtest invalid-get-prop: SUCCESS (0.000s)
[355.233935] [080/161] (774s left) kms_addfb_basic (invalid-get-prop-any)
[355.599731] Starting subtest: invalid-get-prop-any
[355.602764] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[356.180927] [081/161] (774s left) kms_addfb_basic (invalid-set-prop)
[356.561771] Starting subtest: invalid-set-prop
[356.564974] Subtest invalid-set-prop: SUCCESS (0.000s)
[357.134925] [082/161] (773s left) kms_addfb_basic (invalid-set-prop-any)
[357.509851] Starting subtest: invalid-set-prop-any
[357.512738] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[358.081669] [083/161] (773s left) kms_addfb_basic (no-handle)
[358.456263] Starting subtest: no-handle
[358.459502] Subtest no-handle: SUCCESS (0.000s)
[359.058667] [084/161] (772s left) kms_addfb_basic (size-max)
[359.540505] Starting subtest: size-max
[359.544486] Subtest size-max: SUCCESS (0.001s)
[360.008931] [085/161] (771s left) kms_addfb_basic (small-bo)
[360.486959] Starting subtest: small-bo
[360.490322] Subtest small-bo: SUCCESS (0.000s)
[360.980159] [086/161] (771s left) kms_addfb_basic (tile-pitch-mismatch)
[361.356900] Starting subtest: tile-pitch-mismatch
[361.360907] Subtest tile-pitch-mismatch: SUCCESS (0.002s)
[361.935329] [087/161] (770s left) kms_addfb_basic (too-high)
[362.417877] Starting subtest: too-high
[362.426702] Subtest too-high: SUCCESS (0.006s)
[362.891554] [088/161] (770s left) kms_addfb_basic (too-wide)
[363.367520] Starting subtest: too-wide
[363.370722] Subtest too-wide: SUCCESS (0.000s)
[363.829184] [089/161] (769s left) kms_addfb_basic (unused-handle)
[364.206911] Starting subtest: unused-handle
[364.210351] Subtest unused-handle: SUCCESS (0.000s)
[364.794800] [090/161] (769s left) kms_addfb_basic (unused-modifier)
[365.169340] Starting subtest: unused-modifier
[365.172962] Subtest unused-modifier: SUCCESS (0.000s)
[365.749452] [091/161] (768s left) kms_addfb_basic (unused-offsets)
[366.125274] Starting subtest: unused-offsets
[366.128615] Subtest unused-offsets: SUCCESS (0.000s)
[366.706466] [092/161] (767s left) kms_addfb_basic (unused-pitches)
[367.082851] Starting subtest: unused-pitches
[367.085990] Subtest unused-pitches: SUCCESS (0.000s)
[367.668998] [093/161] (767s left) kms_busy (basic)
[368.173893] Starting subtest: basic
[368.179112] Starting dynamic subtest: flip
[370.477379] Dynamic subtest flip: SUCCESS (2.293s)
[370.564048] Starting dynamic subtest: modeset
[375.693276] Dynamic subtest modeset: SUCCESS (5.214s)
[375.696510] Subtest basic: SUCCESS (7.519s)
[376.998774] [094/161] (758s left) kms_prop_blob (basic)
[377.377227] Starting subtest: basic
[377.380527] Subtest basic: SUCCESS (0.000s)
[377.838501] [095/161] (757s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[378.472683] Starting subtest: basic-busy-flip-before-cursor-atomic
[378.478444] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[379.058928] [096/161] (757s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[379.691481] Starting subtest: basic-busy-flip-before-cursor-legacy
[379.697639] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.001s)
[380.291857] [097/161] (756s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[380.918542] Starting subtest: basic-flip-after-cursor-atomic
[382.541253] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.620s)
[383.804558] [098/161] (753s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[384.419207] Starting subtest: basic-flip-after-cursor-legacy
[386.023456] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.604s)
[387.331182] [099/161] (749s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[387.947573] Starting subtest: basic-flip-after-cursor-varying-size
[389.556193] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.609s)
[390.856629] [100/161] (746s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[391.468927] Starting subtest: basic-flip-before-cursor-atomic
[393.084464] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.613s)
[394.399219] [101/161] (743s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[395.014675] Starting subtest: basic-flip-before-cursor-legacy
[396.621238] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.605s)
[397.927931] [102/161] (740s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[398.545837] Starting subtest: basic-flip-before-cursor-varying-size
[400.966569] Subtest basic-flip-before-cursor-varying-size: SUCCESS (2.410s)
[402.007386] [103/161] (736s left) kms_dsc (dsc-basic)
[402.493291] Starting subtest: dsc-basic
[402.506383] Subtest dsc-basic: SKIP (0.004s)
[402.960278] [104/161] (736s left) kms_flip (basic-flip-vs-dpms)
[403.437451] Starting subtest: basic-flip-vs-dpms
[403.448722] Starting dynamic subtest: A-eDP1
[407.371158] Dynamic subtest A-eDP1: SUCCESS (3.923s)
[407.455782] Starting dynamic subtest: B-eDP1
[410.645638] Dynamic subtest B-eDP1: SUCCESS (3.270s)
[410.731097] Starting dynamic subtest: C-eDP1
[413.933227] Dynamic subtest C-eDP1: SUCCESS (3.279s)
[413.939208] Subtest basic-flip-vs-dpms: SUCCESS (10.503s)
[415.205457] [105/161] (724s left) kms_flip (basic-flip-vs-modeset)
[415.691633] Starting subtest: basic-flip-vs-modeset
[415.703105] Starting dynamic subtest: A-eDP1
[419.539724] Dynamic subtest A-eDP1: SUCCESS (3.836s)
[419.625051] Starting dynamic subtest: B-eDP1
[422.680117] Dynamic subtest B-eDP1: SUCCESS (3.133s)
[422.765260] Starting dynamic subtest: C-eDP1
[425.796691] Dynamic subtest C-eDP1: SUCCESS (3.111s)
[425.803931] Subtest basic-flip-vs-modeset: SUCCESS (10.114s)
[427.015137] [106/161] (712s left) kms_flip (basic-flip-vs-wf_vblank)
[427.496981] Starting subtest: basic-flip-vs-wf_vblank
[427.507443] Starting dynamic subtest: A-eDP1
[430.602708] Dynamic subtest A-eDP1: SUCCESS (3.095s)
[430.687223] Starting dynamic subtest: B-eDP1
[433.629481] Dynamic subtest B-eDP1: SUCCESS (3.021s)
[433.719577] Starting dynamic subtest: C-eDP1
[436.702399] Dynamic subtest C-eDP1: SUCCESS (3.066s)
[436.714208] Subtest basic-flip-vs-wf_vblank: SUCCESS (9.220s)
[438.750196] [107/161] (701s left) kms_flip (basic-plain-flip)
[439.240811] Starting subtest: basic-plain-flip
[439.251875] Starting dynamic subtest: A-eDP1
[442.041523] Dynamic subtest A-eDP1: SUCCESS (2.789s)
[442.127474] Starting dynamic subtest: B-eDP1
[445.337683] Dynamic subtest B-eDP1: SUCCESS (3.290s)
[445.423229] Starting dynamic subtest: C-eDP1
[448.117194] Dynamic subtest C-eDP1: SUCCESS (2.772s)
[448.128136] Subtest basic-plain-flip: SUCCESS (8.889s)
[450.109888] [108/161] (690s left) kms_force_connector_basic (force-connector-state)
[450.518238] Starting subtest: force-connector-state
[451.359447] Subtest force-connector-state: SUCCESS (0.841s)
[453.029303] [109/161] (688s left) kms_force_connector_basic (force-edid)
[453.428409] Starting subtest: force-edid
[453.528330] Subtest force-edid: SUCCESS (0.104s)
[454.175344] [110/161] (687s left) kms_force_connector_basic (force-load-detect)
[454.577108] Starting subtest: force-load-detect
[454.583060] Subtest force-load-detect: SKIP (0.000s)
[455.153887] [111/161] (686s left) kms_force_connector_basic (prune-stale-modes)
[455.556754] Starting subtest: prune-stale-modes
[455.666422] Subtest prune-stale-modes: SUCCESS (0.111s)
[456.313541] [112/161] (685s left) kms_frontbuffer_tracking (basic)
[456.833068] Starting subtest: basic
[484.095756] Subtest basic: SUCCESS (27.262s)
[484.700868] [113/161] (657s left) kms_hdmi_inject (inject-audio)
[485.511577] Starting subtest: inject-audio
[486.048641] Subtest inject-audio: SUCCESS (0.535s)
[487.179556] [114/161] (655s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[487.657906] Starting subtest: compare-crc-sanitycheck-xr24
[487.665490] Starting dynamic subtest: pipe-A-eDP-1
[489.431413] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.764s)
[489.521877] Starting dynamic subtest: pipe-B-eDP-1
[491.718936] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.275s)
[491.811991] Starting dynamic subtest: pipe-C-eDP-1
[493.938881] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.195s)
[493.943265] Subtest compare-crc-sanitycheck-xr24: SUCCESS (6.274s)
[495.120143] [115/161] (648s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[495.606508] Starting subtest: compare-crc-sanitycheck-nv12
[495.611608] Starting dynamic subtest: pipe-A-eDP-1
[498.549069] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.934s)
[498.638906] Starting dynamic subtest: pipe-B-eDP-1
[501.973375] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.412s)
[502.068235] Starting dynamic subtest: pipe-C-eDP-1
[505.204702] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.213s)
[505.207676] Subtest compare-crc-sanitycheck-nv12: SUCCESS (9.600s)
[506.483920] [116/161] (637s left) kms_pipe_crc_basic (hang-read-crc)
[506.968642] Starting subtest: hang-read-crc
[506.976938] Starting dynamic subtest: pipe-A-eDP-1
[512.189510] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.211s)
[512.282364] Starting dynamic subtest: pipe-B-eDP-1
[517.830498] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.622s)
[517.925127] Starting dynamic subtest: pipe-C-eDP-1
[524.040563] Dynamic subtest pipe-C-eDP-1: SUCCESS (6.200s)
[524.051284] Subtest hang-read-crc: SUCCESS (17.077s)
[525.032479] [117/161] (619s left) kms_pipe_crc_basic (nonblocking-crc)
[525.515652] Starting subtest: nonblocking-crc
[525.527538] Starting dynamic subtest: pipe-A-eDP-1
[527.568581] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.037s)
[527.660233] Starting dynamic subtest: pipe-B-eDP-1
[530.076440] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.492s)
[530.179371] Starting dynamic subtest: pipe-C-eDP-1
[532.470143] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.382s)
[532.481351] Subtest nonblocking-crc: SUCCESS (6.957s)
[533.773147] [118/161] (610s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[534.256792] Starting subtest: nonblocking-crc-frame-sequence
[534.268628] Starting dynamic subtest: pipe-A-eDP-1
[536.355750] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.085s)
[536.449441] Starting dynamic subtest: pipe-B-eDP-1
[538.872138] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.487s)
[538.963912] Starting dynamic subtest: pipe-C-eDP-1
[541.250535] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.375s)
[541.253511] Subtest nonblocking-crc-frame-sequence: SUCCESS (6.996s)
[542.462973] [119/161] (602s left) kms_pipe_crc_basic (read-crc)
[542.948581] Starting subtest: read-crc
[542.956294] Starting dynamic subtest: pipe-A-eDP-1
[544.958879] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.997s)
[545.055639] Starting dynamic subtest: pipe-B-eDP-1
[547.417628] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.442s)
[547.509568] Starting dynamic subtest: pipe-C-eDP-1
[549.753688] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.321s)
[549.756488] Subtest read-crc: SUCCESS (6.809s)
[551.007319] [120/161] (594s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[551.496115] Starting subtest: read-crc-frame-sequence
[551.511149] Starting dynamic subtest: pipe-A-eDP-1
[553.511150] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.002s)
[553.602217] Starting dynamic subtest: pipe-B-eDP-1
[555.964585] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.441s)
[556.060177] Starting dynamic subtest: pipe-C-eDP-1
[558.296361] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.313s)
[558.299451] Subtest read-crc-frame-sequence: SUCCESS (6.804s)
[559.556165] [121/161] (586s left) kms_pm_backlight (basic-brightness)
[560.053346] Starting subtest: basic-brightness
[560.542216] Starting dynamic subtest: eDP-1
[561.017694] Dynamic subtest eDP-1: SUCCESS (0.481s)
[561.022293] Subtest basic-brightness: SUCCESS (0.969s)
[562.246185] [122/161] (583s left) kms_pm_rpm (basic-pci-d3-state)
[564.738246] Starting subtest: basic-pci-d3-state
[566.699334] Subtest basic-pci-d3-state: SUCCESS (1.957s)
[567.345745] [123/161] (579s left) kms_pm_rpm (basic-rte)
[567.726869] Starting subtest: basic-rte
[571.648604] Subtest basic-rte: SUCCESS (3.929s)
[573.989379] [124/161] (572s left) kms_psr (psr-primary-page-flip)
[574.476176] Starting subtest: psr-primary-page-flip
[574.486590] Starting dynamic subtest: eDP-1
[577.278710] Dynamic subtest eDP-1: SUCCESS (2.791s)
[577.281627] Subtest psr-primary-page-flip: SUCCESS (2.802s)
[578.565121] [125/161] (568s left) kms_psr (psr-cursor-plane-move)
[579.051210] Starting subtest: psr-cursor-plane-move
[579.062169] Starting dynamic subtest: eDP-1
[581.625725] Dynamic subtest eDP-1: SUCCESS (2.565s)
[581.628610] Subtest psr-cursor-plane-move: SUCCESS (2.577s)
[582.917830] [126/161] (564s left) kms_psr (psr-sprite-plane-onoff)
[583.411833] Starting subtest: psr-sprite-plane-onoff
[583.415245] Starting dynamic subtest: eDP-1
[586.192809] Dynamic subtest eDP-1: SUCCESS (2.774s)
[586.195761] Subtest psr-sprite-plane-onoff: SUCCESS (2.786s)
[587.477153] [127/161] (560s left) kms_psr (psr-primary-mmap-gtt)
[587.966350] Starting subtest: psr-primary-mmap-gtt
[587.970565] Starting dynamic subtest: eDP-1
[590.751982] Dynamic subtest eDP-1: SUCCESS (2.772s)
[590.754815] Subtest psr-primary-mmap-gtt: SUCCESS (2.784s)
[592.018401] [128/161] (556s left) kms_setmode (basic-clone-single-crtc)
[592.405334] Starting subtest: basic-clone-single-crtc
[592.414711] Subtest basic-clone-single-crtc: SKIP (0.001s)
[592.881551] [129/161] (556s left) i915_pm_rps (basic-api)
[593.296229] Starting subtest: basic-api
[593.310255] Subtest basic-api: SKIP (0.005s)
[593.793367] [130/161] (555s left) prime_self_import (basic-llseek-bad)
[594.155534] Starting subtest: basic-llseek-bad
[594.169900] Subtest basic-llseek-bad: SUCCESS (0.014s)
[594.664225] [131/161] (555s left) prime_self_import (basic-llseek-size)
[595.034788] Starting subtest: basic-llseek-size
[595.049944] Subtest basic-llseek-size: SUCCESS (0.021s)
[595.542384] [132/161] (554s left) prime_self_import (basic-with_fd_dup)
[595.913806] Starting subtest: basic-with_fd_dup
[595.928346] Subtest basic-with_fd_dup: SUCCESS (0.021s)
[596.426749] [133/161] (554s left) prime_self_import (basic-with_one_bo)
[596.799935] Starting subtest: basic-with_one_bo
[596.816996] Subtest basic-with_one_bo: SUCCESS (0.024s)
[597.320137] [134/161] (553s left) prime_self_import (basic-with_one_bo_two_files)
[597.687115] Starting subtest: basic-with_one_bo_two_files
[597.708151] Subtest basic-with_one_bo_two_files: SUCCESS (0.019s)
[598.196784] [135/161] (553s left) prime_self_import (basic-with_two_bos)
[598.564558] Starting subtest: basic-with_two_bos
[598.585775] Subtest basic-with_two_bos: SUCCESS (0.021s)
[599.100079] [136/161] (552s left) prime_vgem (basic-fence-flip)
[599.555648] Starting subtest: basic-fence-flip
[601.002804] Subtest basic-fence-flip: SUCCESS (1.454s)
[601.560882] [137/161] (550s left) prime_vgem (basic-fence-mmap)
[602.004211] Starting subtest: basic-fence-mmap
[602.204481] Subtest basic-fence-mmap: SUCCESS (0.195s)
[602.695482] [138/161] (549s left) prime_vgem (basic-fence-read)
[603.130317] Starting subtest: basic-fence-read
[603.189699] Subtest basic-fence-read: SKIP (0.057s)
[603.704365] [139/161] (549s left) prime_vgem (basic-gtt)
[604.151557] Starting subtest: basic-gtt
[604.232987] Subtest basic-gtt: SUCCESS (0.088s)
[604.721415] [140/161] (548s left) prime_vgem (basic-read)
[605.173744] Starting subtest: basic-read
[605.225596] Subtest basic-read: SKIP (0.056s)
[605.732984] [141/161] (547s left) prime_vgem (basic-write)
[606.185623] Starting subtest: basic-write
[606.236841] Subtest basic-write: SKIP (0.056s)
[606.737752] [142/161] (547s left) vgem_basic (setversion)
[607.142792] Starting subtest: setversion
[607.149370] Subtest setversion: SUCCESS (0.000s)
[607.590783] [143/161] (546s left) vgem_basic (create)
[607.994051] Starting subtest: create
[607.997708] Subtest create: SUCCESS (0.002s)
[608.461921] [144/161] (546s left) vgem_basic (debugfs)
[608.864591] Starting subtest: debugfs
[608.867806] Subtest debugfs: SUCCESS (0.002s)
[609.337146] [145/161] (545s left) vgem_basic (dmabuf-export)
[609.736485] Starting subtest: dmabuf-export
[609.750554] Subtest dmabuf-export: SUCCESS (0.018s)
[610.232131] [146/161] (545s left) vgem_basic (dmabuf-fence)
[610.633495] Starting subtest: dmabuf-fence
[610.636749] Subtest dmabuf-fence: SUCCESS (0.003s)
[611.097723] [147/161] (544s left) vgem_basic (dmabuf-fence-before)
[611.502221] Starting subtest: dmabuf-fence-before
[611.505540] Subtest dmabuf-fence-before: SUCCESS (0.003s)
[611.972630] [148/161] (544s left) vgem_basic (dmabuf-mmap)
[612.378565] Starting subtest: dmabuf-mmap
[612.473192] Subtest dmabuf-mmap: SUCCESS (0.099s)
[612.930588] [149/161] (543s left) vgem_basic (mmap)
[613.336482] Starting subtest: mmap
[613.398913] Subtest mmap: SUCCESS (0.068s)
[613.876613] [150/161] (543s left) vgem_basic (second-client)
[614.287320] Starting subtest: second-client
[614.331592] Subtest second-client: SUCCESS (0.046s)
[614.782566] [151/161] (542s left) vgem_basic (sysfs)
[615.184431] Starting subtest: sysfs
[615.187904] Subtest sysfs: SUCCESS (0.004s)
[615.647824] [152/161] (542s left) core_hotunplug (unbind-rebind)
[616.327373] Starting subtest: unbind-rebind
[625.386217] Subtest unbind-rebind: SUCCESS (9.053s)
[625.782443] [153/161] (532s left) vgem_basic (unload)
[626.150305] Starting subtest: unload
[655.880170] Subtest unload: SUCCESS (29.695s)
[656.506089] [154/161] (502s left) i915_module_load (reload)
[656.878268] Starting subtest: reload
[672.736483] Subtest reload: SUCCESS (15.848s)
[673.284352] [155/161] (486s left) gem_lmem_swapping (basic)
[673.899100] Subtest basic: SKIP (0.000s)
[674.357375] [156/161] (485s left) gem_lmem_swapping (parallel-random-engines)
[674.775148] Subtest parallel-random-engines: SKIP (0.000s)
[675.200124] [157/161] (484s left) gem_lmem_swapping (random-engines)
[675.617266] Subtest random-engines: SKIP (0.000s)
[676.047414] [158/161] (484s left) gem_lmem_swapping (verify-random)
[676.461239] Subtest verify-random: SKIP (0.000s)
[676.888289] [159/161] (484s left) i915_pm_rpm (module-reload)
[680.150000] Starting subtest: module-reload
[713.989341] Subtest module-reload: SUCCESS (33.832s)
[714.244741] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[714.258072] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[714.285135] [160/161] (446s left) i915_selftest (live)
[714.684315] Starting subtest: live
[714.694900] Starting dynamic subtest: sanitycheck
[725.136630] Dynamic subtest sanitycheck: SUCCESS (10.438s)
[725.204136] Starting dynamic subtest: uncore
[737.030938] Dynamic subtest uncore: SUCCESS (11.885s)
[737.101216] Starting dynamic subtest: workarounds
[749.946383] Dynamic subtest workarounds: SUCCESS (12.914s)
[750.029182] Starting dynamic subtest: gt_engines
[760.951358] Dynamic subtest gt_engines: SUCCESS (10.982s)
[761.018064] Starting dynamic subtest: gt_timelines
[805.084900] Dynamic subtest gt_timelines: SUCCESS (44.122s)
[805.151129] Starting dynamic subtest: gt_contexts
[815.658831] Dynamic subtest gt_contexts: SUCCESS (10.565s)
[815.723364] Starting dynamic subtest: gt_lrc
[829.702947] Dynamic subtest gt_lrc: SUCCESS (14.035s)
[829.769847] Starting dynamic subtest: gt_mocs
[841.344792] Dynamic subtest gt_mocs: SUCCESS (11.632s)
[841.412213] Starting dynamic subtest: gt_pm
[853.934292] Dynamic subtest gt_pm: SUCCESS (12.578s)
[854.004605] Starting dynamic subtest: gt_heartbeat
[864.392656] Dynamic subtest gt_heartbeat: SUCCESS (10.449s)
[864.460883] Starting dynamic subtest: gt_tlb
[876.026096] Dynamic subtest gt_tlb: SUCCESS (11.623s)
[876.096778] Starting dynamic subtest: requests
[896.552978] Dynamic subtest requests: SUCCESS (20.493s)
[896.619615] Starting dynamic subtest: migrate
[911.104186] Dynamic subtest migrate: SUCCESS (14.566s)
[911.183177] Starting dynamic subtest: active
[921.582306] Dynamic subtest active: SUCCESS (10.470s)
[921.652392] Starting dynamic subtest: objects
[936.305647] Dynamic subtest objects: SUCCESS (14.722s)
[936.382521] Starting dynamic subtest: mman
[958.208299] Dynamic subtest mman: SUCCESS (21.884s)
[958.278388] Starting dynamic subtest: dmabuf
[968.885815] Dynamic subtest dmabuf: SUCCESS (10.667s)
[968.955290] Starting dynamic subtest: vma
[979.413120] Dynamic subtest vma: SUCCESS (10.519s)
[979.483296] Starting dynamic subtest: coherency
[1010.262404] Dynamic subtest coherency: SUCCESS (30.840s)
[1010.328853] Starting dynamic subtest: gtt
[1028.861836] Dynamic subtest gtt: SUCCESS (18.585s)
[1028.928380] Starting dynamic subtest: gem
[1040.499421] Dynamic subtest gem: SUCCESS (11.629s)
[1040.563219] Starting dynamic subtest: evict
[1058.671269] Dynamic subtest evict: SUCCESS (18.161s)
[1058.738343] Starting dynamic subtest: hugepages
[1076.211077] Dynamic subtest hugepages: SUCCESS (17.530s)
[1076.278426] Starting dynamic subtest: gem_contexts
[1117.529971] Dynamic subtest gem_contexts: SUCCESS (41.316s)
[1117.605478] Starting dynamic subtest: client
[1128.125191] Dynamic subtest client: SUCCESS (10.578s)
[1128.195903] Starting dynamic subtest: gem_migrate
[1138.553228] Dynamic subtest gem_migrate: SUCCESS (10.419s)
[1138.621255] Starting dynamic subtest: reset
[1152.496400] Dynamic subtest reset: SUCCESS (13.935s)
[1152.574240] Starting dynamic subtest: memory_region
[1162.934255] Dynamic subtest memory_region: SUCCESS (10.422s)
[1163.002645] Starting dynamic subtest: hangcheck
[1194.305365] Dynamic subtest hangcheck: SUCCESS (31.362s)
[1194.373639] Starting dynamic subtest: execlists
[1204.752588] Dynamic subtest execlists: SUCCESS (10.438s)
[1204.824067] Starting dynamic subtest: ring_submission
[1215.132223] Dynamic subtest ring_submission: SUCCESS (10.378s)
[1215.227267] Starting dynamic subtest: perf
[1226.171793] Dynamic subtest perf: SUCCESS (11.017s)
[1226.248532] Starting dynamic subtest: slpc
[1238.383231] Dynamic subtest slpc: SUCCESS (12.193s)
[1238.448097] Starting dynamic subtest: guc
[1251.527867] Dynamic subtest guc: SUCCESS (13.135s)
[1251.593957] Starting dynamic subtest: guc_multi_lrc
[1262.036221] Dynamic subtest guc_multi_lrc: SUCCESS (10.498s)
[1262.109629] Starting dynamic subtest: guc_hang
[1273.753692] Dynamic subtest guc_hang: SUCCESS (11.718s)
[1273.831213] Starting dynamic subtest: late_gt_pm
[1284.346418] Dynamic subtest late_gt_pm: SUCCESS (10.570s)
[1284.349679] Subtest live: SUCCESS (569.631s)
[1284.471665] Overall timeout time exceeded, stopping.
[1284.648867] Closing watchdogs
Done.