[103.500769] Initializing watchdogs
[103.501783]   /dev/watchdog0
[103.549980] [001/165] (960s left) i915_module_load (load)
[104.297268] Starting subtest: load
[112.764500] Subtest load: SUCCESS (8.301s)
[112.979823] [002/165] (951s left) core_auth (basic-auth)
[114.251756] Starting subtest: basic-auth
[114.263413] Subtest basic-auth: SUCCESS (0.007s)
[114.600381] [003/165] (949s left) debugfs_test (read_all_entries)
[115.428632] Starting subtest: read_all_entries
[116.025880] Subtest read_all_entries: SUCCESS (0.595s)
[116.937123] [004/165] (947s left) debugfs_test (basic-hwmon)
[117.623503] Starting subtest: basic-hwmon
[117.642133] Subtest basic-hwmon: SKIP (0.000s)
[118.659188] [005/165] (946s left) fbdev (eof)
[119.489075] Starting subtest: eof
[119.496355] Subtest eof: SUCCESS (0.008s)
[119.741591] [006/165] (945s left) fbdev (info)
[120.347652] Starting subtest: info
[120.356606] Subtest info: SUCCESS (0.000s)
[120.638340] [007/165] (944s left) fbdev (nullptr)
[121.290332] Starting subtest: nullptr
[121.300556] Subtest nullptr: SUCCESS (0.001s)
[121.615317] [008/165] (943s left) fbdev (read)
[122.268125] Starting subtest: read
[124.970312] Subtest read: SUCCESS (2.614s)
[125.236241] [009/165] (940s left) fbdev (write)
[125.859861] Starting subtest: write
[127.226567] Subtest write: SUCCESS (1.305s)
[127.487127] [010/165] (938s left) gem_basic (bad-close)
[128.162791] Starting subtest: bad-close
[128.177214] Subtest bad-close: SUCCESS (0.000s)
[128.402210] [011/165] (937s left) gem_basic (create-close)
[129.013619] Starting subtest: create-close
[129.028389] Subtest create-close: SUCCESS (0.002s)
[129.335333] [012/165] (936s left) gem_basic (create-fd-close)
[129.925654] Starting subtest: create-fd-close
[129.939628] Subtest create-fd-close: SUCCESS (0.002s)
[130.161731] [013/165] (936s left) gem_busy (busy@all-engines)
[131.008680] Starting subtest: busy
[131.016295] Starting dynamic subtest: all-engines
[131.463988] Dynamic subtest all-engines: SUCCESS (0.457s)
[131.470836] Subtest busy: SUCCESS (0.460s)
[132.158962] [014/165] (934s left) gem_close_race (basic-process)
[133.012850] Starting subtest: basic-process
[133.340970] Subtest basic-process: SUCCESS (0.349s)
[133.697503] [015/165] (932s left) gem_close_race (basic-threads)
[134.527747] Starting subtest: basic-threads
[136.314744] Subtest basic-threads: SUCCESS (1.810s)
[136.666164] [016/165] (930s left) gem_ctx_create (basic)
[137.516375] Starting subtest: basic
[137.523441] Subtest basic: SUCCESS (0.001s)
[137.862213] [017/165] (928s left) gem_ctx_create (basic-files)
[138.640723] Starting subtest: basic-files
[140.823780] Subtest basic-files: SUCCESS (2.186s)
[141.279136] [018/165] (925s left) gem_ctx_exec (basic)
[142.025508] Starting subtest: basic
[142.063329] Subtest basic: SUCCESS (0.035s)
[142.359377] [019/165] (924s left) gem_exec_basic (basic)
[143.166796] Starting subtest: basic
[143.180873] Starting dynamic subtest: rcs0-smem
[143.188903] Dynamic subtest rcs0-smem: SUCCESS (0.014s)
[143.274980] Starting dynamic subtest: bcs0-smem
[143.283177] Dynamic subtest bcs0-smem: SUCCESS (0.008s)
[143.363942] Starting dynamic subtest: vcs0-smem
[143.379961] Dynamic subtest vcs0-smem: SUCCESS (0.006s)
[143.476929] Starting dynamic subtest: vecs0-smem
[143.494852] Dynamic subtest vecs0-smem: SUCCESS (0.006s)
[143.502243] Subtest basic: SUCCESS (0.044s)
[143.644806] [020/165] (923s left) gem_exec_create (basic)
[144.555753] Starting subtest: basic
[144.562540] Starting dynamic subtest: smem
[146.727498] Dynamic subtest smem: SUCCESS (2.237s)
[146.736366] Subtest basic: SUCCESS (2.241s)
[147.161953] [021/165] (920s left) gem_exec_fence (basic-busy)
[148.318296] Starting subtest: basic-busy
[148.325627] Starting dynamic subtest: rcs0
[148.339939] Dynamic subtest rcs0: SUCCESS (0.069s)
[148.476535] Starting dynamic subtest: bcs0
[148.491803] Dynamic subtest bcs0: SUCCESS (0.064s)
[148.545784] Starting dynamic subtest: vcs0
[148.559417] Dynamic subtest vcs0: SUCCESS (0.055s)
[148.615898] Starting dynamic subtest: vecs0
[148.627609] Dynamic subtest vecs0: SUCCESS (0.040s)
[148.635682] Subtest basic-busy: SUCCESS (0.238s)
[150.617398] [022/165] (917s left) gem_exec_fence (basic-wait)
[151.628322] Starting subtest: basic-wait
[151.639528] Starting dynamic subtest: rcs0
[151.646951] Dynamic subtest rcs0: SUCCESS (0.067s)
[151.716798] Starting dynamic subtest: bcs0
[151.724386] Dynamic subtest bcs0: SUCCESS (0.053s)
[151.846190] Starting dynamic subtest: vcs0
[151.852856] Dynamic subtest vcs0: SUCCESS (0.046s)
[151.968523] Starting dynamic subtest: vecs0
[151.977216] Dynamic subtest vecs0: SUCCESS (0.045s)
[151.986847] Subtest basic-wait: SUCCESS (0.219s)
[153.609601] [023/165] (914s left) gem_exec_fence (basic-await)
[154.619135] Starting subtest: basic-await
[154.627298] Starting dynamic subtest: rcs0
[155.086607] Dynamic subtest rcs0: SUCCESS (0.458s)
[155.144749] Starting dynamic subtest: bcs0
[155.538360] Dynamic subtest bcs0: SUCCESS (0.455s)
[155.597634] Starting dynamic subtest: vcs0
[155.975926] Dynamic subtest vcs0: SUCCESS (0.435s)
[156.032585] Starting dynamic subtest: vecs0
[156.446319] Dynamic subtest vecs0: SUCCESS (0.459s)
[156.453670] Subtest basic-await: SUCCESS (1.821s)
[158.189261] [024/165] (909s left) gem_exec_fence (nb-await)
[159.199752] Starting subtest: nb-await
[159.207136] Starting dynamic subtest: rcs0
[159.367753] Dynamic subtest rcs0: SUCCESS (0.159s)
[159.426702] Starting dynamic subtest: bcs0
[159.445341] Dynamic subtest bcs0: SUCCESS (0.141s)
[159.581663] Starting dynamic subtest: vcs0
[159.591579] Dynamic subtest vcs0: SUCCESS (0.140s)
[159.658365] Starting dynamic subtest: vecs0
[159.717885] Dynamic subtest vecs0: SUCCESS (0.125s)
[159.724954] Subtest nb-await: SUCCESS (0.573s)
[161.471563] [025/165] (906s left) gem_exec_gttfill (basic)
[162.299622] Starting subtest: basic
[167.597936] Subtest basic: SKIP (5.240s)
[168.056778] [026/165] (900s left) gem_exec_parallel (engines)
[169.339761] Starting subtest: engines
[169.346962] Starting dynamic subtest: basic
[180.902203] Dynamic subtest basic: SUCCESS (11.561s)
[180.975821] Starting dynamic subtest: contexts
[201.148157] Dynamic subtest contexts: SUCCESS (20.239s)
[201.275915] Starting dynamic subtest: fds
[235.390288] Dynamic subtest fds: SUCCESS (34.289s)
[235.509398] Starting dynamic subtest: userptr
[247.016145] Dynamic subtest userptr: SUCCESS (11.611s)
[247.022668] Subtest engines: SUCCESS (77.708s)
[247.615142] [027/165] (821s left) gem_exec_store (basic)
[248.468748] Starting subtest: basic
[248.555762] Subtest basic: SUCCESS (0.144s)
[249.089279] [028/165] (819s left) gem_flink_basic (bad-flink)
[249.825145] Starting subtest: bad-flink
[249.834680] Subtest bad-flink: SUCCESS (0.000s)
[250.143423] [029/165] (819s left) gem_flink_basic (bad-open)
[250.757114] Starting subtest: bad-open
[250.764783] Subtest bad-open: SUCCESS (0.000s)
[251.101908] [030/165] (818s left) gem_flink_basic (basic)
[251.696771] Starting subtest: basic
[251.704903] Subtest basic: SUCCESS (0.001s)
[251.983638] [031/165] (817s left) gem_flink_basic (double-flink)
[252.594387] Starting subtest: double-flink
[252.604679] Subtest double-flink: SUCCESS (0.001s)
[252.881851] [032/165] (816s left) gem_flink_basic (flink-lifetime)
[253.492321] Starting subtest: flink-lifetime
[253.500952] Subtest flink-lifetime: SUCCESS (0.012s)
[253.832315] [033/165] (816s left) gem_huc_copy (huc-copy)
[254.577949] Starting subtest: huc-copy
[254.608497] Subtest huc-copy: SKIP (0.000s)
[254.883606] [034/165] (815s left) gem_linear_blits (basic)
[256.646830] Subtest basic: SKIP (0.000s)
[256.934297] [035/165] (813s left) gem_mmap (basic)
[258.066183] Starting subtest: basic
[258.100889] Subtest basic: SUCCESS (0.004s)
[258.378517] [036/165] (812s left) gem_mmap_gtt (basic)
[259.069324] Starting subtest: basic
[259.077579] Subtest basic: SUCCESS (0.006s)
[259.451256] [037/165] (811s left) gem_render_linear_blits (basic)
[260.245907] Starting subtest: basic
[260.506888] Subtest basic: SUCCESS (0.272s)
[260.799871] [038/165] (810s left) gem_render_tiled_blits (basic)
[261.562420] Starting subtest: basic
[261.891527] Subtest basic: SUCCESS (0.324s)
[262.177677] [039/165] (809s left) gem_ringfill (basic-all)
[263.874847] Starting subtest: basic-all
[265.524436] Subtest basic-all: SUCCESS (1.602s)
[265.789276] [040/165] (805s left) gem_softpin (allocator-basic)
[266.556857] Starting subtest: allocator-basic
[267.735683] Subtest allocator-basic: SUCCESS (1.114s)
[268.216098] [041/165] (803s left) gem_softpin (allocator-basic-reserve)
[268.869792] Starting subtest: allocator-basic-reserve
[270.029392] Subtest allocator-basic-reserve: SUCCESS (1.097s)
[270.423212] [042/165] (801s left) gem_softpin (safe-alignment)
[271.100727] Starting subtest: safe-alignment
[271.294157] Subtest safe-alignment: SUCCESS (0.131s)
[271.572540] [043/165] (800s left) gem_sync (basic-all)
[272.456633] Starting subtest: basic-all
[276.919587] Subtest basic-all: SUCCESS (4.481s)
[277.386306] [044/165] (794s left) gem_sync (basic-each)
[278.110729] Starting subtest: basic-each
[281.594582] Subtest basic-each: SUCCESS (3.485s)
[282.063537] [045/165] (790s left) gem_tiled_blits (basic)
[283.742174] Subtest basic: SKIP (0.000s)
[283.984157] [046/165] (788s left) gem_tiled_fence_blits (basic)
[286.170704] Starting subtest: basic
[286.183560] Subtest basic: SUCCESS (0.099s)
[286.747424] [047/165] (786s left) gem_tiled_pread_basic
[289.269598] [048/165] (783s left) gem_wait (busy@all-engines)
[290.147601] Starting subtest: busy
[290.157263] Starting dynamic subtest: all-engines
[290.769779] Dynamic subtest all-engines: SUCCESS (0.577s)
[290.777535] Subtest busy: SUCCESS (0.580s)
[291.442769] [049/165] (781s left) gem_wait (wait@all-engines)
[292.272706] Starting subtest: wait
[292.279948] Starting dynamic subtest: all-engines
[293.406954] Dynamic subtest all-engines: SUCCESS (1.129s)
[293.414773] Subtest wait: SUCCESS (1.132s)
[294.015989] [050/165] (779s left) i915_getparams_basic (basic-eu-total)
[294.694926] Starting subtest: basic-eu-total
[294.709000] Subtest basic-eu-total: SUCCESS (0.000s)
[294.989720] [051/165] (778s left) i915_getparams_basic (basic-subslice-total)
[295.606805] Starting subtest: basic-subslice-total
[295.623435] Subtest basic-subslice-total: SUCCESS (0.000s)
[295.884498] [052/165] (777s left) i915_hangman (error-state-basic)
[296.688582] Starting subtest: error-state-basic
[296.756409] Subtest error-state-basic: SUCCESS (0.057s)
[297.099130] [053/165] (776s left) i915_pciid
[298.086749] [054/165] (776s left) kms_addfb_basic (addfb25-4-tiled)
[299.884159] Starting subtest: addfb25-4-tiled
[299.891686] Subtest addfb25-4-tiled: SUCCESS (0.002s)
[300.206223] [055/165] (774s left) kms_addfb_basic (addfb25-bad-modifier)
[300.835975] Starting subtest: addfb25-bad-modifier
[300.842705] Subtest addfb25-bad-modifier: SUCCESS (0.003s)
[301.865157] [056/165] (772s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[302.498945] Starting subtest: addfb25-framebuffer-vs-set-tiling
[302.506469] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[303.478426] [057/165] (771s left) kms_addfb_basic (addfb25-modifier-no-flag)
[304.136768] Starting subtest: addfb25-modifier-no-flag
[304.144423] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[305.164706] [058/165] (770s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[305.827753] Starting subtest: addfb25-x-tiled-legacy
[305.835546] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[306.802664] [059/165] (768s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[307.438666] Starting subtest: addfb25-x-tiled-mismatch-legacy
[307.446744] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[308.401378] [060/165] (767s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[309.667649] Starting subtest: addfb25-yf-tiled-legacy
[309.677923] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.001s)
[310.011914] [061/165] (766s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[311.272932] Starting subtest: addfb25-y-tiled-legacy
[311.291920] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[311.602483] [062/165] (764s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[312.872354] Starting subtest: addfb25-y-tiled-small-legacy
[312.879475] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.002s)
[313.182655] [063/165] (763s left) kms_addfb_basic (bad-pitch-0)
[313.804736] Starting subtest: bad-pitch-0
[313.812822] Subtest bad-pitch-0: SUCCESS (0.000s)
[314.821314] [064/165] (761s left) kms_addfb_basic (bad-pitch-1024)
[315.489780] Starting subtest: bad-pitch-1024
[315.497458] Subtest bad-pitch-1024: SUCCESS (0.000s)
[316.485286] [065/165] (760s left) kms_addfb_basic (bad-pitch-128)
[317.136120] Starting subtest: bad-pitch-128
[317.143894] Subtest bad-pitch-128: SUCCESS (0.000s)
[318.143298] [066/165] (759s left) kms_addfb_basic (bad-pitch-256)
[318.805316] Starting subtest: bad-pitch-256
[318.815844] Subtest bad-pitch-256: SUCCESS (0.000s)
[319.821243] [067/165] (757s left) kms_addfb_basic (bad-pitch-32)
[320.439362] Starting subtest: bad-pitch-32
[320.446501] Subtest bad-pitch-32: SUCCESS (0.000s)
[321.448519] [068/165] (756s left) kms_addfb_basic (bad-pitch-63)
[322.071128] Starting subtest: bad-pitch-63
[322.078618] Subtest bad-pitch-63: SUCCESS (0.000s)
[323.081503] [069/165] (755s left) kms_addfb_basic (bad-pitch-65536)
[323.742585] Starting subtest: bad-pitch-65536
[323.749725] Subtest bad-pitch-65536: SUCCESS (0.002s)
[324.794400] [070/165] (753s left) kms_addfb_basic (bad-pitch-999)
[325.445985] Starting subtest: bad-pitch-999
[325.454416] Subtest bad-pitch-999: SUCCESS (0.000s)
[326.406738] [071/165] (752s left) kms_addfb_basic (basic)
[327.046533] Starting subtest: basic
[327.054702] Subtest basic: SUCCESS (0.001s)
[328.003671] [072/165] (750s left) kms_addfb_basic (basic-x-tiled-legacy)
[328.619856] Starting subtest: basic-x-tiled-legacy
[328.627441] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[329.571666] [073/165] (749s left) kms_addfb_basic (basic-y-tiled-legacy)
[330.209311] Starting subtest: basic-y-tiled-legacy
[330.216107] Subtest basic-y-tiled-legacy: SUCCESS (0.003s)
[331.207281] [074/165] (748s left) kms_addfb_basic (bo-too-small)
[332.494816] Starting subtest: bo-too-small
[332.503856] Subtest bo-too-small: SUCCESS (0.003s)
[332.846836] [075/165] (746s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[334.127095] Starting subtest: bo-too-small-due-to-tiling
[334.137412] Subtest bo-too-small-due-to-tiling: SUCCESS (0.003s)
[334.456164] [076/165] (745s left) kms_addfb_basic (clobberred-modifier)
[335.088770] Starting subtest: clobberred-modifier
[335.096872] Subtest clobberred-modifier: SUCCESS (0.001s)
[336.044340] [077/165] (744s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[336.669142] Starting subtest: framebuffer-vs-set-tiling
[336.676973] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[337.666303] [078/165] (742s left) kms_addfb_basic (invalid-get-prop)
[338.282853] Starting subtest: invalid-get-prop
[338.290630] Subtest invalid-get-prop: SUCCESS (0.000s)
[339.292168] [079/165] (741s left) kms_addfb_basic (invalid-get-prop-any)
[339.943912] Starting subtest: invalid-get-prop-any
[339.952937] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[340.926375] [080/165] (739s left) kms_addfb_basic (invalid-set-prop)
[341.542706] Starting subtest: invalid-set-prop
[341.550712] Subtest invalid-set-prop: SUCCESS (0.000s)
[342.516155] [081/165] (738s left) kms_addfb_basic (invalid-set-prop-any)
[343.145576] Starting subtest: invalid-set-prop-any
[343.156226] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[344.122977] [082/165] (737s left) kms_addfb_basic (no-handle)
[344.751378] Starting subtest: no-handle
[344.758905] Subtest no-handle: SUCCESS (0.000s)
[345.760702] [083/165] (735s left) kms_addfb_basic (size-max)
[347.048434] Starting subtest: size-max
[347.056697] Subtest size-max: SUCCESS (0.001s)
[347.403223] [084/165] (734s left) kms_addfb_basic (small-bo)
[348.678265] Starting subtest: small-bo
[348.686347] Subtest small-bo: SUCCESS (0.000s)
[349.028330] [085/165] (733s left) kms_addfb_basic (tile-pitch-mismatch)
[349.649707] Starting subtest: tile-pitch-mismatch
[349.656797] Subtest tile-pitch-mismatch: SUCCESS (0.002s)
[350.657207] [086/165] (731s left) kms_addfb_basic (too-high)
[351.918153] Starting subtest: too-high
[351.926156] Subtest too-high: SUCCESS (0.008s)
[352.248184] [087/165] (730s left) kms_addfb_basic (too-wide)
[353.497148] Starting subtest: too-wide
[353.504352] Subtest too-wide: SUCCESS (0.000s)
[353.838319] [088/165] (729s left) kms_addfb_basic (unused-handle)
[354.454878] Starting subtest: unused-handle
[354.462989] Subtest unused-handle: SUCCESS (0.000s)
[355.429172] [089/165] (727s left) kms_addfb_basic (unused-modifier)
[356.045168] Starting subtest: unused-modifier
[356.053906] Subtest unused-modifier: SUCCESS (0.000s)
[357.035718] [090/165] (726s left) kms_addfb_basic (unused-offsets)
[357.689684] Starting subtest: unused-offsets
[357.696765] Subtest unused-offsets: SUCCESS (0.000s)
[358.730740] [091/165] (724s left) kms_addfb_basic (unused-pitches)
[359.369127] Starting subtest: unused-pitches
[359.385888] Subtest unused-pitches: SUCCESS (0.000s)
[360.376716] [092/165] (723s left) kms_busy (basic)
[361.759980] Starting subtest: basic
[361.770950] Starting dynamic subtest: flip
[364.843768] Dynamic subtest flip: SUCCESS (3.071s)
[364.990675] Starting dynamic subtest: modeset
[369.742260] Dynamic subtest modeset: SUCCESS (4.891s)
[369.749823] Subtest basic: SUCCESS (7.977s)
[370.947276] [093/165] (713s left) kms_prop_blob (basic)
[371.695991] Starting subtest: basic
[371.703144] Subtest basic: SUCCESS (0.000s)
[372.014826] [094/165] (712s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[373.360651] Starting subtest: basic-busy-flip-before-cursor-atomic
[373.375789] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.001s)
[373.736778] [095/165] (711s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[375.069417] Starting subtest: basic-busy-flip-before-cursor-legacy
[375.085875] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.001s)
[375.423332] [096/165] (709s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[376.703311] Starting subtest: basic-flip-after-cursor-atomic
[378.467952] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.765s)
[379.632389] [097/165] (705s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[380.921290] Starting subtest: basic-flip-after-cursor-legacy
[382.726471] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.817s)
[383.894567] [098/165] (701s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[385.176157] Starting subtest: basic-flip-after-cursor-varying-size
[386.945743] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.773s)
[388.118567] [099/165] (697s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[389.394708] Starting subtest: basic-flip-before-cursor-atomic
[391.164914] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.752s)
[392.296779] [100/165] (693s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[393.554536] Starting subtest: basic-flip-before-cursor-legacy
[395.391765] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.834s)
[396.554116] [101/165] (689s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[397.838642] Starting subtest: basic-flip-before-cursor-varying-size
[399.585833] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.744s)
[400.777764] [102/165] (685s left) kms_dsc (dsc-basic)
[402.222787] Subtest dsc-basic: SKIP (0.000s)
[402.540496] [103/165] (684s left) kms_flip (basic-flip-vs-dpms)
[403.511715] Starting subtest: basic-flip-vs-dpms
[403.549635] Starting dynamic subtest: A-eDP1
[407.146219] Dynamic subtest A-eDP1: SUCCESS (3.595s)
[407.300561] Starting dynamic subtest: B-eDP1
[409.952978] Dynamic subtest B-eDP1: SUCCESS (2.792s)
[410.086651] Starting dynamic subtest: C-eDP1
[412.799100] Dynamic subtest C-eDP1: SUCCESS (2.828s)
[412.832856] Subtest basic-flip-vs-dpms: SUCCESS (9.332s)
[413.985318] [104/165] (673s left) kms_flip (basic-flip-vs-modeset)
[414.986705] Starting subtest: basic-flip-vs-modeset
[415.024627] Starting dynamic subtest: A-eDP1
[418.627797] Dynamic subtest A-eDP1: SUCCESS (3.601s)
[418.778713] Starting dynamic subtest: B-eDP1
[421.218355] Dynamic subtest B-eDP1: SUCCESS (2.579s)
[421.370760] Starting dynamic subtest: C-eDP1
[423.928560] Dynamic subtest C-eDP1: SUCCESS (2.680s)
[423.959621] Subtest basic-flip-vs-modeset: SUCCESS (8.999s)
[425.135791] [105/165] (662s left) kms_flip (basic-flip-vs-wf_vblank)
[426.152693] Starting subtest: basic-flip-vs-wf_vblank
[426.247601] Starting dynamic subtest: A-eDP1
[429.072861] Dynamic subtest A-eDP1: SUCCESS (2.814s)
[429.209695] Starting dynamic subtest: B-eDP1
[431.637853] Dynamic subtest B-eDP1: SUCCESS (2.529s)
[431.775545] Starting dynamic subtest: C-eDP1
[434.151787] Dynamic subtest C-eDP1: SUCCESS (2.527s)
[434.159433] Subtest basic-flip-vs-wf_vblank: SUCCESS (8.023s)
[436.235471] [106/165] (651s left) kms_flip (basic-plain-flip)
[437.198374] Starting subtest: basic-plain-flip
[437.262739] Starting dynamic subtest: A-eDP1
[439.836945] Dynamic subtest A-eDP1: SUCCESS (2.542s)
[439.979514] Starting dynamic subtest: B-eDP1
[442.137156] Dynamic subtest B-eDP1: SUCCESS (2.285s)
[442.256668] Starting dynamic subtest: C-eDP1
[444.435788] Dynamic subtest C-eDP1: SUCCESS (2.288s)
[444.444468] Subtest basic-plain-flip: SUCCESS (7.251s)
[446.374327] [107/165] (641s left) kms_force_connector_basic (force-connector-state)
[447.663516] Starting subtest: force-connector-state
[449.744833] Subtest force-connector-state: SUCCESS (2.016s)
[452.219655] [108/165] (635s left) kms_force_connector_basic (force-edid)
[453.453359] Starting subtest: force-edid
[454.005289] Subtest force-edid: SUCCESS (0.542s)
[455.786312] [109/165] (632s left) kms_force_connector_basic (force-load-detect)
[456.983138] Starting subtest: force-load-detect
[457.002651] Subtest force-load-detect: SKIP (0.000s)
[458.485600] [110/165] (630s left) kms_force_connector_basic (prune-stale-modes)
[459.711323] Starting subtest: prune-stale-modes
[460.291858] Subtest prune-stale-modes: SUCCESS (0.577s)
[462.099460] [111/165] (626s left) kms_frontbuffer_tracking (basic)
[463.514739] Starting subtest: basic
[472.566170] Subtest basic: SUCCESS (9.056s)
[473.001359] [112/165] (616s left) kms_hdmi_inject (inject-audio)
[474.247819] Starting subtest: inject-audio
[475.220383] Subtest inject-audio: SUCCESS (0.981s)
[476.618580] [113/165] (612s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[478.021960] Starting subtest: compare-crc-sanitycheck-xr24
[478.039594] Starting dynamic subtest: pipe-A-eDP-1
[479.206689] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.170s)
[479.418148] Starting dynamic subtest: pipe-B-eDP-1
[481.037910] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.811s)
[481.289332] Starting dynamic subtest: pipe-C-eDP-1
[482.882355] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.797s)
[482.890570] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.852s)
[484.045955] [114/165] (605s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[485.339809] Starting subtest: compare-crc-sanitycheck-nv12
[485.348413] Starting dynamic subtest: pipe-A-eDP-1
[487.151926] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.803s)
[487.296596] Starting dynamic subtest: pipe-B-eDP-1
[489.554682] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.365s)
[489.707898] Starting dynamic subtest: pipe-C-eDP-1
[489.721684] Dynamic subtest pipe-C-eDP-1: SKIP (0.009s)
[489.728892] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.260s)
[490.787826] [115/165] (599s left) kms_pipe_crc_basic (hang-read-crc)
[492.082575] Starting subtest: hang-read-crc
[492.100622] Starting dynamic subtest: pipe-A-eDP-1
[495.449280] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.327s)
[495.618803] Starting dynamic subtest: pipe-B-eDP-1
[499.502705] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.033s)
[499.719899] Starting dynamic subtest: pipe-C-eDP-1
[503.436949] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.914s)
[503.448264] Subtest hang-read-crc: SUCCESS (11.348s)
[504.631566] [116/165] (585s left) kms_pipe_crc_basic (nonblocking-crc)
[505.952910] Starting subtest: nonblocking-crc
[505.978985] Starting dynamic subtest: pipe-A-eDP-1
[507.334882] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.350s)
[507.503294] Starting dynamic subtest: pipe-B-eDP-1
[509.364296] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.012s)
[509.542991] Starting dynamic subtest: pipe-C-eDP-1
[511.427221] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.029s)
[511.434787] Subtest nonblocking-crc: SUCCESS (5.474s)
[512.595150] [117/165] (578s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[513.917859] Starting subtest: nonblocking-crc-frame-sequence
[513.943138] Starting dynamic subtest: pipe-A-eDP-1
[515.310648] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.364s)
[515.461413] Starting dynamic subtest: pipe-B-eDP-1
[517.414213] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.080s)
[517.619826] Starting dynamic subtest: pipe-C-eDP-1
[519.411111] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.962s)
[519.420483] Subtest nonblocking-crc-frame-sequence: SUCCESS (5.499s)
[520.612404] [118/165] (570s left) kms_pipe_crc_basic (read-crc)
[521.948848] Starting subtest: read-crc
[521.957260] Starting dynamic subtest: pipe-A-eDP-1
[523.225861] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.269s)
[523.383778] Starting dynamic subtest: pipe-B-eDP-1
[525.284605] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.010s)
[525.439791] Starting dynamic subtest: pipe-C-eDP-1
[527.266404] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.969s)
[527.278711] Subtest read-crc: SUCCESS (5.337s)
[528.431363] [119/165] (562s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[529.732583] Starting subtest: read-crc-frame-sequence
[529.760099] Starting dynamic subtest: pipe-A-eDP-1
[531.084813] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.326s)
[531.344887] Starting dynamic subtest: pipe-B-eDP-1
[533.114984] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.012s)
[533.291995] Starting dynamic subtest: pipe-C-eDP-1
[535.134603] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.985s)
[535.146810] Subtest read-crc-frame-sequence: SUCCESS (5.406s)
[536.318170] [120/165] (555s left) kms_pm_backlight (basic-brightness)
[537.676768] Starting subtest: basic-brightness
[538.220809] Starting dynamic subtest: eDP-1
[539.056574] Dynamic subtest eDP-1: SUCCESS (0.885s)
[539.066933] Subtest basic-brightness: SUCCESS (1.371s)
[540.240444] [121/165] (551s left) kms_pm_rpm (basic-pci-d3-state)
[543.982993] Starting subtest: basic-pci-d3-state
[548.475536] Subtest basic-pci-d3-state: SUCCESS (4.483s)
[549.173812] [122/165] (542s left) kms_pm_rpm (basic-rte)
[549.838107] Starting subtest: basic-rte
[557.381269] Subtest basic-rte: SUCCESS (7.531s)
[559.512369] [123/165] (532s left) kms_psr (primary_page_flip)
[560.836863] Starting subtest: primary_page_flip
[563.161410] Subtest primary_page_flip: SUCCESS (2.317s)
[564.413241] [124/165] (528s left) kms_psr (cursor_plane_move)
[565.734761] Starting subtest: cursor_plane_move
[568.009110] Subtest cursor_plane_move: SUCCESS (2.303s)
[569.179733] [125/165] (523s left) kms_psr (sprite_plane_onoff)
[570.447694] Starting subtest: sprite_plane_onoff
[572.898660] Subtest sprite_plane_onoff: SUCCESS (2.433s)
[574.048454] [126/165] (519s left) kms_psr (primary_mmap_gtt)
[575.378938] Starting subtest: primary_mmap_gtt
[577.715815] Subtest primary_mmap_gtt: SUCCESS (2.334s)
[578.903438] [127/165] (514s left) kms_setmode (basic-clone-single-crtc)
[579.592686] Starting subtest: basic-clone-single-crtc
[579.612168] Subtest basic-clone-single-crtc: SKIP (0.012s)
[579.973118] [128/165] (513s left) i915_pm_rps (basic-api)
[580.701976] Starting subtest: basic-api
[580.720710] Subtest basic-api: SUCCESS (0.039s)
[581.133268] [129/165] (512s left) prime_self_import (basic-llseek-bad)
[581.859976] Starting subtest: basic-llseek-bad
[581.876144] Subtest basic-llseek-bad: SUCCESS (0.016s)
[582.226663] [130/165] (511s left) prime_self_import (basic-llseek-size)
[582.844859] Starting subtest: basic-llseek-size
[582.883182] Subtest basic-llseek-size: SUCCESS (0.024s)
[583.275403] [131/165] (511s left) prime_self_import (basic-with_fd_dup)
[583.892903] Starting subtest: basic-with_fd_dup
[583.930796] Subtest basic-with_fd_dup: SUCCESS (0.026s)
[584.249933] [132/165] (510s left) prime_self_import (basic-with_one_bo)
[584.864742] Starting subtest: basic-with_one_bo
[584.884149] Subtest basic-with_one_bo: SUCCESS (0.028s)
[585.275789] [133/165] (509s left) prime_self_import (basic-with_one_bo_two_files)
[585.891931] Starting subtest: basic-with_one_bo_two_files
[585.905703] Subtest basic-with_one_bo_two_files: SUCCESS (0.022s)
[586.218679] [134/165] (508s left) prime_self_import (basic-with_two_bos)
[586.856119] Starting subtest: basic-with_two_bos
[586.869610] Subtest basic-with_two_bos: SUCCESS (0.028s)
[587.252165] [135/165] (508s left) prime_vgem (basic-fence-flip)
[588.008970] Starting subtest: basic-fence-flip
[589.802131] Subtest basic-fence-flip: SUCCESS (1.712s)
[590.306149] [136/165] (505s left) prime_vgem (basic-fence-mmap)
[591.105153] Starting subtest: basic-fence-mmap
[591.416171] Subtest basic-fence-mmap: SUCCESS (0.278s)
[591.706593] [137/165] (504s left) prime_vgem (basic-fence-read)
[592.396625] Starting subtest: basic-fence-read
[592.872225] Subtest basic-fence-read: SUCCESS (0.424s)
[593.205794] [138/165] (502s left) prime_vgem (basic-gtt)
[593.932377] Starting subtest: basic-gtt
[594.110238] Subtest basic-gtt: SUCCESS (0.132s)
[594.450849] [139/165] (501s left) prime_vgem (basic-read)
[595.150954] Starting subtest: basic-read
[595.369559] Subtest basic-read: SUCCESS (0.217s)
[595.693379] [140/165] (500s left) prime_vgem (basic-write)
[596.422610] Starting subtest: basic-write
[596.734404] Subtest basic-write: SUCCESS (0.249s)
[597.031227] [141/165] (499s left) vgem_basic (setversion)
[597.806796] Starting subtest: setversion
[597.821868] Subtest setversion: SUCCESS (0.000s)
[598.186535] [142/165] (499s left) vgem_basic (create)
[598.873921] Starting subtest: create
[598.883138] Subtest create: SUCCESS (0.002s)
[599.193879] [143/165] (498s left) vgem_basic (debugfs)
[599.883880] Starting subtest: debugfs
[599.894171] Subtest debugfs: SUCCESS (0.004s)
[600.243171] [144/165] (497s left) vgem_basic (dmabuf-export)
[600.891200] Starting subtest: dmabuf-export
[600.908700] Subtest dmabuf-export: SUCCESS (0.018s)
[601.274288] [145/165] (496s left) vgem_basic (dmabuf-fence)
[601.917952] Starting subtest: dmabuf-fence
[601.926181] Subtest dmabuf-fence: SUCCESS (0.003s)
[602.231416] [146/165] (495s left) vgem_basic (dmabuf-fence-before)
[602.920902] Starting subtest: dmabuf-fence-before
[602.928572] Subtest dmabuf-fence-before: SUCCESS (0.004s)
[603.253790] [147/165] (495s left) vgem_basic (dmabuf-mmap)
[603.920485] Starting subtest: dmabuf-mmap
[604.137652] Subtest dmabuf-mmap: SUCCESS (0.133s)
[604.384987] [148/165] (494s left) vgem_basic (mmap)
[605.052683] Starting subtest: mmap
[605.218962] Subtest mmap: SUCCESS (0.098s)
[605.498961] [149/165] (493s left) vgem_basic (second-client)
[606.155653] Starting subtest: second-client
[606.209807] Subtest second-client: SUCCESS (0.044s)
[606.606902] [150/165] (492s left) vgem_basic (sysfs)
[607.298397] Starting subtest: sysfs
[607.307849] Subtest sysfs: SUCCESS (0.006s)
[607.653095] [151/165] (491s left) core_hotunplug (unbind-rebind)
[608.723930] Starting subtest: unbind-rebind
[617.130159] Subtest unbind-rebind: SUCCESS (8.391s)
[617.498276] [152/165] (482s left) vgem_basic (unload)
[618.263716] Starting subtest: unload
[648.172733] Subtest unload: SUCCESS (29.846s)
[648.700910] [153/165] (451s left) i915_module_load (reload)
[649.377375] Starting subtest: reload
[660.883545] Subtest reload: SUCCESS (11.508s)
[661.449677] [154/165] (439s left) gem_lmem_swapping (basic)
[662.524901] Subtest basic: SKIP (0.000s)
[662.821829] [155/165] (438s left) gem_lmem_swapping (parallel-random-engines)
[663.504411] Subtest parallel-random-engines: SKIP (0.000s)
[663.809487] [156/165] (437s left) gem_lmem_swapping (random-engines)
[664.465927] Subtest random-engines: SKIP (0.000s)
[664.778518] [157/165] (436s left) gem_lmem_swapping (verify-random)
[665.537593] Subtest verify-random: SKIP (0.000s)
[665.844767] [158/165] (435s left) i915_pm_rpm (module-reload)
[670.163572] Starting subtest: module-reload
[693.934152] Subtest module-reload: SUCCESS (23.682s)
[694.105326] [159/165] (407s left) i915_selftest (live)
[694.987787] Starting subtest: live
[694.996368] Starting dynamic subtest: sanitycheck
[700.513960] Dynamic subtest sanitycheck: SUCCESS (5.478s)
[700.568933] Starting dynamic subtest: uncore
[705.936368] Dynamic subtest uncore: SUCCESS (5.396s)
[705.993341] Starting dynamic subtest: workarounds
[712.300733] Dynamic subtest workarounds: SUCCESS (6.347s)
[712.356426] Starting dynamic subtest: gt_engines
[718.023156] Dynamic subtest gt_engines: SUCCESS (5.704s)
[718.080882] Starting dynamic subtest: gt_timelines
[778.645409] Dynamic subtest gt_timelines: SUCCESS (60.608s)
[778.702660] Starting dynamic subtest: gt_contexts
[784.495618] Dynamic subtest gt_contexts: SUCCESS (5.843s)
[784.558725] Starting dynamic subtest: gt_lrc
[794.354795] Dynamic subtest gt_lrc: SUCCESS (9.856s)
[794.409746] Starting dynamic subtest: gt_mocs
[800.110437] Dynamic subtest gt_mocs: SUCCESS (5.732s)
[800.166685] Starting dynamic subtest: gt_pm
[810.250137] Dynamic subtest gt_pm: FAIL (8.515s)
[810.310829] Starting dynamic subtest: gt_heartbeat
[818.701434] Dynamic subtest gt_heartbeat: FAIL (9.474s)
[818.758460] Starting dynamic subtest: gt_tlb
[825.181952] Dynamic subtest gt_tlb: SUCCESS (7.041s)
[825.240667] Starting dynamic subtest: requests
[837.341758] Dynamic subtest requests: SUCCESS (12.141s)
[837.398711] Starting dynamic subtest: migrate
[847.970600] Dynamic subtest migrate: SUCCESS (10.625s)
[848.025901] Starting dynamic subtest: active
[853.403858] Dynamic subtest active: SUCCESS (5.421s)
[853.461797] Starting dynamic subtest: objects
[868.129221] Dynamic subtest objects: SUCCESS (14.709s)
[868.184490] Starting dynamic subtest: mman
[889.943718] Dynamic subtest mman: SUCCESS (21.783s)
[890.001414] Starting dynamic subtest: dmabuf
[895.401516] Dynamic subtest dmabuf: SUCCESS (5.460s)
[895.458154] Starting dynamic subtest: vma
[900.847539] Dynamic subtest vma: SUCCESS (5.406s)
[900.923123] Starting dynamic subtest: coherency
[915.954598] Dynamic subtest coherency: SUCCESS (15.119s)
[916.008728] Starting dynamic subtest: gtt
[929.145969] Dynamic subtest gtt: SUCCESS (13.160s)
[929.202683] Starting dynamic subtest: gem
[936.150706] Dynamic subtest gem: SUCCESS (7.001s)
[936.209635] Starting dynamic subtest: evict
[954.234933] Dynamic subtest evict: SUCCESS (18.069s)
[954.291919] Starting dynamic subtest: hugepages
[966.444349] Dynamic subtest hugepages: SUCCESS (12.185s)
[966.502643] Starting dynamic subtest: gem_contexts
[1017.405564] Dynamic subtest gem_contexts: SUCCESS (50.964s)
[1017.467723] Starting dynamic subtest: client
[1023.041689] Dynamic subtest client: SUCCESS (5.607s)
[1023.099312] Starting dynamic subtest: gem_migrate
[1028.413122] Dynamic subtest gem_migrate: SUCCESS (5.355s)
[1028.489947] Starting dynamic subtest: reset
[1038.547691] Dynamic subtest reset: SUCCESS (10.124s)
[1038.608176] Starting dynamic subtest: memory_region
[1043.840115] Dynamic subtest memory_region: SUCCESS (5.271s)
[1043.906890] Starting dynamic subtest: hangcheck
[1072.603915] Dynamic subtest hangcheck: SUCCESS (28.757s)
[1072.720654] Starting dynamic subtest: execlists
[1094.439768] Dynamic subtest execlists: SUCCESS (21.826s)
[1094.565592] Starting dynamic subtest: ring_submission
[1099.783477] Dynamic subtest ring_submission: SUCCESS (5.327s)
[1099.842661] Starting dynamic subtest: perf
[1105.453174] Dynamic subtest perf: SUCCESS (5.630s)
[1105.567151] Starting dynamic subtest: slpc
[1110.820081] Dynamic subtest slpc: SUCCESS (5.383s)
[1110.878759] Starting dynamic subtest: guc
[1116.171729] Dynamic subtest guc: SUCCESS (5.342s)
[1116.231201] Starting dynamic subtest: guc_multi_lrc
[1121.609950] Dynamic subtest guc_multi_lrc: SUCCESS (5.428s)
[1121.668348] Starting dynamic subtest: guc_hang
[1126.961204] Dynamic subtest guc_hang: SUCCESS (5.336s)
[1127.018378] Starting dynamic subtest: late_gt_pm
[1132.560624] Dynamic subtest late_gt_pm: SUCCESS (5.545s)
[1132.580182] Subtest live: FAIL (437.498s)
[1132.666159] Overall timeout time exceeded, stopping.
[1132.668197] Closing watchdogs
Done.