[104.870331] Initializing watchdogs
[104.871320]   /dev/watchdog0
[104.924874] [001/171] (960s left) i915_module_load (load)
[105.849435] Starting subtest: load
[113.929963] Subtest load: SUCCESS (8.083s)
[114.380286] [002/171] (951s left) core_auth (basic-auth)
[116.391986] Starting subtest: basic-auth
[116.402910] Subtest basic-auth: SUCCESS (0.006s)
[116.679560] [003/171] (949s left) debugfs_test (read_all_entries)
[117.578742] Starting subtest: read_all_entries
[118.115706] Subtest read_all_entries: SUCCESS (0.532s)
[119.159760] [004/171] (947s left) debugfs_test (basic-hwmon)
[120.069780] Starting subtest: basic-hwmon
[120.085376] Subtest basic-hwmon: SKIP (0.000s)
[120.974970] [005/171] (945s left) fbdev (eof)
[121.831650] Starting subtest: eof
[121.839751] Subtest eof: SUCCESS (0.001s)
[122.082238] [006/171] (944s left) fbdev (info)
[122.906743] Starting subtest: info
[122.914130] Subtest info: SUCCESS (0.000s)
[123.203216] [007/171] (943s left) fbdev (nullptr)
[124.080477] Starting subtest: nullptr
[124.087797] Subtest nullptr: SUCCESS (0.000s)
[124.357547] [008/171] (942s left) fbdev (read)
[125.241522] Starting subtest: read
[127.710586] Subtest read: SUCCESS (2.409s)
[127.953738] [009/171] (939s left) fbdev (write)
[128.815732] Starting subtest: write
[130.129337] Subtest write: SUCCESS (1.258s)
[130.372378] [010/171] (936s left) gem_basic (bad-close)
[131.175079] Starting subtest: bad-close
[131.190959] Subtest bad-close: SUCCESS (0.000s)
[131.487528] [011/171] (935s left) gem_basic (create-close)
[132.322570] Starting subtest: create-close
[132.340431] Subtest create-close: SUCCESS (0.001s)
[132.591097] [012/171] (935s left) gem_basic (create-fd-close)
[133.423786] Starting subtest: create-fd-close
[133.440602] Subtest create-fd-close: SUCCESS (0.001s)
[133.748224] [013/171] (934s left) gem_busy (busy@all-engines)
[134.777981] Starting subtest: busy
[134.785859] Starting dynamic subtest: all-engines
[135.113372] Dynamic subtest all-engines: SUCCESS (0.348s)
[135.120129] Subtest busy: SUCCESS (0.350s)
[135.756930] [014/171] (932s left) gem_close_race (basic-process)
[136.771293] Starting subtest: basic-process
[137.061121] Subtest basic-process: SUCCESS (0.292s)
[137.475393] [015/171] (930s left) gem_close_race (basic-threads)
[138.440847] Starting subtest: basic-threads
[139.976772] Subtest basic-threads: SUCCESS (1.554s)
[140.405721] [016/171] (927s left) gem_ctx_create (basic)
[141.400549] Starting subtest: basic
[141.407960] Subtest basic: SUCCESS (0.002s)
[141.793640] [017/171] (926s left) gem_ctx_create (basic-files)
[142.764755] Starting subtest: basic-files
[144.844762] Subtest basic-files: SUCCESS (2.126s)
[145.317536] [018/171] (923s left) gem_ctx_exec (basic)
[146.226659] Starting subtest: basic
[146.278715] Subtest basic: SUCCESS (0.044s)
[146.618393] [019/171] (922s left) gem_exec_basic (basic)
[147.537899] Starting subtest: basic
[147.545973] Starting dynamic subtest: rcs0-smem
[147.563150] Dynamic subtest rcs0-smem: SUCCESS (0.018s)
[147.621577] Starting dynamic subtest: bcs0-smem
[147.628348] Dynamic subtest bcs0-smem: SUCCESS (0.013s)
[147.680887] Starting dynamic subtest: vcs0-smem
[147.687702] Dynamic subtest vcs0-smem: SUCCESS (0.005s)
[147.758977] Starting dynamic subtest: vecs0-smem
[147.769883] Dynamic subtest vecs0-smem: SUCCESS (0.005s)
[147.776448] Subtest basic: SUCCESS (0.053s)
[148.111672] [020/171] (920s left) gem_exec_create (basic)
[149.109463] Starting subtest: basic
[149.116973] Starting dynamic subtest: smem
[151.255842] Dynamic subtest smem: SUCCESS (2.200s)
[151.262886] Subtest basic: SUCCESS (2.201s)
[151.712348] [021/171] (917s left) gem_exec_fence (basic-busy)
[152.904392] Starting subtest: basic-busy
[152.914510] Starting dynamic subtest: rcs0
[152.928310] Dynamic subtest rcs0: SUCCESS (0.078s)
[153.004999] Starting dynamic subtest: bcs0
[153.021841] Dynamic subtest bcs0: SUCCESS (0.055s)
[153.085103] Starting dynamic subtest: vcs0
[153.098741] Dynamic subtest vcs0: SUCCESS (0.054s)
[153.155753] Starting dynamic subtest: vecs0
[153.169825] Dynamic subtest vecs0: SUCCESS (0.050s)
[153.177989] Subtest basic-busy: SUCCESS (0.242s)
[155.163191] [022/171] (914s left) gem_exec_fence (basic-wait)
[156.339341] Starting subtest: basic-wait
[156.347745] Starting dynamic subtest: rcs0
[156.356838] Dynamic subtest rcs0: SUCCESS (0.062s)
[156.481347] Starting dynamic subtest: bcs0
[156.488878] Dynamic subtest bcs0: SUCCESS (0.054s)
[156.548186] Starting dynamic subtest: vcs0
[156.554577] Dynamic subtest vcs0: SUCCESS (0.064s)
[156.606528] Starting dynamic subtest: vecs0
[156.614142] Dynamic subtest vecs0: SUCCESS (0.048s)
[156.623439] Subtest basic-wait: SUCCESS (0.234s)
[158.297969] [023/171] (911s left) gem_exec_fence (basic-await)
[159.470924] Starting subtest: basic-await
[159.477831] Starting dynamic subtest: rcs0
[159.843139] Dynamic subtest rcs0: SUCCESS (0.376s)
[159.901926] Starting dynamic subtest: bcs0
[160.188224] Dynamic subtest bcs0: SUCCESS (0.343s)
[160.246834] Starting dynamic subtest: vcs0
[160.551144] Dynamic subtest vcs0: SUCCESS (0.357s)
[160.607638] Starting dynamic subtest: vecs0
[160.903780] Dynamic subtest vecs0: SUCCESS (0.358s)
[160.910763] Subtest basic-await: SUCCESS (1.440s)
[162.576330] [024/171] (907s left) gem_exec_fence (nb-await)
[163.743879] Starting subtest: nb-await
[163.751496] Starting dynamic subtest: rcs0
[163.906744] Dynamic subtest rcs0: SUCCESS (0.162s)
[163.961199] Starting dynamic subtest: bcs0
[163.987993] Dynamic subtest bcs0: SUCCESS (0.131s)
[164.050166] Starting dynamic subtest: vcs0
[164.122708] Dynamic subtest vcs0: SUCCESS (0.134s)
[164.179617] Starting dynamic subtest: vecs0
[164.243709] Dynamic subtest vecs0: SUCCESS (0.123s)
[164.250852] Subtest nb-await: SUCCESS (0.555s)
[165.964432] [025/171] (903s left) gem_exec_gttfill (basic)
[166.928412] Starting subtest: basic
[172.226731] Subtest basic: SKIP (5.248s)
[172.690826] [026/171] (897s left) gem_exec_parallel (engines)
[174.169923] Starting subtest: engines
[174.177339] Starting dynamic subtest: basic
[186.702359] Dynamic subtest basic: SUCCESS (12.552s)
[186.766508] Starting dynamic subtest: contexts
[202.657993] Dynamic subtest contexts: SUCCESS (15.936s)
[202.796196] Starting dynamic subtest: fds
[221.442495] Dynamic subtest fds: SUCCESS (18.835s)
[221.589896] Starting dynamic subtest: userptr
[235.440317] Dynamic subtest userptr: SUCCESS (13.962s)
[235.446930] Subtest engines: SUCCESS (61.290s)
[235.934088] [027/171] (834s left) gem_exec_store (basic)
[236.897750] Starting subtest: basic
[237.065120] Subtest basic: SUCCESS (0.188s)
[237.556236] [028/171] (832s left) gem_flink_basic (bad-flink)
[238.450564] Starting subtest: bad-flink
[238.458908] Subtest bad-flink: SUCCESS (0.000s)
[238.847990] [029/171] (831s left) gem_flink_basic (bad-open)
[239.673209] Starting subtest: bad-open
[239.683513] Subtest bad-open: SUCCESS (0.000s)
[240.034132] [030/171] (830s left) gem_flink_basic (basic)
[240.864739] Starting subtest: basic
[240.872954] Subtest basic: SUCCESS (0.001s)
[241.215308] [031/171] (829s left) gem_flink_basic (double-flink)
[242.014696] Starting subtest: double-flink
[242.024371] Subtest double-flink: SUCCESS (0.000s)
[242.380205] [032/171] (828s left) gem_flink_basic (flink-lifetime)
[243.191857] Starting subtest: flink-lifetime
[243.200074] Subtest flink-lifetime: SUCCESS (0.009s)
[243.605726] [033/171] (827s left) gem_huc_copy (huc-copy)
[244.486949] Starting subtest: huc-copy
[244.504730] Subtest huc-copy: SKIP (0.000s)
[244.792250] [034/171] (826s left) gem_linear_blits (basic)
[246.686810] Subtest basic: SKIP (0.000s)
[246.998270] [035/171] (824s left) gem_mmap (basic)
[248.325696] Starting subtest: basic
[248.361758] Subtest basic: SUCCESS (0.004s)
[248.692722] [036/171] (823s left) gem_mmap_gtt (basic)
[249.494775] Starting subtest: basic
[249.503124] Subtest basic: SUCCESS (0.006s)
[249.868263] [037/171] (822s left) gem_render_linear_blits (basic)
[250.739774] Starting subtest: basic
[250.998848] Subtest basic: SUCCESS (0.265s)
[251.286214] [038/171] (821s left) gem_render_tiled_blits (basic)
[252.141886] Starting subtest: basic
[252.512891] Subtest basic: SUCCESS (0.367s)
[252.726316] [039/171] (819s left) gem_ringfill (basic-all)
[254.561770] Starting subtest: basic-all
[256.093492] Subtest basic-all: SUCCESS (1.508s)
[256.489783] [040/171] (816s left) gem_softpin (allocator-basic)
[257.362760] Starting subtest: allocator-basic
[258.512556] Subtest allocator-basic: SUCCESS (1.089s)
[258.959638] [041/171] (813s left) gem_softpin (allocator-basic-reserve)
[259.798105] Starting subtest: allocator-basic-reserve
[260.912797] Subtest allocator-basic-reserve: SUCCESS (1.058s)
[261.376932] [042/171] (811s left) gem_softpin (safe-alignment)
[262.232254] Starting subtest: safe-alignment
[262.412606] Subtest safe-alignment: SUCCESS (0.127s)
[262.719136] [043/171] (810s left) gem_sync (basic-all)
[263.657875] Starting subtest: basic-all
[267.703626] Subtest basic-all: SUCCESS (4.054s)
[268.235773] [044/171] (805s left) gem_sync (basic-each)
[269.164818] Starting subtest: basic-each
[272.217489] Subtest basic-each: SUCCESS (3.052s)
[272.727083] [045/171] (801s left) gem_tiled_blits (basic)
[274.583497] Subtest basic: SKIP (0.000s)
[274.855900] [046/171] (799s left) gem_tiled_fence_blits (basic)
[277.135877] Starting subtest: basic
[277.189286] Subtest basic: SUCCESS (0.107s)
[277.608137] [047/171] (796s left) gem_tiled_pread_basic
[280.165416] [048/171] (794s left) gem_wait (busy@all-engines)
[281.192312] Starting subtest: busy
[281.199163] Starting dynamic subtest: all-engines
[281.745902] Dynamic subtest all-engines: SUCCESS (0.580s)
[281.753333] Subtest busy: SUCCESS (0.583s)
[282.370384] [049/171] (792s left) gem_wait (wait@all-engines)
[283.368170] Starting subtest: wait
[283.376372] Starting dynamic subtest: all-engines
[284.476359] Dynamic subtest all-engines: SUCCESS (1.162s)
[284.483953] Subtest wait: SUCCESS (1.164s)
[285.118972] [050/171] (789s left) i915_getparams_basic (basic-eu-total)
[285.925109] Starting subtest: basic-eu-total
[285.938747] Subtest basic-eu-total: SUCCESS (0.000s)
[286.313869] [051/171] (788s left) i915_getparams_basic (basic-subslice-total)
[287.128423] Starting subtest: basic-subslice-total
[287.143253] Subtest basic-subslice-total: SUCCESS (0.000s)
[287.427845] [052/171] (787s left) i915_hangman (error-state-basic)
[288.394774] Starting subtest: error-state-basic
[288.455947] Subtest error-state-basic: SUCCESS (0.057s)
[288.769991] [053/171] (786s left) i915_pciid
[289.959096] [054/171] (785s left) kms_addfb_basic (addfb25-bad-modifier)
[290.795403] Starting subtest: addfb25-bad-modifier
[290.803432] Subtest addfb25-bad-modifier: SUCCESS (0.003s)
[292.160485] [055/171] (783s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[293.035336] Starting subtest: addfb25-framebuffer-vs-set-tiling
[293.045613] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[294.026336] [056/171] (782s left) kms_addfb_basic (addfb25-modifier-no-flag)
[294.852998] Starting subtest: addfb25-modifier-no-flag
[294.860527] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[295.860386] [057/171] (780s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[296.701776] Starting subtest: addfb25-x-tiled-legacy
[296.707930] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[297.728394] [058/171] (778s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[298.592597] Starting subtest: addfb25-x-tiled-mismatch-legacy
[298.600793] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.002s)
[299.578158] [059/171] (777s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[301.076816] Starting subtest: addfb25-yf-tiled-legacy
[301.083590] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.001s)
[301.428479] [060/171] (775s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[302.909242] Starting subtest: addfb25-y-tiled-legacy
[302.916590] Subtest addfb25-y-tiled-legacy: SUCCESS (0.001s)
[303.219964] [061/171] (774s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[304.708811] Starting subtest: addfb25-y-tiled-small-legacy
[304.716797] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.002s)
[305.032209] [062/171] (772s left) kms_addfb_basic (bad-pitch-0)
[305.847613] Starting subtest: bad-pitch-0
[305.856679] Subtest bad-pitch-0: SUCCESS (0.000s)
[306.871774] [063/171] (770s left) kms_addfb_basic (bad-pitch-1024)
[307.689645] Starting subtest: bad-pitch-1024
[307.697622] Subtest bad-pitch-1024: SUCCESS (0.000s)
[308.700918] [064/171] (769s left) kms_addfb_basic (bad-pitch-128)
[309.507864] Starting subtest: bad-pitch-128
[309.516182] Subtest bad-pitch-128: SUCCESS (0.000s)
[310.502465] [065/171] (767s left) kms_addfb_basic (bad-pitch-256)
[311.332577] Starting subtest: bad-pitch-256
[311.340567] Subtest bad-pitch-256: SUCCESS (0.001s)
[312.360931] [066/171] (766s left) kms_addfb_basic (bad-pitch-32)
[313.201334] Starting subtest: bad-pitch-32
[313.208545] Subtest bad-pitch-32: SUCCESS (0.000s)
[314.197457] [067/171] (764s left) kms_addfb_basic (bad-pitch-63)
[315.029398] Starting subtest: bad-pitch-63
[315.035981] Subtest bad-pitch-63: SUCCESS (0.000s)
[316.035487] [068/171] (763s left) kms_addfb_basic (bad-pitch-65536)
[316.883889] Starting subtest: bad-pitch-65536
[316.891489] Subtest bad-pitch-65536: SUCCESS (0.003s)
[317.903246] [069/171] (761s left) kms_addfb_basic (bad-pitch-999)
[318.733733] Starting subtest: bad-pitch-999
[318.741691] Subtest bad-pitch-999: SUCCESS (0.000s)
[319.712346] [070/171] (759s left) kms_addfb_basic (basic)
[320.590757] Starting subtest: basic
[320.597726] Subtest basic: SUCCESS (0.000s)
[321.601530] [071/171] (758s left) kms_addfb_basic (basic-x-tiled-legacy)
[322.409816] Starting subtest: basic-x-tiled-legacy
[322.418133] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[323.397388] [072/171] (756s left) kms_addfb_basic (basic-y-tiled-legacy)
[324.233108] Starting subtest: basic-y-tiled-legacy
[324.240988] Subtest basic-y-tiled-legacy: SUCCESS (0.002s)
[325.215769] [073/171] (755s left) kms_addfb_basic (bo-too-small)
[326.721237] Starting subtest: bo-too-small
[326.728738] Subtest bo-too-small: SUCCESS (0.002s)
[327.057924] [074/171] (753s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[328.522303] Starting subtest: bo-too-small-due-to-tiling
[328.530499] Subtest bo-too-small-due-to-tiling: SUCCESS (0.003s)
[328.875885] [075/171] (751s left) kms_addfb_basic (clobberred-modifier)
[329.696493] Starting subtest: clobberred-modifier
[329.704514] Subtest clobberred-modifier: SUCCESS (0.001s)
[330.719669] [076/171] (750s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[331.564494] Starting subtest: framebuffer-vs-set-tiling
[331.570783] Subtest framebuffer-vs-set-tiling: SUCCESS (0.001s)
[332.596556] [077/171] (748s left) kms_addfb_basic (invalid-get-prop)
[333.407786] Starting subtest: invalid-get-prop
[333.415800] Subtest invalid-get-prop: SUCCESS (0.001s)
[334.411947] [078/171] (747s left) kms_addfb_basic (invalid-get-prop-any)
[335.248298] Starting subtest: invalid-get-prop-any
[335.256134] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[336.256928] [079/171] (745s left) kms_addfb_basic (invalid-set-prop)
[337.082782] Starting subtest: invalid-set-prop
[337.090859] Subtest invalid-set-prop: SUCCESS (0.000s)
[338.099085] [080/171] (743s left) kms_addfb_basic (invalid-set-prop-any)
[338.921691] Starting subtest: invalid-set-prop-any
[338.929139] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[339.919479] [081/171] (742s left) kms_addfb_basic (no-handle)
[340.755817] Starting subtest: no-handle
[340.762711] Subtest no-handle: SUCCESS (0.000s)
[341.792115] [082/171] (740s left) kms_addfb_basic (size-max)
[343.293329] Starting subtest: size-max
[343.300956] Subtest size-max: SUCCESS (0.001s)
[343.674407] [083/171] (739s left) kms_addfb_basic (small-bo)
[345.145463] Starting subtest: small-bo
[345.154819] Subtest small-bo: SUCCESS (0.000s)
[345.507260] [084/171] (737s left) kms_addfb_basic (tile-pitch-mismatch)
[346.401611] Starting subtest: tile-pitch-mismatch
[346.408971] Subtest tile-pitch-mismatch: SUCCESS (0.002s)
[347.384112] [085/171] (735s left) kms_addfb_basic (too-high)
[348.882908] Starting subtest: too-high
[348.892463] Subtest too-high: SUCCESS (0.006s)
[349.234466] [086/171] (734s left) kms_addfb_basic (too-wide)
[350.717766] Starting subtest: too-wide
[350.725526] Subtest too-wide: SUCCESS (0.000s)
[351.059492] [087/171] (732s left) kms_addfb_basic (unused-handle)
[351.869497] Starting subtest: unused-handle
[351.876842] Subtest unused-handle: SUCCESS (0.000s)
[352.869616] [088/171] (731s left) kms_addfb_basic (unused-modifier)
[353.703371] Starting subtest: unused-modifier
[353.712414] Subtest unused-modifier: SUCCESS (0.001s)
[354.721958] [089/171] (729s left) kms_addfb_basic (unused-offsets)
[355.586548] Starting subtest: unused-offsets
[355.593895] Subtest unused-offsets: SUCCESS (0.000s)
[356.525766] [090/171] (727s left) kms_addfb_basic (unused-pitches)
[357.359786] Starting subtest: unused-pitches
[357.366939] Subtest unused-pitches: SUCCESS (0.000s)
[358.344190] [091/171] (726s left) kms_busy (basic)
[359.899092] Starting subtest: basic
[359.909957] Starting dynamic subtest: flip
[363.058666] Dynamic subtest flip: SUCCESS (3.134s)
[363.218273] Starting dynamic subtest: modeset
[367.777964] Dynamic subtest modeset: SUCCESS (4.716s)
[367.788683] Subtest basic: SUCCESS (7.863s)
[368.957312] [092/171] (716s left) kms_chamelium_hpd (dp-hpd-fast)
[370.962846] Subtest dp-hpd-fast: SKIP (0.000s)
[372.001881] [093/171] (713s left) kms_chamelium_edid (dp-edid-read)
[373.920856] Subtest dp-edid-read: SKIP (0.000s)
[374.914308] [094/171] (710s left) kms_chamelium_frames (dp-crc-fast)
[377.009744] Subtest dp-crc-fast: SKIP (0.000s)
[377.998427] [095/171] (707s left) kms_chamelium_hpd (hdmi-hpd-fast)
[379.898283] Subtest hdmi-hpd-fast: SKIP (0.000s)
[380.875100] [096/171] (704s left) kms_chamelium_edid (hdmi-edid-read)
[382.854525] Subtest hdmi-edid-read: SKIP (0.000s)
[383.817359] [097/171] (702s left) kms_chamelium_frames (hdmi-crc-fast)
[385.758614] Subtest hdmi-crc-fast: SKIP (0.000s)
[386.752181] [098/171] (699s left) kms_chamelium_hpd (vga-hpd-fast)
[388.681996] Subtest vga-hpd-fast: SKIP (0.000s)
[389.663906] [099/171] (696s left) kms_chamelium_edid (vga-edid-read)
[391.626761] Subtest vga-edid-read: SKIP (0.000s)
[392.605940] [100/171] (694s left) kms_prop_blob (basic)
[393.424340] Starting subtest: basic
[393.431646] Subtest basic: SUCCESS (0.000s)
[393.737216] [101/171] (693s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[395.289471] Starting subtest: basic-busy-flip-before-cursor-atomic
[395.305806] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[395.651200] [102/171] (691s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[397.145814] Starting subtest: basic-busy-flip-before-cursor-legacy
[397.159842] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.001s)
[397.509348] [103/171] (689s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[399.032310] Starting subtest: basic-flip-after-cursor-atomic
[400.911309] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.881s)
[402.089611] [104/171] (685s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[403.557577] Starting subtest: basic-flip-after-cursor-legacy
[405.370921] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.813s)
[406.516275] [105/171] (681s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[407.990643] Starting subtest: basic-flip-after-cursor-varying-size
[409.814880] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.828s)
[410.974538] [106/171] (677s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[412.471515] Starting subtest: basic-flip-before-cursor-atomic
[414.329973] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.862s)
[415.482324] [107/171] (672s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[416.941396] Starting subtest: basic-flip-before-cursor-legacy
[418.761454] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.819s)
[419.942737] [108/171] (668s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[421.429290] Starting subtest: basic-flip-before-cursor-varying-size
[423.325616] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.903s)
[424.473892] [109/171] (664s left) kms_flip (basic-flip-vs-dpms)
[425.582853] Starting subtest: basic-flip-vs-dpms
[425.631849] Starting dynamic subtest: A-eDP1
[429.329961] Dynamic subtest A-eDP1: SUCCESS (3.702s)
[429.463439] Starting dynamic subtest: B-eDP1
[432.135942] Dynamic subtest B-eDP1: SUCCESS (2.802s)
[432.296588] Starting dynamic subtest: C-eDP1
[434.967777] Dynamic subtest C-eDP1: SUCCESS (2.816s)
[435.018117] Subtest basic-flip-vs-dpms: SUCCESS (9.437s)
[436.081949] [110/171] (652s left) kms_flip (basic-flip-vs-modeset)
[437.201279] Starting subtest: basic-flip-vs-modeset
[437.253790] Starting dynamic subtest: A-eDP1
[440.895586] Dynamic subtest A-eDP1: SUCCESS (3.648s)
[441.027826] Starting dynamic subtest: B-eDP1
[443.584515] Dynamic subtest B-eDP1: SUCCESS (2.681s)
[443.720307] Starting dynamic subtest: C-eDP1
[446.278326] Dynamic subtest C-eDP1: SUCCESS (2.694s)
[446.339296] Subtest basic-flip-vs-modeset: SUCCESS (9.139s)
[447.470397] [111/171] (641s left) kms_flip (basic-flip-vs-wf_vblank)
[448.668138] Starting subtest: basic-flip-vs-wf_vblank
[448.692968] Starting dynamic subtest: A-eDP1
[451.609650] Dynamic subtest A-eDP1: SUCCESS (2.892s)
[451.727147] Starting dynamic subtest: B-eDP1
[454.300622] Dynamic subtest B-eDP1: SUCCESS (2.669s)
[454.435551] Starting dynamic subtest: C-eDP1
[456.961947] Dynamic subtest C-eDP1: SUCCESS (2.640s)
[456.970281] Subtest basic-flip-vs-wf_vblank: SUCCESS (8.325s)
[458.906804] [112/171] (630s left) kms_flip (basic-plain-flip)
[460.036878] Starting subtest: basic-plain-flip
[460.083133] Starting dynamic subtest: A-eDP1
[462.744470] Dynamic subtest A-eDP1: SUCCESS (2.607s)
[462.811511] Starting dynamic subtest: B-eDP1
[465.143707] Dynamic subtest B-eDP1: SUCCESS (2.404s)
[465.277797] Starting dynamic subtest: C-eDP1
[467.525571] Dynamic subtest C-eDP1: SUCCESS (2.362s)
[467.533729] Subtest basic-plain-flip: SUCCESS (7.490s)
[469.389407] [113/171] (620s left) kms_force_connector_basic (force-connector-state)
[470.835907] Starting subtest: force-connector-state
[473.650182] Subtest force-connector-state: SUCCESS (2.807s)
[476.165107] [114/171] (613s left) kms_force_connector_basic (force-edid)
[477.588617] Starting subtest: force-edid
[478.108820] Subtest force-edid: SUCCESS (0.522s)
[479.579788] [115/171] (610s left) kms_force_connector_basic (force-load-detect)
[481.007172] Starting subtest: force-load-detect
[481.025754] Subtest force-load-detect: SKIP (0.000s)
[482.495386] [116/171] (608s left) kms_force_connector_basic (prune-stale-modes)
[483.912358] Starting subtest: prune-stale-modes
[484.487074] Subtest prune-stale-modes: SUCCESS (0.574s)
[486.285321] [117/171] (604s left) kms_frontbuffer_tracking (basic)
[487.809786] Starting subtest: basic
[496.744730] Subtest basic: SUCCESS (8.946s)
[497.496901] [118/171] (593s left) kms_pipe_crc_basic (compare-crc-sanitycheck)
[499.008679] Starting subtest: compare-crc-sanitycheck
[499.017854] Starting dynamic subtest: pipe-A-eDP-1
[500.235643] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.216s)
[500.369640] Starting dynamic subtest: pipe-B-eDP-1
[502.189328] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.928s)
[502.350805] Starting dynamic subtest: pipe-C-eDP-1
[504.216216] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.018s)
[504.224287] Subtest compare-crc-sanitycheck: SUCCESS (5.217s)
[505.467168] [119/171] (585s left) kms_pipe_crc_basic (hang-read-crc)
[506.932867] Starting subtest: hang-read-crc
[506.961475] Starting dynamic subtest: pipe-A-eDP-1
[510.191899] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.232s)
[510.365596] Starting dynamic subtest: pipe-B-eDP-1
[514.225949] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.019s)
[514.377910] Starting dynamic subtest: pipe-C-eDP-1
[518.671740] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.435s)
[518.679950] Subtest hang-read-crc: SUCCESS (11.735s)
[519.860405] [120/171] (571s left) kms_pipe_crc_basic (nonblocking-crc)
[521.429726] Starting subtest: nonblocking-crc
[521.455547] Starting dynamic subtest: pipe-A-eDP-1
[522.816555] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.368s)
[522.993842] Starting dynamic subtest: pipe-B-eDP-1
[524.849818] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.013s)
[525.021763] Starting dynamic subtest: pipe-C-eDP-1
[527.156679] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.290s)
[527.164851] Subtest nonblocking-crc: SUCCESS (5.726s)
[528.310355] [121/171] (563s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[529.795640] Starting subtest: nonblocking-crc-frame-sequence
[529.817414] Starting dynamic subtest: pipe-A-eDP-1
[531.147784] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.348s)
[531.297540] Starting dynamic subtest: pipe-B-eDP-1
[533.181359] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.019s)
[533.359338] Starting dynamic subtest: pipe-C-eDP-1
[535.430744] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.233s)
[535.437836] Subtest nonblocking-crc-frame-sequence: SUCCESS (5.652s)
[536.604871] [122/171] (555s left) kms_pipe_crc_basic (read-crc)
[538.164722] Starting subtest: read-crc
[538.174175] Starting dynamic subtest: pipe-A-eDP-1
[539.504546] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.327s)
[539.702903] Starting dynamic subtest: pipe-B-eDP-1
[541.473829] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.951s)
[541.653945] Starting dynamic subtest: pipe-C-eDP-1
[543.749803] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.258s)
[543.758436] Subtest read-crc: SUCCESS (5.590s)
[544.911743] [123/171] (547s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[546.415129] Starting subtest: read-crc-frame-sequence
[546.423737] Starting dynamic subtest: pipe-A-eDP-1
[547.781341] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.348s)
[547.935402] Starting dynamic subtest: pipe-B-eDP-1
[549.736871] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.940s)
[549.881967] Starting dynamic subtest: pipe-C-eDP-1
[551.949739] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.199s)
[551.957446] Subtest read-crc-frame-sequence: SUCCESS (5.542s)
[553.185264] [124/171] (539s left) kms_psr (primary_page_flip)
[554.703896] Starting subtest: primary_page_flip
[557.598142] Subtest primary_page_flip: SUCCESS (2.907s)
[558.761128] [125/171] (534s left) kms_psr (cursor_plane_move)
[560.279175] Starting subtest: cursor_plane_move
[563.078821] Subtest cursor_plane_move: SUCCESS (2.784s)
[564.248689] [126/171] (528s left) kms_psr (sprite_plane_onoff)
[565.759598] Starting subtest: sprite_plane_onoff
[568.671875] Subtest sprite_plane_onoff: SUCCESS (2.910s)
[569.814665] [127/171] (523s left) kms_psr (primary_mmap_gtt)
[571.314670] Starting subtest: primary_mmap_gtt
[574.270613] Subtest primary_mmap_gtt: SUCCESS (2.945s)
[575.442386] [128/171] (518s left) kms_setmode (basic-clone-single-crtc)
[576.338199] Starting subtest: basic-clone-single-crtc
[576.360866] Subtest basic-clone-single-crtc: SKIP (0.007s)
[576.715277] [129/171] (517s left) i915_pm_backlight (basic-brightness)
[578.245136] Starting subtest: basic-brightness
[578.814667] Starting dynamic subtest: eDP-1
[579.475967] Dynamic subtest eDP-1: SUCCESS (0.710s)
[579.494839] Subtest basic-brightness: SUCCESS (1.224s)
[580.603195] [130/171] (513s left) i915_pm_rpm (basic-pci-d3-state)
[584.395905] Starting subtest: basic-pci-d3-state
[589.308473] Subtest basic-pci-d3-state: SUCCESS (4.907s)
[589.968144] [131/171] (504s left) i915_pm_rpm (basic-rte)
[590.777269] Starting subtest: basic-rte
[598.598899] Subtest basic-rte: SUCCESS (7.796s)
[604.354551] [132/171] (490s left) i915_pm_rps (basic-api)
[605.320513] Starting subtest: basic-api
[605.328525] Subtest basic-api: SUCCESS (0.035s)
[605.702859] [133/171] (489s left) prime_self_import (basic-llseek-bad)
[606.589943] Starting subtest: basic-llseek-bad
[606.617163] Subtest basic-llseek-bad: SUCCESS (0.015s)
[607.014456] [134/171] (488s left) prime_self_import (basic-llseek-size)
[607.855576] Starting subtest: basic-llseek-size
[607.882579] Subtest basic-llseek-size: SUCCESS (0.026s)
[608.292339] [135/171] (487s left) prime_self_import (basic-with_fd_dup)
[609.113853] Starting subtest: basic-with_fd_dup
[609.141840] Subtest basic-with_fd_dup: SUCCESS (0.025s)
[609.543714] [136/171] (486s left) prime_self_import (basic-with_one_bo)
[610.372973] Starting subtest: basic-with_one_bo
[610.401650] Subtest basic-with_one_bo: SUCCESS (0.028s)
[610.813754] [137/171] (485s left) prime_self_import (basic-with_one_bo_two_files)
[611.631842] Starting subtest: basic-with_one_bo_two_files
[611.640825] Subtest basic-with_one_bo_two_files: SUCCESS (0.018s)
[612.022513] [138/171] (484s left) prime_self_import (basic-with_two_bos)
[612.849809] Starting subtest: basic-with_two_bos
[612.876897] Subtest basic-with_two_bos: SUCCESS (0.024s)
[613.279311] [139/171] (483s left) prime_vgem (basic-fence-flip)
[614.344810] Starting subtest: basic-fence-flip
[615.990669] Subtest basic-fence-flip: SUCCESS (1.588s)
[616.507737] [140/171] (480s left) prime_vgem (basic-fence-mmap)
[617.419588] Starting subtest: basic-fence-mmap
[617.693922] Subtest basic-fence-mmap: SUCCESS (0.217s)
[618.021770] [141/171] (478s left) prime_vgem (basic-fence-read)
[618.940436] Starting subtest: basic-fence-read
[619.393764] Subtest basic-fence-read: SUCCESS (0.400s)
[619.739592] [142/171] (477s left) prime_vgem (basic-gtt)
[620.667426] Starting subtest: basic-gtt
[620.843839] Subtest basic-gtt: SUCCESS (0.113s)
[621.156521] [143/171] (476s left) prime_vgem (basic-read)
[622.091554] Starting subtest: basic-read
[622.283618] Subtest basic-read: SUCCESS (0.192s)
[622.643720] [144/171] (474s left) prime_vgem (basic-write)
[623.577905] Starting subtest: basic-write
[623.777813] Subtest basic-write: SUCCESS (0.213s)
[624.111806] [145/171] (473s left) prime_vgem (basic-userptr)
[625.056915] Starting subtest: basic-userptr
[625.140523] Subtest basic-userptr: SKIP (0.076s)
[625.607241] [146/171] (472s left) vgem_basic (setversion)
[626.548662] Starting subtest: setversion
[626.565928] Subtest setversion: SUCCESS (0.000s)
[626.903941] [147/171] (471s left) vgem_basic (create)
[627.793406] Starting subtest: create
[627.801121] Subtest create: SUCCESS (0.002s)
[628.090548] [148/171] (470s left) vgem_basic (debugfs)
[628.978758] Starting subtest: debugfs
[628.986891] Subtest debugfs: SUCCESS (0.005s)
[629.272145] [149/171] (469s left) vgem_basic (dmabuf-export)
[630.159822] Starting subtest: dmabuf-export
[630.187093] Subtest dmabuf-export: SUCCESS (0.013s)
[630.552773] [150/171] (468s left) vgem_basic (dmabuf-fence)
[631.439219] Starting subtest: dmabuf-fence
[631.447971] Subtest dmabuf-fence: SUCCESS (0.003s)
[631.751382] [151/171] (467s left) vgem_basic (dmabuf-fence-before)
[632.648693] Starting subtest: dmabuf-fence-before
[632.656988] Subtest dmabuf-fence-before: SUCCESS (0.004s)
[632.938404] [152/171] (466s left) vgem_basic (dmabuf-mmap)
[633.821661] Starting subtest: dmabuf-mmap
[634.020617] Subtest dmabuf-mmap: SUCCESS (0.142s)
[634.249344] [153/171] (465s left) vgem_basic (mmap)
[635.132756] Starting subtest: mmap
[635.277711] Subtest mmap: SUCCESS (0.095s)
[635.504665] [154/171] (464s left) vgem_basic (second-client)
[636.350106] Starting subtest: second-client
[636.468270] Subtest second-client: SUCCESS (0.107s)
[636.847876] [155/171] (463s left) vgem_basic (sysfs)
[637.717924] Starting subtest: sysfs
[637.726846] Subtest sysfs: SUCCESS (0.006s)
[638.120487] [156/171] (462s left) core_hotunplug (unbind-rebind)
[639.455673] Starting subtest: unbind-rebind
[646.884088] Subtest unbind-rebind: SUCCESS (7.398s)
[647.142482] [157/171] (453s left) vgem_basic (unload)
[648.183746] Starting subtest: unload
[678.112769] Subtest unload: SUCCESS (29.871s)
[678.783141] [158/171] (422s left) i915_module_load (reload)
[679.800645] Starting subtest: reload
[693.529629] Subtest reload: SUCCESS (13.725s)
[694.148344] [159/171] (407s left) gem_lmem_swapping (basic)
[695.651878] Subtest basic: SKIP (0.000s)
[695.870858] [160/171] (405s left) gem_lmem_swapping (parallel-random-engines)
[696.859473] Subtest parallel-random-engines: SKIP (0.000s)
[697.210587] [161/171] (404s left) gem_lmem_swapping (random-engines)
[698.131354] Subtest random-engines: SKIP (0.000s)
[698.483157] [162/171] (403s left) gem_lmem_swapping (verify-random)
[699.474755] Subtest verify-random: SKIP (0.000s)
[699.792601] [163/171] (402s left) i915_pm_rpm (module-reload)
[704.381877] Starting subtest: module-reload
[736.822513] Subtest module-reload: SUCCESS (32.303s)
[736.956839] [164/171] (365s left) i915_selftest (live)
[737.959933] Starting subtest: live
[737.968354] Starting dynamic subtest: sanitycheck
[743.466225] Dynamic subtest sanitycheck: SUCCESS (5.500s)
[743.625140] Starting dynamic subtest: uncore
[748.865109] Dynamic subtest uncore: SUCCESS (5.411s)
[749.001698] Starting dynamic subtest: workarounds
[755.284771] Dynamic subtest workarounds: SUCCESS (6.405s)
[755.432324] Starting dynamic subtest: gt_engines
[761.123131] Dynamic subtest gt_engines: SUCCESS (5.841s)
[761.255851] Starting dynamic subtest: gt_timelines
[826.396962] Dynamic subtest gt_timelines: SUCCESS (65.267s)
[826.526887] Starting dynamic subtest: gt_contexts
[832.246619] Dynamic subtest gt_contexts: SUCCESS (5.838s)
[832.370316] Starting dynamic subtest: gt_lrc
[842.476585] Dynamic subtest gt_lrc: SUCCESS (10.221s)
[842.600306] Starting dynamic subtest: gt_mocs
[848.313546] Dynamic subtest gt_mocs: SUCCESS (5.830s)
[848.448769] Starting dynamic subtest: gt_pm
[857.786423] Dynamic subtest gt_pm: FAIL (8.814s)
[857.847736] Starting dynamic subtest: gt_heartbeat
[866.543418] Dynamic subtest gt_heartbeat: FAIL (8.676s)
[866.603922] Starting dynamic subtest: requests
[878.833886] Dynamic subtest requests: SUCCESS (13.002s)
[878.891666] Starting dynamic subtest: migrate
[889.974497] Dynamic subtest migrate: SUCCESS (11.134s)
[890.106350] Starting dynamic subtest: active
[895.455258] Dynamic subtest active: SUCCESS (5.489s)
[895.509556] Starting dynamic subtest: objects
[911.278453] Dynamic subtest objects: SUCCESS (15.794s)
[911.405897] Starting dynamic subtest: mman
[933.295494] Dynamic subtest mman: SUCCESS (22.006s)
[933.429373] Starting dynamic subtest: dmabuf
[938.925748] Dynamic subtest dmabuf: SUCCESS (5.632s)
[939.052670] Starting dynamic subtest: vma
[944.377718] Dynamic subtest vma: SUCCESS (5.440s)
[944.488823] Starting dynamic subtest: coherency
[959.944716] Dynamic subtest coherency: SUCCESS (15.562s)
[960.058579] Starting dynamic subtest: gtt
[975.124943] Dynamic subtest gtt: SUCCESS (15.184s)
[975.187456] Starting dynamic subtest: gem
[982.254431] Dynamic subtest gem: SUCCESS (7.105s)
[982.364721] Starting dynamic subtest: evict
[1000.100878] Dynamic subtest evict: SUCCESS (17.854s)
[1000.155748] Starting dynamic subtest: hugepages
[1011.848599] Dynamic subtest hugepages: SUCCESS (11.734s)
[1011.905589] Starting dynamic subtest: gem_contexts
[1060.080887] Dynamic subtest gem_contexts: SUCCESS (48.211s)
[1060.137485] Starting dynamic subtest: client
[1065.800439] Dynamic subtest client: SUCCESS (5.714s)
[1065.941312] Starting dynamic subtest: gem_migrate
[1071.183620] Dynamic subtest gem_migrate: SUCCESS (5.375s)
[1071.317494] Starting dynamic subtest: reset
[1081.741295] Dynamic subtest reset: SUCCESS (10.551s)
[1081.868564] Starting dynamic subtest: memory_region
[1087.137600] Dynamic subtest memory_region: SUCCESS (5.403s)
[1087.193828] Starting dynamic subtest: hangcheck
[1116.161843] Dynamic subtest hangcheck: SUCCESS (29.005s)
[1116.219807] Starting dynamic subtest: execlists
[1137.593967] Dynamic subtest execlists: SUCCESS (21.428s)
[1137.648625] Starting dynamic subtest: ring_submission
[1143.052682] Dynamic subtest ring_submission: SUCCESS (5.440s)
[1143.191828] Starting dynamic subtest: perf
[1149.148925] Dynamic subtest perf: SUCCESS (6.090s)
[1149.262596] Starting dynamic subtest: slpc
[1154.536829] Dynamic subtest slpc: SUCCESS (5.377s)
[1154.663890] Starting dynamic subtest: guc
[1160.022235] Dynamic subtest guc: SUCCESS (5.482s)
[1160.141507] Starting dynamic subtest: guc_multi_lrc
[1165.417118] Dynamic subtest guc_multi_lrc: SUCCESS (5.384s)
[1165.551660] Starting dynamic subtest: guc_hang
[1170.916763] Dynamic subtest guc_hang: SUCCESS (5.496s)
[1171.045792] Starting dynamic subtest: late_gt_pm
[1176.573835] Dynamic subtest late_gt_pm: SUCCESS (5.626s)
[1176.581142] Subtest live: FAIL (438.615s)
[1176.812251] Overall timeout time exceeded, stopping.
[1176.814234] Closing watchdogs
Done.