[41.120467] Initializing watchdogs
[41.120660]   /dev/watchdog0
[41.154953] [001/165] (960s left) i915_module_load (load)
[41.395342] Starting subtest: load
[43.954237] Subtest load: SUCCESS (2.555s)
[44.045944] [002/165] (957s left) core_auth (basic-auth)
[44.391813] Starting subtest: basic-auth
[44.413659] Subtest basic-auth: SUCCESS (0.006s)
[44.498545] [003/165] (957s left) debugfs_test (read_all_entries)
[44.750264] Starting subtest: read_all_entries
[45.155751] Subtest read_all_entries: SUCCESS (0.402s)
[45.371269] [004/165] (956s left) debugfs_test (basic-hwmon)
[45.608223] Starting subtest: basic-hwmon
[45.649351] Subtest basic-hwmon: SKIP (0.000s)
[45.817125] [005/165] (956s left) fbdev (eof)
[46.039252] Starting subtest: eof
[46.059285] Subtest eof: SUCCESS (0.002s)
[46.165987] [006/165] (955s left) fbdev (info)
[46.380766] Starting subtest: info
[46.403846] Subtest info: SUCCESS (0.000s)
[46.510989] [007/165] (955s left) fbdev (nullptr)
[46.719446] Starting subtest: nullptr
[46.739441] Subtest nullptr: SUCCESS (0.000s)
[46.846608] [008/165] (955s left) fbdev (read)
[47.055575] Starting subtest: read
[48.522360] Subtest read: SUCCESS (1.471s)
[48.636273] [009/165] (953s left) fbdev (write)
[48.826432] Starting subtest: write
[49.577433] Subtest write: SUCCESS (0.762s)
[49.686660] [010/165] (952s left) gem_basic (bad-close)
[49.919873] Starting subtest: bad-close
[49.960585] Subtest bad-close: SUCCESS (0.000s)
[50.050538] [011/165] (952s left) gem_basic (create-close)
[50.264336] Starting subtest: create-close
[50.308854] Subtest create-close: SUCCESS (0.000s)
[50.395295] [012/165] (952s left) gem_basic (create-fd-close)
[50.626135] Starting subtest: create-fd-close
[50.669179] Subtest create-fd-close: SUCCESS (0.000s)
[50.756545] [013/165] (951s left) gem_busy (busy@all-engines)
[50.996454] Starting subtest: busy
[51.017196] Starting dynamic subtest: all-engines
[51.092246] Dynamic subtest all-engines: SUCCESS (0.102s)
[51.115542] Subtest busy: SUCCESS (0.102s)
[51.513586] [014/165] (951s left) gem_close_race (basic-process)
[51.776170] Starting subtest: basic-process
[51.869793] Subtest basic-process: SUCCESS (0.112s)
[52.098483] [015/165] (950s left) gem_close_race (basic-threads)
[52.307770] Starting subtest: basic-threads
[53.530843] Subtest basic-threads: SUCCESS (1.220s)
[53.752454] [016/165] (949s left) gem_ctx_create (basic)
[53.994626] Starting subtest: basic
[54.015859] Subtest basic: SUCCESS (0.000s)
[54.212941] [017/165] (948s left) gem_ctx_create (basic-files)
[54.446647] Starting subtest: basic-files
[56.493872] Subtest basic-files: SUCCESS (2.039s)
[56.729177] [018/165] (946s left) gem_ctx_exec (basic)
[56.970095] Starting subtest: basic
[56.990778] Subtest basic: SUCCESS (0.009s)
[57.081680] [019/165] (946s left) gem_exec_basic (basic)
[57.322799] Starting subtest: basic
[57.344960] Starting dynamic subtest: rcs0-smem
[57.367842] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[57.402657] Starting dynamic subtest: bcs0-smem
[57.422772] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[57.459331] Starting dynamic subtest: vcs0-smem
[57.481554] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[57.516716] Starting dynamic subtest: vecs0-smem
[57.537287] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[57.559248] Subtest basic: SUCCESS (0.011s)
[57.649318] [020/165] (945s left) gem_exec_create (basic)
[57.890660] Starting subtest: basic
[57.911414] Starting dynamic subtest: smem
[59.959286] Dynamic subtest smem: SUCCESS (2.063s)
[59.979192] Subtest basic: SUCCESS (2.063s)
[60.163503] [021/165] (943s left) gem_exec_fence (basic-busy)
[60.529430] Starting subtest: basic-busy
[60.550262] Starting dynamic subtest: rcs0
[60.591565] Dynamic subtest rcs0: SUCCESS (0.031s)
[60.628333] Starting dynamic subtest: bcs0
[60.671154] Dynamic subtest bcs0: SUCCESS (0.032s)
[60.706601] Starting dynamic subtest: vcs0
[60.750194] Dynamic subtest vcs0: SUCCESS (0.027s)
[60.784946] Starting dynamic subtest: vecs0
[60.828231] Dynamic subtest vecs0: SUCCESS (0.020s)
[60.848814] Subtest basic-busy: SUCCESS (0.111s)
[61.430455] [022/165] (941s left) gem_exec_fence (basic-wait)
[61.793147] Starting subtest: basic-wait
[61.813640] Starting dynamic subtest: rcs0
[61.834875] Dynamic subtest rcs0: SUCCESS (0.028s)
[61.873413] Starting dynamic subtest: bcs0
[61.894159] Dynamic subtest bcs0: SUCCESS (0.028s)
[61.930044] Starting dynamic subtest: vcs0
[61.951328] Dynamic subtest vcs0: SUCCESS (0.021s)
[61.987628] Starting dynamic subtest: vecs0
[62.008473] Dynamic subtest vecs0: SUCCESS (0.032s)
[62.028447] Subtest basic-wait: SUCCESS (0.109s)
[62.604822] [023/165] (940s left) gem_exec_fence (basic-await)
[62.962814] Starting subtest: basic-await
[62.984478] Starting dynamic subtest: rcs0
[63.105906] Dynamic subtest rcs0: SUCCESS (0.142s)
[63.145356] Starting dynamic subtest: bcs0
[63.238205] Dynamic subtest bcs0: SUCCESS (0.132s)
[63.278741] Starting dynamic subtest: vcs0
[63.367112] Dynamic subtest vcs0: SUCCESS (0.130s)
[63.405353] Starting dynamic subtest: vecs0
[63.521799] Dynamic subtest vecs0: SUCCESS (0.134s)
[63.544551] Subtest basic-await: SUCCESS (0.540s)
[64.223885] [024/165] (939s left) gem_exec_fence (nb-await)
[64.593487] Starting subtest: nb-await
[64.619673] Starting dynamic subtest: rcs0
[64.668918] Dynamic subtest rcs0: SUCCESS (0.077s)
[64.711266] Starting dynamic subtest: bcs0
[64.761905] Dynamic subtest bcs0: SUCCESS (0.070s)
[64.799424] Starting dynamic subtest: vcs0
[64.829135] Dynamic subtest vcs0: SUCCESS (0.071s)
[64.868442] Starting dynamic subtest: vecs0
[64.892393] Dynamic subtest vecs0: SUCCESS (0.069s)
[64.915759] Subtest nb-await: SUCCESS (0.290s)
[65.567433] [025/165] (938s left) gem_exec_gttfill (basic)
[65.809137] Starting subtest: basic
[87.653386] Subtest basic: SUCCESS (21.853s)
[87.885555] [026/165] (915s left) gem_exec_parallel (engines)
[88.264449] Starting subtest: engines
[88.287357] Starting dynamic subtest: basic
[92.064190] Dynamic subtest basic: SUCCESS (3.630s)
[92.102173] Starting dynamic subtest: contexts
[96.976235] Dynamic subtest contexts: SUCCESS (4.919s)
[97.016051] Starting dynamic subtest: fds
[103.176949] Dynamic subtest fds: SUCCESS (6.212s)
[103.215911] Starting dynamic subtest: userptr
[106.887060] Dynamic subtest userptr: SUCCESS (3.693s)
[106.908850] Subtest engines: SUCCESS (18.455s)
[107.017358] [027/165] (896s left) gem_exec_store (basic)
[107.323901] Starting subtest: basic
[107.344343] Subtest basic: SUCCESS (0.078s)
[107.547629] [028/165] (896s left) gem_flink_basic (bad-flink)
[107.807336] Starting subtest: bad-flink
[107.836147] Subtest bad-flink: SUCCESS (0.000s)
[107.953745] [029/165] (896s left) gem_flink_basic (bad-open)
[108.221076] Starting subtest: bad-open
[108.252548] Subtest bad-open: SUCCESS (0.000s)
[108.366683] [030/165] (895s left) gem_flink_basic (basic)
[108.627210] Starting subtest: basic
[108.653101] Subtest basic: SUCCESS (0.000s)
[108.770390] [031/165] (895s left) gem_flink_basic (double-flink)
[109.008648] Starting subtest: double-flink
[109.031183] Subtest double-flink: SUCCESS (0.000s)
[109.120777] [032/165] (895s left) gem_flink_basic (flink-lifetime)
[109.349698] Starting subtest: flink-lifetime
[109.370142] Subtest flink-lifetime: SUCCESS (0.003s)
[109.461657] [033/165] (894s left) gem_huc_copy (huc-copy)
[109.691155] Starting subtest: huc-copy
[109.713712] Subtest huc-copy: SUCCESS (0.005s)
[109.813300] [034/165] (894s left) gem_linear_blits (basic)
[110.189945] Starting subtest: basic
[110.211571] Subtest basic: SUCCESS (0.018s)
[110.388859] [035/165] (894s left) gem_mmap (basic)
[110.786893] Starting subtest: basic
[110.893924] Subtest basic: SUCCESS (0.001s)
[110.987248] [036/165] (893s left) gem_mmap_gtt (basic)
[111.229310] Starting subtest: basic
[111.252500] Subtest basic: SUCCESS (0.001s)
[111.343800] [037/165] (893s left) gem_render_linear_blits (basic)
[111.577407] Starting subtest: basic
[111.681475] Subtest basic: SUCCESS (0.093s)
[111.775833] [038/165] (893s left) gem_render_tiled_blits (basic)
[112.018626] Starting subtest: basic
[112.146492] Subtest basic: SUCCESS (0.117s)
[112.238659] [039/165] (892s left) gem_ringfill (basic-all)
[112.746086] Starting subtest: basic-all
[113.917305] Subtest basic-all: SUCCESS (1.204s)
[114.069365] [040/165] (890s left) gem_softpin (allocator-basic)
[114.310546] Starting subtest: allocator-basic
[114.534943] Subtest allocator-basic: SUCCESS (0.250s)
[114.682793] [041/165] (890s left) gem_softpin (allocator-basic-reserve)
[114.912152] Starting subtest: allocator-basic-reserve
[115.124840] Subtest allocator-basic-reserve: SUCCESS (0.238s)
[115.266410] [042/165] (889s left) gem_softpin (safe-alignment)
[115.495264] Starting subtest: safe-alignment
[115.515387] Subtest safe-alignment: SUCCESS (0.031s)
[115.632332] [043/165] (889s left) gem_sync (basic-all)
[116.042617] Starting subtest: basic-all
[119.064779] Subtest basic-all: SUCCESS (3.029s)
[119.155164] [044/165] (886s left) gem_sync (basic-each)
[119.555547] Starting subtest: basic-each
[121.699698] Subtest basic-each: SUCCESS (2.309s)
[121.916980] [045/165] (883s left) gem_tiled_blits (basic)
[122.284183] Starting subtest: basic
[122.331368] Subtest basic: SUCCESS (0.049s)
[122.520558] [046/165] (883s left) gem_tiled_fence_blits (basic)
[123.084161] Starting subtest: basic
[123.130264] Subtest basic: SUCCESS (0.042s)
[123.338247] [047/165] (882s left) gem_tiled_pread_basic
[124.099410] [048/165] (881s left) gem_wait (busy@all-engines)
[124.362909] Starting subtest: busy
[124.383960] Starting dynamic subtest: all-engines
[124.892159] Dynamic subtest all-engines: SUCCESS (0.533s)
[124.913077] Subtest busy: SUCCESS (0.533s)
[125.153835] [049/165] (880s left) gem_wait (wait@all-engines)
[125.385162] Starting subtest: wait
[125.405252] Starting dynamic subtest: all-engines
[126.425660] Dynamic subtest all-engines: SUCCESS (1.044s)
[126.445770] Subtest wait: SUCCESS (1.044s)
[126.684699] [050/165] (879s left) i915_getparams_basic (basic-eu-total)
[126.929874] Starting subtest: basic-eu-total
[126.973560] Subtest basic-eu-total: SUCCESS (0.000s)
[127.066583] [051/165] (878s left) i915_getparams_basic (basic-subslice-total)
[127.297389] Starting subtest: basic-subslice-total
[127.338431] Subtest basic-subslice-total: SUCCESS (0.000s)
[127.429244] [052/165] (878s left) i915_hangman (error-state-basic)
[127.669481] Starting subtest: error-state-basic
[127.970957] Subtest error-state-basic: SUCCESS (0.293s)
[128.069658] [053/165] (878s left) i915_pciid
[128.414591] [054/165] (877s left) kms_addfb_basic (addfb25-4-tiled)
[128.801283] Starting subtest: addfb25-4-tiled
[128.821825] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[128.916715] [055/165] (877s left) kms_addfb_basic (addfb25-bad-modifier)
[129.148146] Starting subtest: addfb25-bad-modifier
[129.168840] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[129.351293] [056/165] (877s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[129.580786] Starting subtest: addfb25-framebuffer-vs-set-tiling
[129.601465] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[129.785907] [057/165] (876s left) kms_addfb_basic (addfb25-modifier-no-flag)
[130.017855] Starting subtest: addfb25-modifier-no-flag
[130.039675] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[130.215942] [058/165] (876s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[130.446443] Starting subtest: addfb25-x-tiled-legacy
[130.466934] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[130.650276] [059/165] (876s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[130.880995] Starting subtest: addfb25-x-tiled-mismatch-legacy
[130.900352] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[131.086309] [060/165] (875s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[131.403204] Starting subtest: addfb25-yf-tiled-legacy
[131.424646] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[131.516701] [061/165] (875s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[131.833912] Starting subtest: addfb25-y-tiled-legacy
[131.855387] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[131.950692] [062/165] (875s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[132.254385] Starting subtest: addfb25-y-tiled-small-legacy
[132.274117] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[132.368391] [063/165] (874s left) kms_addfb_basic (bad-pitch-0)
[132.600351] Starting subtest: bad-pitch-0
[132.622168] Subtest bad-pitch-0: SUCCESS (0.000s)
[132.799230] [064/165] (874s left) kms_addfb_basic (bad-pitch-1024)
[133.034567] Starting subtest: bad-pitch-1024
[133.055024] Subtest bad-pitch-1024: SUCCESS (0.000s)
[133.233286] [065/165] (873s left) kms_addfb_basic (bad-pitch-128)
[133.464135] Starting subtest: bad-pitch-128
[133.483556] Subtest bad-pitch-128: SUCCESS (0.000s)
[133.668086] [066/165] (873s left) kms_addfb_basic (bad-pitch-256)
[133.918180] Starting subtest: bad-pitch-256
[133.942909] Subtest bad-pitch-256: SUCCESS (0.000s)
[134.103081] [067/165] (873s left) kms_addfb_basic (bad-pitch-32)
[134.359326] Starting subtest: bad-pitch-32
[134.387982] Subtest bad-pitch-32: SUCCESS (0.000s)
[134.551119] [068/165] (872s left) kms_addfb_basic (bad-pitch-63)
[134.816524] Starting subtest: bad-pitch-63
[134.844783] Subtest bad-pitch-63: SUCCESS (0.000s)
[134.998555] [069/165] (872s left) kms_addfb_basic (bad-pitch-65536)
[135.230970] Starting subtest: bad-pitch-65536
[135.250817] Subtest bad-pitch-65536: SUCCESS (0.001s)
[135.434334] [070/165] (872s left) kms_addfb_basic (bad-pitch-999)
[135.662115] Starting subtest: bad-pitch-999
[135.681772] Subtest bad-pitch-999: SUCCESS (0.000s)
[135.868966] [071/165] (871s left) kms_addfb_basic (basic)
[136.096750] Starting subtest: basic
[136.119042] Subtest basic: SUCCESS (0.000s)
[136.308567] [072/165] (871s left) kms_addfb_basic (basic-x-tiled-legacy)
[136.541225] Starting subtest: basic-x-tiled-legacy
[136.562631] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[136.731474] [073/165] (871s left) kms_addfb_basic (basic-y-tiled-legacy)
[136.963116] Starting subtest: basic-y-tiled-legacy
[136.982508] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[137.166798] [074/165] (870s left) kms_addfb_basic (bo-too-small)
[137.469140] Starting subtest: bo-too-small
[137.489154] Subtest bo-too-small: SUCCESS (0.001s)
[137.603592] [075/165] (870s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[137.920951] Starting subtest: bo-too-small-due-to-tiling
[137.942695] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[138.034945] [076/165] (870s left) kms_addfb_basic (clobberred-modifier)
[138.265891] Starting subtest: clobberred-modifier
[138.285381] Subtest clobberred-modifier: SUCCESS (0.000s)
[138.469695] [077/165] (869s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[138.703078] Starting subtest: framebuffer-vs-set-tiling
[138.725464] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[138.900258] [078/165] (869s left) kms_addfb_basic (invalid-get-prop)
[139.133311] Starting subtest: invalid-get-prop
[139.154502] Subtest invalid-get-prop: SUCCESS (0.000s)
[139.331171] [079/165] (869s left) kms_addfb_basic (invalid-get-prop-any)
[139.563196] Starting subtest: invalid-get-prop-any
[139.582982] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[139.750925] [080/165] (868s left) kms_addfb_basic (invalid-set-prop)
[139.982383] Starting subtest: invalid-set-prop
[140.001778] Subtest invalid-set-prop: SUCCESS (0.000s)
[140.185750] [081/165] (868s left) kms_addfb_basic (invalid-set-prop-any)
[140.420647] Starting subtest: invalid-set-prop-any
[140.442897] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[140.617782] [082/165] (868s left) kms_addfb_basic (no-handle)
[140.849361] Starting subtest: no-handle
[140.870952] Subtest no-handle: SUCCESS (0.000s)
[141.049903] [083/165] (867s left) kms_addfb_basic (size-max)
[141.347265] Starting subtest: size-max
[141.369408] Subtest size-max: SUCCESS (0.000s)
[141.462958] [084/165] (867s left) kms_addfb_basic (small-bo)
[141.779138] Starting subtest: small-bo
[141.799041] Subtest small-bo: SUCCESS (0.000s)
[141.892551] [085/165] (867s left) kms_addfb_basic (tile-pitch-mismatch)
[142.121702] Starting subtest: tile-pitch-mismatch
[142.143227] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[142.335529] [086/165] (866s left) kms_addfb_basic (too-high)
[142.650236] Starting subtest: too-high
[142.679294] Subtest too-high: SUCCESS (0.002s)
[142.800077] [087/165] (866s left) kms_addfb_basic (too-wide)
[143.124619] Starting subtest: too-wide
[143.152285] Subtest too-wide: SUCCESS (0.000s)
[143.269322] [088/165] (865s left) kms_addfb_basic (unused-handle)
[143.533675] Starting subtest: unused-handle
[143.563081] Subtest unused-handle: SUCCESS (0.000s)
[143.721314] [089/165] (865s left) kms_addfb_basic (unused-modifier)
[143.953445] Starting subtest: unused-modifier
[143.975702] Subtest unused-modifier: SUCCESS (0.000s)
[144.149586] [090/165] (865s left) kms_addfb_basic (unused-offsets)
[144.382874] Starting subtest: unused-offsets
[144.404274] Subtest unused-offsets: SUCCESS (0.000s)
[144.581227] [091/165] (864s left) kms_addfb_basic (unused-pitches)
[144.809605] Starting subtest: unused-pitches
[144.829479] Subtest unused-pitches: SUCCESS (0.000s)
[145.001609] [092/165] (864s left) kms_busy (basic)
[145.329641] Starting subtest: basic
[145.350836] Starting dynamic subtest: flip
[146.317263] Dynamic subtest flip: SUCCESS (0.979s)
[146.375531] Starting dynamic subtest: modeset
[147.705948] Dynamic subtest modeset: SUCCESS (1.404s)
[147.727668] Subtest basic: SUCCESS (2.387s)
[148.089569] [093/165] (861s left) kms_prop_blob (basic)
[148.333089] Starting subtest: basic
[148.354426] Subtest basic: SUCCESS (0.000s)
[148.447624] [094/165] (861s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[148.780958] Starting subtest: basic-busy-flip-before-cursor-atomic
[148.822266] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[148.917468] [095/165] (860s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[149.238998] Starting subtest: basic-busy-flip-before-cursor-legacy
[149.282417] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[149.374494] [096/165] (860s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[149.672869] Starting subtest: basic-flip-after-cursor-atomic
[150.750891] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.058s)
[151.094477] [097/165] (858s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[151.412479] Starting subtest: basic-flip-after-cursor-legacy
[152.465916] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.061s)
[152.835816] [098/165] (857s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[153.156855] Starting subtest: basic-flip-after-cursor-varying-size
[154.247388] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.086s)
[154.620829] [099/165] (855s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[154.927819] Starting subtest: basic-flip-before-cursor-atomic
[155.978445] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.058s)
[156.341696] [100/165] (853s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[156.662942] Starting subtest: basic-flip-before-cursor-legacy
[157.718635] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.055s)
[158.064620] [101/165] (852s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[158.362093] Starting subtest: basic-flip-before-cursor-varying-size
[159.446584] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.076s)
[159.801981] [102/165] (850s left) kms_dsc (dsc-basic)
[160.154690] Subtest dsc-basic: SKIP (0.000s)
[160.247264] [103/165] (850s left) kms_flip (basic-flip-vs-dpms)
[160.575160] Starting subtest: basic-flip-vs-dpms
[160.595159] Starting dynamic subtest: A-DP1
[161.796697] Dynamic subtest A-DP1: SUCCESS (1.249s)
[161.838741] Starting dynamic subtest: B-DP1
[162.931541] Dynamic subtest B-DP1: SUCCESS (1.118s)
[162.981487] Starting dynamic subtest: C-DP1
[164.074653] Dynamic subtest C-DP1: SUCCESS (1.142s)
[164.096041] Subtest basic-flip-vs-dpms: SUCCESS (3.518s)
[164.475354] [104/165] (846s left) kms_flip (basic-flip-vs-modeset)
[164.794451] Starting subtest: basic-flip-vs-modeset
[164.816999] Starting dynamic subtest: A-DP1
[166.048974] Dynamic subtest A-DP1: SUCCESS (1.283s)
[166.087675] Starting dynamic subtest: B-DP1
[167.179333] Dynamic subtest B-DP1: SUCCESS (1.127s)
[167.218481] Starting dynamic subtest: C-DP1
[168.304103] Dynamic subtest C-DP1: SUCCESS (1.126s)
[168.326742] Subtest basic-flip-vs-modeset: SUCCESS (3.546s)
[168.697658] [105/165] (842s left) kms_flip (basic-flip-vs-wf_vblank)
[169.024440] Starting subtest: basic-flip-vs-wf_vblank
[169.044385] Starting dynamic subtest: A-DP1
[170.478711] Dynamic subtest A-DP1: SUCCESS (1.467s)
[170.527155] Starting dynamic subtest: B-DP1
[171.867824] Dynamic subtest B-DP1: SUCCESS (1.389s)
[171.914898] Starting dynamic subtest: C-DP1
[173.250878] Dynamic subtest C-DP1: SUCCESS (1.395s)
[173.271507] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.261s)
[173.712710] [106/165] (837s left) kms_flip (basic-plain-flip)
[174.033994] Starting subtest: basic-plain-flip
[174.053937] Starting dynamic subtest: A-DP1
[175.188242] Dynamic subtest A-DP1: SUCCESS (1.180s)
[175.227247] Starting dynamic subtest: B-DP1
[176.294825] Dynamic subtest B-DP1: SUCCESS (1.108s)
[176.335536] Starting dynamic subtest: C-DP1
[177.409897] Dynamic subtest C-DP1: SUCCESS (1.108s)
[177.429487] Subtest basic-plain-flip: SUCCESS (3.406s)
[177.854883] [107/165] (833s left) kms_force_connector_basic (force-connector-state)
[178.117883] Subtest force-connector-state: SKIP (0.000s)
[178.213951] [108/165] (832s left) kms_force_connector_basic (force-edid)
[178.466763] Subtest force-edid: SKIP (0.000s)
[178.559617] [109/165] (832s left) kms_force_connector_basic (force-load-detect)
[178.810991] Subtest force-load-detect: SKIP (0.000s)
[178.906547] [110/165] (832s left) kms_force_connector_basic (prune-stale-modes)
[179.164218] Subtest prune-stale-modes: SKIP (0.000s)
[179.265844] [111/165] (832s left) kms_frontbuffer_tracking (basic)
[179.661047] Starting subtest: basic
[182.627604] Subtest basic: SUCCESS (3.032s)
[182.751907] [112/165] (828s left) kms_hdmi_inject (inject-audio)
[183.039335] Subtest inject-audio: SKIP (0.000s)
[183.133148] [113/165] (828s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[183.467633] Starting subtest: compare-crc-sanitycheck-xr24
[183.488436] Starting dynamic subtest: pipe-A-DP-1
[183.898125] Dynamic subtest pipe-A-DP-1: SUCCESS (0.431s)
[183.938995] Starting dynamic subtest: pipe-B-DP-1
[184.493111] Dynamic subtest pipe-B-DP-1: SUCCESS (0.607s)
[184.533698] Starting dynamic subtest: pipe-C-DP-1
[185.128398] Dynamic subtest pipe-C-DP-1: SUCCESS (0.612s)
[185.149589] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.663s)
[185.483723] [114/165] (826s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[185.803898] Starting subtest: compare-crc-sanitycheck-nv12
[185.825915] Starting dynamic subtest: pipe-A-DP-1
[185.865790] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[185.906229] Starting dynamic subtest: pipe-B-DP-1
[185.948243] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[185.986241] Starting dynamic subtest: pipe-C-DP-1
[186.031096] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[186.071245] Subtest compare-crc-sanitycheck-nv12: SKIP (0.016s)
[186.166954] [115/165] (825s left) kms_pipe_crc_basic (hang-read-crc)
[186.491163] Starting subtest: hang-read-crc
[186.513208] Starting dynamic subtest: pipe-A-DP-1
[187.948572] Dynamic subtest pipe-A-DP-1: SUCCESS (1.461s)
[187.991585] Starting dynamic subtest: pipe-B-DP-1
[189.571880] Dynamic subtest pipe-B-DP-1: SUCCESS (1.619s)
[189.623629] Starting dynamic subtest: pipe-C-DP-1
[191.203703] Dynamic subtest pipe-C-DP-1: SUCCESS (1.625s)
[191.225783] Subtest hang-read-crc: SUCCESS (4.719s)
[191.545489] [116/165] (820s left) kms_pipe_crc_basic (nonblocking-crc)
[191.885430] Starting subtest: nonblocking-crc
[191.911905] Starting dynamic subtest: pipe-A-DP-1
[192.529519] Dynamic subtest pipe-A-DP-1: SUCCESS (0.590s)
[192.573716] Starting dynamic subtest: pipe-B-DP-1
[193.248903] Dynamic subtest pipe-B-DP-1: SUCCESS (0.746s)
[193.306950] Starting dynamic subtest: pipe-C-DP-1
[193.979232] Dynamic subtest pipe-C-DP-1: SUCCESS (0.752s)
[194.008406] Subtest nonblocking-crc: SUCCESS (2.102s)
[194.341867] [117/165] (817s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[194.643898] Starting subtest: nonblocking-crc-frame-sequence
[194.663785] Starting dynamic subtest: pipe-A-DP-1
[195.251180] Dynamic subtest pipe-A-DP-1: SUCCESS (0.582s)
[195.306332] Starting dynamic subtest: pipe-B-DP-1
[195.997134] Dynamic subtest pipe-B-DP-1: SUCCESS (0.745s)
[196.038852] Starting dynamic subtest: pipe-C-DP-1
[196.756722] Dynamic subtest pipe-C-DP-1: SUCCESS (0.769s)
[196.778145] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.110s)
[197.124056] [118/165] (814s left) kms_pipe_crc_basic (read-crc)
[197.444848] Starting subtest: read-crc
[197.466778] Starting dynamic subtest: pipe-A-DP-1
[197.942796] Dynamic subtest pipe-A-DP-1: SUCCESS (0.516s)
[197.995379] Starting dynamic subtest: pipe-B-DP-1
[198.644839] Dynamic subtest pipe-B-DP-1: SUCCESS (0.678s)
[198.686295] Starting dynamic subtest: pipe-C-DP-1
[199.315549] Dynamic subtest pipe-C-DP-1: SUCCESS (0.686s)
[199.335058] Subtest read-crc: SUCCESS (1.895s)
[199.676462] [119/165] (812s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[199.976032] Starting subtest: read-crc-frame-sequence
[199.996056] Starting dynamic subtest: pipe-A-DP-1
[200.509947] Dynamic subtest pipe-A-DP-1: SUCCESS (0.497s)
[200.568362] Starting dynamic subtest: pipe-B-DP-1
[201.209824] Dynamic subtest pipe-B-DP-1: SUCCESS (0.695s)
[201.258434] Starting dynamic subtest: pipe-C-DP-1
[201.869913] Dynamic subtest pipe-C-DP-1: SUCCESS (0.687s)
[201.892678] Subtest read-crc-frame-sequence: SUCCESS (1.894s)
[202.235856] [120/165] (809s left) kms_pm_backlight (basic-brightness)
[202.566263] Subtest basic-brightness: SKIP (0.000s)
[202.672695] [121/165] (809s left) kms_pm_rpm (basic-pci-d3-state)
[204.563185] Starting subtest: basic-pci-d3-state
[205.130771] Subtest basic-pci-d3-state: SUCCESS (0.557s)
[205.353276] [122/165] (806s left) kms_pm_rpm (basic-rte)
[205.566263] Starting subtest: basic-rte
[207.940782] Subtest basic-rte: SUCCESS (2.385s)
[208.886150] [123/165] (803s left) kms_psr (primary_page_flip)
[209.152053] Subtest primary_page_flip: SKIP (0.000s)
[209.249660] [124/165] (803s left) kms_psr (cursor_plane_move)
[209.504868] Subtest cursor_plane_move: SKIP (0.000s)
[209.604530] [125/165] (803s left) kms_psr (sprite_plane_onoff)
[209.860416] Subtest sprite_plane_onoff: SKIP (0.000s)
[209.958256] [126/165] (802s left) kms_psr (primary_mmap_gtt)
[210.196667] Subtest primary_mmap_gtt: SKIP (0.000s)
[210.293595] [127/165] (802s left) kms_setmode (basic-clone-single-crtc)
[210.535121] Starting subtest: basic-clone-single-crtc
[210.598528] Subtest basic-clone-single-crtc: SKIP (0.000s)
[210.695631] [128/165] (802s left) i915_pm_rps (basic-api)
[210.955559] Starting subtest: basic-api
[210.980715] Subtest basic-api: SUCCESS (0.007s)
[211.126845] [129/165] (801s left) prime_self_import (basic-llseek-bad)
[211.375494] Starting subtest: basic-llseek-bad
[211.429936] Subtest basic-llseek-bad: SUCCESS (0.004s)
[211.533939] [130/165] (801s left) prime_self_import (basic-llseek-size)
[211.751525] Starting subtest: basic-llseek-size
[211.793117] Subtest basic-llseek-size: SUCCESS (0.006s)
[211.893093] [131/165] (801s left) prime_self_import (basic-with_fd_dup)
[212.097814] Starting subtest: basic-with_fd_dup
[212.142052] Subtest basic-with_fd_dup: SUCCESS (0.006s)
[212.241161] [132/165] (801s left) prime_self_import (basic-with_one_bo)
[212.454462] Starting subtest: basic-with_one_bo
[212.499986] Subtest basic-with_one_bo: SUCCESS (0.007s)
[212.599522] [133/165] (800s left) prime_self_import (basic-with_one_bo_two_files)
[212.813210] Starting subtest: basic-with_one_bo_two_files
[212.858957] Subtest basic-with_one_bo_two_files: SUCCESS (0.006s)
[212.958361] [134/165] (800s left) prime_self_import (basic-with_two_bos)
[213.157869] Starting subtest: basic-with_two_bos
[213.204680] Subtest basic-with_two_bos: SUCCESS (0.006s)
[213.301526] [135/165] (800s left) prime_vgem (basic-fence-flip)
[213.566584] Starting subtest: basic-fence-flip
[214.156774] Subtest basic-fence-flip: SUCCESS (0.619s)
[214.284808] [136/165] (799s left) prime_vgem (basic-fence-mmap)
[214.541371] Starting subtest: basic-fence-mmap
[214.593669] Subtest basic-fence-mmap: SUCCESS (0.072s)
[214.717414] [137/165] (799s left) prime_vgem (basic-fence-read)
[214.999194] Starting subtest: basic-fence-read
[215.056844] Subtest basic-fence-read: SUCCESS (0.112s)
[215.187630] [138/165] (798s left) prime_vgem (basic-gtt)
[215.426345] Starting subtest: basic-gtt
[215.449431] Subtest basic-gtt: SUCCESS (0.038s)
[215.592184] [139/165] (798s left) prime_vgem (basic-read)
[215.860398] Starting subtest: basic-read
[215.880761] Subtest basic-read: SUCCESS (0.057s)
[216.000151] [140/165] (798s left) prime_vgem (basic-write)
[216.235785] Starting subtest: basic-write
[216.276389] Subtest basic-write: SUCCESS (0.070s)
[216.393768] [141/165] (797s left) vgem_basic (setversion)
[216.663952] Starting subtest: setversion
[216.706146] Subtest setversion: SUCCESS (0.000s)
[216.803110] [142/165] (797s left) vgem_basic (create)
[217.038353] Starting subtest: create
[217.061969] Subtest create: SUCCESS (0.000s)
[217.159581] [143/165] (797s left) vgem_basic (debugfs)
[217.397185] Starting subtest: debugfs
[217.417643] Subtest debugfs: SUCCESS (0.001s)
[217.517525] [144/165] (797s left) vgem_basic (dmabuf-export)
[217.759708] Starting subtest: dmabuf-export
[217.804775] Subtest dmabuf-export: SUCCESS (0.005s)
[217.902546] [145/165] (796s left) vgem_basic (dmabuf-fence)
[218.139759] Starting subtest: dmabuf-fence
[218.160630] Subtest dmabuf-fence: SUCCESS (0.002s)
[218.261770] [146/165] (796s left) vgem_basic (dmabuf-fence-before)
[218.490936] Starting subtest: dmabuf-fence-before
[218.513525] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[218.612503] [147/165] (796s left) vgem_basic (dmabuf-mmap)
[218.830407] Starting subtest: dmabuf-mmap
[218.851651] Subtest dmabuf-mmap: SUCCESS (0.042s)
[218.954183] [148/165] (796s left) vgem_basic (mmap)
[219.169862] Starting subtest: mmap
[219.193285] Subtest mmap: SUCCESS (0.027s)
[219.290791] [149/165] (795s left) vgem_basic (second-client)
[219.524771] Starting subtest: second-client
[219.565644] Subtest second-client: SUCCESS (0.032s)
[219.667636] [150/165] (795s left) vgem_basic (sysfs)
[219.929638] Starting subtest: sysfs
[219.950949] Subtest sysfs: SUCCESS (0.002s)
[220.080343] [151/165] (795s left) core_hotunplug (unbind-rebind)
[220.517950] Starting subtest: unbind-rebind
[224.428638] Subtest unbind-rebind: SUCCESS (3.944s)
[224.528314] [152/165] (790s left) vgem_basic (unload)
[224.749599] Starting subtest: unload
[253.968308] Subtest unload: SUCCESS (29.062s)
[254.065294] [153/165] (761s left) i915_module_load (reload)
[254.281897] Starting subtest: reload
[258.040650] Subtest reload: SUCCESS (3.771s)
[258.194613] [154/165] (757s left) gem_lmem_swapping (basic)
[258.468422] Subtest basic: SKIP (0.000s)
[258.561709] [155/165] (757s left) gem_lmem_swapping (parallel-random-engines)
[258.797286] Subtest parallel-random-engines: SKIP (0.000s)
[258.889806] [156/165] (756s left) gem_lmem_swapping (random-engines)
[259.142855] Subtest random-engines: SKIP (0.000s)
[259.241415] [157/165] (756s left) gem_lmem_swapping (verify-random)
[259.494702] Subtest verify-random: SKIP (0.000s)
[259.587434] [158/165] (756s left) i915_pm_rpm (module-reload)
[261.820759] Starting subtest: module-reload
[271.049792] Subtest module-reload: SUCCESS (9.234s)
[271.144413] [159/165] (745s left) i915_selftest (live)
[271.370188] Starting subtest: live
[271.391716] Starting dynamic subtest: sanitycheck
[273.014820] Dynamic subtest sanitycheck: SUCCESS (1.642s)
[273.063155] Starting dynamic subtest: uncore
[274.678140] Dynamic subtest uncore: SUCCESS (1.660s)
[274.714211] Starting dynamic subtest: workarounds
[276.571583] Dynamic subtest workarounds: SUCCESS (1.889s)
[276.618420] Starting dynamic subtest: gt_engines
[278.686953] Dynamic subtest gt_engines: SUCCESS (2.109s)
[278.734708] Starting dynamic subtest: gt_timelines
[301.334709] Dynamic subtest gt_timelines: SUCCESS (22.645s)
[301.376493] Starting dynamic subtest: gt_contexts
[303.180175] Dynamic subtest gt_contexts: SUCCESS (1.839s)
[303.227516] Starting dynamic subtest: gt_lrc
[307.576691] Dynamic subtest gt_lrc: SUCCESS (4.395s)
[307.625595] Starting dynamic subtest: gt_mocs
[309.287243] Dynamic subtest gt_mocs: SUCCESS (1.707s)
[309.325984] Starting dynamic subtest: gt_pm
[317.316089] Dynamic subtest gt_pm: SUCCESS (8.025s)
[317.367530] Starting dynamic subtest: gt_heartbeat
[333.561560] Dynamic subtest gt_heartbeat: FAIL (10.601s)
[333.621984] Starting dynamic subtest: gt_tlb
[333.643253] Dynamic subtest gt_tlb: SUCCESS (2.428s)
[333.773555] Starting dynamic subtest: requests
[338.405861] Dynamic subtest requests: SUCCESS (8.048s)
[338.443213] Starting dynamic subtest: migrate
[343.409951] Dynamic subtest migrate: SUCCESS (5.001s)
[343.465687] Starting dynamic subtest: active
[345.162867] Dynamic subtest active: SUCCESS (1.747s)
[345.198911] Starting dynamic subtest: objects
[349.823752] Dynamic subtest objects: SUCCESS (4.650s)
[349.866275] Starting dynamic subtest: mman
[358.700377] Dynamic subtest mman: SUCCESS (8.881s)
[358.735770] Starting dynamic subtest: dmabuf
[360.287495] Dynamic subtest dmabuf: SUCCESS (1.586s)
[360.325531] Starting dynamic subtest: vma
[362.028974] Dynamic subtest vma: SUCCESS (1.739s)
[362.075646] Starting dynamic subtest: coherency
[366.410500] Dynamic subtest coherency: SUCCESS (4.376s)
[366.447615] Starting dynamic subtest: gtt
[375.842626] Dynamic subtest gtt: SUCCESS (9.429s)
[375.887349] Starting dynamic subtest: gem
[378.990863] Dynamic subtest gem: SUCCESS (3.145s)
[379.039519] Starting dynamic subtest: evict
[385.346761] Dynamic subtest evict: SUCCESS (6.351s)
[385.394159] Starting dynamic subtest: hugepages
[393.475624] Dynamic subtest hugepages: SUCCESS (8.125s)
[393.512575] Starting dynamic subtest: gem_contexts
[418.603407] Dynamic subtest gem_contexts: SUCCESS (24.953s)
[418.640430] Starting dynamic subtest: client
[419.922979] Dynamic subtest client: SUCCESS (1.490s)
[419.958707] Starting dynamic subtest: gem_migrate
[421.482255] Dynamic subtest gem_migrate: SUCCESS (1.553s)
[421.520223] Starting dynamic subtest: reset
[434.427911] Dynamic subtest reset: SUCCESS (12.942s)
[434.477365] Starting dynamic subtest: memory_region
[435.974665] Dynamic subtest memory_region: SUCCESS (1.545s)
[436.010601] Starting dynamic subtest: hangcheck
[460.824299] Dynamic subtest hangcheck: SUCCESS (24.848s)
[460.863323] Starting dynamic subtest: execlists
[473.254317] Dynamic subtest execlists: SUCCESS (12.416s)
[473.310832] Starting dynamic subtest: ring_submission
[474.765484] Dynamic subtest ring_submission: SUCCESS (1.508s)
[474.802579] Starting dynamic subtest: perf
[476.445926] Dynamic subtest perf: SUCCESS (1.683s)
[476.494646] Starting dynamic subtest: slpc
[478.103894] Dynamic subtest slpc: SUCCESS (1.652s)
[478.153858] Starting dynamic subtest: guc
[479.810307] Dynamic subtest guc: SUCCESS (1.703s)
[479.859304] Starting dynamic subtest: guc_multi_lrc
[481.482169] Dynamic subtest guc_multi_lrc: SUCCESS (1.671s)
[481.520859] Starting dynamic subtest: guc_hang
[483.036954] Dynamic subtest guc_hang: SUCCESS (1.546s)
[483.074776] Starting dynamic subtest: late_gt_pm
[484.759830] Dynamic subtest late_gt_pm: SUCCESS (1.720s)
[484.782319] Subtest live: FAIL (213.389s)
[484.876418] [160/165] (531s left) dmabuf (all-tests)
[485.087610] Starting subtest: all-tests
[485.108824] Starting dynamic subtest: sanitycheck
[485.180977] Dynamic subtest sanitycheck: SUCCESS (0.098s)
[485.218584] Starting dynamic subtest: dma_fence
[485.373913] Dynamic subtest dma_fence: SUCCESS (0.188s)
[485.410054] Starting dynamic subtest: dma_fence_chain
[508.787726] Dynamic subtest dma_fence_chain: SUCCESS (23.413s)
[508.831350] Starting dynamic subtest: dma_fence_unwrap
[508.879288] Dynamic subtest dma_fence_unwrap: SUCCESS (0.080s)
[508.918938] Starting dynamic subtest: dma_resv
[508.966908] Dynamic subtest dma_resv: SUCCESS (0.084s)
[508.987500] Subtest all-tests: SUCCESS (23.879s)
[509.083289] [161/165] (507s left) i915_suspend (basic-s2idle-without-i915)
[509.288811] Starting subtest: basic-s2idle-without-i915
[529.127090] Subtest basic-s2idle-without-i915: SUCCESS (4.849s)
[532.778560] [162/165] (501s left) i915_suspend (basic-s3-without-i915)
[532.999976] Starting subtest: basic-s3-without-i915
[551.100065] Subtest basic-s3-without-i915: SUCCESS (3.421s)
[554.839016] [163/165] (497s left) gem_exec_suspend (basic-s0)
[555.111417] Starting subtest: basic-S0
[555.135527] Starting dynamic subtest: smem
[576.812855] Dynamic subtest smem: SUCCESS (5.488s)
[576.835636] Subtest basic-S0: SUCCESS (5.491s)
[580.200302] [164/165] (491s left) gem_exec_suspend (basic-s3)
[580.434712] Starting subtest: basic-S3
[580.454620] Starting dynamic subtest: smem
[601.220084] Dynamic subtest smem: SUCCESS (5.799s)
[601.240860] Subtest basic-S3: SUCCESS (5.801s)
[604.611578] [165/165] (485s left) kms_pipe_crc_basic (suspend-read-crc)
[604.937157] Starting subtest: suspend-read-crc
[604.958483] Starting dynamic subtest: pipe-A-DP-1
[623.081737] Dynamic subtest pipe-A-DP-1: SUCCESS (2.483s)
[623.156165] Starting dynamic subtest: pipe-B-DP-1
[642.060097] Dynamic subtest pipe-B-DP-1: SUCCESS (2.446s)
[642.128083] Starting dynamic subtest: pipe-C-DP-1
[660.063777] Dynamic subtest pipe-C-DP-1: SUCCESS (2.545s)
[660.085415] Subtest suspend-read-crc: SUCCESS (7.489s)
[664.475458] Closing watchdogs
Done.