[166.375510] Initializing watchdogs
[166.376585]   /dev/watchdog0
[166.467712] [001/170] (960s left) i915_module_load (load)
[167.568438] Starting subtest: load
[176.112973] Subtest load: SUCCESS (8.437s)
[176.519136] [002/170] (950s left) core_auth (basic-auth)
[178.430739] Starting subtest: basic-auth
[178.439741] Subtest basic-auth: SUCCESS (0.006s)
[178.776872] [003/170] (948s left) debugfs_test (read_all_entries)
[179.856588] Starting subtest: read_all_entries
[180.414874] Subtest read_all_entries: SUCCESS (0.590s)
[181.400967] [004/170] (946s left) debugfs_test (basic-hwmon)
[182.607662] Starting subtest: basic-hwmon
[182.622333] Subtest basic-hwmon: SKIP (0.000s)
[183.583975] [005/170] (944s left) fbdev (eof)
[184.634742] Starting subtest: eof
[184.644687] Subtest eof: SUCCESS (0.002s)
[184.950216] [006/170] (943s left) fbdev (info)
[185.920279] Starting subtest: info
[185.927622] Subtest info: SUCCESS (0.000s)
[186.233924] [007/170] (942s left) fbdev (nullptr)
[187.225642] Starting subtest: nullptr
[187.232757] Subtest nullptr: SUCCESS (0.001s)
[187.543961] [008/170] (941s left) fbdev (read)
[188.533713] Starting subtest: read
[190.998500] Subtest read: SUCCESS (2.397s)
[191.261871] [009/170] (937s left) fbdev (write)
[192.240881] Starting subtest: write
[193.590106] Subtest write: SUCCESS (1.292s)
[193.899850] [010/170] (935s left) gem_basic (bad-close)
[194.936938] Starting subtest: bad-close
[194.952253] Subtest bad-close: SUCCESS (0.000s)
[195.301901] [011/170] (934s left) gem_basic (create-close)
[196.197900] Starting subtest: create-close
[196.213714] Subtest create-close: SUCCESS (0.001s)
[196.580387] [012/170] (933s left) gem_basic (create-fd-close)
[197.515675] Starting subtest: create-fd-close
[197.531479] Subtest create-fd-close: SUCCESS (0.002s)
[197.866483] [013/170] (932s left) gem_busy (busy@all-engines)
[199.061347] Starting subtest: busy
[199.068823] Starting dynamic subtest: all-engines
[199.491728] Dynamic subtest all-engines: SUCCESS (0.461s)
[199.500745] Subtest busy: SUCCESS (0.463s)
[200.323336] [014/170] (929s left) gem_close_race (basic-process)
[201.624206] Starting subtest: basic-process
[201.928609] Subtest basic-process: SUCCESS (0.341s)
[202.417834] [015/170] (927s left) gem_close_race (basic-threads)
[203.548901] Starting subtest: basic-threads
[205.305678] Subtest basic-threads: SUCCESS (1.819s)
[205.894898] [016/170] (924s left) gem_ctx_create (basic)
[207.244892] Starting subtest: basic
[207.254977] Subtest basic: SUCCESS (0.000s)
[207.692182] [017/170] (923s left) gem_ctx_create (basic-files)
[208.841631] Starting subtest: basic-files
[211.042769] Subtest basic-files: SUCCESS (2.193s)
[211.517883] [018/170] (919s left) gem_ctx_exec (basic)
[212.636891] Starting subtest: basic
[212.681579] Subtest basic: SUCCESS (0.044s)
[213.097312] [019/170] (918s left) gem_exec_basic (basic)
[214.216682] Starting subtest: basic
[214.225677] Starting dynamic subtest: rcs0-smem
[214.233791] Dynamic subtest rcs0-smem: SUCCESS (0.019s)
[214.319406] Starting dynamic subtest: bcs0-smem
[214.334323] Dynamic subtest bcs0-smem: SUCCESS (0.011s)
[214.423200] Starting dynamic subtest: vcs0-smem
[214.440503] Dynamic subtest vcs0-smem: SUCCESS (0.005s)
[214.532550] Starting dynamic subtest: vecs0-smem
[214.543528] Dynamic subtest vecs0-smem: SUCCESS (0.005s)
[214.560136] Subtest basic: SUCCESS (0.047s)
[214.751537] [020/170] (916s left) gem_exec_create (basic)
[215.957913] Starting subtest: basic
[215.969744] Starting dynamic subtest: smem
[218.119405] Dynamic subtest smem: SUCCESS (2.234s)
[218.127280] Subtest basic: SUCCESS (2.236s)
[218.668130] [021/170] (913s left) gem_exec_fence (basic-busy)
[220.220383] Starting subtest: basic-busy
[220.234783] Starting dynamic subtest: rcs0
[220.249319] Dynamic subtest rcs0: SUCCESS (0.063s)
[220.359510] Starting dynamic subtest: bcs0
[220.378685] Dynamic subtest bcs0: SUCCESS (0.057s)
[220.458926] Starting dynamic subtest: vcs0
[220.482564] Dynamic subtest vcs0: SUCCESS (0.051s)
[220.572765] Starting dynamic subtest: vecs0
[220.615757] Dynamic subtest vecs0: SUCCESS (0.057s)
[220.622700] Subtest basic-busy: SUCCESS (0.233s)
[222.663193] [022/170] (909s left) gem_exec_fence (basic-wait)
[224.028677] Starting subtest: basic-wait
[224.035912] Starting dynamic subtest: rcs0
[224.044510] Dynamic subtest rcs0: SUCCESS (0.069s)
[224.120327] Starting dynamic subtest: bcs0
[224.130648] Dynamic subtest bcs0: SUCCESS (0.069s)
[224.202153] Starting dynamic subtest: vcs0
[224.208966] Dynamic subtest vcs0: SUCCESS (0.054s)
[224.284430] Starting dynamic subtest: vecs0
[224.291695] Dynamic subtest vecs0: SUCCESS (0.044s)
[224.301751] Subtest basic-wait: SUCCESS (0.242s)
[226.106192] [023/170] (906s left) gem_exec_fence (basic-await)
[227.465564] Starting subtest: basic-await
[227.472772] Starting dynamic subtest: rcs0
[227.920450] Dynamic subtest rcs0: SUCCESS (0.447s)
[228.000761] Starting dynamic subtest: bcs0
[228.366712] Dynamic subtest bcs0: SUCCESS (0.453s)
[228.478746] Starting dynamic subtest: vcs0
[228.797142] Dynamic subtest vcs0: SUCCESS (0.426s)
[228.885557] Starting dynamic subtest: vecs0
[229.253972] Dynamic subtest vecs0: SUCCESS (0.461s)
[229.262780] Subtest basic-await: SUCCESS (1.795s)
[231.073283] [024/170] (901s left) gem_exec_fence (nb-await)
[232.402299] Starting subtest: nb-await
[232.414522] Starting dynamic subtest: rcs0
[232.590340] Dynamic subtest rcs0: SUCCESS (0.167s)
[232.680977] Starting dynamic subtest: bcs0
[232.688516] Dynamic subtest bcs0: SUCCESS (0.126s)
[232.778838] Starting dynamic subtest: vcs0
[232.790199] Dynamic subtest vcs0: SUCCESS (0.129s)
[232.861395] Starting dynamic subtest: vecs0
[232.907797] Dynamic subtest vecs0: SUCCESS (0.128s)
[232.919770] Subtest nb-await: SUCCESS (0.555s)
[234.807446] [025/170] (897s left) gem_exec_gttfill (basic)
[236.070400] Starting subtest: basic
[241.239927] Subtest basic: SKIP (5.101s)
[241.760723] [026/170] (891s left) gem_exec_parallel (engines)
[243.479865] Starting subtest: engines
[243.487496] Starting dynamic subtest: basic
[256.257762] Dynamic subtest basic: SUCCESS (12.803s)
[256.343350] Starting dynamic subtest: contexts
[272.500923] Dynamic subtest contexts: SUCCESS (16.230s)
[272.654868] Starting dynamic subtest: fds
[290.929569] Dynamic subtest fds: SUCCESS (18.426s)
[291.101804] Starting dynamic subtest: userptr
[303.679376] Dynamic subtest userptr: SUCCESS (12.785s)
[303.686726] Subtest engines: SUCCESS (60.249s)
[304.200476] [027/170] (828s left) gem_exec_store (basic)
[305.376705] Starting subtest: basic
[305.537868] Subtest basic: SUCCESS (0.169s)
[306.068713] [028/170] (827s left) gem_flink_basic (bad-flink)
[306.990124] Starting subtest: bad-flink
[307.002425] Subtest bad-flink: SUCCESS (0.000s)
[307.388281] [029/170] (826s left) gem_flink_basic (bad-open)
[308.270364] Starting subtest: bad-open
[308.280102] Subtest bad-open: SUCCESS (0.000s)
[308.643396] [030/170] (825s left) gem_flink_basic (basic)
[309.533732] Starting subtest: basic
[309.541823] Subtest basic: SUCCESS (0.001s)
[309.907959] [031/170] (824s left) gem_flink_basic (double-flink)
[310.803420] Starting subtest: double-flink
[310.810707] Subtest double-flink: SUCCESS (0.001s)
[311.267460] [032/170] (823s left) gem_flink_basic (flink-lifetime)
[312.159668] Starting subtest: flink-lifetime
[312.179301] Subtest flink-lifetime: SUCCESS (0.010s)
[312.600246] [033/170] (822s left) gem_huc_copy (huc-copy)
[313.694615] Starting subtest: huc-copy
[313.723863] Subtest huc-copy: SKIP (0.000s)
[314.056506] [034/170] (820s left) gem_linear_blits (basic)
[316.154112] Subtest basic: SKIP (0.000s)
[316.548905] [035/170] (818s left) gem_mmap (basic)
[318.046887] Starting subtest: basic
[318.082944] Subtest basic: SUCCESS (0.003s)
[318.474995] [036/170] (816s left) gem_mmap_gtt (basic)
[319.562658] Starting subtest: basic
[319.570699] Subtest basic: SUCCESS (0.007s)
[319.974613] [037/170] (815s left) gem_render_linear_blits (basic)
[321.001903] Starting subtest: basic
[321.266672] Subtest basic: SUCCESS (0.270s)
[321.579202] [038/170] (814s left) gem_render_tiled_blits (basic)
[322.650427] Starting subtest: basic
[322.979204] Subtest basic: SUCCESS (0.326s)
[323.308557] [039/170] (812s left) gem_ringfill (basic-all)
[325.447723] Starting subtest: basic-all
[327.264935] Subtest basic-all: SUCCESS (1.801s)
[327.663841] [040/170] (808s left) gem_softpin (allocator-basic)
[328.724989] Starting subtest: allocator-basic
[329.902565] Subtest allocator-basic: SUCCESS (1.121s)
[330.378474] [041/170] (806s left) gem_softpin (allocator-basic-reserve)
[331.314722] Starting subtest: allocator-basic-reserve
[332.497812] Subtest allocator-basic-reserve: SUCCESS (1.132s)
[333.022166] [042/170] (803s left) gem_softpin (safe-alignment)
[333.930733] Starting subtest: safe-alignment
[334.113167] Subtest safe-alignment: SUCCESS (0.126s)
[334.435257] [043/170] (802s left) gem_sync (basic-all)
[335.544931] Starting subtest: basic-all
[339.686417] Subtest basic-all: SUCCESS (4.143s)
[340.240188] [044/170] (796s left) gem_sync (basic-each)
[341.233354] Starting subtest: basic-each
[344.486774] Subtest basic-each: SUCCESS (3.254s)
[345.069233] [045/170] (792s left) gem_tiled_blits (basic)
[347.172916] Subtest basic: SKIP (0.000s)
[347.443302] [046/170] (790s left) gem_tiled_fence_blits (basic)
[349.894769] Starting subtest: basic
[349.957723] Subtest basic: SUCCESS (0.107s)
[350.531592] [047/170] (787s left) gem_tiled_pread_basic
[353.368867] [048/170] (784s left) gem_wait (busy@all-engines)
[354.719311] Starting subtest: busy
[354.728144] Starting dynamic subtest: all-engines
[355.311805] Dynamic subtest all-engines: SUCCESS (0.585s)
[355.319643] Subtest busy: SUCCESS (0.587s)
[355.973385] [049/170] (782s left) gem_wait (wait@all-engines)
[357.065216] Starting subtest: wait
[357.073240] Starting dynamic subtest: all-engines
[358.231880] Dynamic subtest all-engines: SUCCESS (1.126s)
[358.238949] Subtest wait: SUCCESS (1.127s)
[358.952455] [050/170] (779s left) i915_getparams_basic (basic-eu-total)
[359.907798] Starting subtest: basic-eu-total
[359.924487] Subtest basic-eu-total: SUCCESS (0.000s)
[360.278505] [051/170] (778s left) i915_getparams_basic (basic-subslice-total)
[361.164819] Starting subtest: basic-subslice-total
[361.181848] Subtest basic-subslice-total: SUCCESS (0.000s)
[361.567440] [052/170] (777s left) i915_hangman (error-state-basic)
[362.737971] Starting subtest: error-state-basic
[362.808778] Subtest error-state-basic: SUCCESS (0.059s)
[363.207322] [053/170] (776s left) i915_pciid
[364.580423] [054/170] (775s left) kms_addfb_basic (addfb25-bad-modifier)
[365.573700] Starting subtest: addfb25-bad-modifier
[365.584149] Subtest addfb25-bad-modifier: SUCCESS (0.002s)
[366.966876] [055/170] (772s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[367.892901] Starting subtest: addfb25-framebuffer-vs-set-tiling
[367.900868] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.001s)
[368.942766] [056/170] (771s left) kms_addfb_basic (addfb25-modifier-no-flag)
[369.901290] Starting subtest: addfb25-modifier-no-flag
[369.909135] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[370.945791] [057/170] (769s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[371.824365] Starting subtest: addfb25-x-tiled-legacy
[371.832114] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[372.803375] [058/170] (767s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[373.728706] Starting subtest: addfb25-x-tiled-mismatch-legacy
[373.735469] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[374.802655] [059/170] (766s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[376.395740] Starting subtest: addfb25-yf-tiled-legacy
[376.412849] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.001s)
[376.788929] [060/170] (764s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[378.436410] Starting subtest: addfb25-y-tiled-legacy
[378.445356] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[378.889612] [061/170] (762s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[380.479795] Starting subtest: addfb25-y-tiled-small-legacy
[380.489609] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.002s)
[380.861381] [062/170] (760s left) kms_addfb_basic (bad-pitch-0)
[381.789325] Starting subtest: bad-pitch-0
[381.797361] Subtest bad-pitch-0: SUCCESS (0.000s)
[382.845938] [063/170] (759s left) kms_addfb_basic (bad-pitch-1024)
[383.795906] Starting subtest: bad-pitch-1024
[383.804421] Subtest bad-pitch-1024: SUCCESS (0.000s)
[384.857482] [064/170] (757s left) kms_addfb_basic (bad-pitch-128)
[385.798859] Starting subtest: bad-pitch-128
[385.807407] Subtest bad-pitch-128: SUCCESS (0.000s)
[386.773880] [065/170] (755s left) kms_addfb_basic (bad-pitch-256)
[387.684963] Starting subtest: bad-pitch-256
[387.693575] Subtest bad-pitch-256: SUCCESS (0.000s)
[388.670261] [066/170] (754s left) kms_addfb_basic (bad-pitch-32)
[389.599803] Starting subtest: bad-pitch-32
[389.607661] Subtest bad-pitch-32: SUCCESS (0.000s)
[390.644850] [067/170] (752s left) kms_addfb_basic (bad-pitch-63)
[391.596170] Starting subtest: bad-pitch-63
[391.602504] Subtest bad-pitch-63: SUCCESS (0.000s)
[392.642801] [068/170] (750s left) kms_addfb_basic (bad-pitch-65536)
[393.588930] Starting subtest: bad-pitch-65536
[393.596795] Subtest bad-pitch-65536: SUCCESS (0.002s)
[394.615879] [069/170] (749s left) kms_addfb_basic (bad-pitch-999)
[395.569402] Starting subtest: bad-pitch-999
[395.575845] Subtest bad-pitch-999: SUCCESS (0.000s)
[396.624406] [070/170] (747s left) kms_addfb_basic (basic)
[397.573806] Starting subtest: basic
[397.580624] Subtest basic: SUCCESS (0.000s)
[398.641125] [071/170] (745s left) kms_addfb_basic (basic-x-tiled-legacy)
[399.586346] Starting subtest: basic-x-tiled-legacy
[399.593932] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[400.579152] [072/170] (744s left) kms_addfb_basic (basic-y-tiled-legacy)
[401.504243] Starting subtest: basic-y-tiled-legacy
[401.511567] Subtest basic-y-tiled-legacy: SUCCESS (0.003s)
[402.528385] [073/170] (742s left) kms_addfb_basic (bo-too-small)
[404.097783] Starting subtest: bo-too-small
[404.105901] Subtest bo-too-small: SUCCESS (0.002s)
[404.434747] [074/170] (740s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[406.036832] Starting subtest: bo-too-small-due-to-tiling
[406.052174] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[406.394828] [075/170] (738s left) kms_addfb_basic (clobberred-modifier)
[407.304811] Starting subtest: clobberred-modifier
[407.311670] Subtest clobberred-modifier: SUCCESS (0.001s)
[408.345547] [076/170] (737s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[409.259232] Starting subtest: framebuffer-vs-set-tiling
[409.266767] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[410.295443] [077/170] (735s left) kms_addfb_basic (invalid-get-prop)
[411.242852] Starting subtest: invalid-get-prop
[411.250982] Subtest invalid-get-prop: SUCCESS (0.000s)
[412.264684] [078/170] (733s left) kms_addfb_basic (invalid-get-prop-any)
[413.216709] Starting subtest: invalid-get-prop-any
[413.224677] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[414.215320] [079/170] (732s left) kms_addfb_basic (invalid-set-prop)
[415.141375] Starting subtest: invalid-set-prop
[415.148936] Subtest invalid-set-prop: SUCCESS (0.000s)
[416.168843] [080/170] (730s left) kms_addfb_basic (invalid-set-prop-any)
[417.099824] Starting subtest: invalid-set-prop-any
[417.107776] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[418.131599] [081/170] (728s left) kms_addfb_basic (no-handle)
[419.035648] Starting subtest: no-handle
[419.043926] Subtest no-handle: SUCCESS (0.000s)
[420.026453] [082/170] (727s left) kms_addfb_basic (size-max)
[421.624161] Starting subtest: size-max
[421.633436] Subtest size-max: SUCCESS (0.001s)
[421.963355] [083/170] (725s left) kms_addfb_basic (small-bo)
[423.536737] Starting subtest: small-bo
[423.545464] Subtest small-bo: SUCCESS (0.000s)
[423.889830] [084/170] (723s left) kms_addfb_basic (tile-pitch-mismatch)
[424.802554] Starting subtest: tile-pitch-mismatch
[424.810841] Subtest tile-pitch-mismatch: SUCCESS (0.002s)
[425.817518] [085/170] (722s left) kms_addfb_basic (too-high)
[427.445663] Starting subtest: too-high
[427.455434] Subtest too-high: SUCCESS (0.007s)
[427.843182] [086/170] (720s left) kms_addfb_basic (too-wide)
[429.427913] Starting subtest: too-wide
[429.436529] Subtest too-wide: SUCCESS (0.000s)
[429.802981] [087/170] (718s left) kms_addfb_basic (unused-handle)
[430.779982] Starting subtest: unused-handle
[430.788232] Subtest unused-handle: SUCCESS (0.000s)
[431.873197] [088/170] (716s left) kms_addfb_basic (unused-modifier)
[432.794820] Starting subtest: unused-modifier
[432.803627] Subtest unused-modifier: SUCCESS (0.000s)
[433.827918] [089/170] (715s left) kms_addfb_basic (unused-offsets)
[434.773509] Starting subtest: unused-offsets
[434.780676] Subtest unused-offsets: SUCCESS (0.000s)
[435.836412] [090/170] (713s left) kms_addfb_basic (unused-pitches)
[436.738460] Starting subtest: unused-pitches
[436.744813] Subtest unused-pitches: SUCCESS (0.000s)
[437.733155] [091/170] (711s left) kms_busy (basic)
[439.512986] Starting subtest: basic
[439.533681] Starting dynamic subtest: flip
[442.848343] Dynamic subtest flip: SUCCESS (3.316s)
[442.999534] Starting dynamic subtest: modeset
[447.826326] Dynamic subtest modeset: SUCCESS (4.971s)
[447.834557] Subtest basic: SUCCESS (8.302s)
[449.026332] [092/170] (700s left) kms_chamelium_hpd (dp-hpd-fast)
[451.133904] Subtest dp-hpd-fast: SKIP (0.000s)
[452.114904] [093/170] (698s left) kms_chamelium_edid (dp-edid-read)
[454.221971] Subtest dp-edid-read: SKIP (0.000s)
[455.206935] [094/170] (695s left) kms_chamelium_frames (dp-crc-fast)
[457.289831] Subtest dp-crc-fast: SKIP (0.000s)
[458.275892] [095/170] (692s left) kms_chamelium_hpd (hdmi-hpd-fast)
[460.344454] Subtest hdmi-hpd-fast: SKIP (0.000s)
[461.350175] [096/170] (689s left) kms_chamelium_edid (hdmi-edid-read)
[463.418370] Subtest hdmi-edid-read: SKIP (0.000s)
[464.419649] [097/170] (686s left) kms_chamelium_frames (hdmi-crc-fast)
[466.456175] Subtest hdmi-crc-fast: SKIP (0.000s)
[467.470392] [098/170] (683s left) kms_chamelium_hpd (vga-hpd-fast)
[469.577681] Subtest vga-hpd-fast: SKIP (0.000s)
[470.601867] [099/170] (680s left) kms_chamelium_edid (vga-edid-read)
[472.799364] Subtest vga-edid-read: SKIP (0.000s)
[473.813449] [100/170] (677s left) kms_prop_blob (basic)
[474.838275] Starting subtest: basic
[474.846981] Subtest basic: SUCCESS (0.000s)
[475.270425] [101/170] (676s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[476.927681] Starting subtest: basic-busy-flip-before-cursor-atomic
[476.943831] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[477.315295] [102/170] (675s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[478.901412] Starting subtest: basic-busy-flip-before-cursor-legacy
[478.926819] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[479.296199] [103/170] (673s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[480.901428] Starting subtest: basic-flip-after-cursor-atomic
[482.816254] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.918s)
[484.077578] [104/170] (668s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[485.670823] Starting subtest: basic-flip-after-cursor-legacy
[487.495754] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.828s)
[488.721619] [105/170] (664s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[490.347735] Starting subtest: basic-flip-after-cursor-varying-size
[492.199488] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.844s)
[493.419932] [106/170] (660s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[495.024945] Starting subtest: basic-flip-before-cursor-atomic
[496.921803] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.903s)
[498.173290] [107/170] (655s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[499.755883] Starting subtest: basic-flip-before-cursor-legacy
[501.593337] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.835s)
[502.920496] [108/170] (651s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[504.661844] Starting subtest: basic-flip-before-cursor-varying-size
[506.583463] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.923s)
[507.777911] [109/170] (646s left) kms_flip (basic-flip-vs-dpms)
[509.078317] Starting subtest: basic-flip-vs-dpms
[509.102729] Starting dynamic subtest: A-eDP1
[512.890144] Dynamic subtest A-eDP1: SUCCESS (3.772s)
[513.037913] Starting dynamic subtest: B-eDP1
[515.829524] Dynamic subtest B-eDP1: SUCCESS (2.929s)
[515.977688] Starting dynamic subtest: C-eDP1
[518.832347] Dynamic subtest C-eDP1: SUCCESS (3.000s)
[518.869538] Subtest basic-flip-vs-dpms: SUCCESS (9.816s)
[520.005128] [110/170] (634s left) kms_flip (basic-flip-vs-modeset)
[521.256404] Starting subtest: basic-flip-vs-modeset
[521.283912] Starting dynamic subtest: A-eDP1
[525.054955] Dynamic subtest A-eDP1: SUCCESS (3.759s)
[525.198231] Starting dynamic subtest: B-eDP1
[527.830995] Dynamic subtest B-eDP1: SUCCESS (2.781s)
[527.970573] Starting dynamic subtest: C-eDP1
[530.670675] Dynamic subtest C-eDP1: SUCCESS (2.831s)
[530.730581] Subtest basic-flip-vs-modeset: SUCCESS (9.492s)
[531.873305] [111/170] (623s left) kms_flip (basic-flip-vs-wf_vblank)
[533.144613] Starting subtest: basic-flip-vs-wf_vblank
[533.168991] Starting dynamic subtest: A-eDP1
[536.177698] Dynamic subtest A-eDP1: SUCCESS (2.950s)
[536.336139] Starting dynamic subtest: B-eDP1
[538.874414] Dynamic subtest B-eDP1: SUCCESS (2.672s)
[539.018424] Starting dynamic subtest: C-eDP1
[541.477700] Dynamic subtest C-eDP1: SUCCESS (2.634s)
[541.497955] Subtest basic-flip-vs-wf_vblank: SUCCESS (8.378s)
[543.490244] [112/170] (611s left) kms_flip (basic-plain-flip)
[544.726673] Starting subtest: basic-plain-flip
[544.768583] Starting dynamic subtest: A-eDP1
[547.452887] Dynamic subtest A-eDP1: SUCCESS (2.656s)
[547.594828] Starting dynamic subtest: B-eDP1
[549.886801] Dynamic subtest B-eDP1: SUCCESS (2.384s)
[550.052824] Starting dynamic subtest: C-eDP1
[552.301859] Dynamic subtest C-eDP1: SUCCESS (2.398s)
[552.317918] Subtest basic-plain-flip: SUCCESS (7.551s)
[554.313370] [113/170] (601s left) kms_force_connector_basic (force-connector-state)
[555.866851] Starting subtest: force-connector-state
[558.898095] Subtest force-connector-state: SUCCESS (3.020s)
[561.557295] [114/170] (594s left) kms_force_connector_basic (force-edid)
[563.128849] Starting subtest: force-edid
[563.685733] Subtest force-edid: SUCCESS (0.554s)
[565.512762] [115/170] (590s left) kms_force_connector_basic (force-load-detect)
[567.007816] Starting subtest: force-load-detect
[567.022668] Subtest force-load-detect: SKIP (0.000s)
[568.511163] [116/170] (587s left) kms_force_connector_basic (prune-stale-modes)
[569.988535] Starting subtest: prune-stale-modes
[570.593946] Subtest prune-stale-modes: SUCCESS (0.603s)
[572.445980] [117/170] (584s left) kms_frontbuffer_tracking (basic)
[574.133794] Starting subtest: basic
[583.718196] Subtest basic: SUCCESS (9.582s)
[584.123418] [118/170] (572s left) kms_pipe_crc_basic (compare-crc-sanitycheck)
[585.864787] Starting subtest: compare-crc-sanitycheck
[585.885152] Starting dynamic subtest: pipe-A-eDP-1
[587.199099] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.302s)
[587.348986] Starting dynamic subtest: pipe-B-eDP-1
[589.234628] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.012s)
[589.396906] Starting dynamic subtest: pipe-C-eDP-1
[591.553193] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.286s)
[591.563524] Subtest compare-crc-sanitycheck: SUCCESS (5.686s)
[592.807669] [119/170] (564s left) kms_pipe_crc_basic (hang-read-crc)
[594.418162] Starting subtest: hang-read-crc
[594.436751] Starting dynamic subtest: pipe-A-eDP-1
[597.826940] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.372s)
[598.027179] Starting dynamic subtest: pipe-B-eDP-1
[602.062803] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.224s)
[602.272107] Starting dynamic subtest: pipe-C-eDP-1
[606.822425] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.728s)
[606.831378] Subtest hang-read-crc: SUCCESS (12.391s)
[607.974266] [120/170] (549s left) kms_pipe_crc_basic (nonblocking-crc)
[609.635818] Starting subtest: nonblocking-crc
[609.662859] Starting dynamic subtest: pipe-A-eDP-1
[611.035865] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.373s)
[611.186717] Starting dynamic subtest: pipe-B-eDP-1
[613.198692] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.143s)
[613.368594] Starting dynamic subtest: pipe-C-eDP-1
[615.596497] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.377s)
[615.604700] Subtest nonblocking-crc: SUCCESS (5.962s)
[616.799547] [121/170] (541s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[618.400155] Starting subtest: nonblocking-crc-frame-sequence
[618.425455] Starting dynamic subtest: pipe-A-eDP-1
[619.827612] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.400s)
[619.954096] Starting dynamic subtest: pipe-B-eDP-1
[622.013496] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.159s)
[622.175429] Starting dynamic subtest: pipe-C-eDP-1
[624.469927] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.430s)
[624.476615] Subtest nonblocking-crc-frame-sequence: SUCCESS (6.062s)
[625.652562] [122/170] (532s left) kms_pipe_crc_basic (read-crc)
[627.245305] Starting subtest: read-crc
[627.272458] Starting dynamic subtest: pipe-A-eDP-1
[628.653509] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.349s)
[628.825845] Starting dynamic subtest: pipe-B-eDP-1
[630.806796] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.152s)
[630.986741] Starting dynamic subtest: pipe-C-eDP-1
[633.220815] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.398s)
[633.227875] Subtest read-crc: SUCCESS (5.977s)
[634.415698] [123/170] (523s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[635.993910] Starting subtest: read-crc-frame-sequence
[636.019639] Starting dynamic subtest: pipe-A-eDP-1
[637.342380] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.324s)
[637.509825] Starting dynamic subtest: pipe-B-eDP-1
[639.437763] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.077s)
[639.639291] Starting dynamic subtest: pipe-C-eDP-1
[641.840584] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.365s)
[641.847932] Subtest read-crc-frame-sequence: SUCCESS (5.840s)
[643.026463] [124/170] (515s left) kms_psr (primary_page_flip)
[644.753507] Starting subtest: primary_page_flip
[647.763867] Subtest primary_page_flip: SUCCESS (3.008s)
[648.925651] [125/170] (509s left) kms_psr (cursor_plane_move)
[650.523485] Starting subtest: cursor_plane_move
[653.567483] Subtest cursor_plane_move: SUCCESS (3.033s)
[654.708994] [126/170] (504s left) kms_psr (sprite_plane_onoff)
[656.281566] Starting subtest: sprite_plane_onoff
[659.390850] Subtest sprite_plane_onoff: SUCCESS (3.094s)
[660.627920] [127/170] (498s left) kms_psr (primary_mmap_gtt)
[662.298735] Starting subtest: primary_mmap_gtt
[665.538175] Subtest primary_mmap_gtt: SUCCESS (3.244s)
[666.711780] [128/170] (492s left) kms_setmode (basic-clone-single-crtc)
[667.694723] Starting subtest: basic-clone-single-crtc
[667.719718] Subtest basic-clone-single-crtc: SKIP (0.005s)
[668.063832] [129/170] (491s left) i915_pm_backlight (basic-brightness)
[669.686124] Starting subtest: basic-brightness
[670.278298] Starting dynamic subtest: eDP-1
[670.981779] Dynamic subtest eDP-1: SUCCESS (0.764s)
[670.990793] Subtest basic-brightness: SUCCESS (1.291s)
[672.178318] [130/170] (488s left) i915_pm_rpm (basic-pci-d3-state)
[676.178749] Starting subtest: basic-pci-d3-state
[681.039514] Subtest basic-pci-d3-state: SUCCESS (4.888s)
[681.689371] [131/170] (478s left) i915_pm_rpm (basic-rte)
[682.606730] Starting subtest: basic-rte
[690.516777] Subtest basic-rte: SUCCESS (7.895s)
[696.530388] [132/170] (464s left) i915_pm_rps (basic-api)
[697.604640] Starting subtest: basic-api
[697.615879] Subtest basic-api: SUCCESS (0.037s)
[697.981811] [133/170] (463s left) prime_self_import (basic-llseek-bad)
[698.988694] Starting subtest: basic-llseek-bad
[698.998629] Subtest basic-llseek-bad: SUCCESS (0.013s)
[699.460242] [134/170] (461s left) prime_self_import (basic-llseek-size)
[700.403798] Starting subtest: basic-llseek-size
[700.431649] Subtest basic-llseek-size: SUCCESS (0.026s)
[700.876178] [135/170] (460s left) prime_self_import (basic-with_fd_dup)
[701.815861] Starting subtest: basic-with_fd_dup
[701.842159] Subtest basic-with_fd_dup: SUCCESS (0.028s)
[702.274101] [136/170] (459s left) prime_self_import (basic-with_one_bo)
[703.235865] Starting subtest: basic-with_one_bo
[703.261461] Subtest basic-with_one_bo: SUCCESS (0.034s)
[703.713650] [137/170] (458s left) prime_self_import (basic-with_one_bo_two_files)
[704.634893] Starting subtest: basic-with_one_bo_two_files
[704.658819] Subtest basic-with_one_bo_two_files: SUCCESS (0.021s)
[705.086388] [138/170] (457s left) prime_self_import (basic-with_two_bos)
[705.986885] Starting subtest: basic-with_two_bos
[706.014853] Subtest basic-with_two_bos: SUCCESS (0.028s)
[706.442935] [139/170] (456s left) prime_vgem (basic-fence-flip)
[707.574887] Starting subtest: basic-fence-flip
[709.312507] Subtest basic-fence-flip: SUCCESS (1.683s)
[709.794930] [140/170] (453s left) prime_vgem (basic-fence-mmap)
[710.801907] Starting subtest: basic-fence-mmap
[711.135135] Subtest basic-fence-mmap: SUCCESS (0.278s)
[711.459691] [141/170] (451s left) prime_vgem (basic-fence-read)
[712.476119] Starting subtest: basic-fence-read
[712.969097] Subtest basic-fence-read: SUCCESS (0.450s)
[713.339619] [142/170] (450s left) prime_vgem (basic-gtt)
[714.366589] Starting subtest: basic-gtt
[714.536908] Subtest basic-gtt: SUCCESS (0.125s)
[714.951207] [143/170] (448s left) prime_vgem (basic-read)
[715.943162] Starting subtest: basic-read
[716.157297] Subtest basic-read: SUCCESS (0.210s)
[716.619937] [144/170] (447s left) prime_vgem (basic-write)
[717.637605] Starting subtest: basic-write
[717.857497] Subtest basic-write: SUCCESS (0.218s)
[718.301591] [145/170] (446s left) vgem_basic (setversion)
[719.292750] Starting subtest: setversion
[719.306971] Subtest setversion: SUCCESS (0.000s)
[719.656626] [146/170] (444s left) vgem_basic (create)
[720.617857] Starting subtest: create
[720.624961] Subtest create: SUCCESS (0.003s)
[721.006881] [147/170] (443s left) vgem_basic (debugfs)
[721.975709] Starting subtest: debugfs
[721.982635] Subtest debugfs: SUCCESS (0.004s)
[722.365922] [148/170] (442s left) vgem_basic (dmabuf-export)
[723.319199] Starting subtest: dmabuf-export
[723.327603] Subtest dmabuf-export: SUCCESS (0.014s)
[723.740560] [149/170] (441s left) vgem_basic (dmabuf-fence)
[724.693806] Starting subtest: dmabuf-fence
[724.701672] Subtest dmabuf-fence: SUCCESS (0.003s)
[725.061755] [150/170] (440s left) vgem_basic (dmabuf-fence-before)
[726.024707] Starting subtest: dmabuf-fence-before
[726.033591] Subtest dmabuf-fence-before: SUCCESS (0.003s)
[726.387336] [151/170] (439s left) vgem_basic (dmabuf-mmap)
[727.357691] Starting subtest: dmabuf-mmap
[727.564191] Subtest dmabuf-mmap: SUCCESS (0.137s)
[727.887889] [152/170] (438s left) vgem_basic (mmap)
[728.882722] Starting subtest: mmap
[729.045094] Subtest mmap: SUCCESS (0.098s)
[729.403539] [153/170] (437s left) vgem_basic (second-client)
[730.426754] Starting subtest: second-client
[730.528252] Subtest second-client: SUCCESS (0.090s)
[730.931198] [154/170] (435s left) vgem_basic (sysfs)
[731.914894] Starting subtest: sysfs
[731.922858] Subtest sysfs: SUCCESS (0.005s)
[732.275398] [155/170] (434s left) core_hotunplug (unbind-rebind)
[733.623306] Starting subtest: unbind-rebind
[741.135581] Subtest unbind-rebind: SUCCESS (7.451s)
[741.511277] [156/170] (425s left) vgem_basic (unload)
[742.759750] Starting subtest: unload
[772.844837] Subtest unload: SUCCESS (30.036s)
[773.482971] [157/170] (394s left) i915_module_load (reload)
[774.484359] Starting subtest: reload
[786.703293] Subtest reload: SUCCESS (12.220s)
[787.204168] [158/170] (380s left) gem_lmem_swapping (basic)
[788.797641] Subtest basic: SKIP (0.000s)
[789.005589] [159/170] (379s left) gem_lmem_swapping (parallel-random-engines)
[789.988610] Subtest parallel-random-engines: SKIP (0.000s)
[790.307872] [160/170] (378s left) gem_lmem_swapping (random-engines)
[791.277857] Subtest random-engines: SKIP (0.000s)
[791.684676] [161/170] (377s left) gem_lmem_swapping (verify-random)
[792.690931] Subtest verify-random: SKIP (0.000s)
[793.040794] [162/170] (376s left) i915_pm_rpm (module-reload)
[797.900760] Starting subtest: module-reload
[830.850730] Subtest module-reload: SUCCESS (32.896s)
[830.972731] [163/170] (338s left) i915_selftest (live)
[832.032396] Starting subtest: live
[832.038449] Starting dynamic subtest: sanitycheck
[837.882620] Dynamic subtest sanitycheck: SUCCESS (5.831s)
[838.011945] Starting dynamic subtest: uncore
[843.439910] Dynamic subtest uncore: SUCCESS (5.554s)
[843.569931] Starting dynamic subtest: workarounds
[849.872655] Dynamic subtest workarounds: SUCCESS (6.424s)
[850.003997] Starting dynamic subtest: gt_engines
[856.115707] Dynamic subtest gt_engines: SUCCESS (6.230s)
[856.251547] Starting dynamic subtest: gt_timelines
[923.287592] Dynamic subtest gt_timelines: SUCCESS (67.163s)
[923.420922] Starting dynamic subtest: gt_contexts
[929.442501] Dynamic subtest gt_contexts: SUCCESS (6.178s)
[929.511226] Starting dynamic subtest: gt_lrc
[940.000688] Dynamic subtest gt_lrc: SUCCESS (10.518s)
[940.129616] Starting dynamic subtest: gt_mocs
[945.985456] Dynamic subtest gt_mocs: SUCCESS (5.990s)
[946.041450] Starting dynamic subtest: gt_pm
[956.973555] Dynamic subtest gt_pm: FAIL (9.290s)
[957.032989] Starting dynamic subtest: gt_heartbeat
[961.457994] Dynamic subtest gt_heartbeat: SUCCESS (6.141s)
[961.592877] Starting dynamic subtest: gt_tlb
[968.705859] Dynamic subtest gt_tlb: SUCCESS (7.250s)
[968.834804] Starting dynamic subtest: requests
[981.329119] Dynamic subtest requests: SUCCESS (12.613s)
[981.455991] Starting dynamic subtest: migrate
[992.950446] Dynamic subtest migrate: SUCCESS (11.596s)
[993.078875] Starting dynamic subtest: active
[998.714931] Dynamic subtest active: SUCCESS (5.772s)
[998.842751] Starting dynamic subtest: objects
[1014.405759] Dynamic subtest objects: SUCCESS (15.680s)
[1014.545626] Starting dynamic subtest: mman
[1036.823823] Dynamic subtest mman: SUCCESS (22.410s)
[1036.951817] Starting dynamic subtest: dmabuf
[1042.573821] Dynamic subtest dmabuf: SUCCESS (5.759s)
[1042.630800] Starting dynamic subtest: vma
[1048.358656] Dynamic subtest vma: SUCCESS (5.760s)
[1048.491677] Starting dynamic subtest: coherency
[1064.188270] Dynamic subtest coherency: SUCCESS (15.815s)
[1064.295211] Starting dynamic subtest: gtt
[1077.487811] Dynamic subtest gtt: SUCCESS (13.297s)
[1077.546128] Starting dynamic subtest: gem
[1084.650905] Dynamic subtest gem: SUCCESS (7.151s)
[1084.780583] Starting dynamic subtest: evict
[1102.809732] Dynamic subtest evict: SUCCESS (18.166s)
[1102.867205] Starting dynamic subtest: hugepages
[1114.230585] Dynamic subtest hugepages: SUCCESS (11.403s)
[1114.287613] Starting dynamic subtest: gem_contexts
[1164.539960] Dynamic subtest gem_contexts: SUCCESS (50.311s)
[1164.613142] Starting dynamic subtest: client
[1170.568986] Dynamic subtest client: SUCCESS (6.010s)
[1170.705659] Starting dynamic subtest: gem_migrate
[1176.190787] Dynamic subtest gem_migrate: SUCCESS (5.610s)
[1176.320184] Starting dynamic subtest: reset
[1186.742409] Dynamic subtest reset: SUCCESS (10.544s)
[1186.873339] Starting dynamic subtest: memory_region
[1192.508735] Dynamic subtest memory_region: SUCCESS (5.765s)
[1192.623111] Starting dynamic subtest: hangcheck
[1221.782901] Dynamic subtest hangcheck: SUCCESS (29.256s)
[1221.839621] Starting dynamic subtest: execlists
[1243.536738] Dynamic subtest execlists: SUCCESS (21.765s)
[1243.594702] Starting dynamic subtest: ring_submission
[1249.303227] Dynamic subtest ring_submission: SUCCESS (5.746s)
[1249.436610] Starting dynamic subtest: perf
[1255.443180] Dynamic subtest perf: SUCCESS (6.103s)
[1255.575966] Starting dynamic subtest: slpc
[1260.946700] Dynamic subtest slpc: SUCCESS (5.535s)
[1261.004779] Starting dynamic subtest: guc
[1266.563436] Dynamic subtest guc: SUCCESS (5.593s)
[1266.698769] Starting dynamic subtest: guc_multi_lrc
[1272.166157] Dynamic subtest guc_multi_lrc: SUCCESS (5.580s)
[1272.296771] Starting dynamic subtest: guc_hang
[1277.779631] Dynamic subtest guc_hang: SUCCESS (5.620s)
[1277.913763] Starting dynamic subtest: late_gt_pm
[1283.683960] Dynamic subtest late_gt_pm: SUCCESS (5.950s)
[1283.691863] Subtest live: FAIL (451.697s)
[1284.126196] Overall timeout time exceeded, stopping.
[1284.128317] Closing watchdogs
Done.