[32.296104] Initializing watchdogs
[32.296355]   /dev/watchdog0
[32.329203] [001/160] (960s left) i915_module_load (load)
[32.514127] Starting subtest: load
[34.118308] Subtest load: SUCCESS (1.611s)
[34.236177] [002/160] (958s left) core_auth (basic-auth)
[34.479314] Starting subtest: basic-auth
[34.499899] Subtest basic-auth: SUCCESS (0.001s)
[34.580360] [003/160] (958s left) debugfs_test (read_all_entries)
[34.754063] Starting subtest: read_all_entries
[34.797036] Subtest read_all_entries: SUCCESS (0.048s)
[34.888702] [004/160] (958s left) debugfs_test (basic-hwmon)
[35.062202] Starting subtest: basic-hwmon
[35.099588] Subtest basic-hwmon: SKIP (0.000s)
[35.188141] [005/160] (957s left) fbdev (eof)
[35.381027] Starting subtest: eof
[35.401650] Subtest eof: SUCCESS (0.001s)
[35.495516] [006/160] (957s left) fbdev (info)
[35.644736] Starting subtest: info
[35.666400] Subtest info: SUCCESS (0.000s)
[35.814937] [007/160] (957s left) fbdev (nullptr)
[36.176944] Starting subtest: nullptr
[36.200874] Subtest nullptr: SUCCESS (0.000s)
[36.311518] [008/160] (957s left) fbdev (read)
[36.481581] Starting subtest: read
[36.739727] Subtest read: SUCCESS (0.271s)
[36.846321] [009/160] (956s left) fbdev (write)
[37.039941] Starting subtest: write
[37.179166] Subtest write: SUCCESS (0.145s)
[37.293420] [010/160] (956s left) gem_basic (bad-close)
[37.473355] Starting subtest: bad-close
[37.522101] Subtest bad-close: SUCCESS (0.000s)
[37.605220] [011/160] (956s left) gem_basic (create-close)
[37.819469] Starting subtest: create-close
[37.863255] Subtest create-close: SUCCESS (0.000s)
[37.952725] [012/160] (955s left) gem_basic (create-fd-close)
[38.133278] Starting subtest: create-fd-close
[38.181197] Subtest create-fd-close: SUCCESS (0.000s)
[38.282100] [013/160] (955s left) gem_busy (busy@all-engines)
[38.465572] Starting subtest: busy
[38.485526] Starting dynamic subtest: all-engines
[38.504727] Dynamic subtest all-engines: SUCCESS (0.033s)
[38.523701] Subtest busy: SUCCESS (0.033s)
[38.837359] [014/160] (955s left) gem_close_race (basic-process)
[39.017658] Starting subtest: basic-process
[39.056374] Subtest basic-process: SUCCESS (0.051s)
[39.264519] [015/160] (954s left) gem_close_race (basic-threads)
[39.443516] Starting subtest: basic-threads
[40.667321] Subtest basic-threads: SUCCESS (1.242s)
[40.894157] [016/160] (953s left) gem_ctx_create (basic)
[41.087675] Starting subtest: basic
[41.107663] Subtest basic: SUCCESS (0.000s)
[41.286729] [017/160] (952s left) gem_ctx_create (basic-files)
[41.458333] Starting subtest: basic-files
[43.480648] Subtest basic-files: SUCCESS (2.018s)
[43.758986] [018/160] (950s left) gem_ctx_exec (basic)
[43.938840] Starting subtest: basic
[43.960920] Subtest basic: SUCCESS (0.001s)
[44.039761] [019/160] (950s left) gem_exec_basic (basic)
[44.221199] Starting subtest: basic
[44.242255] Starting dynamic subtest: rcs0-smem
[44.262097] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[44.290063] Starting dynamic subtest: vcs0-smem
[44.308684] Dynamic subtest vcs0-smem: SUCCESS (0.000s)
[44.327249] Subtest basic: SUCCESS (0.001s)
[44.409976] [020/160] (949s left) gem_exec_create (basic)
[44.582881] Starting subtest: basic
[44.603917] Starting dynamic subtest: smem
[46.617779] Dynamic subtest smem: SUCCESS (2.050s)
[46.636730] Subtest basic: SUCCESS (2.051s)
[46.826671] [021/160] (947s left) gem_exec_fence (basic-busy)
[47.130967] Starting subtest: basic-busy
[47.149847] Starting dynamic subtest: rcs0
[47.187901] Dynamic subtest rcs0: SUCCESS (0.026s)
[47.221046] Starting dynamic subtest: vcs0
[47.263577] Dynamic subtest vcs0: SUCCESS (0.025s)
[47.283990] Subtest basic-busy: SUCCESS (0.052s)
[47.736941] [022/160] (946s left) gem_exec_fence (basic-wait)
[48.031405] Starting subtest: basic-wait
[48.052367] Starting dynamic subtest: rcs0
[48.073757] Dynamic subtest rcs0: SUCCESS (0.017s)
[48.109241] Starting dynamic subtest: vcs0
[48.129991] Dynamic subtest vcs0: SUCCESS (0.019s)
[48.150073] Subtest basic-wait: SUCCESS (0.038s)
[48.575335] [023/160] (945s left) gem_exec_fence (basic-await)
[48.872702] Starting subtest: basic-await
[48.892412] Starting dynamic subtest: rcs0
[48.943452] Dynamic subtest rcs0: SUCCESS (0.069s)
[48.970098] Starting dynamic subtest: vcs0
[49.015434] Dynamic subtest vcs0: SUCCESS (0.073s)
[49.033783] Subtest basic-await: SUCCESS (0.143s)
[49.531236] [024/160] (945s left) gem_exec_fence (nb-await)
[49.834246] Starting subtest: nb-await
[49.855405] Starting dynamic subtest: rcs0
[49.893281] Dynamic subtest rcs0: SUCCESS (0.054s)
[49.924742] Starting dynamic subtest: vcs0
[49.943437] Dynamic subtest vcs0: SUCCESS (0.054s)
[49.964609] Subtest nb-await: SUCCESS (0.108s)
[50.454305] [025/160] (944s left) gem_exec_gttfill (basic)
[50.636109] Starting subtest: basic
[60.450984] Subtest basic: SUCCESS (9.828s)
[60.668538] [026/160] (934s left) gem_exec_parallel (engines)
[60.969923] Starting subtest: engines
[60.988232] Starting dynamic subtest: basic
[62.796062] Dynamic subtest basic: SUCCESS (1.841s)
[62.824372] Starting dynamic subtest: contexts
[64.910406] Dynamic subtest contexts: SUCCESS (2.121s)
[64.940242] Starting dynamic subtest: fds
[64.978507] Dynamic subtest fds: SKIP (0.002s)
[65.005855] Starting dynamic subtest: userptr
[67.045570] Dynamic subtest userptr: SUCCESS (2.122s)
[67.064483] Subtest engines: SUCCESS (6.087s)
[67.244616] [027/160] (927s left) gem_exec_store (basic)
[67.486741] Starting subtest: basic
[67.505857] Subtest basic: SUCCESS (0.037s)
[67.661775] [028/160] (927s left) gem_flink_basic (bad-flink)
[67.835306] Starting subtest: bad-flink
[67.856355] Subtest bad-flink: SUCCESS (0.000s)
[67.934075] [029/160] (927s left) gem_flink_basic (bad-open)
[68.110052] Starting subtest: bad-open
[68.129196] Subtest bad-open: SUCCESS (0.000s)
[68.206925] [030/160] (926s left) gem_flink_basic (basic)
[68.407135] Starting subtest: basic
[68.437415] Subtest basic: SUCCESS (0.000s)
[68.538468] [031/160] (926s left) gem_flink_basic (double-flink)
[68.787380] Starting subtest: double-flink
[68.816578] Subtest double-flink: SUCCESS (0.000s)
[68.920158] [032/160] (926s left) gem_flink_basic (flink-lifetime)
[69.137094] Starting subtest: flink-lifetime
[69.163379] Subtest flink-lifetime: SUCCESS (0.003s)
[69.270735] [033/160] (926s left) gem_huc_copy (huc-copy)
[69.517376] Subtest huc-copy: SKIP (0.000s)
[69.656074] [034/160] (925s left) gem_linear_blits (basic)
[69.912686] Starting subtest: basic
[69.944583] Subtest basic: SUCCESS (0.019s)
[70.136212] [035/160] (925s left) gem_mmap (basic)
[70.474119] Starting subtest: basic
[70.578373] Subtest basic: SUCCESS (0.001s)
[70.655533] [036/160] (924s left) gem_mmap_gtt (basic)
[70.854541] Starting subtest: basic
[70.875049] Subtest basic: SUCCESS (0.001s)
[70.953435] [037/160] (924s left) gem_render_linear_blits (basic)
[71.136414] Starting subtest: basic
[71.240271] Subtest basic: SUCCESS (0.031s)
[71.320564] [038/160] (924s left) gem_render_tiled_blits (basic)
[71.525157] Starting subtest: basic
[71.647125] Subtest basic: SUCCESS (0.036s)
[71.728225] [039/160] (924s left) gem_ringfill (basic-all)
[71.962797] Starting subtest: basic-all
[73.056772] Subtest basic-all: SUCCESS (1.112s)
[73.161107] [040/160] (922s left) gem_softpin (allocator-basic)
[73.378856] Subtest allocator-basic: SKIP (0.000s)
[73.483608] [041/160] (922s left) gem_softpin (allocator-basic-reserve)
[73.702142] Subtest allocator-basic-reserve: SKIP (0.000s)
[73.804731] [042/160] (922s left) gem_softpin (safe-alignment)
[74.016848] Starting subtest: safe-alignment
[74.038196] Subtest safe-alignment: SUCCESS (0.008s)
[74.118352] [043/160] (921s left) gem_sync (basic-all)
[74.327329] Starting subtest: basic-all
[76.444225] Subtest basic-all: SUCCESS (2.162s)
[76.645475] [044/160] (919s left) gem_sync (basic-each)
[76.872863] Starting subtest: basic-each
[78.897066] Subtest basic-each: SUCCESS (2.062s)
[79.087272] [045/160] (917s left) gem_tiled_blits (basic)
[79.341992] Starting subtest: basic
[79.380509] Subtest basic: SUCCESS (0.035s)
[79.534776] [046/160] (916s left) gem_tiled_fence_blits (basic)
[79.931894] Starting subtest: basic
[79.953306] Subtest basic: SUCCESS (0.014s)
[80.095862] [047/160] (916s left) gem_tiled_pread_basic
[80.657530] [048/160] (915s left) gem_wait (busy@all-engines)
[80.823163] Starting subtest: busy
[80.844978] Starting dynamic subtest: all-engines
[81.331259] Dynamic subtest all-engines: SUCCESS (0.511s)
[81.350898] Subtest busy: SUCCESS (0.511s)
[81.571332] [049/160] (914s left) gem_wait (wait@all-engines)
[81.750185] Starting subtest: wait
[81.778126] Starting dynamic subtest: all-engines
[82.767238] Dynamic subtest all-engines: SUCCESS (1.020s)
[82.786639] Subtest wait: SUCCESS (1.020s)
[83.019211] [050/160] (913s left) i915_getparams_basic (basic-eu-total)
[83.191384] Starting subtest: basic-eu-total
[83.234401] Subtest basic-eu-total: SUCCESS (0.000s)
[83.311941] [051/160] (913s left) i915_getparams_basic (basic-subslice-total)
[83.494380] Starting subtest: basic-subslice-total
[83.533353] Subtest basic-subslice-total: SUCCESS (0.000s)
[83.615821] [052/160] (913s left) i915_hangman (error-state-basic)
[83.790080] Starting subtest: error-state-basic
[83.811717] Subtest error-state-basic: SUCCESS (0.004s)
[83.913294] [053/160] (912s left) i915_pciid
[84.217534] [054/160] (912s left) kms_addfb_basic (addfb25-4-tiled)
[84.456433] Starting subtest: addfb25-4-tiled
[84.476334] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[84.570946] [055/160] (912s left) kms_addfb_basic (addfb25-bad-modifier)
[84.745233] Starting subtest: addfb25-bad-modifier
[84.766543] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[84.850435] [056/160] (912s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[85.051849] Starting subtest: addfb25-framebuffer-vs-set-tiling
[85.072620] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[85.153244] [057/160] (911s left) kms_addfb_basic (addfb25-modifier-no-flag)
[85.331899] Starting subtest: addfb25-modifier-no-flag
[85.352344] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[85.434986] [058/160] (911s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[85.600527] Starting subtest: addfb25-x-tiled-legacy
[85.622627] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[85.706904] [059/160] (911s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[85.863826] Starting subtest: addfb25-x-tiled-mismatch-legacy
[85.886125] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[85.972429] [060/160] (911s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[86.166880] Starting subtest: addfb25-yf-tiled-legacy
[86.187202] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[86.267125] [061/160] (911s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[86.454515] Starting subtest: addfb25-y-tiled-legacy
[86.473993] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[86.556791] [062/160] (910s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[86.733951] Starting subtest: addfb25-y-tiled-small-legacy
[86.776803] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[86.862361] [063/160] (910s left) kms_addfb_basic (bad-pitch-0)
[87.045455] Starting subtest: bad-pitch-0
[87.067825] Subtest bad-pitch-0: SUCCESS (0.000s)
[87.147384] [064/160] (910s left) kms_addfb_basic (bad-pitch-1024)
[87.342537] Starting subtest: bad-pitch-1024
[87.363414] Subtest bad-pitch-1024: SUCCESS (0.000s)
[87.443753] [065/160] (910s left) kms_addfb_basic (bad-pitch-128)
[87.618526] Starting subtest: bad-pitch-128
[87.639215] Subtest bad-pitch-128: SUCCESS (0.000s)
[87.724339] [066/160] (910s left) kms_addfb_basic (bad-pitch-256)
[87.904277] Starting subtest: bad-pitch-256
[87.927407] Subtest bad-pitch-256: SUCCESS (0.000s)
[88.010673] [067/160] (909s left) kms_addfb_basic (bad-pitch-32)
[88.208799] Starting subtest: bad-pitch-32
[88.229165] Subtest bad-pitch-32: SUCCESS (0.000s)
[88.308086] [068/160] (909s left) kms_addfb_basic (bad-pitch-63)
[88.488391] Starting subtest: bad-pitch-63
[88.507446] Subtest bad-pitch-63: SUCCESS (0.000s)
[88.592297] [069/160] (909s left) kms_addfb_basic (bad-pitch-65536)
[88.764985] Starting subtest: bad-pitch-65536
[88.786814] Subtest bad-pitch-65536: SUCCESS (0.001s)
[88.871423] [070/160] (909s left) kms_addfb_basic (bad-pitch-999)
[89.055267] Starting subtest: bad-pitch-999
[89.078428] Subtest bad-pitch-999: SUCCESS (0.000s)
[89.158246] [071/160] (909s left) kms_addfb_basic (basic)
[89.350120] Starting subtest: basic
[89.369478] Subtest basic: SUCCESS (0.000s)
[89.454958] [072/160] (908s left) kms_addfb_basic (basic-x-tiled-legacy)
[89.647406] Starting subtest: basic-x-tiled-legacy
[89.667370] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[89.751554] [073/160] (908s left) kms_addfb_basic (basic-y-tiled-legacy)
[89.929230] Starting subtest: basic-y-tiled-legacy
[89.949150] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[90.076071] [074/160] (908s left) kms_addfb_basic (bo-too-small)
[90.290101] Starting subtest: bo-too-small
[90.314507] Subtest bo-too-small: SUCCESS (0.000s)
[90.426246] [075/160] (908s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[90.627384] Starting subtest: bo-too-small-due-to-tiling
[90.656840] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[90.756624] [076/160] (907s left) kms_addfb_basic (clobberred-modifier)
[90.961451] Starting subtest: clobberred-modifier
[90.986996] Subtest clobberred-modifier: SUCCESS (0.000s)
[91.092718] [077/160] (907s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[91.307396] Starting subtest: framebuffer-vs-set-tiling
[91.334843] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[91.446912] [078/160] (907s left) kms_addfb_basic (invalid-get-prop)
[91.669329] Starting subtest: invalid-get-prop
[91.696434] Subtest invalid-get-prop: SUCCESS (0.000s)
[91.805820] [079/160] (907s left) kms_addfb_basic (invalid-get-prop-any)
[91.980481] Starting subtest: invalid-get-prop-any
[92.002075] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[92.085500] [080/160] (906s left) kms_addfb_basic (invalid-set-prop)
[92.264178] Starting subtest: invalid-set-prop
[92.284221] Subtest invalid-set-prop: SUCCESS (0.000s)
[92.365688] [081/160] (906s left) kms_addfb_basic (invalid-set-prop-any)
[92.543308] Starting subtest: invalid-set-prop-any
[92.562806] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[92.647867] [082/160] (906s left) kms_addfb_basic (no-handle)
[92.821144] Starting subtest: no-handle
[92.841031] Subtest no-handle: SUCCESS (0.000s)
[92.927898] [083/160] (906s left) kms_addfb_basic (size-max)
[93.121343] Starting subtest: size-max
[93.142995] Subtest size-max: SUCCESS (0.000s)
[93.224141] [084/160] (906s left) kms_addfb_basic (small-bo)
[93.412403] Starting subtest: small-bo
[93.432393] Subtest small-bo: SUCCESS (0.000s)
[93.512790] [085/160] (905s left) kms_addfb_basic (tile-pitch-mismatch)
[93.720386] Starting subtest: tile-pitch-mismatch
[93.740857] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[93.828178] [086/160] (905s left) kms_addfb_basic (too-high)
[94.009873] Starting subtest: too-high
[94.031036] Subtest too-high: SUCCESS (0.001s)
[94.112282] [087/160] (905s left) kms_addfb_basic (too-wide)
[94.286676] Starting subtest: too-wide
[94.307902] Subtest too-wide: SUCCESS (0.000s)
[94.387975] [088/160] (905s left) kms_addfb_basic (unused-handle)
[94.590218] Starting subtest: unused-handle
[94.609822] Subtest unused-handle: SUCCESS (0.000s)
[94.696340] [089/160] (905s left) kms_addfb_basic (unused-modifier)
[94.878332] Starting subtest: unused-modifier
[94.900168] Subtest unused-modifier: SUCCESS (0.000s)
[94.986623] [090/160] (904s left) kms_addfb_basic (unused-offsets)
[95.165353] Starting subtest: unused-offsets
[95.185917] Subtest unused-offsets: SUCCESS (0.000s)
[95.266317] [091/160] (904s left) kms_addfb_basic (unused-pitches)
[95.457572] Starting subtest: unused-pitches
[95.477069] Subtest unused-pitches: SUCCESS (0.000s)
[95.559939] [092/160] (904s left) kms_busy (basic)
[95.770158] Starting subtest: basic
[95.792556] Starting dynamic subtest: flip
[96.567086] Dynamic subtest flip: SUCCESS (0.817s)
[96.594398] Starting dynamic subtest: modeset
[97.174077] Dynamic subtest modeset: SUCCESS (0.624s)
[97.198169] Subtest basic: SUCCESS (1.444s)
[97.359379] [093/160] (902s left) kms_prop_blob (basic)
[97.554890] Starting subtest: basic
[97.574052] Subtest basic: SUCCESS (0.000s)
[97.660573] [094/160] (902s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[97.844060] Starting subtest: basic-busy-flip-before-cursor-atomic
[98.840568] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (0.986s)
[99.018171] [095/160] (901s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[99.200560] Starting subtest: basic-busy-flip-before-cursor-legacy
[100.180059] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (0.972s)
[100.347492] [096/160] (899s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[100.521846] Starting subtest: basic-flip-after-cursor-atomic
[101.479891] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.965s)
[101.651346] [097/160] (898s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[101.834588] Starting subtest: basic-flip-after-cursor-legacy
[102.805916] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.976s)
[102.957676] [098/160] (897s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[103.167645] Starting subtest: basic-flip-after-cursor-varying-size
[104.149118] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.990s)
[104.320069] [099/160] (896s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[104.494090] Starting subtest: basic-flip-before-cursor-atomic
[105.484462] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.984s)
[105.650950] [100/160] (894s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[105.829596] Starting subtest: basic-flip-before-cursor-legacy
[106.806710] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.976s)
[106.959805] [101/160] (893s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[107.145895] Starting subtest: basic-flip-before-cursor-varying-size
[108.125911] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.996s)
[108.285663] [102/160] (892s left) kms_dsc (dsc-basic)
[108.504694] Subtest dsc-basic: SKIP (0.000s)
[108.587521] [103/160] (892s left) kms_flip (basic-flip-vs-dpms)
[108.789923] Starting subtest: basic-flip-vs-dpms
[108.809056] Starting dynamic subtest: A-VGA1
[110.071902] Dynamic subtest A-VGA1: SUCCESS (1.296s)
[110.107119] Starting dynamic subtest: B-VGA1
[111.297872] Dynamic subtest B-VGA1: SUCCESS (1.236s)
[111.316749] Subtest basic-flip-vs-dpms: SUCCESS (2.535s)
[111.523445] [104/160] (889s left) kms_flip (basic-flip-vs-modeset)
[111.746262] Starting subtest: basic-flip-vs-modeset
[111.770710] Starting dynamic subtest: A-VGA1
[113.050765] Dynamic subtest A-VGA1: SUCCESS (1.349s)
[113.083067] Starting dynamic subtest: B-VGA1
[114.335233] Dynamic subtest B-VGA1: SUCCESS (1.271s)
[114.354675] Subtest basic-flip-vs-modeset: SUCCESS (2.624s)
[114.510798] [105/160] (886s left) kms_flip (basic-flip-vs-wf_vblank)
[114.719679] Starting subtest: basic-flip-vs-wf_vblank
[114.739082] Starting dynamic subtest: A-VGA1
[116.225054] Dynamic subtest A-VGA1: SUCCESS (1.533s)
[116.258601] Starting dynamic subtest: B-VGA1
[117.746468] Dynamic subtest B-VGA1: SUCCESS (1.521s)
[117.766543] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.058s)
[117.970284] [106/160] (883s left) kms_flip (basic-plain-flip)
[118.178745] Starting subtest: basic-plain-flip
[118.198381] Starting dynamic subtest: A-VGA1
[119.390986] Dynamic subtest A-VGA1: SUCCESS (1.248s)
[119.418526] Starting dynamic subtest: B-VGA1
[120.604256] Dynamic subtest B-VGA1: SUCCESS (1.214s)
[120.623074] Subtest basic-plain-flip: SUCCESS (2.467s)
[120.834441] [107/160] (880s left) kms_force_connector_basic (force-connector-state)
[121.051140] Starting subtest: force-connector-state
[121.137613] Subtest force-connector-state: SUCCESS (0.109s)
[121.331978] [108/160] (879s left) kms_force_connector_basic (force-edid)
[121.506562] Starting subtest: force-edid
[121.530028] Subtest force-edid: SUCCESS (0.019s)
[121.643865] [109/160] (879s left) kms_force_connector_basic (force-load-detect)
[121.823073] Starting subtest: force-load-detect
[121.988543] Subtest force-load-detect: SUCCESS (0.174s)
[122.187287] [110/160] (879s left) kms_force_connector_basic (prune-stale-modes)
[122.388632] Starting subtest: prune-stale-modes
[122.408466] Subtest prune-stale-modes: SUCCESS (0.015s)
[122.516085] [111/160] (878s left) kms_frontbuffer_tracking (basic)
[122.763393] Starting subtest: basic
[124.933192] Subtest basic: SUCCESS (2.230s)
[125.062148] [112/160] (876s left) kms_hdmi_inject (inject-audio)
[125.276949] Starting subtest: inject-audio
[125.456544] Subtest inject-audio: SUCCESS (0.154s)
[125.681130] [113/160] (875s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[125.882812] Starting subtest: compare-crc-sanitycheck-xr24
[125.903303] Starting dynamic subtest: pipe-A-VGA-1
[126.199447] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.329s)
[126.233066] Starting dynamic subtest: pipe-B-VGA-1
[126.556195] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.351s)
[126.576113] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.684s)
[126.735497] [114/160] (874s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[126.901967] Starting subtest: compare-crc-sanitycheck-nv12
[126.921070] Starting dynamic subtest: pipe-A-VGA-1
[126.964106] Dynamic subtest pipe-A-VGA-1: SKIP (0.000s)
[127.000341] Starting dynamic subtest: pipe-B-VGA-1
[127.042929] Dynamic subtest pipe-B-VGA-1: SKIP (0.000s)
[127.082107] Subtest compare-crc-sanitycheck-nv12: SKIP (0.004s)
[127.167559] [115/160] (874s left) kms_pipe_crc_basic (hang-read-crc)
[127.365247] Starting subtest: hang-read-crc
[127.387138] Starting dynamic subtest: pipe-A-VGA-1
[128.176732] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.832s)
[128.206312] Starting dynamic subtest: pipe-B-VGA-1
[129.080549] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.901s)
[129.099288] Subtest hang-read-crc: SUCCESS (1.737s)
[129.242604] [116/160] (872s left) kms_pipe_crc_basic (nonblocking-crc)
[129.421492] Starting subtest: nonblocking-crc
[129.442629] Starting dynamic subtest: pipe-A-VGA-1
[129.906869] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.487s)
[129.933803] Starting dynamic subtest: pipe-B-VGA-1
[130.391344] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.495s)
[130.412691] Subtest nonblocking-crc: SUCCESS (0.985s)
[130.550361] [117/160] (871s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[130.736849] Starting subtest: nonblocking-crc-frame-sequence
[130.756365] Starting dynamic subtest: pipe-A-VGA-1
[131.239390] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.501s)
[131.269853] Starting dynamic subtest: pipe-B-VGA-1
[131.729643] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.493s)
[131.748674] Subtest nonblocking-crc-frame-sequence: SUCCESS (0.998s)
[131.884886] [118/160] (870s left) kms_pipe_crc_basic (read-crc)
[132.059892] Starting subtest: read-crc
[132.079725] Starting dynamic subtest: pipe-A-VGA-1
[132.491623] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.427s)
[132.527622] Starting dynamic subtest: pipe-B-VGA-1
[132.948340] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.448s)
[132.974044] Subtest read-crc: SUCCESS (0.880s)
[133.118200] [119/160] (868s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[133.360993] Starting subtest: read-crc-frame-sequence
[133.386333] Starting dynamic subtest: pipe-A-VGA-1
[133.743053] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.422s)
[133.775557] Starting dynamic subtest: pipe-B-VGA-1
[134.160346] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.430s)
[134.183101] Subtest read-crc-frame-sequence: SUCCESS (0.856s)
[134.339415] [120/160] (867s left) kms_pm_backlight (basic-brightness)
[134.595370] Subtest basic-brightness: SKIP (0.000s)
[134.675994] [121/160] (867s left) kms_pm_rpm (basic-pci-d3-state)
[136.149885] Subtest basic-pci-d3-state: SKIP (0.000s)
[136.236380] [122/160] (866s left) kms_pm_rpm (basic-rte)
[136.425796] Starting subtest: basic-rte
[138.015590] Subtest basic-rte: SKIP (1.570s)
[138.149878] [123/160] (864s left) kms_psr (psr-primary-page-flip)
[138.362644] Subtest psr-primary-page-flip: SKIP (0.000s)
[138.445858] [124/160] (864s left) kms_psr (psr-cursor-plane-move)
[138.647262] Subtest psr-cursor-plane-move: SKIP (0.000s)
[138.733497] [125/160] (863s left) kms_psr (psr-sprite-plane-onoff)
[138.925682] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[139.013127] [126/160] (863s left) kms_psr (psr-primary-mmap-gtt)
[139.235969] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[139.318675] [127/160] (863s left) kms_setmode (basic-clone-single-crtc)
[139.500765] Starting subtest: basic-clone-single-crtc
[139.559512] Subtest basic-clone-single-crtc: SKIP (0.000s)
[139.656061] [128/160] (863s left) i915_pm_rps (basic-api)
[139.862375] Subtest basic-api: SKIP (0.000s)
[139.945422] [129/160] (862s left) prime_self_import (basic-llseek-bad)
[140.115916] Starting subtest: basic-llseek-bad
[140.157165] Subtest basic-llseek-bad: SUCCESS (0.002s)
[140.237169] [130/160] (862s left) prime_self_import (basic-llseek-size)
[140.392747] Starting subtest: basic-llseek-size
[140.432026] Subtest basic-llseek-size: SUCCESS (0.007s)
[140.530184] [131/160] (862s left) prime_self_import (basic-with_fd_dup)
[140.679545] Starting subtest: basic-with_fd_dup
[140.723239] Subtest basic-with_fd_dup: SUCCESS (0.007s)
[140.807905] [132/160] (862s left) prime_self_import (basic-with_one_bo)
[140.954939] Starting subtest: basic-with_one_bo
[140.998100] Subtest basic-with_one_bo: SUCCESS (0.005s)
[141.080169] [133/160] (862s left) prime_self_import (basic-with_one_bo_two_files)
[141.232145] Starting subtest: basic-with_one_bo_two_files
[141.274413] Subtest basic-with_one_bo_two_files: SUCCESS (0.003s)
[141.356995] [134/160] (861s left) prime_self_import (basic-with_two_bos)
[141.526535] Starting subtest: basic-with_two_bos
[141.567078] Subtest basic-with_two_bos: SUCCESS (0.004s)
[141.656911] [135/160] (861s left) prime_vgem (basic-fence-flip)
[141.887854] Starting subtest: basic-fence-flip
[142.250045] Subtest basic-fence-flip: SUCCESS (0.410s)
[142.378567] [136/160] (861s left) prime_vgem (basic-fence-mmap)
[142.593362] Starting subtest: basic-fence-mmap
[142.615315] Subtest basic-fence-mmap: SUCCESS (0.052s)
[142.730477] [137/160] (860s left) prime_vgem (basic-fence-read)
[142.911841] Starting subtest: basic-fence-read
[142.951706] Subtest basic-fence-read: SUCCESS (0.066s)
[143.055506] [138/160] (860s left) prime_vgem (basic-gtt)
[143.274112] Starting subtest: basic-gtt
[143.295942] Subtest basic-gtt: SUCCESS (0.025s)
[143.396631] [139/160] (860s left) prime_vgem (basic-read)
[143.596322] Starting subtest: basic-read
[143.615674] Subtest basic-read: SUCCESS (0.032s)
[143.720523] [140/160] (860s left) prime_vgem (basic-write)
[143.913792] Starting subtest: basic-write
[143.934814] Subtest basic-write: SUCCESS (0.035s)
[144.037078] [141/160] (859s left) vgem_basic (setversion)
[144.241243] Starting subtest: setversion
[144.280000] Subtest setversion: SUCCESS (0.000s)
[144.368652] [142/160] (859s left) vgem_basic (create)
[144.576672] Starting subtest: create
[144.597992] Subtest create: SUCCESS (0.001s)
[144.678128] [143/160] (859s left) vgem_basic (debugfs)
[144.874208] Starting subtest: debugfs
[144.893659] Subtest debugfs: SUCCESS (0.001s)
[144.973167] [144/160] (859s left) vgem_basic (dmabuf-export)
[145.145501] Starting subtest: dmabuf-export
[145.185479] Subtest dmabuf-export: SUCCESS (0.003s)
[145.279349] [145/160] (858s left) vgem_basic (dmabuf-fence)
[145.446256] Starting subtest: dmabuf-fence
[145.468392] Subtest dmabuf-fence: SUCCESS (0.001s)
[145.557256] [146/160] (858s left) vgem_basic (dmabuf-fence-before)
[145.772197] Starting subtest: dmabuf-fence-before
[145.793079] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[145.872123] [147/160] (858s left) vgem_basic (dmabuf-mmap)
[146.076509] Starting subtest: dmabuf-mmap
[146.098219] Subtest dmabuf-mmap: SUCCESS (0.028s)
[146.186554] [148/160] (858s left) vgem_basic (mmap)
[146.337660] Starting subtest: mmap
[146.359386] Subtest mmap: SUCCESS (0.022s)
[146.447795] [149/160] (858s left) vgem_basic (second-client)
[146.624125] Starting subtest: second-client
[146.667034] Subtest second-client: SUCCESS (0.033s)
[146.754029] [150/160] (857s left) vgem_basic (sysfs)
[146.946081] Starting subtest: sysfs
[146.966544] Subtest sysfs: SUCCESS (0.001s)
[147.051406] [151/160] (857s left) core_hotunplug (unbind-rebind)
[147.264746] Starting subtest: unbind-rebind
[150.086413] Subtest unbind-rebind: SUCCESS (2.810s)
[150.169229] [152/160] (854s left) vgem_basic (unload)
[150.351768] Starting subtest: unload
[179.461244] Subtest unload: SUCCESS (28.943s)
[179.548303] [153/160] (825s left) i915_module_load (reload)
[179.708070] Starting subtest: reload
[181.822039] Subtest reload: SUCCESS (2.109s)
[181.921728] [154/160] (823s left) gem_lmem_swapping (basic)
[182.118132] Subtest basic: SKIP (0.000s)
[182.218613] [155/160] (822s left) gem_lmem_swapping (parallel-random-engines)
[182.424070] Subtest parallel-random-engines: SKIP (0.000s)
[182.526253] [156/160] (822s left) gem_lmem_swapping (random-engines)
[182.775834] Subtest random-engines: SKIP (0.000s)
[182.886451] [157/160] (822s left) gem_lmem_swapping (verify-random)
[183.141844] Subtest verify-random: SKIP (0.000s)
[183.245941] [158/160] (822s left) i915_pm_rpm (module-reload)
[184.758707] Subtest module-reload: SKIP (0.000s)
[184.854282] [159/160] (820s left) i915_selftest (live)
[185.670999] Starting subtest: live
[185.691243] Starting dynamic subtest: sanitycheck
[186.365218] Dynamic subtest sanitycheck: SUCCESS (0.717s)
[186.389806] Starting dynamic subtest: uncore
[187.079976] Dynamic subtest uncore: SUCCESS (0.709s)
[187.111125] Starting dynamic subtest: workarounds
[187.747107] Dynamic subtest workarounds: SUCCESS (0.667s)
[187.773879] Starting dynamic subtest: gt_engines
[188.465382] Dynamic subtest gt_engines: SUCCESS (0.715s)
[188.491444] Starting dynamic subtest: gt_timelines
[192.947071] Dynamic subtest gt_timelines: SUCCESS (4.478s)
[192.972767] Starting dynamic subtest: gt_contexts
[193.717037] Dynamic subtest gt_contexts: SUCCESS (0.765s)
[193.747049] Starting dynamic subtest: gt_lrc
[194.373162] Dynamic subtest gt_lrc: SUCCESS (0.655s)
[194.400838] Starting dynamic subtest: gt_mocs
[195.052558] Dynamic subtest gt_mocs: SUCCESS (0.674s)
[195.078548] Starting dynamic subtest: gt_pm
[196.301138] Dynamic subtest gt_pm: SUCCESS (1.247s)
[196.328443] Starting dynamic subtest: gt_heartbeat
[197.003208] Dynamic subtest gt_heartbeat: SUCCESS (0.701s)
[197.028053] Starting dynamic subtest: gt_tlb
[197.734104] Dynamic subtest gt_tlb: SUCCESS (0.728s)
[197.759380] Starting dynamic subtest: requests
[202.001923] Dynamic subtest requests: SUCCESS (4.262s)
[202.030204] Starting dynamic subtest: migrate
[202.681888] Dynamic subtest migrate: SUCCESS (0.676s)
[202.710806] Starting dynamic subtest: active
[203.410933] Dynamic subtest active: SUCCESS (0.727s)
[203.442063] Starting dynamic subtest: objects
[205.460887] Dynamic subtest objects: SUCCESS (2.048s)
[205.492163] Starting dynamic subtest: mman
[211.428963] Dynamic subtest mman: SUCCESS (5.962s)
[211.453952] Starting dynamic subtest: dmabuf
[212.216297] Dynamic subtest dmabuf: SUCCESS (0.784s)
[212.243745] Starting dynamic subtest: vma
[212.862556] Dynamic subtest vma: SUCCESS (0.645s)
[212.889201] Starting dynamic subtest: coherency
[214.971969] Dynamic subtest coherency: SUCCESS (2.102s)
[214.999258] Starting dynamic subtest: gtt
[218.631136] Dynamic subtest gtt: SUCCESS (3.657s)
[218.657979] Starting dynamic subtest: gem
[219.475044] Dynamic subtest gem: SUCCESS (0.839s)
[219.500408] Starting dynamic subtest: evict
[220.142043] Dynamic subtest evict: SUCCESS (0.665s)
[220.169564] Starting dynamic subtest: hugepages
[220.860513] Dynamic subtest hugepages: SUCCESS (0.714s)
[220.885410] Starting dynamic subtest: gem_contexts
[224.607855] Dynamic subtest gem_contexts: SUCCESS (3.742s)
[224.634042] Starting dynamic subtest: client
[225.330976] Dynamic subtest client: SUCCESS (0.721s)
[225.358398] Starting dynamic subtest: gem_migrate
[226.005263] Dynamic subtest gem_migrate: SUCCESS (0.669s)
[226.033931] Starting dynamic subtest: reset
[226.778126] Dynamic subtest reset: SUCCESS (0.765s)
[226.809359] Starting dynamic subtest: memory_region
[227.515285] Dynamic subtest memory_region: SUCCESS (0.739s)
[227.542439] Starting dynamic subtest: hangcheck
[229.807531] Dynamic subtest hangcheck: SUCCESS (2.286s)
[229.833050] Starting dynamic subtest: execlists
[230.550338] Dynamic subtest execlists: SUCCESS (0.742s)
[230.575350] Starting dynamic subtest: ring_submission
[231.216621] Dynamic subtest ring_submission: SUCCESS (0.659s)
[231.246895] Starting dynamic subtest: perf
[231.951990] Dynamic subtest perf: SUCCESS (0.734s)
[231.982409] Starting dynamic subtest: slpc
[232.697330] Dynamic subtest slpc: SUCCESS (0.739s)
[232.729639] Starting dynamic subtest: guc
[233.367077] Dynamic subtest guc: SUCCESS (0.665s)
[233.398774] Starting dynamic subtest: guc_multi_lrc
[234.042824] Dynamic subtest guc_multi_lrc: SUCCESS (0.673s)
[234.073233] Starting dynamic subtest: guc_hang
[234.701070] Dynamic subtest guc_hang: SUCCESS (0.656s)
[234.729923] Starting dynamic subtest: late_gt_pm
[235.432071] Dynamic subtest late_gt_pm: SUCCESS (0.730s)
[235.452809] Subtest live: SUCCESS (49.760s)
[235.547187] [160/160] (770s left) dmabuf (all-tests)
[235.746051] Starting subtest: all-tests
[235.765265] Starting dynamic subtest: sanitycheck
[235.817866] Dynamic subtest sanitycheck: SUCCESS (0.072s)
[235.847630] Starting dynamic subtest: dma_fence
[235.994080] Dynamic subtest dma_fence: SUCCESS (0.176s)
[236.019972] Starting dynamic subtest: dma_fence_chain
[251.398957] Dynamic subtest dma_fence_chain: SUCCESS (15.394s)
[251.425251] Starting dynamic subtest: dma_fence_unwrap
[251.469387] Dynamic subtest dma_fence_unwrap: SUCCESS (0.070s)
[251.496491] Starting dynamic subtest: dma_resv
[251.544336] Dynamic subtest dma_resv: SUCCESS (0.071s)
[251.565104] Subtest all-tests: SUCCESS (15.793s)
[251.622202] Closing watchdogs
Done.