[175.280675] Initializing watchdogs
[175.281595]   /dev/watchdog0
[175.354159] [001/165] (960s left) i915_module_load (load)
[177.891946] Starting subtest: load
[187.308166] Subtest load: SUCCESS (9.321s)
[187.622862] [002/165] (948s left) core_auth (basic-auth)
[188.579593] Starting subtest: basic-auth
[188.589837] Subtest basic-auth: SUCCESS (0.007s)
[188.969473] [003/165] (947s left) debugfs_test (read_all_entries)
[189.739117] Starting subtest: read_all_entries
[190.274146] Subtest read_all_entries: SUCCESS (0.570s)
[191.252247] [004/165] (945s left) debugfs_test (basic-hwmon)
[191.939799] Starting subtest: basic-hwmon
[191.964270] Subtest basic-hwmon: SKIP (0.000s)
[192.951356] [005/165] (943s left) fbdev (eof)
[193.627717] Starting subtest: eof
[193.637696] Subtest eof: SUCCESS (0.007s)
[193.965879] [006/165] (943s left) fbdev (info)
[194.587898] Starting subtest: info
[194.600528] Subtest info: SUCCESS (0.000s)
[194.960388] [007/165] (942s left) fbdev (nullptr)
[195.588413] Starting subtest: nullptr
[195.595334] Subtest nullptr: SUCCESS (0.000s)
[195.931411] [008/165] (941s left) fbdev (read)
[196.679404] Starting subtest: read
[199.169777] Subtest read: SUCCESS (2.416s)
[199.380236] [009/165] (938s left) fbdev (write)
[200.033427] Starting subtest: write
[201.397755] Subtest write: SUCCESS (1.298s)
[201.638272] [010/165] (936s left) gem_basic (bad-close)
[202.268217] Starting subtest: bad-close
[202.284440] Subtest bad-close: SUCCESS (0.000s)
[202.510952] [011/165] (935s left) gem_basic (create-close)
[203.147736] Starting subtest: create-close
[203.164395] Subtest create-close: SUCCESS (0.001s)
[203.406250] [012/165] (934s left) gem_basic (create-fd-close)
[204.043416] Starting subtest: create-fd-close
[204.058744] Subtest create-fd-close: SUCCESS (0.001s)
[204.287401] [013/165] (934s left) gem_busy (busy@all-engines)
[205.172208] Starting subtest: busy
[205.181782] Starting dynamic subtest: all-engines
[205.592919] Dynamic subtest all-engines: SUCCESS (0.458s)
[205.606517] Subtest busy: SUCCESS (0.460s)
[206.392403] [014/165] (932s left) gem_close_race (basic-process)
[207.264675] Starting subtest: basic-process
[207.598175] Subtest basic-process: SUCCESS (0.353s)
[208.049698] [015/165] (930s left) gem_close_race (basic-threads)
[208.873271] Starting subtest: basic-threads
[210.525696] Subtest basic-threads: SUCCESS (1.665s)
[210.882712] [016/165] (928s left) gem_ctx_create (basic)
[211.661869] Starting subtest: basic
[211.669260] Subtest basic: SUCCESS (0.000s)
[212.073279] [017/165] (927s left) gem_ctx_create (basic-files)
[212.884821] Starting subtest: basic-files
[215.091936] Subtest basic-files: SUCCESS (2.188s)
[215.491138] [018/165] (923s left) gem_ctx_exec (basic)
[216.139725] Starting subtest: basic
[216.166930] Subtest basic: SUCCESS (0.035s)
[216.434995] [019/165] (923s left) gem_exec_basic (basic)
[217.209723] Starting subtest: basic
[217.216823] Starting dynamic subtest: rcs0-smem
[217.223449] Dynamic subtest rcs0-smem: SUCCESS (0.020s)
[217.279450] Starting dynamic subtest: bcs0-smem
[217.291497] Dynamic subtest bcs0-smem: SUCCESS (0.011s)
[217.368337] Starting dynamic subtest: vcs0-smem
[217.382148] Dynamic subtest vcs0-smem: SUCCESS (0.005s)
[217.461112] Starting dynamic subtest: vecs0-smem
[217.471303] Dynamic subtest vecs0-smem: SUCCESS (0.004s)
[217.479791] Subtest basic: SUCCESS (0.050s)
[217.692981] [020/165] (922s left) gem_exec_create (basic)
[218.536714] Starting subtest: basic
[218.546628] Starting dynamic subtest: smem
[220.708374] Dynamic subtest smem: SUCCESS (2.238s)
[220.714933] Subtest basic: SUCCESS (2.241s)
[221.242134] [021/165] (918s left) gem_exec_fence (basic-busy)
[222.281825] Starting subtest: basic-busy
[222.290794] Starting dynamic subtest: rcs0
[222.312846] Dynamic subtest rcs0: SUCCESS (0.075s)
[222.386502] Starting dynamic subtest: bcs0
[222.399925] Dynamic subtest bcs0: SUCCESS (0.067s)
[222.463745] Starting dynamic subtest: vcs0
[222.480282] Dynamic subtest vcs0: SUCCESS (0.049s)
[222.534635] Starting dynamic subtest: vecs0
[222.548161] Dynamic subtest vecs0: SUCCESS (0.048s)
[222.555160] Subtest basic-busy: SUCCESS (0.248s)
[224.670273] [022/165] (915s left) gem_exec_fence (basic-wait)
[225.695307] Starting subtest: basic-wait
[225.704766] Starting dynamic subtest: rcs0
[225.714884] Dynamic subtest rcs0: SUCCESS (0.060s)
[225.779969] Starting dynamic subtest: bcs0
[225.791733] Dynamic subtest bcs0: SUCCESS (0.057s)
[225.918984] Starting dynamic subtest: vcs0
[225.925720] Dynamic subtest vcs0: SUCCESS (0.043s)
[226.045900] Starting dynamic subtest: vecs0
[226.054581] Dynamic subtest vecs0: SUCCESS (0.061s)
[226.061788] Subtest basic-wait: SUCCESS (0.227s)
[227.645582] [023/165] (912s left) gem_exec_fence (basic-await)
[228.654909] Starting subtest: basic-await
[228.662194] Starting dynamic subtest: rcs0
[229.136279] Dynamic subtest rcs0: SUCCESS (0.476s)
[229.201250] Starting dynamic subtest: bcs0
[229.590888] Dynamic subtest bcs0: SUCCESS (0.453s)
[229.647787] Starting dynamic subtest: vcs0
[230.040563] Dynamic subtest vcs0: SUCCESS (0.448s)
[230.099511] Starting dynamic subtest: vecs0
[230.499125] Dynamic subtest vecs0: SUCCESS (0.450s)
[230.506903] Subtest basic-await: SUCCESS (1.839s)
[232.285445] [024/165] (908s left) gem_exec_fence (nb-await)
[233.306365] Starting subtest: nb-await
[233.315709] Starting dynamic subtest: rcs0
[233.483600] Dynamic subtest rcs0: SUCCESS (0.157s)
[233.622770] Starting dynamic subtest: bcs0
[233.635997] Dynamic subtest bcs0: SUCCESS (0.134s)
[233.724162] Starting dynamic subtest: vcs0
[233.731858] Dynamic subtest vcs0: SUCCESS (0.124s)
[233.817330] Starting dynamic subtest: vecs0
[233.824835] Dynamic subtest vecs0: SUCCESS (0.123s)
[233.833825] Subtest nb-await: SUCCESS (0.549s)
[235.670221] [025/165] (905s left) gem_exec_gttfill (basic)
[236.476868] Starting subtest: basic
[243.200696] Subtest basic: SKIP (6.678s)
[243.708356] [026/165] (897s left) gem_exec_parallel (engines)
[244.996708] Starting subtest: engines
[245.002818] Starting dynamic subtest: basic
[256.826555] Dynamic subtest basic: SUCCESS (11.833s)
[256.953292] Starting dynamic subtest: contexts
[277.565920] Dynamic subtest contexts: SUCCESS (20.735s)
[277.689911] Starting dynamic subtest: fds
[315.542722] Dynamic subtest fds: SUCCESS (38.029s)
[315.659650] Starting dynamic subtest: userptr
[327.351803] Dynamic subtest userptr: SUCCESS (11.771s)
[327.359220] Subtest engines: SUCCESS (82.375s)
[327.908429] [027/165] (813s left) gem_exec_store (basic)
[328.806769] Starting subtest: basic
[328.906748] Subtest basic: SUCCESS (0.156s)
[329.396307] [028/165] (812s left) gem_flink_basic (bad-flink)
[330.140147] Starting subtest: bad-flink
[330.148192] Subtest bad-flink: SUCCESS (0.000s)
[330.440214] [029/165] (811s left) gem_flink_basic (bad-open)
[331.096665] Starting subtest: bad-open
[331.104888] Subtest bad-open: SUCCESS (0.000s)
[331.431410] [030/165] (810s left) gem_flink_basic (basic)
[332.079280] Starting subtest: basic
[332.086107] Subtest basic: SUCCESS (0.001s)
[332.421722] [031/165] (809s left) gem_flink_basic (double-flink)
[333.032699] Starting subtest: double-flink
[333.040927] Subtest double-flink: SUCCESS (0.001s)
[333.329223] [032/165] (809s left) gem_flink_basic (flink-lifetime)
[333.961104] Starting subtest: flink-lifetime
[333.969455] Subtest flink-lifetime: SUCCESS (0.012s)
[334.268746] [033/165] (808s left) gem_huc_copy (huc-copy)
[334.980955] Starting subtest: huc-copy
[334.996540] Subtest huc-copy: SKIP (0.000s)
[335.277543] [034/165] (807s left) gem_linear_blits (basic)
[337.152428] Subtest basic: SKIP (0.000s)
[337.423289] [035/165] (805s left) gem_mmap (basic)
[338.738138] Starting subtest: basic
[338.776108] Subtest basic: SUCCESS (0.004s)
[339.076327] [036/165] (804s left) gem_mmap_gtt (basic)
[339.962715] Starting subtest: basic
[339.971377] Subtest basic: SUCCESS (0.006s)
[340.302419] [037/165] (803s left) gem_render_linear_blits (basic)
[341.119703] Starting subtest: basic
[341.461313] Subtest basic: SUCCESS (0.334s)
[341.794131] [038/165] (801s left) gem_render_tiled_blits (basic)
[342.743847] Starting subtest: basic
[343.220314] Subtest basic: SUCCESS (0.469s)
[343.615500] [039/165] (800s left) gem_ringfill (basic-all)
[346.162133] Starting subtest: basic-all
[348.224981] Subtest basic-all: SUCCESS (2.057s)
[348.497811] [040/165] (795s left) gem_softpin (allocator-basic)
[349.297956] Starting subtest: allocator-basic
[350.485919] Subtest allocator-basic: SUCCESS (1.112s)
[350.870200] [041/165] (793s left) gem_softpin (allocator-basic-reserve)
[351.535152] Starting subtest: allocator-basic-reserve
[352.752111] Subtest allocator-basic-reserve: SUCCESS (1.142s)
[353.193237] [042/165] (791s left) gem_softpin (safe-alignment)
[353.870951] Starting subtest: safe-alignment
[354.046679] Subtest safe-alignment: SUCCESS (0.126s)
[354.315119] [043/165] (790s left) gem_sync (basic-all)
[355.106941] Starting subtest: basic-all
[359.562803] Subtest basic-all: SUCCESS (4.462s)
[360.069749] [044/165] (785s left) gem_sync (basic-each)
[360.791135] Starting subtest: basic-each
[364.222652] Subtest basic-each: SUCCESS (3.443s)
[364.787147] [045/165] (780s left) gem_tiled_blits (basic)
[366.683556] Subtest basic: SKIP (0.000s)
[366.899119] [046/165] (778s left) gem_tiled_fence_blits (basic)
[369.061971] Starting subtest: basic
[369.118909] Subtest basic: SUCCESS (0.103s)
[369.563328] [047/165] (776s left) gem_tiled_pread_basic
[372.062550] [048/165] (773s left) gem_wait (busy@all-engines)
[372.998713] Starting subtest: busy
[373.007431] Starting dynamic subtest: all-engines
[373.590097] Dynamic subtest all-engines: SUCCESS (0.631s)
[373.597811] Subtest busy: SUCCESS (0.633s)
[374.273237] [049/165] (771s left) gem_wait (wait@all-engines)
[375.082887] Starting subtest: wait
[375.093833] Starting dynamic subtest: all-engines
[376.222831] Dynamic subtest all-engines: SUCCESS (1.121s)
[376.230635] Subtest wait: SUCCESS (1.124s)
[376.902203] [050/165] (769s left) i915_getparams_basic (basic-eu-total)
[377.608311] Starting subtest: basic-eu-total
[377.623511] Subtest basic-eu-total: SUCCESS (0.000s)
[377.884918] [051/165] (768s left) i915_getparams_basic (basic-subslice-total)
[378.511293] Starting subtest: basic-subslice-total
[378.529590] Subtest basic-subslice-total: SUCCESS (0.001s)
[378.858435] [052/165] (767s left) i915_hangman (error-state-basic)
[379.637869] Starting subtest: error-state-basic
[379.699975] Subtest error-state-basic: SUCCESS (0.050s)
[380.030838] [053/165] (766s left) i915_pciid
[380.998866] [054/165] (766s left) kms_addfb_basic (addfb25-4-tiled)
[382.749881] Starting subtest: addfb25-4-tiled
[382.758527] Subtest addfb25-4-tiled: SUCCESS (0.002s)
[383.114306] [055/165] (764s left) kms_addfb_basic (addfb25-bad-modifier)
[383.767662] Starting subtest: addfb25-bad-modifier
[383.775746] Subtest addfb25-bad-modifier: SUCCESS (0.004s)
[384.771186] [056/165] (762s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[385.419899] Starting subtest: addfb25-framebuffer-vs-set-tiling
[385.427928] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.001s)
[386.380213] [057/165] (761s left) kms_addfb_basic (addfb25-modifier-no-flag)
[387.006563] Starting subtest: addfb25-modifier-no-flag
[387.016139] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[388.062876] [058/165] (760s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[388.727856] Starting subtest: addfb25-x-tiled-legacy
[388.738748] Subtest addfb25-x-tiled-legacy: SUCCESS (0.001s)
[389.729367] [059/165] (758s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[390.407842] Starting subtest: addfb25-x-tiled-mismatch-legacy
[390.416517] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.003s)
[391.399313] [060/165] (757s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[392.682126] Starting subtest: addfb25-yf-tiled-legacy
[392.710398] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[393.066198] [061/165] (755s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[394.353614] Starting subtest: addfb25-y-tiled-legacy
[394.363640] Subtest addfb25-y-tiled-legacy: SUCCESS (0.001s)
[394.708527] [062/165] (754s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[396.010788] Starting subtest: addfb25-y-tiled-small-legacy
[396.017905] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.002s)
[396.353594] [063/165] (753s left) kms_addfb_basic (bad-pitch-0)
[397.019582] Starting subtest: bad-pitch-0
[397.027346] Subtest bad-pitch-0: SUCCESS (0.000s)
[398.020217] [064/165] (751s left) kms_addfb_basic (bad-pitch-1024)
[398.678506] Starting subtest: bad-pitch-1024
[398.687224] Subtest bad-pitch-1024: SUCCESS (0.000s)
[399.722112] [065/165] (750s left) kms_addfb_basic (bad-pitch-128)
[400.382950] Starting subtest: bad-pitch-128
[400.392502] Subtest bad-pitch-128: SUCCESS (0.000s)
[401.371950] [066/165] (748s left) kms_addfb_basic (bad-pitch-256)
[402.021878] Starting subtest: bad-pitch-256
[402.029717] Subtest bad-pitch-256: SUCCESS (0.000s)
[403.020147] [067/165] (747s left) kms_addfb_basic (bad-pitch-32)
[403.665915] Starting subtest: bad-pitch-32
[403.674408] Subtest bad-pitch-32: SUCCESS (0.000s)
[404.670742] [068/165] (746s left) kms_addfb_basic (bad-pitch-63)
[405.327879] Starting subtest: bad-pitch-63
[405.335664] Subtest bad-pitch-63: SUCCESS (0.000s)
[406.322593] [069/165] (744s left) kms_addfb_basic (bad-pitch-65536)
[406.991127] Starting subtest: bad-pitch-65536
[406.998948] Subtest bad-pitch-65536: SUCCESS (0.002s)
[408.021393] [070/165] (743s left) kms_addfb_basic (bad-pitch-999)
[408.659796] Starting subtest: bad-pitch-999
[408.668265] Subtest bad-pitch-999: SUCCESS (0.000s)
[409.692549] [071/165] (742s left) kms_addfb_basic (basic)
[410.348645] Starting subtest: basic
[410.356961] Subtest basic: SUCCESS (0.001s)
[411.382495] [072/165] (740s left) kms_addfb_basic (basic-x-tiled-legacy)
[412.054619] Starting subtest: basic-x-tiled-legacy
[412.062543] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[413.038249] [073/165] (739s left) kms_addfb_basic (basic-y-tiled-legacy)
[413.714233] Starting subtest: basic-y-tiled-legacy
[413.721481] Subtest basic-y-tiled-legacy: SUCCESS (0.003s)
[414.705131] [074/165] (737s left) kms_addfb_basic (bo-too-small)
[415.980149] Starting subtest: bo-too-small
[415.990692] Subtest bo-too-small: SUCCESS (0.002s)
[416.336993] [075/165] (736s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[417.638139] Starting subtest: bo-too-small-due-to-tiling
[417.645174] Subtest bo-too-small-due-to-tiling: SUCCESS (0.003s)
[417.998789] [076/165] (735s left) kms_addfb_basic (clobberred-modifier)
[418.614257] Starting subtest: clobberred-modifier
[418.623505] Subtest clobberred-modifier: SUCCESS (0.001s)
[419.635279] [077/165] (733s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[420.338765] Starting subtest: framebuffer-vs-set-tiling
[420.349943] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[421.345367] [078/165] (732s left) kms_addfb_basic (invalid-get-prop)
[421.988744] Starting subtest: invalid-get-prop
[421.995629] Subtest invalid-get-prop: SUCCESS (0.000s)
[422.993377] [079/165] (730s left) kms_addfb_basic (invalid-get-prop-any)
[423.649411] Starting subtest: invalid-get-prop-any
[423.658237] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[424.638954] [080/165] (729s left) kms_addfb_basic (invalid-set-prop)
[425.329812] Starting subtest: invalid-set-prop
[425.338562] Subtest invalid-set-prop: SUCCESS (0.000s)
[426.345142] [081/165] (728s left) kms_addfb_basic (invalid-set-prop-any)
[427.033818] Starting subtest: invalid-set-prop-any
[427.043272] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[428.059411] [082/165] (726s left) kms_addfb_basic (no-handle)
[428.729417] Starting subtest: no-handle
[428.737546] Subtest no-handle: SUCCESS (0.000s)
[429.750763] [083/165] (725s left) kms_addfb_basic (size-max)
[431.074332] Starting subtest: size-max
[431.087755] Subtest size-max: SUCCESS (0.001s)
[431.433772] [084/165] (723s left) kms_addfb_basic (small-bo)
[432.750199] Starting subtest: small-bo
[432.769265] Subtest small-bo: SUCCESS (0.000s)
[433.165524] [085/165] (722s left) kms_addfb_basic (tile-pitch-mismatch)
[434.076107] Starting subtest: tile-pitch-mismatch
[434.084188] Subtest tile-pitch-mismatch: SUCCESS (0.003s)
[435.123611] [086/165] (720s left) kms_addfb_basic (too-high)
[436.418311] Starting subtest: too-high
[436.427967] Subtest too-high: SUCCESS (0.007s)
[436.782682] [087/165] (719s left) kms_addfb_basic (too-wide)
[438.130611] Starting subtest: too-wide
[438.140585] Subtest too-wide: SUCCESS (0.000s)
[438.493495] [088/165] (717s left) kms_addfb_basic (unused-handle)
[439.147433] Starting subtest: unused-handle
[439.153808] Subtest unused-handle: SUCCESS (0.000s)
[440.157355] [089/165] (716s left) kms_addfb_basic (unused-modifier)
[440.810403] Starting subtest: unused-modifier
[440.818235] Subtest unused-modifier: SUCCESS (0.001s)
[441.821739] [090/165] (715s left) kms_addfb_basic (unused-offsets)
[442.463515] Starting subtest: unused-offsets
[442.471345] Subtest unused-offsets: SUCCESS (0.000s)
[443.478213] [091/165] (713s left) kms_addfb_basic (unused-pitches)
[444.126154] Starting subtest: unused-pitches
[444.136609] Subtest unused-pitches: SUCCESS (0.000s)
[445.170342] [092/165] (712s left) kms_busy (basic)
[446.574344] Starting subtest: basic
[446.589827] Starting dynamic subtest: flip
[449.714748] Dynamic subtest flip: SUCCESS (3.123s)
[449.886816] Starting dynamic subtest: modeset
[454.612925] Dynamic subtest modeset: SUCCESS (4.898s)
[454.621278] Subtest basic: SUCCESS (8.036s)
[455.870510] [093/165] (702s left) kms_prop_blob (basic)
[456.635386] Starting subtest: basic
[456.643664] Subtest basic: SUCCESS (0.000s)
[457.028202] [094/165] (701s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[458.404833] Starting subtest: basic-busy-flip-before-cursor-atomic
[458.418865] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.001s)
[458.731590] [095/165] (699s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[460.008974] Starting subtest: basic-busy-flip-before-cursor-legacy
[460.023825] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.001s)
[460.341732] [096/165] (698s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[461.622686] Starting subtest: basic-flip-after-cursor-atomic
[463.385936] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.766s)
[464.568515] [097/165] (694s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[465.880644] Starting subtest: basic-flip-after-cursor-legacy
[467.756401] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.869s)
[468.929825] [098/165] (690s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[470.215295] Starting subtest: basic-flip-after-cursor-varying-size
[471.961611] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.745s)
[473.176392] [099/165] (686s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[474.445353] Starting subtest: basic-flip-before-cursor-atomic
[476.193945] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.754s)
[477.406966] [100/165] (682s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[478.705845] Starting subtest: basic-flip-before-cursor-legacy
[480.539148] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.840s)
[481.721227] [101/165] (678s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[483.011999] Starting subtest: basic-flip-before-cursor-varying-size
[484.774265] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.755s)
[485.945123] [102/165] (674s left) kms_dsc (dsc-basic)
[487.331928] Subtest dsc-basic: SKIP (0.000s)
[487.612970] [103/165] (672s left) kms_flip (basic-flip-vs-dpms)
[488.635474] Starting subtest: basic-flip-vs-dpms
[488.677528] Starting dynamic subtest: A-eDP1
[492.293507] Dynamic subtest A-eDP1: SUCCESS (3.603s)
[492.423603] Starting dynamic subtest: B-eDP1
[495.069845] Dynamic subtest B-eDP1: SUCCESS (2.781s)
[495.232271] Starting dynamic subtest: C-eDP1
[497.903234] Dynamic subtest C-eDP1: SUCCESS (2.823s)
[497.950100] Subtest basic-flip-vs-dpms: SUCCESS (9.323s)
[499.066444] [104/165] (661s left) kms_flip (basic-flip-vs-modeset)
[500.018151] Starting subtest: basic-flip-vs-modeset
[500.056743] Starting dynamic subtest: A-eDP1
[503.694313] Dynamic subtest A-eDP1: SUCCESS (3.632s)
[503.847829] Starting dynamic subtest: B-eDP1
[506.383955] Dynamic subtest B-eDP1: SUCCESS (2.666s)
[506.544720] Starting dynamic subtest: C-eDP1
[509.010715] Dynamic subtest C-eDP1: SUCCESS (2.636s)
[509.068305] Subtest basic-flip-vs-modeset: SUCCESS (9.064s)
[510.191915] [105/165] (650s left) kms_flip (basic-flip-vs-wf_vblank)
[511.145140] Starting subtest: basic-flip-vs-wf_vblank
[511.179642] Starting dynamic subtest: A-eDP1
[514.062739] Dynamic subtest A-eDP1: SUCCESS (2.829s)
[514.182763] Starting dynamic subtest: B-eDP1
[516.624134] Dynamic subtest B-eDP1: SUCCESS (2.568s)
[516.743151] Starting dynamic subtest: C-eDP1
[519.189909] Dynamic subtest C-eDP1: SUCCESS (2.542s)
[519.198154] Subtest basic-flip-vs-wf_vblank: SUCCESS (8.064s)
[521.195547] [106/165] (640s left) kms_flip (basic-plain-flip)
[522.118725] Starting subtest: basic-plain-flip
[522.171850] Starting dynamic subtest: A-eDP1
[524.805351] Dynamic subtest A-eDP1: SUCCESS (2.567s)
[524.923801] Starting dynamic subtest: B-eDP1
[527.171834] Dynamic subtest B-eDP1: SUCCESS (2.307s)
[527.308650] Starting dynamic subtest: C-eDP1
[529.525934] Dynamic subtest C-eDP1: SUCCESS (2.400s)
[529.533978] Subtest basic-plain-flip: SUCCESS (7.422s)
[531.496416] [107/165] (630s left) kms_force_connector_basic (force-connector-state)
[532.846745] Starting subtest: force-connector-state
[534.884576] Subtest force-connector-state: SUCCESS (2.000s)
[537.509230] [108/165] (624s left) kms_force_connector_basic (force-edid)
[538.822606] Starting subtest: force-edid
[539.429736] Subtest force-edid: SUCCESS (0.603s)
[541.195140] [109/165] (621s left) kms_force_connector_basic (force-load-detect)
[542.436139] Starting subtest: force-load-detect
[542.454151] Subtest force-load-detect: SKIP (0.000s)
[543.937991] [110/165] (618s left) kms_force_connector_basic (prune-stale-modes)
[545.209594] Starting subtest: prune-stale-modes
[545.804374] Subtest prune-stale-modes: SUCCESS (0.577s)
[547.601216] [111/165] (615s left) kms_frontbuffer_tracking (basic)
[549.043648] Starting subtest: basic
[558.013987] Subtest basic: SUCCESS (8.956s)
[558.469547] [112/165] (604s left) kms_hdmi_inject (inject-audio)
[559.635198] Starting subtest: inject-audio
[560.615102] Subtest inject-audio: SUCCESS (0.982s)
[561.966354] [113/165] (601s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[563.388833] Starting subtest: compare-crc-sanitycheck-xr24
[563.426645] Starting dynamic subtest: pipe-A-eDP-1
[564.541584] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.138s)
[564.738310] Starting dynamic subtest: pipe-B-eDP-1
[566.375330] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.795s)
[566.561974] Starting dynamic subtest: pipe-C-eDP-1
[568.215857] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.788s)
[568.228160] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.803s)
[569.443574] [114/165] (594s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[570.772973] Starting subtest: compare-crc-sanitycheck-nv12
[570.791739] Starting dynamic subtest: pipe-A-eDP-1
[572.622131] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.803s)
[572.803133] Starting dynamic subtest: pipe-B-eDP-1
[574.842854] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.204s)
[574.984875] Starting dynamic subtest: pipe-C-eDP-1
[575.008135] Dynamic subtest pipe-C-eDP-1: SKIP (0.007s)
[575.016101] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.098s)
[576.024978] [115/165] (587s left) kms_pipe_crc_basic (hang-read-crc)
[577.338740] Starting subtest: hang-read-crc
[577.362987] Starting dynamic subtest: pipe-A-eDP-1
[580.647805] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.289s)
[580.887159] Starting dynamic subtest: pipe-B-eDP-1
[584.698201] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.023s)
[584.880416] Starting dynamic subtest: pipe-C-eDP-1
[588.934909] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.207s)
[588.947781] Subtest hang-read-crc: SUCCESS (11.604s)
[590.113375] [116/165] (574s left) kms_pipe_crc_basic (nonblocking-crc)
[591.410500] Starting subtest: nonblocking-crc
[591.423768] Starting dynamic subtest: pipe-A-eDP-1
[592.756721] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.340s)
[592.931260] Starting dynamic subtest: pipe-B-eDP-1
[594.845770] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.064s)
[595.091820] Starting dynamic subtest: pipe-C-eDP-1
[596.914232] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.032s)
[596.923829] Subtest nonblocking-crc: SUCCESS (5.513s)
[598.114631] [117/165] (566s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[599.475907] Starting subtest: nonblocking-crc-frame-sequence
[599.502895] Starting dynamic subtest: pipe-A-eDP-1
[600.846672] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.347s)
[601.013790] Starting dynamic subtest: pipe-B-eDP-1
[602.921851] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.031s)
[603.173377] Starting dynamic subtest: pipe-C-eDP-1
[604.959209] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.026s)
[604.971388] Subtest nonblocking-crc-frame-sequence: SUCCESS (5.492s)
[606.183312] [118/165] (558s left) kms_pipe_crc_basic (read-crc)
[607.503826] Starting subtest: read-crc
[607.525484] Starting dynamic subtest: pipe-A-eDP-1
[608.821748] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.289s)
[609.012340] Starting dynamic subtest: pipe-B-eDP-1
[610.795876] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.942s)
[610.987938] Starting dynamic subtest: pipe-C-eDP-1
[612.829665] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.001s)
[612.846380] Subtest read-crc: SUCCESS (5.329s)
[614.066311] [119/165] (551s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[615.462367] Starting subtest: read-crc-frame-sequence
[615.479263] Starting dynamic subtest: pipe-A-eDP-1
[616.776742] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.291s)
[616.956573] Starting dynamic subtest: pipe-B-eDP-1
[618.791662] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.007s)
[618.980796] Starting dynamic subtest: pipe-C-eDP-1
[620.710785] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.884s)
[620.723820] Subtest read-crc-frame-sequence: SUCCESS (5.274s)
[621.926703] [120/165] (543s left) kms_pm_backlight (basic-brightness)
[623.359272] Starting subtest: basic-brightness
[623.947491] Starting dynamic subtest: eDP-1
[624.724267] Dynamic subtest eDP-1: SUCCESS (0.869s)
[624.738839] Subtest basic-brightness: SUCCESS (1.362s)
[625.967349] [121/165] (539s left) kms_pm_rpm (basic-pci-d3-state)
[629.693383] Starting subtest: basic-pci-d3-state
[634.239899] Subtest basic-pci-d3-state: SUCCESS (4.544s)
[634.893776] [122/165] (531s left) kms_pm_rpm (basic-rte)
[635.582164] Starting subtest: basic-rte
[643.138234] Subtest basic-rte: SUCCESS (7.551s)
[649.177365] [123/165] (517s left) kms_psr (primary_page_flip)
[650.573428] Starting subtest: primary_page_flip
[652.932073] Subtest primary_page_flip: SUCCESS (2.340s)
[654.112896] [124/165] (512s left) kms_psr (cursor_plane_move)
[655.416190] Starting subtest: cursor_plane_move
[657.730539] Subtest cursor_plane_move: SUCCESS (2.313s)
[658.923299] [125/165] (507s left) kms_psr (sprite_plane_onoff)
[660.227689] Starting subtest: sprite_plane_onoff
[662.633938] Subtest sprite_plane_onoff: SUCCESS (2.393s)
[663.861835] [126/165] (503s left) kms_psr (primary_mmap_gtt)
[665.181912] Starting subtest: primary_mmap_gtt
[667.502814] Subtest primary_mmap_gtt: SUCCESS (2.297s)
[668.702165] [127/165] (498s left) kms_setmode (basic-clone-single-crtc)
[669.432624] Starting subtest: basic-clone-single-crtc
[669.455868] Subtest basic-clone-single-crtc: SKIP (0.006s)
[669.778502] [128/165] (497s left) i915_pm_rps (basic-api)
[670.616840] Starting subtest: basic-api
[670.637830] Subtest basic-api: SUCCESS (0.034s)
[670.967964] [129/165] (497s left) prime_self_import (basic-llseek-bad)
[671.653526] Starting subtest: basic-llseek-bad
[671.670774] Subtest basic-llseek-bad: SUCCESS (0.016s)
[672.006175] [130/165] (496s left) prime_self_import (basic-llseek-size)
[672.615414] Starting subtest: basic-llseek-size
[672.652595] Subtest basic-llseek-size: SUCCESS (0.034s)
[672.966453] [131/165] (495s left) prime_self_import (basic-with_fd_dup)
[673.602719] Starting subtest: basic-with_fd_dup
[673.618918] Subtest basic-with_fd_dup: SUCCESS (0.025s)
[673.936254] [132/165] (494s left) prime_self_import (basic-with_one_bo)
[674.579826] Starting subtest: basic-with_one_bo
[674.598783] Subtest basic-with_one_bo: SUCCESS (0.027s)
[675.007170] [133/165] (493s left) prime_self_import (basic-with_one_bo_two_files)
[675.631762] Starting subtest: basic-with_one_bo_two_files
[675.667578] Subtest basic-with_one_bo_two_files: SUCCESS (0.021s)
[676.039751] [134/165] (493s left) prime_self_import (basic-with_two_bos)
[676.665117] Starting subtest: basic-with_two_bos
[676.702689] Subtest basic-with_two_bos: SUCCESS (0.024s)
[677.073072] [135/165] (492s left) prime_vgem (basic-fence-flip)
[677.886897] Starting subtest: basic-fence-flip
[679.658966] Subtest basic-fence-flip: SUCCESS (1.725s)
[680.140941] [136/165] (489s left) prime_vgem (basic-fence-mmap)
[680.855330] Starting subtest: basic-fence-mmap
[681.167721] Subtest basic-fence-mmap: SUCCESS (0.273s)
[681.506975] [137/165] (488s left) prime_vgem (basic-fence-read)
[682.211642] Starting subtest: basic-fence-read
[682.688815] Subtest basic-fence-read: SUCCESS (0.430s)
[683.015655] [138/165] (487s left) prime_vgem (basic-gtt)
[683.762482] Starting subtest: basic-gtt
[683.941880] Subtest basic-gtt: SUCCESS (0.132s)
[684.216386] [139/165] (486s left) prime_vgem (basic-read)
[684.967504] Starting subtest: basic-read
[685.267838] Subtest basic-read: SUCCESS (0.222s)
[685.573398] [140/165] (485s left) prime_vgem (basic-write)
[686.298792] Starting subtest: basic-write
[686.603137] Subtest basic-write: SUCCESS (0.251s)
[686.931996] [141/165] (484s left) vgem_basic (setversion)
[687.764845] Starting subtest: setversion
[687.782417] Subtest setversion: SUCCESS (0.000s)
[688.142123] [142/165] (483s left) vgem_basic (create)
[688.791238] Starting subtest: create
[688.799156] Subtest create: SUCCESS (0.003s)
[689.100212] [143/165] (482s left) vgem_basic (debugfs)
[689.768417] Starting subtest: debugfs
[689.775710] Subtest debugfs: SUCCESS (0.004s)
[690.105747] [144/165] (481s left) vgem_basic (dmabuf-export)
[690.790701] Starting subtest: dmabuf-export
[690.808457] Subtest dmabuf-export: SUCCESS (0.018s)
[691.120636] [145/165] (480s left) vgem_basic (dmabuf-fence)
[691.758920] Starting subtest: dmabuf-fence
[691.768873] Subtest dmabuf-fence: SUCCESS (0.005s)
[692.078816] [146/165] (480s left) vgem_basic (dmabuf-fence-before)
[692.738091] Starting subtest: dmabuf-fence-before
[692.748301] Subtest dmabuf-fence-before: SUCCESS (0.003s)
[693.035649] [147/165] (479s left) vgem_basic (dmabuf-mmap)
[693.693437] Starting subtest: dmabuf-mmap
[693.886169] Subtest dmabuf-mmap: SUCCESS (0.137s)
[694.135696] [148/165] (478s left) vgem_basic (mmap)
[694.798259] Starting subtest: mmap
[694.950766] Subtest mmap: SUCCESS (0.092s)
[695.193766] [149/165] (477s left) vgem_basic (second-client)
[695.872225] Starting subtest: second-client
[695.905916] Subtest second-client: SUCCESS (0.042s)
[696.196172] [150/165] (476s left) vgem_basic (sysfs)
[696.855824] Starting subtest: sysfs
[696.866710] Subtest sysfs: SUCCESS (0.006s)
[697.163460] [151/165] (476s left) core_hotunplug (unbind-rebind)
[698.223197] Starting subtest: unbind-rebind
[706.775914] Subtest unbind-rebind: SUCCESS (8.559s)
[707.146800] [152/165] (466s left) vgem_basic (unload)
[707.929579] Starting subtest: unload
[737.867737] Subtest unload: SUCCESS (29.892s)
[738.416878] [153/165] (435s left) i915_module_load (reload)
[739.154545] Starting subtest: reload
[750.747676] Subtest reload: SUCCESS (11.506s)
[751.066728] [154/165] (423s left) gem_lmem_swapping (basic)
[752.593709] Subtest basic: SKIP (0.000s)
[752.880922] [155/165] (421s left) gem_lmem_swapping (parallel-random-engines)
[753.564647] Subtest parallel-random-engines: SKIP (0.000s)
[753.863671] [156/165] (420s left) gem_lmem_swapping (random-engines)
[754.544705] Subtest random-engines: SKIP (0.000s)
[754.889590] [157/165] (420s left) gem_lmem_swapping (verify-random)
[755.667669] Subtest verify-random: SKIP (0.000s)
[756.031501] [158/165] (419s left) i915_pm_rpm (module-reload)
[760.567252] Starting subtest: module-reload
[785.981837] Subtest module-reload: SUCCESS (25.392s)
[786.107396] [159/165] (389s left) i915_selftest (live)
[786.989161] Starting subtest: live
[786.996699] Starting dynamic subtest: sanitycheck
[792.583160] Dynamic subtest sanitycheck: SUCCESS (5.588s)
[792.639930] Starting dynamic subtest: uncore
[798.127774] Dynamic subtest uncore: SUCCESS (5.497s)
[798.214660] Starting dynamic subtest: workarounds
[804.634662] Dynamic subtest workarounds: SUCCESS (6.505s)
[804.693088] Starting dynamic subtest: gt_engines
[810.641119] Dynamic subtest gt_engines: SUCCESS (5.986s)
[810.699840] Starting dynamic subtest: gt_timelines
[869.946813] Dynamic subtest gt_timelines: SUCCESS (59.296s)
[870.004683] Starting dynamic subtest: gt_contexts
[875.794344] Dynamic subtest gt_contexts: SUCCESS (5.818s)
[875.852390] Starting dynamic subtest: gt_lrc
[886.068420] Dynamic subtest gt_lrc: SUCCESS (10.280s)
[886.127644] Starting dynamic subtest: gt_mocs
[891.828869] Dynamic subtest gt_mocs: SUCCESS (5.748s)
[891.888995] Starting dynamic subtest: gt_pm
[902.220684] Dynamic subtest gt_pm: FAIL (8.778s)
[902.277244] Starting dynamic subtest: gt_heartbeat
[906.666964] Dynamic subtest gt_heartbeat: SUCCESS (6.029s)
[906.781928] Starting dynamic subtest: gt_tlb
[913.668962] Dynamic subtest gt_tlb: SUCCESS (6.982s)
[913.727605] Starting dynamic subtest: requests
[926.140179] Dynamic subtest requests: SUCCESS (12.476s)
[926.204846] Starting dynamic subtest: migrate
[937.472884] Dynamic subtest migrate: SUCCESS (11.296s)
[937.531831] Starting dynamic subtest: active
[943.095905] Dynamic subtest active: SUCCESS (5.607s)
[943.154659] Starting dynamic subtest: objects
[957.789313] Dynamic subtest objects: SUCCESS (14.729s)
[957.874146] Starting dynamic subtest: mman
[981.504817] Dynamic subtest mman: SUCCESS (23.641s)
[981.570614] Starting dynamic subtest: dmabuf
[987.079703] Dynamic subtest dmabuf: SUCCESS (5.566s)
[987.137808] Starting dynamic subtest: vma
[992.706165] Dynamic subtest vma: SUCCESS (5.616s)
[992.765452] Starting dynamic subtest: coherency
[1010.989406] Dynamic subtest coherency: SUCCESS (18.269s)
[1011.048888] Starting dynamic subtest: gtt
[1025.050753] Dynamic subtest gtt: SUCCESS (14.019s)
[1025.168653] Starting dynamic subtest: gem
[1032.060417] Dynamic subtest gem: SUCCESS (7.008s)
[1032.120564] Starting dynamic subtest: evict
[1050.288834] Dynamic subtest evict: SUCCESS (18.272s)
[1050.374637] Starting dynamic subtest: hugepages
[1062.511308] Dynamic subtest hugepages: SUCCESS (12.157s)
[1062.570862] Starting dynamic subtest: gem_contexts
[1114.509818] Dynamic subtest gem_contexts: SUCCESS (52.024s)
[1114.583663] Starting dynamic subtest: client
[1120.359473] Dynamic subtest client: SUCCESS (5.791s)
[1120.421168] Starting dynamic subtest: gem_migrate
[1125.847913] Dynamic subtest gem_migrate: SUCCESS (5.474s)
[1125.908560] Starting dynamic subtest: reset
[1136.409644] Dynamic subtest reset: SUCCESS (10.538s)
[1136.525691] Starting dynamic subtest: memory_region
[1141.903152] Dynamic subtest memory_region: SUCCESS (5.487s)
[1141.962955] Starting dynamic subtest: hangcheck
[1170.777541] Dynamic subtest hangcheck: SUCCESS (28.839s)
[1170.869115] Starting dynamic subtest: execlists
[1192.424718] Dynamic subtest execlists: SUCCESS (21.643s)
[1192.538748] Starting dynamic subtest: ring_submission
[1197.973997] Dynamic subtest ring_submission: SUCCESS (5.538s)
[1198.038772] Starting dynamic subtest: perf
[1203.765768] Dynamic subtest perf: SUCCESS (5.768s)
[1203.831401] Starting dynamic subtest: slpc
[1209.239608] Dynamic subtest slpc: SUCCESS (5.468s)
[1209.297739] Starting dynamic subtest: guc
[1214.792587] Dynamic subtest guc: SUCCESS (5.530s)
[1214.852342] Starting dynamic subtest: guc_multi_lrc
[1220.287398] Dynamic subtest guc_multi_lrc: SUCCESS (5.489s)
[1220.346662] Starting dynamic subtest: guc_hang
[1225.752699] Dynamic subtest guc_hang: SUCCESS (5.437s)
[1225.812715] Starting dynamic subtest: late_gt_pm
[1233.263904] Dynamic subtest late_gt_pm: SUCCESS (7.499s)
[1233.272901] Subtest live: FAIL (446.282s)
[1233.469519] Overall timeout time exceeded, stopping.
[1233.472396] Closing watchdogs
Done.