[46.303533] Initializing watchdogs
[46.303880]   /dev/watchdog0
[46.369478] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:5a85 Intel Broxton (Gen9) HD Graphics 500
[46.400754] [001/161] (960s left) i915_module_load (load)
[47.050129] Starting subtest: load
[51.398269] Subtest load: SUCCESS (4.325s)
[51.539934] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[51.548219] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[51.611179] [002/161] (955s left) core_auth (basic-auth)
[51.908543] Starting subtest: basic-auth
[51.930124] Subtest basic-auth: SUCCESS (0.002s)
[52.082401] [003/161] (955s left) debugfs_test (read_all_entries)
[52.326744] Starting subtest: read_all_entries
[52.501894] Subtest read_all_entries: SUCCESS (0.157s)
[52.817289] [004/161] (954s left) debugfs_test (basic-hwmon)
[53.088107] Starting subtest: basic-hwmon
[53.128175] Subtest basic-hwmon: SKIP (0.000s)
[53.382294] [005/161] (954s left) debugfs_test (sysfs)
[53.657099] Starting subtest: sysfs
[53.800425] Subtest sysfs: SUCCESS (0.160s)
[54.095922] [006/161] (953s left) fbdev (eof)
[54.369159] Starting subtest: eof
[54.390894] Subtest eof: SUCCESS (0.004s)
[54.550985] [007/161] (953s left) fbdev (info)
[54.824503] Starting subtest: info
[54.847086] Subtest info: SUCCESS (0.000s)
[55.005454] [008/161] (953s left) fbdev (nullptr)
[55.260289] Starting subtest: nullptr
[55.282883] Subtest nullptr: SUCCESS (0.000s)
[55.445777] [009/161] (952s left) fbdev (read)
[55.675498] Starting subtest: read
[57.220096] Subtest read: SUCCESS (1.500s)
[57.380153] [010/161] (951s left) fbdev (write)
[57.626680] Starting subtest: write
[58.388650] Subtest write: SUCCESS (0.776s)
[58.571682] [011/161] (950s left) gem_basic (bad-close)
[58.830349] Starting subtest: bad-close
[58.871176] Subtest bad-close: SUCCESS (0.000s)
[59.034725] [012/161] (949s left) gem_basic (create-close)
[59.310118] Starting subtest: create-close
[59.352194] Subtest create-close: SUCCESS (0.000s)
[59.508815] [013/161] (949s left) gem_basic (create-fd-close)
[59.763465] Starting subtest: create-fd-close
[59.807119] Subtest create-fd-close: SUCCESS (0.000s)
[59.969785] [014/161] (949s left) gem_busy (busy@all-engines)
[60.238760] Starting subtest: busy
[60.262435] Starting dynamic subtest: all-engines
[60.336068] Dynamic subtest all-engines: SUCCESS (0.116s)
[60.359547] Subtest busy: SUCCESS (0.117s)
[60.840872] [015/161] (948s left) gem_close_race (basic-process)
[61.117081] Starting subtest: basic-process
[61.224475] Subtest basic-process: SUCCESS (0.118s)
[61.516573] [016/161] (948s left) gem_close_race (basic-threads)
[61.795380] Starting subtest: basic-threads
[63.041336] Subtest basic-threads: SUCCESS (1.235s)
[63.330734] [017/161] (946s left) gem_ctx_create (basic)
[63.601274] Starting subtest: basic
[63.622282] Subtest basic: SUCCESS (0.000s)
[63.861184] [018/161] (946s left) gem_ctx_create (basic-files)
[64.100504] Starting subtest: basic-files
[66.155132] Subtest basic-files: SUCCESS (2.022s)
[66.444686] [019/161] (943s left) gem_ctx_exec (basic)
[66.682864] Starting subtest: basic
[66.703708] Subtest basic: SUCCESS (0.010s)
[66.861674] [020/161] (943s left) gem_exec_basic (basic)
[67.119811] Starting subtest: basic
[67.142595] Starting dynamic subtest: rcs0-smem
[67.163045] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[67.203891] Starting dynamic subtest: bcs0-smem
[67.226769] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[67.271412] Starting dynamic subtest: vcs0-smem
[67.291740] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[67.332536] Starting dynamic subtest: vecs0-smem
[67.355264] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[67.377571] Subtest basic: SUCCESS (0.014s)
[67.537591] [021/161] (942s left) gem_exec_create (basic)
[67.945866] Starting subtest: basic
[67.967762] Starting dynamic subtest: smem
[70.029543] Dynamic subtest smem: SUCCESS (2.080s)
[70.049915] Subtest basic: SUCCESS (2.082s)
[70.345250] [022/161] (940s left) gem_exec_fence (basic-busy)
[70.769228] Starting subtest: basic-busy
[70.791097] Starting dynamic subtest: rcs0
[70.836684] Dynamic subtest rcs0: SUCCESS (0.039s)
[70.881556] Starting dynamic subtest: bcs0
[70.922391] Dynamic subtest bcs0: SUCCESS (0.030s)
[70.991471] Starting dynamic subtest: vcs0
[71.032886] Dynamic subtest vcs0: SUCCESS (0.038s)
[71.081255] Starting dynamic subtest: vecs0
[71.123790] Dynamic subtest vecs0: SUCCESS (0.032s)
[71.149306] Subtest basic-busy: SUCCESS (0.144s)
[71.847905] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[71.871369] [023/161] (939s left) gem_exec_fence (basic-wait)
[72.261622] Starting subtest: basic-wait
[72.282907] Starting dynamic subtest: rcs0
[72.303057] Dynamic subtest rcs0: SUCCESS (0.036s)
[72.353143] Starting dynamic subtest: bcs0
[72.382352] Dynamic subtest bcs0: SUCCESS (0.033s)
[72.436536] Starting dynamic subtest: vcs0
[72.457588] Dynamic subtest vcs0: SUCCESS (0.041s)
[72.515242] Starting dynamic subtest: vecs0
[72.537780] Dynamic subtest vecs0: SUCCESS (0.027s)
[72.558702] Subtest basic-wait: SUCCESS (0.141s)
[73.233281] [024/161] (937s left) gem_exec_fence (basic-await)
[73.627390] Starting subtest: basic-await
[73.647561] Starting dynamic subtest: rcs0
[73.797159] Dynamic subtest rcs0: SUCCESS (0.167s)
[73.847088] Starting dynamic subtest: bcs0
[73.966477] Dynamic subtest bcs0: SUCCESS (0.169s)
[74.011149] Starting dynamic subtest: vcs0
[74.132535] Dynamic subtest vcs0: SUCCESS (0.165s)
[74.177079] Starting dynamic subtest: vecs0
[74.303279] Dynamic subtest vecs0: SUCCESS (0.167s)
[74.324115] Subtest basic-await: SUCCESS (0.675s)
[75.127400] [025/161] (936s left) gem_exec_fence (nb-await)
[75.537393] Starting subtest: nb-await
[75.561260] Starting dynamic subtest: rcs0
[75.605425] Dynamic subtest rcs0: SUCCESS (0.083s)
[75.666962] Starting dynamic subtest: bcs0
[75.690759] Dynamic subtest bcs0: SUCCESS (0.074s)
[75.754270] Starting dynamic subtest: vcs0
[75.775455] Dynamic subtest vcs0: SUCCESS (0.075s)
[75.829604] Starting dynamic subtest: vecs0
[75.852347] Dynamic subtest vecs0: SUCCESS (0.075s)
[75.874598] Subtest nb-await: SUCCESS (0.311s)
[76.628579] [026/161] (934s left) gem_exec_gttfill (basic)
[76.890854] Starting subtest: basic
[107.337334] Subtest basic: SUCCESS (30.443s)
[107.652556] [027/161] (903s left) gem_exec_parallel (engines)
[108.073424] Starting subtest: engines
[108.096229] Starting dynamic subtest: basic
[112.473472] Dynamic subtest basic: SUCCESS (4.229s)
[112.526906] Starting dynamic subtest: contexts
[118.414726] Dynamic subtest contexts: SUCCESS (5.931s)
[118.466968] Starting dynamic subtest: fds
[123.924278] Dynamic subtest fds: SUCCESS (5.715s)
[123.975476] Starting dynamic subtest: userptr
[128.281140] Dynamic subtest userptr: SUCCESS (4.171s)
[128.303503] Subtest engines: SUCCESS (20.049s)
[128.481662] [028/161] (883s left) gem_exec_store (basic)
[128.822948] Starting subtest: basic
[128.843052] Subtest basic: SUCCESS (0.079s)
[129.128611] [029/161] (882s left) gem_flink_basic (bad-flink)
[129.406671] Starting subtest: bad-flink
[129.428786] Subtest bad-flink: SUCCESS (0.000s)
[129.601650] [030/161] (882s left) gem_flink_basic (bad-open)
[129.842066] Starting subtest: bad-open
[129.862138] Subtest bad-open: SUCCESS (0.000s)
[130.047658] [031/161] (882s left) gem_flink_basic (basic)
[130.310344] Starting subtest: basic
[130.332235] Subtest basic: SUCCESS (0.000s)
[130.500106] [032/161] (881s left) gem_flink_basic (double-flink)
[130.768188] Starting subtest: double-flink
[130.789447] Subtest double-flink: SUCCESS (0.000s)
[130.958538] [033/161] (881s left) gem_flink_basic (flink-lifetime)
[131.203170] Starting subtest: flink-lifetime
[131.224912] Subtest flink-lifetime: SUCCESS (0.003s)
[131.398555] [034/161] (881s left) gem_huc_copy (huc-copy)
[131.646672] Starting subtest: huc-copy
[131.688297] Subtest huc-copy: SKIP (0.000s)
[131.857610] [035/161] (881s left) gem_linear_blits (basic)
[132.272068] Starting subtest: basic
[132.313953] Subtest basic: SUCCESS (0.028s)
[132.592683] [036/161] (880s left) gem_mmap (basic)
[133.002804] Starting subtest: basic
[133.110096] Subtest basic: SUCCESS (0.001s)
[133.285881] [037/161] (880s left) gem_mmap_gtt (basic)
[133.546372] Starting subtest: basic
[133.567603] Subtest basic: SUCCESS (0.002s)
[133.766332] [038/161] (879s left) gem_render_linear_blits (basic)
[134.005492] Starting subtest: basic
[134.112149] Subtest basic: SUCCESS (0.104s)
[134.304730] [039/161] (879s left) gem_render_tiled_blits (basic)
[134.554947] Starting subtest: basic
[134.683173] Subtest basic: SUCCESS (0.130s)
[134.876150] [040/161] (879s left) gem_ringfill (basic-all)
[135.450584] Starting subtest: basic-all
[136.672296] Subtest basic-all: SUCCESS (1.246s)
[136.894437] [041/161] (877s left) gem_softpin (allocator-basic)
[137.156201] Starting subtest: allocator-basic
[137.395123] Subtest allocator-basic: SUCCESS (0.282s)
[137.623490] [042/161] (876s left) gem_softpin (allocator-basic-reserve)
[137.874389] Starting subtest: allocator-basic-reserve
[138.121485] Subtest allocator-basic-reserve: SUCCESS (0.289s)
[138.341429] [043/161] (876s left) gem_softpin (safe-alignment)
[138.563874] Starting subtest: safe-alignment
[138.591556] Subtest safe-alignment: SUCCESS (0.035s)
[138.809270] [044/161] (875s left) gem_sync (basic-all)
[139.274854] Starting subtest: basic-all
[141.687766] Subtest basic-all: SUCCESS (2.442s)
[141.881788] [045/161] (872s left) gem_sync (basic-each)
[142.387502] Starting subtest: basic-each
[144.592219] Subtest basic-each: SUCCESS (2.451s)
[144.890614] [046/161] (870s left) gem_tiled_blits (basic)
[145.279756] Starting subtest: basic
[145.313157] Subtest basic: SUCCESS (0.060s)
[145.590582] [047/161] (869s left) gem_tiled_fence_blits (basic)
[146.140531] Starting subtest: basic
[146.162433] Subtest basic: SUCCESS (0.044s)
[146.435763] [048/161] (868s left) gem_tiled_pread_basic
[147.282884] [049/161] (868s left) gem_wait (busy@all-engines)
[147.557413] Starting subtest: busy
[147.577624] Starting dynamic subtest: all-engines
[148.099468] Dynamic subtest all-engines: SUCCESS (0.537s)
[148.119598] Subtest busy: SUCCESS (0.537s)
[148.449583] [050/161] (867s left) gem_wait (wait@all-engines)
[148.718573] Starting subtest: wait
[148.741391] Starting dynamic subtest: all-engines
[149.775789] Dynamic subtest all-engines: SUCCESS (1.052s)
[149.798698] Subtest wait: SUCCESS (1.053s)
[150.125118] [051/161] (865s left) i915_getparams_basic (basic-eu-total)
[150.378691] Starting subtest: basic-eu-total
[150.418485] Subtest basic-eu-total: SUCCESS (0.000s)
[150.589071] [052/161] (865s left) i915_getparams_basic (basic-subslice-total)
[150.852403] Starting subtest: basic-subslice-total
[150.894721] Subtest basic-subslice-total: SUCCESS (0.000s)
[151.069409] [053/161] (865s left) i915_hangman (error-state-basic)
[151.337461] Starting subtest: error-state-basic
[151.357755] Subtest error-state-basic: SUCCESS (0.018s)
[151.539403] [054/161] (864s left) i915_pciid
[151.980242] [055/161] (864s left) kms_addfb_basic (addfb25-4-tiled)
[152.416189] Starting subtest: addfb25-4-tiled
[152.436675] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[152.626516] [056/161] (864s left) kms_addfb_basic (addfb25-bad-modifier)
[152.980605] Starting subtest: addfb25-bad-modifier
[153.000787] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[153.177844] [057/161] (863s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[153.532145] Starting subtest: addfb25-framebuffer-vs-set-tiling
[153.555638] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[153.729665] [058/161] (863s left) kms_addfb_basic (addfb25-modifier-no-flag)
[154.076325] Starting subtest: addfb25-modifier-no-flag
[154.099063] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[154.275705] [059/161] (862s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[154.627376] Starting subtest: addfb25-x-tiled-legacy
[154.650068] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[154.848718] [060/161] (862s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[155.200434] Starting subtest: addfb25-x-tiled-mismatch-legacy
[155.226346] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[155.411475] [061/161] (862s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[155.789158] Starting subtest: addfb25-yf-tiled-legacy
[155.814460] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[156.000323] [062/161] (861s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[156.372410] Starting subtest: addfb25-y-tiled-legacy
[156.394674] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[156.565102] [063/161] (861s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[156.896374] Starting subtest: addfb25-y-tiled-small-legacy
[156.916349] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[157.095762] [064/161] (861s left) kms_addfb_basic (bad-pitch-0)
[157.337806] Starting subtest: bad-pitch-0
[157.357779] Subtest bad-pitch-0: SUCCESS (0.000s)
[157.660697] [065/161] (860s left) kms_addfb_basic (bad-pitch-1024)
[157.925680] Starting subtest: bad-pitch-1024
[157.945180] Subtest bad-pitch-1024: SUCCESS (0.000s)
[158.193720] [066/161] (860s left) kms_addfb_basic (bad-pitch-128)
[158.453989] Starting subtest: bad-pitch-128
[158.476235] Subtest bad-pitch-128: SUCCESS (0.000s)
[158.744715] [067/161] (859s left) kms_addfb_basic (bad-pitch-256)
[158.988393] Starting subtest: bad-pitch-256
[159.007710] Subtest bad-pitch-256: SUCCESS (0.000s)
[159.294184] [068/161] (859s left) kms_addfb_basic (bad-pitch-32)
[159.533878] Starting subtest: bad-pitch-32
[159.555407] Subtest bad-pitch-32: SUCCESS (0.000s)
[159.845090] [069/161] (859s left) kms_addfb_basic (bad-pitch-63)
[160.123954] Starting subtest: bad-pitch-63
[160.145594] Subtest bad-pitch-63: SUCCESS (0.000s)
[160.411603] [070/161] (858s left) kms_addfb_basic (bad-pitch-65536)
[160.651583] Starting subtest: bad-pitch-65536
[160.671060] Subtest bad-pitch-65536: SUCCESS (0.001s)
[160.959700] [071/161] (858s left) kms_addfb_basic (bad-pitch-999)
[161.222457] Starting subtest: bad-pitch-999
[161.241798] Subtest bad-pitch-999: SUCCESS (0.000s)
[161.513727] [072/161] (857s left) kms_addfb_basic (basic)
[161.774782] Starting subtest: basic
[161.795482] Subtest basic: SUCCESS (0.000s)
[162.063998] [073/161] (857s left) kms_addfb_basic (basic-x-tiled-legacy)
[162.329551] Starting subtest: basic-x-tiled-legacy
[162.351218] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[162.611369] [074/161] (857s left) kms_addfb_basic (basic-y-tiled-legacy)
[162.833028] Starting subtest: basic-y-tiled-legacy
[162.852695] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[163.147407] [075/161] (856s left) kms_addfb_basic (bo-too-small)
[163.494819] Starting subtest: bo-too-small
[163.514694] Subtest bo-too-small: SUCCESS (0.001s)
[163.700639] [076/161] (856s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[164.052404] Starting subtest: bo-too-small-due-to-tiling
[164.072569] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[164.244298] [077/161] (856s left) kms_addfb_basic (clobberred-modifier)
[164.508922] Starting subtest: clobberred-modifier
[164.529385] Subtest clobberred-modifier: SUCCESS (0.000s)
[164.816804] [078/161] (855s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[165.088337] Starting subtest: framebuffer-vs-set-tiling
[165.116857] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[165.389572] [079/161] (855s left) kms_addfb_basic (invalid-get-prop)
[165.681220] Starting subtest: invalid-get-prop
[165.706715] Subtest invalid-get-prop: SUCCESS (0.000s)
[165.946868] [080/161] (854s left) kms_addfb_basic (invalid-get-prop-any)
[166.204343] Starting subtest: invalid-get-prop-any
[166.225992] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[166.492894] [081/161] (854s left) kms_addfb_basic (invalid-set-prop)
[166.736977] Starting subtest: invalid-set-prop
[166.756689] Subtest invalid-set-prop: SUCCESS (0.000s)
[167.041654] [082/161] (854s left) kms_addfb_basic (invalid-set-prop-any)
[167.302894] Starting subtest: invalid-set-prop-any
[167.322751] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[167.595444] [083/161] (853s left) kms_addfb_basic (no-handle)
[167.823907] Starting subtest: no-handle
[167.844721] Subtest no-handle: SUCCESS (0.000s)
[168.125665] [084/161] (853s left) kms_addfb_basic (size-max)
[168.461420] Starting subtest: size-max
[168.482814] Subtest size-max: SUCCESS (0.000s)
[168.660788] [085/161] (853s left) kms_addfb_basic (small-bo)
[169.014838] Starting subtest: small-bo
[169.034315] Subtest small-bo: SUCCESS (0.000s)
[169.232283] [086/161] (852s left) kms_addfb_basic (tile-pitch-mismatch)
[169.476785] Starting subtest: tile-pitch-mismatch
[169.497470] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[169.778816] [087/161] (852s left) kms_addfb_basic (too-high)
[170.133959] Starting subtest: too-high
[170.155582] Subtest too-high: SUCCESS (0.003s)
[170.331099] [088/161] (851s left) kms_addfb_basic (too-wide)
[170.683468] Starting subtest: too-wide
[170.705987] Subtest too-wide: SUCCESS (0.000s)
[170.902096] [089/161] (851s left) kms_addfb_basic (unused-handle)
[171.166563] Starting subtest: unused-handle
[171.188696] Subtest unused-handle: SUCCESS (0.000s)
[171.449444] [090/161] (851s left) kms_addfb_basic (unused-modifier)
[171.706520] Starting subtest: unused-modifier
[171.727323] Subtest unused-modifier: SUCCESS (0.000s)
[171.991701] [091/161] (850s left) kms_addfb_basic (unused-offsets)
[172.259811] Starting subtest: unused-offsets
[172.280697] Subtest unused-offsets: SUCCESS (0.000s)
[172.547386] [092/161] (850s left) kms_addfb_basic (unused-pitches)
[172.814267] Starting subtest: unused-pitches
[172.841620] Subtest unused-pitches: SUCCESS (0.000s)
[173.102151] [093/161] (849s left) kms_busy (basic)
[173.462651] Starting subtest: basic
[173.483052] Starting dynamic subtest: flip
[174.298485] Dynamic subtest flip: SUCCESS (0.811s)
[174.355523] Starting dynamic subtest: modeset
[175.764391] Dynamic subtest modeset: SUCCESS (1.489s)
[175.785889] Subtest basic: SUCCESS (2.304s)
[176.262963] [094/161] (846s left) kms_prop_blob (basic)
[176.539333] Starting subtest: basic
[176.560313] Subtest basic: SUCCESS (0.000s)
[176.735074] [095/161] (846s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[177.090917] Starting subtest: basic-busy-flip-before-cursor-atomic
[177.139953] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[177.313317] [096/161] (846s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[177.664699] Starting subtest: basic-busy-flip-before-cursor-legacy
[177.706360] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[177.878108] [097/161] (845s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[178.234149] Starting subtest: basic-flip-after-cursor-atomic
[179.220532] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.995s)
[179.678089] [098/161] (844s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[180.042837] Starting subtest: basic-flip-after-cursor-legacy
[181.018234] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.987s)
[181.469160] [099/161] (842s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[181.815948] Starting subtest: basic-flip-after-cursor-varying-size
[182.791199] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.988s)
[183.241200] [100/161] (841s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[183.594351] Starting subtest: basic-flip-before-cursor-atomic
[184.567562] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.986s)
[185.021620] [101/161] (839s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[185.354476] Starting subtest: basic-flip-before-cursor-legacy
[186.325714] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.986s)
[186.782944] [102/161] (837s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[187.129952] Starting subtest: basic-flip-before-cursor-varying-size
[188.093411] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.985s)
[188.556142] [103/161] (836s left) kms_dsc (dsc-basic)
[188.945651] Subtest dsc-basic: SKIP (0.000s)
[189.120341] [104/161] (835s left) kms_flip (basic-flip-vs-dpms)
[189.488303] Starting subtest: basic-flip-vs-dpms
[189.508571] Starting dynamic subtest: A-DP1
[190.734073] Dynamic subtest A-DP1: SUCCESS (1.277s)
[190.780215] Starting dynamic subtest: B-DP1
[191.917087] Dynamic subtest B-DP1: SUCCESS (1.175s)
[191.969159] Starting dynamic subtest: C-DP1
[193.111720] Dynamic subtest C-DP1: SUCCESS (1.186s)
[193.182229] Subtest basic-flip-vs-dpms: SUCCESS (3.649s)
[193.598769] [105/161] (831s left) kms_flip (basic-flip-vs-modeset)
[193.956748] Starting subtest: basic-flip-vs-modeset
[193.977943] Starting dynamic subtest: A-DP1
[195.238348] Dynamic subtest A-DP1: SUCCESS (1.273s)
[195.291732] Starting dynamic subtest: B-DP1
[196.390944] Dynamic subtest B-DP1: SUCCESS (1.160s)
[196.440178] Starting dynamic subtest: C-DP1
[197.558793] Dynamic subtest C-DP1: SUCCESS (1.161s)
[197.580742] Subtest basic-flip-vs-modeset: SUCCESS (3.604s)
[198.014364] [106/161] (827s left) kms_flip (basic-flip-vs-wf_vblank)
[198.395761] Starting subtest: basic-flip-vs-wf_vblank
[198.417190] Starting dynamic subtest: A-DP1
[199.826222] Dynamic subtest A-DP1: SUCCESS (1.457s)
[199.873583] Starting dynamic subtest: B-DP1
[201.249311] Dynamic subtest B-DP1: SUCCESS (1.420s)
[201.296207] Starting dynamic subtest: C-DP1
[202.676299] Dynamic subtest C-DP1: SUCCESS (1.421s)
[202.697974] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.311s)
[203.222998] [107/161] (822s left) kms_flip (basic-plain-flip)
[203.601267] Starting subtest: basic-plain-flip
[203.623292] Starting dynamic subtest: A-DP1
[204.754662] Dynamic subtest A-DP1: SUCCESS (1.168s)
[204.807170] Starting dynamic subtest: B-DP1
[205.906257] Dynamic subtest B-DP1: SUCCESS (1.144s)
[205.960888] Starting dynamic subtest: C-DP1
[207.037379] Dynamic subtest C-DP1: SUCCESS (1.139s)
[207.061175] Subtest basic-plain-flip: SUCCESS (3.464s)
[207.578904] [108/161] (818s left) kms_force_connector_basic (force-connector-state)
[207.875951] Subtest force-connector-state: SKIP (0.000s)
[208.049463] [109/161] (817s left) kms_force_connector_basic (force-edid)
[208.323177] Subtest force-edid: SKIP (0.000s)
[208.494737] [110/161] (817s left) kms_force_connector_basic (force-load-detect)
[208.783547] Subtest force-load-detect: SKIP (0.000s)
[208.960074] [111/161] (817s left) kms_force_connector_basic (prune-stale-modes)
[209.255564] Subtest prune-stale-modes: SKIP (0.000s)
[209.434778] [112/161] (816s left) kms_frontbuffer_tracking (basic)
[209.843482] Starting subtest: basic
[213.583669] Subtest basic: SUCCESS (3.801s)
[213.805678] [113/161] (812s left) kms_hdmi_inject (inject-audio)
[214.079127] Subtest inject-audio: SKIP (0.000s)
[214.254449] [114/161] (812s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[214.598663] Starting subtest: compare-crc-sanitycheck-xr24
[214.621336] Starting dynamic subtest: pipe-A-DP-1
[215.068835] Dynamic subtest pipe-A-DP-1: SUCCESS (0.466s)
[215.136676] Starting dynamic subtest: pipe-B-DP-1
[215.697336] Dynamic subtest pipe-B-DP-1: SUCCESS (0.636s)
[215.746222] Starting dynamic subtest: pipe-C-DP-1
[216.350387] Dynamic subtest pipe-C-DP-1: SUCCESS (0.642s)
[216.370109] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.758s)
[216.797989] [115/161] (810s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[217.182089] Starting subtest: compare-crc-sanitycheck-nv12
[217.208513] Starting dynamic subtest: pipe-A-DP-1
[217.258903] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[217.333963] Starting dynamic subtest: pipe-B-DP-1
[217.389494] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[217.464994] Starting dynamic subtest: pipe-C-DP-1
[217.518770] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[217.573069] Subtest compare-crc-sanitycheck-nv12: SKIP (0.017s)
[217.765615] [116/161] (809s left) kms_pipe_crc_basic (hang-read-crc)
[218.125656] Starting subtest: hang-read-crc
[218.146937] Starting dynamic subtest: pipe-A-DP-1
[219.479042] Dynamic subtest pipe-A-DP-1: SUCCESS (1.338s)
[219.528036] Starting dynamic subtest: pipe-B-DP-1
[220.940389] Dynamic subtest pipe-B-DP-1: SUCCESS (1.469s)
[220.989173] Starting dynamic subtest: pipe-C-DP-1
[222.447301] Dynamic subtest pipe-C-DP-1: SUCCESS (1.488s)
[222.471153] Subtest hang-read-crc: SUCCESS (4.311s)
[222.898657] [117/161] (804s left) kms_pipe_crc_basic (nonblocking-crc)
[223.262925] Starting subtest: nonblocking-crc
[223.284211] Starting dynamic subtest: pipe-A-DP-1
[223.900826] Dynamic subtest pipe-A-DP-1: SUCCESS (0.634s)
[223.969642] Starting dynamic subtest: pipe-B-DP-1
[224.716899] Dynamic subtest pipe-B-DP-1: SUCCESS (0.813s)
[224.765264] Starting dynamic subtest: pipe-C-DP-1
[225.560858] Dynamic subtest pipe-C-DP-1: SUCCESS (0.812s)
[225.583778] Subtest nonblocking-crc: SUCCESS (2.273s)
[225.962481] [118/161] (801s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[226.340272] Starting subtest: nonblocking-crc-frame-sequence
[226.366990] Starting dynamic subtest: pipe-A-DP-1
[226.984336] Dynamic subtest pipe-A-DP-1: SUCCESS (0.647s)
[227.039447] Starting dynamic subtest: pipe-B-DP-1
[227.777810] Dynamic subtest pipe-B-DP-1: SUCCESS (0.789s)
[227.824183] Starting dynamic subtest: pipe-C-DP-1
[228.586192] Dynamic subtest pipe-C-DP-1: SUCCESS (0.799s)
[228.606681] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.251s)
[229.003613] [119/161] (798s left) kms_pipe_crc_basic (read-crc)
[229.369634] Starting subtest: read-crc
[229.391369] Starting dynamic subtest: pipe-A-DP-1
[229.955038] Dynamic subtest pipe-A-DP-1: SUCCESS (0.583s)
[230.006472] Starting dynamic subtest: pipe-B-DP-1
[230.669544] Dynamic subtest pipe-B-DP-1: SUCCESS (0.728s)
[230.719827] Starting dynamic subtest: pipe-C-DP-1
[231.423305] Dynamic subtest pipe-C-DP-1: SUCCESS (0.733s)
[231.444908] Subtest read-crc: SUCCESS (2.060s)
[231.848899] [120/161] (795s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[232.210442] Starting subtest: read-crc-frame-sequence
[232.229904] Starting dynamic subtest: pipe-A-DP-1
[232.772967] Dynamic subtest pipe-A-DP-1: SUCCESS (0.554s)
[232.822124] Starting dynamic subtest: pipe-B-DP-1
[233.516337] Dynamic subtest pipe-B-DP-1: SUCCESS (0.735s)
[233.577760] Starting dynamic subtest: pipe-C-DP-1
[234.252275] Dynamic subtest pipe-C-DP-1: SUCCESS (0.734s)
[234.275046] Subtest read-crc-frame-sequence: SUCCESS (2.040s)
[234.708492] [121/161] (793s left) kms_pm_backlight (basic-brightness)
[235.103943] Subtest basic-brightness: SKIP (0.000s)
[235.277489] [122/161] (792s left) kms_pm_rpm (basic-pci-d3-state)
[237.113225] Starting subtest: basic-pci-d3-state
[237.788721] Subtest basic-pci-d3-state: SUCCESS (0.669s)
[238.122526] [123/161] (790s left) kms_pm_rpm (basic-rte)
[238.367796] Starting subtest: basic-rte
[240.568811] Subtest basic-rte: SUCCESS (2.232s)
[241.549707] [124/161] (787s left) kms_psr (psr-primary-page-flip)
[241.937615] Subtest psr-primary-page-flip: SKIP (0.000s)
[242.111958] [125/161] (786s left) kms_psr (psr-cursor-plane-move)
[242.494403] Subtest psr-cursor-plane-move: SKIP (0.000s)
[242.669864] [126/161] (786s left) kms_psr (psr-sprite-plane-onoff)
[243.055468] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[243.230611] [127/161] (785s left) kms_psr (psr-primary-mmap-gtt)
[243.611627] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[243.783896] [128/161] (785s left) kms_setmode (basic-clone-single-crtc)
[244.064989] Starting subtest: basic-clone-single-crtc
[244.131599] Subtest basic-clone-single-crtc: SKIP (0.000s)
[244.308709] [129/161] (785s left) i915_pm_rps (basic-api)
[244.588566] Starting subtest: basic-api
[244.611772] Subtest basic-api: SUCCESS (0.007s)
[244.808312] [130/161] (784s left) prime_self_import (basic-llseek-bad)
[245.044888] Starting subtest: basic-llseek-bad
[245.086686] Subtest basic-llseek-bad: SUCCESS (0.005s)
[245.261674] [131/161] (784s left) prime_self_import (basic-llseek-size)
[245.486993] Starting subtest: basic-llseek-size
[245.537409] Subtest basic-llseek-size: SUCCESS (0.008s)
[245.728901] [132/161] (784s left) prime_self_import (basic-with_fd_dup)
[245.989191] Starting subtest: basic-with_fd_dup
[246.045935] Subtest basic-with_fd_dup: SUCCESS (0.008s)
[246.236146] [133/161] (783s left) prime_self_import (basic-with_one_bo)
[246.479846] Starting subtest: basic-with_one_bo
[246.532658] Subtest basic-with_one_bo: SUCCESS (0.007s)
[246.721739] [134/161] (783s left) prime_self_import (basic-with_one_bo_two_files)
[246.976100] Starting subtest: basic-with_one_bo_two_files
[247.018856] Subtest basic-with_one_bo_two_files: SUCCESS (0.007s)
[247.196773] [135/161] (783s left) prime_self_import (basic-with_two_bos)
[247.443108] Starting subtest: basic-with_two_bos
[247.484264] Subtest basic-with_two_bos: SUCCESS (0.008s)
[247.665722] [136/161] (783s left) prime_vgem (basic-fence-flip)
[247.941420] Starting subtest: basic-fence-flip
[248.576568] Subtest basic-fence-flip: SUCCESS (0.669s)
[248.795297] [137/161] (782s left) prime_vgem (basic-fence-mmap)
[249.080167] Starting subtest: basic-fence-mmap
[249.129194] Subtest basic-fence-mmap: SUCCESS (0.074s)
[249.326972] [138/161] (781s left) prime_vgem (basic-fence-read)
[249.614546] Starting subtest: basic-fence-read
[249.720466] Subtest basic-fence-read: SUCCESS (0.145s)
[249.944865] [139/161] (781s left) prime_vgem (basic-gtt)
[250.224124] Starting subtest: basic-gtt
[250.246115] Subtest basic-gtt: SUCCESS (0.033s)
[250.442911] [140/161] (781s left) prime_vgem (basic-read)
[250.727844] Starting subtest: basic-read
[250.751358] Subtest basic-read: SUCCESS (0.067s)
[250.954479] [141/161] (780s left) prime_vgem (basic-write)
[251.217914] Starting subtest: basic-write
[251.263847] Subtest basic-write: SUCCESS (0.095s)
[251.466626] [142/161] (780s left) vgem_basic (setversion)
[251.742568] Starting subtest: setversion
[251.784991] Subtest setversion: SUCCESS (0.000s)
[251.962130] [143/161] (780s left) vgem_basic (create)
[252.211839] Starting subtest: create
[252.234979] Subtest create: SUCCESS (0.001s)
[252.409274] [144/161] (779s left) vgem_basic (debugfs)
[252.655860] Starting subtest: debugfs
[252.676642] Subtest debugfs: SUCCESS (0.001s)
[252.853286] [145/161] (779s left) vgem_basic (dmabuf-export)
[253.121457] Starting subtest: dmabuf-export
[253.162838] Subtest dmabuf-export: SUCCESS (0.017s)
[253.346152] [146/161] (779s left) vgem_basic (dmabuf-fence)
[253.613999] Starting subtest: dmabuf-fence
[253.635110] Subtest dmabuf-fence: SUCCESS (0.002s)
[253.812331] [147/161] (779s left) vgem_basic (dmabuf-fence-before)
[254.081857] Starting subtest: dmabuf-fence-before
[254.104211] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[254.277475] [148/161] (778s left) vgem_basic (dmabuf-mmap)
[254.537706] Starting subtest: dmabuf-mmap
[254.560838] Subtest dmabuf-mmap: SUCCESS (0.039s)
[254.735796] [149/161] (778s left) vgem_basic (mmap)
[254.984539] Starting subtest: mmap
[255.004981] Subtest mmap: SUCCESS (0.024s)
[255.186865] [150/161] (778s left) vgem_basic (second-client)
[255.433875] Starting subtest: second-client
[255.479152] Subtest second-client: SUCCESS (0.032s)
[255.657856] [151/161] (777s left) vgem_basic (sysfs)
[255.908377] Starting subtest: sysfs
[255.929069] Subtest sysfs: SUCCESS (0.001s)
[256.108955] [152/161] (777s left) core_hotunplug (unbind-rebind)
[256.500907] Starting subtest: unbind-rebind
[261.193197] Subtest unbind-rebind: SUCCESS (4.704s)
[261.381705] [153/161] (772s left) vgem_basic (unload)
[261.647642] Starting subtest: unload
[290.959697] Subtest unload: SUCCESS (29.191s)
[291.133557] [154/161] (743s left) i915_module_load (reload)
[291.384711] Starting subtest: reload
[297.167861] Subtest reload: SUCCESS (5.822s)
[297.446329] [155/161] (737s left) gem_lmem_swapping (basic)
[297.746237] Subtest basic: SKIP (0.000s)
[297.919698] [156/161] (736s left) gem_lmem_swapping (parallel-random-engines)
[298.208995] Subtest parallel-random-engines: SKIP (0.000s)
[298.382478] [157/161] (736s left) gem_lmem_swapping (random-engines)
[298.672475] Subtest random-engines: SKIP (0.000s)
[298.852297] [158/161] (736s left) gem_lmem_swapping (verify-random)
[299.140763] Subtest verify-random: SKIP (0.000s)
[299.318990] [159/161] (735s left) i915_pm_rpm (module-reload)
[301.460098] Starting subtest: module-reload
[316.418127] Subtest module-reload: SUCCESS (14.965s)
[316.545570] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[316.549916] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[316.573796] [160/161] (718s left) i915_selftest (live)
[316.817291] Starting subtest: live
[316.840430] Starting dynamic subtest: sanitycheck
[320.853784] Dynamic subtest sanitycheck: SUCCESS (4.041s)
[320.914051] Starting dynamic subtest: uncore
[324.836225] Dynamic subtest uncore: SUCCESS (3.981s)
[324.880929] Starting dynamic subtest: workarounds
[329.253111] Dynamic subtest workarounds: SUCCESS (4.404s)
[329.297708] Starting dynamic subtest: gt_engines
[333.698256] Dynamic subtest gt_engines: SUCCESS (4.445s)
[333.743350] Starting dynamic subtest: gt_timelines
[357.775054] Dynamic subtest gt_timelines: SUCCESS (24.072s)
[357.818463] Starting dynamic subtest: gt_contexts
[361.908159] Dynamic subtest gt_contexts: SUCCESS (4.130s)
[361.957945] Starting dynamic subtest: gt_lrc
[368.870751] Dynamic subtest gt_lrc: SUCCESS (6.954s)
[368.932322] Starting dynamic subtest: gt_mocs
[373.022370] Dynamic subtest gt_mocs: SUCCESS (4.141s)
[373.066137] Starting dynamic subtest: gt_pm
[385.248654] Dynamic subtest gt_pm: SUCCESS (12.227s)
[385.293243] Starting dynamic subtest: gt_heartbeat
[389.361564] Dynamic subtest gt_heartbeat: SUCCESS (4.112s)
[389.403784] Starting dynamic subtest: gt_tlb
[394.161723] Dynamic subtest gt_tlb: SUCCESS (4.795s)
[394.206935] Starting dynamic subtest: requests
[404.680483] Dynamic subtest requests: SUCCESS (10.513s)
[404.723142] Starting dynamic subtest: migrate
[412.232350] Dynamic subtest migrate: SUCCESS (7.547s)
[412.274877] Starting dynamic subtest: active
[415.957098] Dynamic subtest active: SUCCESS (3.720s)
[415.998643] Starting dynamic subtest: objects
[422.482260] Dynamic subtest objects: SUCCESS (6.518s)
[422.542103] Starting dynamic subtest: mman
[432.458130] Dynamic subtest mman: SUCCESS (9.971s)
[432.519375] Starting dynamic subtest: dmabuf
[436.674452] Dynamic subtest dmabuf: SUCCESS (4.208s)
[436.718410] Starting dynamic subtest: vma
[440.506199] Dynamic subtest vma: SUCCESS (3.829s)
[440.565613] Starting dynamic subtest: coherency
[448.101544] Dynamic subtest coherency: SUCCESS (7.591s)
[448.145911] Starting dynamic subtest: gtt
[459.432244] Dynamic subtest gtt: SUCCESS (11.326s)
[459.475440] Starting dynamic subtest: gem
[464.771206] Dynamic subtest gem: SUCCESS (5.324s)
[464.822507] Starting dynamic subtest: evict
[473.881100] Dynamic subtest evict: SUCCESS (9.114s)
[473.923239] Starting dynamic subtest: hugepages
[484.627795] Dynamic subtest hugepages: SUCCESS (10.741s)
[484.669954] Starting dynamic subtest: gem_contexts
[511.312990] Dynamic subtest gem_contexts: SUCCESS (26.506s)
[511.366141] Starting dynamic subtest: client
[515.310552] Dynamic subtest client: SUCCESS (4.166s)
[515.394323] Starting dynamic subtest: gem_migrate
[519.365645] Dynamic subtest gem_migrate: SUCCESS (4.051s)
[519.418517] Starting dynamic subtest: reset
[535.531299] Dynamic subtest reset: SUCCESS (16.159s)
[535.573925] Starting dynamic subtest: memory_region
[539.202208] Dynamic subtest memory_region: SUCCESS (3.668s)
[539.289937] Starting dynamic subtest: hangcheck
[566.319294] Dynamic subtest hangcheck: SUCCESS (27.112s)
[566.361358] Starting dynamic subtest: execlists
[580.999672] Dynamic subtest execlists: SUCCESS (14.673s)
[581.061294] Starting dynamic subtest: ring_submission
[584.847246] Dynamic subtest ring_submission: SUCCESS (3.843s)
[584.909196] Starting dynamic subtest: perf
[588.932705] Dynamic subtest perf: SUCCESS (4.083s)
[588.995912] Starting dynamic subtest: slpc
[592.832733] Dynamic subtest slpc: SUCCESS (3.889s)
[592.894605] Starting dynamic subtest: guc
[596.640808] Dynamic subtest guc: SUCCESS (3.810s)
[596.703455] Starting dynamic subtest: guc_multi_lrc
[600.599254] Dynamic subtest guc_multi_lrc: SUCCESS (3.955s)
[600.662443] Starting dynamic subtest: guc_hang
[604.585325] Dynamic subtest guc_hang: SUCCESS (3.979s)
[604.670063] Starting dynamic subtest: late_gt_pm
[608.674405] Dynamic subtest late_gt_pm: SUCCESS (4.081s)
[608.697202] Subtest live: SUCCESS (291.856s)
[608.849445] [161/161] (426s left) dmabuf (all-tests)
[609.103577] Starting subtest: all-tests
[609.124706] Starting dynamic subtest: sanitycheck
[609.212149] Dynamic subtest sanitycheck: SUCCESS (0.103s)
[609.256059] Starting dynamic subtest: dma_fence
[609.455221] Dynamic subtest