[122.717259] Initializing watchdogs
[122.718246]   /dev/watchdog0
[122.773215] [001/162] (960s left) i915_module_load (load)
[123.889940] Starting subtest: load
[132.896373] Subtest load: SUCCESS (8.931s)
[133.255327] [002/162] (950s left) core_auth (basic-auth)
[135.099881] Starting subtest: basic-auth
[135.107971] Subtest basic-auth: SUCCESS (0.008s)
[135.556824] [003/162] (948s left) debugfs_test (read_all_entries)
[136.657474] Starting subtest: read_all_entries
[137.257672] Subtest read_all_entries: SUCCESS (0.603s)
[138.198417] [004/162] (946s left) debugfs_test (basic-hwmon)
[139.160085] Starting subtest: basic-hwmon
[139.175957] Subtest basic-hwmon: SKIP (0.000s)
[140.243901] [005/162] (944s left) fbdev (eof)
[141.253994] Starting subtest: eof
[141.262095] Subtest eof: SUCCESS (0.005s)
[141.533411] [006/162] (943s left) fbdev (info)
[142.466954] Starting subtest: info
[142.475718] Subtest info: SUCCESS (0.000s)
[142.798176] [007/162] (942s left) fbdev (nullptr)
[143.758820] Starting subtest: nullptr
[143.767169] Subtest nullptr: SUCCESS (0.000s)
[144.047977] [008/162] (941s left) fbdev (read)
[145.010236] Starting subtest: read
[147.579704] Subtest read: SUCCESS (2.514s)
[147.980841] [009/162] (937s left) fbdev (write)
[149.293319] Starting subtest: write
[150.628148] Subtest write: SUCCESS (1.286s)
[150.901209] [010/162] (934s left) gem_basic (bad-close)
[151.848717] Starting subtest: bad-close
[151.864886] Subtest bad-close: SUCCESS (0.000s)
[152.192659] [011/162] (933s left) gem_basic (create-close)
[153.087681] Starting subtest: create-close
[153.102881] Subtest create-close: SUCCESS (0.002s)
[153.437268] [012/162] (932s left) gem_basic (create-fd-close)
[154.342881] Starting subtest: create-fd-close
[154.358727] Subtest create-fd-close: SUCCESS (0.002s)
[154.739157] [013/162] (931s left) gem_busy (busy@all-engines)
[155.900762] Starting subtest: busy
[155.907995] Starting dynamic subtest: all-engines
[156.349547] Dynamic subtest all-engines: SUCCESS (0.455s)
[156.356655] Subtest busy: SUCCESS (0.458s)
[157.185887] [014/162] (929s left) gem_close_race (basic-process)
[158.395934] Starting subtest: basic-process
[158.749964] Subtest basic-process: SUCCESS (0.364s)
[159.194199] [015/162] (927s left) gem_close_race (basic-threads)
[160.316417] Starting subtest: basic-threads
[161.986768] Subtest basic-threads: SUCCESS (1.723s)
[162.479986] [016/162] (924s left) gem_ctx_create (basic)
[163.653211] Starting subtest: basic
[163.661450] Subtest basic: SUCCESS (0.000s)
[164.110385] [017/162] (922s left) gem_ctx_create (basic-files)
[165.175453] Starting subtest: basic-files
[167.362405] Subtest basic-files: SUCCESS (2.194s)
[167.829741] [018/162] (919s left) gem_ctx_exec (basic)
[168.854112] Starting subtest: basic
[168.889668] Subtest basic: SUCCESS (0.036s)
[169.254534] [019/162] (918s left) gem_exec_basic (basic)
[170.297415] Starting subtest: basic
[170.305568] Starting dynamic subtest: rcs0-smem
[170.324863] Dynamic subtest rcs0-smem: SUCCESS (0.018s)
[170.385794] Starting dynamic subtest: bcs0-smem
[170.392394] Dynamic subtest bcs0-smem: SUCCESS (0.010s)
[170.450822] Starting dynamic subtest: vcs0-smem
[170.460606] Dynamic subtest vcs0-smem: SUCCESS (0.005s)
[170.575395] Starting dynamic subtest: vecs0-smem
[170.582787] Dynamic subtest vecs0-smem: SUCCESS (0.005s)
[170.592832] Subtest basic: SUCCESS (0.047s)
[170.933908] [020/162] (916s left) gem_exec_create (basic)
[172.063144] Starting subtest: basic
[172.070779] Starting dynamic subtest: smem
[174.231369] Dynamic subtest smem: SUCCESS (2.240s)
[174.240142] Subtest basic: SUCCESS (2.244s)
[174.799312] [021/162] (913s left) gem_exec_fence (basic-busy)
[176.200769] Starting subtest: basic-busy
[176.212269] Starting dynamic subtest: rcs0
[176.240766] Dynamic subtest rcs0: SUCCESS (0.077s)
[176.364113] Starting dynamic subtest: bcs0
[176.378114] Dynamic subtest bcs0: SUCCESS (0.061s)
[176.440537] Starting dynamic subtest: vcs0
[176.455414] Dynamic subtest vcs0: SUCCESS (0.054s)
[176.514873] Starting dynamic subtest: vecs0
[176.527855] Dynamic subtest vecs0: SUCCESS (0.052s)
[176.534842] Subtest basic-busy: SUCCESS (0.254s)
[178.712708] [022/162] (909s left) gem_exec_fence (basic-wait)
[180.308864] Starting subtest: basic-wait
[180.327815] Starting dynamic subtest: rcs0
[180.395806] Dynamic subtest rcs0: SUCCESS (0.153s)
[180.544986] Starting dynamic subtest: bcs0
[180.553777] Dynamic subtest bcs0: SUCCESS (0.073s)
[180.699442] Starting dynamic subtest: vcs0
[180.709227] Dynamic subtest vcs0: SUCCESS (0.072s)
[180.833896] Starting dynamic subtest: vecs0
[180.844599] Dynamic subtest vecs0: SUCCESS (0.059s)
[180.851714] Subtest basic-wait: SUCCESS (0.370s)
[182.729944] [023/162] (905s left) gem_exec_fence (basic-await)
[184.041762] Starting subtest: basic-await
[184.048751] Starting dynamic subtest: rcs0
[184.525458] Dynamic subtest rcs0: SUCCESS (0.465s)
[184.583085] Starting dynamic subtest: bcs0
[184.958163] Dynamic subtest bcs0: SUCCESS (0.439s)
[185.073789] Starting dynamic subtest: vcs0
[185.447163] Dynamic subtest vcs0: SUCCESS (0.483s)
[185.506201] Starting dynamic subtest: vecs0
[185.874989] Dynamic subtest vecs0: SUCCESS (0.433s)
[185.882911] Subtest basic-await: SUCCESS (1.833s)
[187.771820] [024/162] (900s left) gem_exec_fence (nb-await)
[189.066886] Starting subtest: nb-await
[189.074157] Starting dynamic subtest: rcs0
[189.248798] Dynamic subtest rcs0: SUCCESS (0.173s)
[189.309838] Starting dynamic subtest: bcs0
[189.343844] Dynamic subtest bcs0: SUCCESS (0.144s)
[189.465138] Starting dynamic subtest: vcs0
[189.492279] Dynamic subtest vcs0: SUCCESS (0.142s)
[189.560734] Starting dynamic subtest: vecs0
[189.689983] Dynamic subtest vecs0: SUCCESS (0.134s)
[189.697172] Subtest nb-await: SUCCESS (0.605s)
[191.578625] [025/162] (897s left) gem_exec_gttfill (basic)
[192.757510] Starting subtest: basic
[198.085225] Subtest basic: SKIP (5.312s)
[198.646918] [026/162] (890s left) gem_exec_parallel (engines)
[200.284201] Starting subtest: engines
[200.291472] Starting dynamic subtest: basic
[213.364934] Dynamic subtest basic: SUCCESS (13.099s)
[213.493978] Starting dynamic subtest: contexts
[230.251379] Dynamic subtest contexts: SUCCESS (16.875s)
[230.319218] Starting dynamic subtest: fds
[249.442600] Dynamic subtest fds: SUCCESS (19.191s)
[249.536874] Starting dynamic subtest: userptr
[262.838729] Dynamic subtest userptr: SUCCESS (13.402s)
[262.846995] Subtest engines: SUCCESS (62.575s)
[263.491830] [027/162] (825s left) gem_exec_store (basic)
[264.623593] Starting subtest: basic
[264.773973] Subtest basic: SUCCESS (0.161s)
[265.293621] [028/162] (824s left) gem_flink_basic (bad-flink)
[266.238164] Starting subtest: bad-flink
[266.245863] Subtest bad-flink: SUCCESS (0.000s)
[266.646174] [029/162] (823s left) gem_flink_basic (bad-open)
[267.517665] Starting subtest: bad-open
[267.526341] Subtest bad-open: SUCCESS (0.000s)
[267.915865] [030/162] (822s left) gem_flink_basic (basic)
[268.830941] Starting subtest: basic
[268.839912] Subtest basic: SUCCESS (0.001s)
[269.211253] [031/162] (821s left) gem_flink_basic (double-flink)
[270.129512] Starting subtest: double-flink
[270.137480] Subtest double-flink: SUCCESS (0.002s)
[270.555422] [032/162] (820s left) gem_flink_basic (flink-lifetime)
[271.452449] Starting subtest: flink-lifetime
[271.461862] Subtest flink-lifetime: SUCCESS (0.012s)
[271.866445] [033/162] (818s left) gem_huc_copy (huc-copy)
[272.877911] Starting subtest: huc-copy
[272.893630] Subtest huc-copy: SKIP (0.000s)
[273.246795] [034/162] (817s left) gem_linear_blits (basic)
[275.358490] Subtest basic: SKIP (0.000s)
[275.665414] [035/162] (815s left) gem_mmap (basic)
[277.119753] Starting subtest: basic
[277.155769] Subtest basic: SUCCESS (0.004s)
[277.521383] [036/162] (813s left) gem_mmap_gtt (basic)
[278.550938] Starting subtest: basic
[278.560766] Subtest basic: SUCCESS (0.006s)
[278.951558] [037/162] (812s left) gem_render_linear_blits (basic)
[279.961799] Starting subtest: basic
[280.232898] Subtest basic: SUCCESS (0.270s)
[280.532484] [038/162] (811s left) gem_render_tiled_blits (basic)
[281.539558] Starting subtest: basic
[281.882763] Subtest basic: SUCCESS (0.337s)
[282.218573] [039/162] (809s left) gem_ringfill (basic-all)
[284.385769] Starting subtest: basic-all
[286.076136] Subtest basic-all: SUCCESS (1.645s)
[286.468138] [040/162] (805s left) gem_softpin (allocator-basic)
[287.518640] Starting subtest: allocator-basic
[288.699373] Subtest allocator-basic: SUCCESS (1.118s)
[289.187542] [041/162] (803s left) gem_softpin (allocator-basic-reserve)
[290.163424] Starting subtest: allocator-basic-reserve
[291.390444] Subtest allocator-basic-reserve: SUCCESS (1.176s)
[291.909861] [042/162] (800s left) gem_softpin (safe-alignment)
[292.895220] Starting subtest: safe-alignment
[293.074860] Subtest safe-alignment: SUCCESS (0.131s)
[293.397176] [043/162] (799s left) gem_sync (basic-all)
[294.548977] Starting subtest: basic-all
[299.375384] Subtest basic-all: SUCCESS (4.832s)
[299.966240] [044/162] (793s left) gem_sync (basic-each)
[301.058527] Starting subtest: basic-each
[304.879204] Subtest basic-each: SUCCESS (3.795s)
[305.399193] [045/162] (788s left) gem_tiled_blits (basic)
[307.543992] Subtest basic: SKIP (0.000s)
[307.864178] [046/162] (785s left) gem_tiled_fence_blits (basic)
[310.425979] Starting subtest: basic
[310.469429] Subtest basic: SUCCESS (0.104s)
[310.946292] [047/162] (783s left) gem_tiled_pread_basic
[313.802817] [048/162] (780s left) gem_wait (busy@all-engines)
[315.030312] Starting subtest: busy
[315.040546] Starting dynamic subtest: all-engines
[315.698108] Dynamic subtest all-engines: SUCCESS (0.664s)
[315.705812] Subtest busy: SUCCESS (0.671s)
[316.460702] [049/162] (778s left) gem_wait (wait@all-engines)
[317.562924] Starting subtest: wait
[317.570871] Starting dynamic subtest: all-engines
[318.703733] Dynamic subtest all-engines: SUCCESS (1.121s)
[318.710497] Subtest wait: SUCCESS (1.123s)
[319.430184] [050/162] (775s left) i915_getparams_basic (basic-eu-total)
[320.398177] Starting subtest: basic-eu-total
[320.413708] Subtest basic-eu-total: SUCCESS (0.000s)
[320.770173] [051/162] (774s left) i915_getparams_basic (basic-subslice-total)
[321.680243] Starting subtest: basic-subslice-total
[321.697125] Subtest basic-subslice-total: SUCCESS (0.000s)
[322.062112] [052/162] (773s left) i915_hangman (error-state-basic)
[323.114639] Starting subtest: error-state-basic
[323.183627] Subtest error-state-basic: SUCCESS (0.057s)
[323.582926] [053/162] (771s left) i915_pciid
[324.921292] [054/162] (770s left) kms_addfb_basic (addfb25-bad-modifier)
[325.929177] Starting subtest: addfb25-bad-modifier
[325.943208] Subtest addfb25-bad-modifier: SUCCESS (0.003s)
[327.347611] [055/162] (768s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[328.256771] Starting subtest: addfb25-framebuffer-vs-set-tiling
[328.264777] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.001s)
[329.271429] [056/162] (766s left) kms_addfb_basic (addfb25-modifier-no-flag)
[330.210711] Starting subtest: addfb25-modifier-no-flag
[330.219414] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[331.253237] [057/162] (765s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[332.188268] Starting subtest: addfb25-x-tiled-legacy
[332.196110] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[333.209336] [058/162] (763s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[334.183741] Starting subtest: addfb25-x-tiled-mismatch-legacy
[334.191926] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[335.184861] [059/162] (761s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[336.757818] Starting subtest: addfb25-yf-tiled-legacy
[336.766980] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.001s)
[337.097975] [060/162] (760s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[338.699144] Starting subtest: addfb25-y-tiled-legacy
[338.707766] Subtest addfb25-y-tiled-legacy: SUCCESS (0.001s)
[339.076593] [061/162] (758s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[340.714997] Starting subtest: addfb25-y-tiled-small-legacy
[340.723290] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.003s)
[341.128968] [062/162] (756s left) kms_addfb_basic (bad-pitch-0)
[342.120305] Starting subtest: bad-pitch-0
[342.129773] Subtest bad-pitch-0: SUCCESS (0.000s)
[343.196994] [063/162] (754s left) kms_addfb_basic (bad-pitch-1024)
[344.142094] Starting subtest: bad-pitch-1024
[344.150639] Subtest bad-pitch-1024: SUCCESS (0.000s)
[345.154395] [064/162] (753s left) kms_addfb_basic (bad-pitch-128)
[346.108184] Starting subtest: bad-pitch-128
[346.114660] Subtest bad-pitch-128: SUCCESS (0.001s)
[347.144166] [065/162] (751s left) kms_addfb_basic (bad-pitch-256)
[348.100143] Starting subtest: bad-pitch-256
[348.108890] Subtest bad-pitch-256: SUCCESS (0.000s)
[349.111443] [066/162] (749s left) kms_addfb_basic (bad-pitch-32)
[350.053808] Starting subtest: bad-pitch-32
[350.062734] Subtest bad-pitch-32: SUCCESS (0.000s)
[351.108710] [067/162] (748s left) kms_addfb_basic (bad-pitch-63)
[352.048896] Starting subtest: bad-pitch-63
[352.056426] Subtest bad-pitch-63: SUCCESS (0.000s)
[353.068748] [068/162] (746s left) kms_addfb_basic (bad-pitch-65536)
[354.001597] Starting subtest: bad-pitch-65536
[354.011743] Subtest bad-pitch-65536: SUCCESS (0.003s)
[355.026894] [069/162] (744s left) kms_addfb_basic (bad-pitch-999)
[355.947838] Starting subtest: bad-pitch-999
[355.957110] Subtest bad-pitch-999: SUCCESS (0.000s)
[356.977123] [070/162] (743s left) kms_addfb_basic (basic)
[357.909313] Starting subtest: basic
[357.917514] Subtest basic: SUCCESS (0.000s)
[358.990600] [071/162] (741s left) kms_addfb_basic (basic-x-tiled-legacy)
[359.983114] Starting subtest: basic-x-tiled-legacy
[359.990870] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[361.063556] [072/162] (739s left) kms_addfb_basic (basic-y-tiled-legacy)
[362.011849] Starting subtest: basic-y-tiled-legacy
[362.019978] Subtest basic-y-tiled-legacy: SUCCESS (0.002s)
[363.013970] [073/162] (737s left) kms_addfb_basic (bo-too-small)
[364.628934] Starting subtest: bo-too-small
[364.637900] Subtest bo-too-small: SUCCESS (0.002s)
[364.984898] [074/162] (736s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[366.578459] Starting subtest: bo-too-small-due-to-tiling
[366.586326] Subtest bo-too-small-due-to-tiling: SUCCESS (0.002s)
[366.949152] [075/162] (734s left) kms_addfb_basic (clobberred-modifier)
[367.909901] Starting subtest: clobberred-modifier
[367.918666] Subtest clobberred-modifier: SUCCESS (0.001s)
[368.948928] [076/162] (732s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[369.890906] Starting subtest: framebuffer-vs-set-tiling
[369.898788] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[370.916801] [077/162] (731s left) kms_addfb_basic (invalid-get-prop)
[371.858477] Starting subtest: invalid-get-prop
[371.866840] Subtest invalid-get-prop: SUCCESS (0.000s)
[372.899144] [078/162] (729s left) kms_addfb_basic (invalid-get-prop-any)
[373.826637] Starting subtest: invalid-get-prop-any
[373.834852] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[374.833799] [079/162] (727s left) kms_addfb_basic (invalid-set-prop)
[375.772291] Starting subtest: invalid-set-prop
[375.780767] Subtest invalid-set-prop: SUCCESS (0.000s)
[376.802555] [080/162] (725s left) kms_addfb_basic (invalid-set-prop-any)
[377.770201] Starting subtest: invalid-set-prop-any
[377.778350] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[378.804266] [081/162] (724s left) kms_addfb_basic (no-handle)
[379.758873] Starting subtest: no-handle
[379.766629] Subtest no-handle: SUCCESS (0.000s)
[380.780580] [082/162] (722s left) kms_addfb_basic (size-max)
[382.399729] Starting subtest: size-max
[382.408960] Subtest size-max: SUCCESS (0.002s)
[382.820498] [083/162] (720s left) kms_addfb_basic (small-bo)
[384.430649] Starting subtest: small-bo
[384.440709] Subtest small-bo: SUCCESS (0.000s)
[384.800777] [084/162] (719s left) kms_addfb_basic (tile-pitch-mismatch)
[385.788128] Starting subtest: tile-pitch-mismatch
[385.795891] Subtest tile-pitch-mismatch: SUCCESS (0.003s)
[386.819158] [085/162] (717s left) kms_addfb_basic (too-high)
[388.421916] Starting subtest: too-high
[388.430843] Subtest too-high: SUCCESS (0.008s)
[388.834171] [086/162] (715s left) kms_addfb_basic (too-wide)
[390.469754] Starting subtest: too-wide
[390.479965] Subtest too-wide: SUCCESS (0.000s)
[390.863765] [087/162] (713s left) kms_addfb_basic (unused-handle)
[391.863674] Starting subtest: unused-handle
[391.872118] Subtest unused-handle: SUCCESS (0.000s)
[392.914913] [088/162] (712s left) kms_addfb_basic (unused-modifier)
[393.849361] Starting subtest: unused-modifier
[393.857863] Subtest unused-modifier: SUCCESS (0.000s)
[394.873728] [089/162] (710s left) kms_addfb_basic (unused-offsets)
[395.835632] Starting subtest: unused-offsets
[395.847567] Subtest unused-offsets: SUCCESS (0.000s)
[396.843825] [090/162] (708s left) kms_addfb_basic (unused-pitches)
[397.764808] Starting subtest: unused-pitches
[397.773960] Subtest unused-pitches: SUCCESS (0.001s)
[398.822995] [091/162] (707s left) kms_busy (basic)
[400.581679] Starting subtest: basic
[400.603813] Starting dynamic subtest: flip
[403.775672] Dynamic subtest flip: SUCCESS (3.175s)
[403.920341] Starting dynamic subtest: modeset
[408.668784] Dynamic subtest modeset: SUCCESS (4.882s)
[408.677082] Subtest basic: SUCCESS (8.075s)
[409.890251] [092/162] (696s left) kms_prop_blob (basic)
[411.039121] Starting subtest: basic
[411.048913] Subtest basic: SUCCESS (0.000s)
[411.392447] [093/162] (695s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[413.351805] Starting subtest: basic-busy-flip-before-cursor-atomic
[413.366982] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.001s)
[413.701982] [094/162] (693s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[415.312390] Starting subtest: basic-busy-flip-before-cursor-legacy
[415.326805] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[415.665413] [095/162] (691s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[417.242108] Starting subtest: basic-flip-after-cursor-atomic
[419.071926] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.824s)
[420.316181] [096/162] (686s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[421.904992] Starting subtest: basic-flip-after-cursor-legacy
[423.793885] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.892s)
[424.952148] [097/162] (682s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[426.528130] Starting subtest: basic-flip-after-cursor-varying-size
[428.369867] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.837s)
[429.557576] [098/162] (678s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[431.147834] Starting subtest: basic-flip-before-cursor-atomic
[433.034796] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.883s)
[434.262353] [099/162] (673s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[435.863958] Starting subtest: basic-flip-before-cursor-legacy
[437.736396] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.871s)
[438.924465] [100/162] (669s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[440.500744] Starting subtest: basic-flip-before-cursor-varying-size
[442.309584] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.809s)
[443.473348] [101/162] (665s left) kms_flip (basic-flip-vs-dpms)
[444.785869] Starting subtest: basic-flip-vs-dpms
[444.824597] Starting dynamic subtest: A-eDP1
[448.523433] Dynamic subtest A-eDP1: SUCCESS (3.694s)
[448.674637] Starting dynamic subtest: B-eDP1
[451.363545] Dynamic subtest B-eDP1: SUCCESS (2.835s)
[451.510803] Starting dynamic subtest: C-eDP1
[454.245851] Dynamic subtest C-eDP1: SUCCESS (2.872s)
[454.300159] Subtest basic-flip-vs-dpms: SUCCESS (9.522s)
[455.420431] [102/162] (653s left) kms_flip (basic-flip-vs-modeset)
[456.676282] Starting subtest: basic-flip-vs-modeset
[456.716195] Starting dynamic subtest: A-eDP1
[460.398175] Dynamic subtest A-eDP1: SUCCESS (3.668s)
[460.560532] Starting dynamic subtest: B-eDP1
[463.117394] Dynamic subtest B-eDP1: SUCCESS (2.715s)
[463.278817] Starting dynamic subtest: C-eDP1
[465.793799] Dynamic subtest C-eDP1: SUCCESS (2.664s)
[465.831462] Subtest basic-flip-vs-modeset: SUCCESS (9.167s)
[467.018231] [103/162] (642s left) kms_flip (basic-flip-vs-wf_vblank)
[468.283341] Starting subtest: basic-flip-vs-wf_vblank
[468.311884] Starting dynamic subtest: A-eDP1
[471.225944] Dynamic subtest A-eDP1: SUCCESS (2.848s)
[471.392854] Starting dynamic subtest: B-eDP1
[473.778861] Dynamic subtest B-eDP1: SUCCESS (2.575s)
[473.912686] Starting dynamic subtest: C-eDP1
[476.392714] Dynamic subtest C-eDP1: SUCCESS (2.577s)
[476.401107] Subtest basic-flip-vs-wf_vblank: SUCCESS (8.141s)
[478.390290] [104/162] (631s left) kms_flip (basic-plain-flip)
[479.605700] Starting subtest: basic-plain-flip
[479.644828] Starting dynamic subtest: A-eDP1
[482.305302] Dynamic subtest A-eDP1: SUCCESS (2.601s)
[482.430553] Starting dynamic subtest: B-eDP1
[484.678826] Dynamic subtest B-eDP1: SUCCESS (2.361s)
[484.809649] Starting dynamic subtest: C-eDP1
[487.149808] Dynamic subtest C-eDP1: SUCCESS (2.471s)
[487.169816] Subtest basic-plain-flip: SUCCESS (7.575s)
[489.479207] [105/162] (620s left) kms_force_connector_basic (force-connector-state)
[491.517717] Starting subtest: force-connector-state
[494.490912] Subtest force-connector-state: SUCCESS (2.917s)
[496.978742] [106/162] (613s left) kms_force_connector_basic (force-edid)
[498.506137] Starting subtest: force-edid
[499.072202] Subtest force-edid: SUCCESS (0.566s)
[501.041978] [107/162] (609s left) kms_force_connector_basic (force-load-detect)
[502.602998] Starting subtest: force-load-detect
[502.620623] Subtest force-load-detect: SKIP (0.000s)
[504.121651] [108/162] (606s left) kms_force_connector_basic (prune-stale-modes)
[505.648410] Starting subtest: prune-stale-modes
[506.243699] Subtest prune-stale-modes: SUCCESS (0.588s)
[508.084822] [109/162] (602s left) kms_frontbuffer_tracking (basic)
[509.829909] Starting subtest: basic
[519.232000] Subtest basic: SUCCESS (9.398s)
[519.692681] [110/162] (591s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[521.331724] Starting subtest: compare-crc-sanitycheck-xr24
[521.354719] Starting dynamic subtest: pipe-A-eDP-1
[522.507863] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.150s)
[522.668644] Starting dynamic subtest: pipe-B-eDP-1
[524.466905] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.931s)
[524.635827] Starting dynamic subtest: pipe-C-eDP-1
[526.578781] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.096s)
[526.590701] Subtest compare-crc-sanitycheck-xr24: SUCCESS (5.260s)
[527.805671] [111/162] (583s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[529.440754] Starting subtest: compare-crc-sanitycheck-nv12
[529.450558] Starting dynamic subtest: pipe-A-eDP-1
[531.311747] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.856s)
[531.476648] Starting dynamic subtest: pipe-B-eDP-1
[533.925360] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.582s)
[534.156799] Starting dynamic subtest: pipe-C-eDP-1
[534.175084] Dynamic subtest pipe-C-eDP-1: SKIP (0.007s)
[534.185192] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.528s)
[535.180563] [112/162] (576s left) kms_pipe_crc_basic (hang-read-crc)
[536.746340] Starting subtest: hang-read-crc
[536.780848] Starting dynamic subtest: pipe-A-eDP-1
[540.152884] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.378s)
[540.343856] Starting dynamic subtest: pipe-B-eDP-1
[544.237956] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.054s)
[544.392871] Starting dynamic subtest: pipe-C-eDP-1
[548.807596] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.540s)
[548.814958] Subtest hang-read-crc: SUCCESS (12.048s)
[549.967578] [113/162] (562s left) kms_pipe_crc_basic (nonblocking-crc)
[551.588521] Starting subtest: nonblocking-crc
[551.607711] Starting dynamic subtest: pipe-A-eDP-1
[552.963811] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.350s)
[553.154804] Starting dynamic subtest: pipe-B-eDP-1
[555.053555] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.058s)
[555.250106] Starting dynamic subtest: pipe-C-eDP-1
[557.402552] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.318s)
[557.410961] Subtest nonblocking-crc: SUCCESS (5.818s)
[558.625493] [114/162] (553s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[560.238382] Starting subtest: nonblocking-crc-frame-sequence
[560.275592] Starting dynamic subtest: pipe-A-eDP-1
[561.635122] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.362s)
[561.792851] Starting dynamic subtest: pipe-B-eDP-1
[563.725898] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.062s)
[563.904771] Starting dynamic subtest: pipe-C-eDP-1
[566.074567] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.311s)
[566.082187] Subtest nonblocking-crc-frame-sequence: SUCCESS (5.830s)
[567.273117] [115/162] (545s left) kms_pipe_crc_basic (read-crc)
[568.897544] Starting subtest: read-crc
[568.906608] Starting dynamic subtest: pipe-A-eDP-1
[570.193391] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.282s)
[570.387643] Starting dynamic subtest: pipe-B-eDP-1
[572.248801] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.033s)
[572.501651] Starting dynamic subtest: pipe-C-eDP-1
[574.533664] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.255s)
[574.540908] Subtest read-crc: SUCCESS (5.660s)
[575.745701] [116/162] (537s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[577.642863] Starting subtest: read-crc-frame-sequence
[577.651929] Starting dynamic subtest: pipe-A-eDP-1
[579.090903] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.442s)
[579.228483] Starting dynamic subtest: pipe-B-eDP-1
[581.258113] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.120s)
[581.411292] Starting dynamic subtest: pipe-C-eDP-1
[583.511819] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.244s)
[583.520147] Subtest read-crc-frame-sequence: SUCCESS (5.902s)
[584.708852] [117/162] (528s left) kms_psr (primary_page_flip)
[586.376794] Starting subtest: primary_page_flip
[589.301538] Subtest primary_page_flip: SUCCESS (2.900s)
[590.470578] [118/162] (523s left) kms_psr (cursor_plane_move)
[592.089929] Starting subtest: cursor_plane_move
[594.944696] Subtest cursor_plane_move: SUCCESS (2.841s)
[596.150671] [119/162] (517s left) kms_psr (sprite_plane_onoff)
[597.760463] Starting subtest: sprite_plane_onoff
[600.671516] Subtest sprite_plane_onoff: SUCCESS (2.908s)
[601.838519] [120/162] (512s left) kms_psr (primary_mmap_gtt)
[603.470090] Starting subtest: primary_mmap_gtt
[606.358455] Subtest primary_mmap_gtt: SUCCESS (2.887s)
[607.545154] [121/162] (506s left) kms_setmode (basic-clone-single-crtc)
[608.514879] Starting subtest: basic-clone-single-crtc
[608.537943] Subtest basic-clone-single-crtc: SKIP (0.007s)
[608.905751] [122/162] (505s left) i915_pm_backlight (basic-brightness)
[610.707918] Starting subtest: basic-brightness
[611.276848] Starting dynamic subtest: eDP-1
[612.102148] Dynamic subtest eDP-1: SUCCESS (0.876s)
[612.110459] Subtest basic-brightness: SUCCESS (1.385s)
[613.279675] [123/162] (501s left) i915_pm_rpm (basic-pci-d3-state)
[617.295702] Starting subtest: basic-pci-d3-state
[622.163505] Subtest basic-pci-d3-state: SUCCESS (4.851s)
[622.852795] [124/162] (492s left) i915_pm_rpm (basic-rte)
[623.781735] Starting subtest: basic-rte
[631.631273] Subtest basic-rte: SUCCESS (7.842s)
[634.314088] [125/162] (481s left) i915_pm_rps (basic-api)
[635.372758] Starting subtest: basic-api
[635.409352] Subtest basic-api: SUCCESS (0.039s)
[635.841785] [126/162] (479s left) prime_self_import (basic-llseek-bad)
[636.830516] Starting subtest: basic-llseek-bad
[636.850845] Subtest basic-llseek-bad: SUCCESS (0.019s)
[637.288498] [127/162] (478s left) prime_self_import (basic-llseek-size)
[638.264733] Starting subtest: basic-llseek-size
[638.298707] Subtest basic-llseek-size: SUCCESS (0.031s)
[638.728517] [128/162] (477s left) prime_self_import (basic-with_fd_dup)
[639.651770] Starting subtest: basic-with_fd_dup
[639.681127] Subtest basic-with_fd_dup: SUCCESS (0.029s)
[640.112234] [129/162] (476s left) prime_self_import (basic-with_one_bo)
[641.049939] Starting subtest: basic-with_one_bo
[641.065133] Subtest basic-with_one_bo: SUCCESS (0.034s)
[641.496943] [130/162] (475s left) prime_self_import (basic-with_one_bo_two_files)
[642.399933] Starting subtest: basic-with_one_bo_two_files
[642.430343] Subtest basic-with_one_bo_two_files: SUCCESS (0.025s)
[642.896805] [131/162] (474s left) prime_self_import (basic-with_two_bos)
[643.824821] Starting subtest: basic-with_two_bos
[643.860297] Subtest basic-with_two_bos: SUCCESS (0.033s)
[644.273704] [132/162] (473s left) prime_vgem (basic-fence-flip)
[645.305945] Starting subtest: basic-fence-flip
[647.008807] Subtest basic-fence-flip: SUCCESS (1.656s)
[647.554738] [133/162] (470s left) prime_vgem (basic-fence-mmap)
[648.607742] Starting subtest: basic-fence-mmap
[648.905747] Subtest basic-fence-mmap: SUCCESS (0.259s)
[649.261468] [134/162] (468s left) prime_vgem (basic-fence-read)
[650.296768] Starting subtest: basic-fence-read
[650.816850] Subtest basic-fence-read: SUCCESS (0.461s)
[651.218495] [135/162] (467s left) prime_vgem (basic-gtt)
[652.267435] Starting subtest: basic-gtt
[652.442560] Subtest basic-gtt: SUCCESS (0.119s)
[652.827695] [136/162] (465s left) prime_vgem (basic-read)
[653.847817] Starting subtest: basic-read
[654.044397] Subtest basic-read: SUCCESS (0.210s)
[654.477189] [137/162] (464s left) prime_vgem (basic-write)
[655.513403] Starting subtest: basic-write
[655.740760] Subtest basic-write: SUCCESS (0.225s)
[656.202619] [138/162] (462s left) vgem_basic (setversion)
[657.206287] Starting subtest: setversion
[657.221350] Subtest setversion: SUCCESS (0.000s)
[657.578447] [139/162] (461s left) vgem_basic (create)
[658.537804] Starting subtest: create
[658.544935] Subtest create: SUCCESS (0.003s)
[658.957554] [140/162] (460s left) vgem_basic (debugfs)
[659.956330] Starting subtest: debugfs
[659.964133] Subtest debugfs: SUCCESS (0.004s)
[660.366515] [141/162] (459s left) vgem_basic (dmabuf-export)
[661.389914] Starting subtest: dmabuf-export
[661.403842] Subtest dmabuf-export: SUCCESS (0.020s)
[661.822930] [142/162] (458s left) vgem_basic (dmabuf-fence)
[662.800146] Starting subtest: dmabuf-fence
[662.806944] Subtest dmabuf-fence: SUCCESS (0.004s)
[663.198433] [143/162] (457s left) vgem_basic (dmabuf-fence-before)
[664.181884] Starting subtest: dmabuf-fence-before
[664.191373] Subtest dmabuf-fence-before: SUCCESS (0.003s)
[664.556821] [144/162] (456s left) vgem_basic (dmabuf-mmap)
[665.530772] Starting subtest: dmabuf-mmap
[665.727159] Subtest dmabuf-mmap: SUCCESS (0.143s)
[666.074902] [145/162] (455s left) vgem_basic (mmap)
[667.042353] Starting subtest: mmap
[667.121470] Subtest mmap: SUCCESS (0.093s)
[667.499317] [146/162] (453s left) vgem_basic (second-client)
[668.464988] Starting subtest: second-client
[668.521185] Subtest second-client: SUCCESS (0.061s)
[668.901464] [147/162] (452s left) vgem_basic (sysfs)
[669.876868] Starting subtest: sysfs
[669.884822] Subtest sysfs: SUCCESS (0.007s)
[670.275828] [148/162] (451s left) core_hotunplug (unbind-rebind)
[671.582824] Starting subtest: unbind-rebind
[680.619149] Subtest unbind-rebind: SUCCESS (9.029s)
[680.995184] [149/162] (441s left) vgem_basic (unload)
[682.060626] Starting subtest: unload
[712.200624] Subtest unload: SUCCESS (30.084s)
[712.853513] [150/162] (409s left) i915_module_load (reload)
[713.816736] Starting subtest: reload
[726.359857] Subtest reload: SUCCESS (12.542s)
[726.872214] [151/162] (396s left) gem_lmem_swapping (basic)
[728.278372] Subtest basic: SKIP (0.000s)
[728.595526] [152/162] (394s left) gem_lmem_swapping (parallel-random-engines)
[729.646841] Subtest parallel-random-engines: SKIP (0.000s)
[729.978445] [153/162] (393s left) gem_lmem_swapping (random-engines)
[731.012965] Subtest random-engines: SKIP (0.000s)
[731.431951] [154/162] (392s left) gem_lmem_swapping (verify-random)
[732.449776] Subtest verify-random: SKIP (0.000s)
[732.787785] [155/162] (391s left) i915_pm_rpm (module-reload)
[737.506758] Starting subtest: module-reload
[770.917181] Subtest module-reload: SUCCESS (33.340s)
[771.035176] [156/162] (353s left) i915_selftest (live)
[772.128667] Starting subtest: live
[772.139227] Starting dynamic subtest: sanitycheck
[778.066463] Dynamic subtest sanitycheck: SUCCESS (5.930s)
[778.198319] Starting dynamic subtest: uncore
[784.039493] Dynamic subtest uncore: SUCCESS (5.932s)
[784.210779] Starting dynamic subtest: workarounds
[790.876268] Dynamic subtest workarounds: SUCCESS (6.836s)
[791.020112] Starting dynamic subtest: gt_engines
[797.156405] Dynamic subtest gt_engines: SUCCESS (6.221s)
[797.291309] Starting dynamic subtest: gt_timelines
[864.334662] Dynamic subtest gt_timelines: SUCCESS (67.200s)
[864.466740] Starting dynamic subtest: gt_contexts
[870.427843] Dynamic subtest gt_contexts: SUCCESS (6.078s)
[870.563826] Starting dynamic subtest: gt_lrc
[881.905880] Dynamic subtest gt_lrc: SUCCESS (11.453s)
[882.038990] Starting dynamic subtest: gt_mocs
[887.996731] Dynamic subtest gt_mocs: SUCCESS (6.078s)
[888.113191] Starting dynamic subtest: gt_pm
[905.341994] Dynamic subtest gt_pm: FAIL (15.747s)
[905.503854] Starting dynamic subtest: gt_heartbeat
[910.063809] Dynamic subtest gt_heartbeat: SUCCESS (6.281s)
[910.198909] Starting dynamic subtest: gt_tlb
[917.343611] Dynamic subtest gt_tlb: SUCCESS (7.263s)
[917.472295] Starting dynamic subtest: requests
[930.178798] Dynamic subtest requests: SUCCESS (12.823s)
[930.320824] Starting dynamic subtest: migrate
[941.928774] Dynamic subtest migrate: SUCCESS (11.731s)
[942.067526] Starting dynamic subtest: active
[948.011360] Dynamic subtest active: SUCCESS (6.064s)
[948.148316] Starting dynamic subtest: objects
[963.606455] Dynamic subtest objects: SUCCESS (15.576s)
[963.738508] Starting dynamic subtest: mman
[986.523706] Dynamic subtest mman: SUCCESS (22.898s)
[986.656617] Starting dynamic subtest: dmabuf
[992.242454] Dynamic subtest dmabuf: SUCCESS (5.710s)
[992.333640] Starting dynamic subtest: vma
[997.992888] Dynamic subtest vma: SUCCESS (5.726s)
[998.129117] Starting dynamic subtest: coherency
[1014.157303] Dynamic subtest coherency: SUCCESS (16.146s)
[1014.287844] Starting dynamic subtest: gtt
[1028.055776] Dynamic subtest gtt: SUCCESS (13.928s)
[1028.113872] Starting dynamic subtest: gem
[1035.385525] Dynamic subtest gem: SUCCESS (7.277s)
[1035.524927] Starting dynamic subtest: evict
[1054.157524] Dynamic subtest evict: SUCCESS (18.765s)
[1054.215932] Starting dynamic subtest: hugepages
[1066.786610] Dynamic subtest hugepages: SUCCESS (12.610s)
[1066.848648] Starting dynamic subtest: gem_contexts
[1118.489602] Dynamic subtest gem_contexts: SUCCESS (51.682s)
[1118.548154] Starting dynamic subtest: client
[1124.691132] Dynamic subtest client: SUCCESS (6.182s)
[1124.819833] Starting dynamic subtest: gem_migrate
[1130.404658] Dynamic subtest gem_migrate: SUCCESS (5.697s)
[1130.537674] Starting dynamic subtest: reset
[1140.970869] Dynamic subtest reset: SUCCESS (10.557s)
[1141.033625] Starting dynamic subtest: memory_region
[1146.683393] Dynamic subtest memory_region: SUCCESS (5.690s)
[1146.803853] Starting dynamic subtest: hangcheck
[1175.787605] Dynamic subtest hangcheck: SUCCESS (29.092s)
[1175.845726] Starting dynamic subtest: execlists
[1198.273721] Dynamic subtest execlists: SUCCESS (22.471s)
[1198.384806] Starting dynamic subtest: ring_submission
[1204.030167] Dynamic subtest ring_submission: SUCCESS (5.738s)
[1204.162735] Starting dynamic subtest: perf
[1210.070618] Dynamic subtest perf: SUCCESS (6.020s)
[1210.206490] Starting dynamic subtest: slpc
[1215.804152] Dynamic subtest slpc: SUCCESS (5.710s)
[1215.937367] Starting dynamic subtest: guc
[1221.522456] Dynamic subtest guc: SUCCESS (5.712s)
[1221.656975] Starting dynamic subtest: guc_multi_lrc
[1227.265182] Dynamic subtest guc_multi_lrc: SUCCESS (5.724s)
[1227.401506] Starting dynamic subtest: guc_hang
[1233.095635] Dynamic subtest guc_hang: SUCCESS (5.812s)
[1233.230461] Starting dynamic subtest: late_gt_pm
[1238.943984] Dynamic subtest late_gt_pm: SUCCESS (5.834s)
[1238.956642] Subtest live: FAIL (466.825s)
[1239.309707] Overall timeout time exceeded, stopping.
[1239.311990] Closing watchdogs
Done.